[cisco-voip] Arc Enterprise v504.485 with CUCM702

FrogOnDSCP46EF ciscoboy2006 at gmail.com
Sun May 17 05:11:53 EDT 2009


Hi Guys,

I was playing with the Arc 504 enterprise "arc connect CT server"
integration with CCM702.10000,18

When there is a double line from same MAC address/device, ARC CT server is
going banana. It is keep shutting down the arc ct server and restarting it.
Once I de-associated the 2nd line from ARC TAPI user in CCM, of same device
then its okay. it doesn't shut/restart.

Q1: Is there any fix for this bug for Arc console? End user can easily do
this kind of mistake (associate both phone lines) and can loose the whole
console.

Q2: is CTI server from ARC is a must to install? I found its working without
Arc CTI server. Calls are routed.

Is ARC CTI server in Enterprise v5 for pulling users info from ccm without a
need of associatng each and every ccm phone manually? It pulls using
application user all devices from ccm?
or is there any other purpose?

Here are the ccm and arc server logs:


CCM CTI SDL traces
============================================================



000016201| 2009/05/17 08:56:26.304| 001| AppInfo
|                                       |                               |
CTIHandler(1,200,16,15)         |
|                                         | Send CTI  Server Heartbeat
000016202| 2009/05/17 08:56:26.573| 001| SdlSig    |
CtiClientHeartbeatTimer               | ready                         |
CTIHandler(1,200,16,15)         | SdlTimerService(1,200,3,1)      |
(1,200,15,1).1450-(*:*)                 | [R:HP - HP: 0, NP: 0, LP: 0, VLP:
0, LZP: 0 DBP: 0]
000016203| 2009/05/17 08:56:56.130| 001| AppInfo
|                                       |                               |
CTIHandler(1,200,16,15)         |
|                                         | [CTI-APP]
[CTIHandler::processIncomingMessage]     CTI   Client Heartbeat   (
application name=CiscoTSP001-142.2.64.51)
000016204| 2009/05/17 08:56:56.130| 001| SdlSig    |
CtiHeartbeat                          | ready                         |
CTIHandler(1,200,16,15)         | CTIHandler(1,200,16,15)         |
(1,200,15,1).1510-(*:*)                 | [R:NP - HP: 0, NP: 0, LP: 0, VLP:
0, LZP: 0 DBP: 0]
000016205| 2009/05/17 08:56:56.320| 001| SdlSig    |
CtiServerHeartbeatTimer               | ready                         |
CTIHandler(1,200,16,15)         | SdlTimerService(1,200,3,1)      |
(1,200,15,1).1450-(*:*)                 | [R:HP - HP: 0, NP: 0, LP: 0, VLP:
0, LZP: 0 DBP: 0]
000016206| 2009/05/17 08:56:56.320| 001| AppInfo
|                                       |                               |
CTIHandler(1,200,16,15)         |
|                                         | [CTI-APP]
[CTIHandler::OutputCtiMessage      ]     CTI   Server Heartbeat   (
application name=CiscoTSP001-142.2.64.51)
000016207| 2009/05/17 08:56:56.320| 001| AppInfo
|                                       |                               |
CTIHandler(1,200,16,15)         |
|                                         | Send CTI  Server Heartbeat
000016208| 2009/05/17 08:56:56.578| 001| SdlSig    |
CtiClientHeartbeatTimer               | ready                         |
CTIHandler(1,200,16,15)         | SdlTimerService(1,200,3,1)      |
(1,200,15,1).1450-(*:*)                 | [R:HP - HP: 0, NP: 0, LP: 0, VLP:
0, LZP: 0 DBP: 0]
000016209| 2009/05/17 08:57:26.153| 001| AppInfo
|                                       |                               |
CTIHandler(1,200,16,15)         |
|                                         | [CTI-APP]
[CTIHandler::processIncomingMessage]     CTI   Client Heartbeat   (
application name=CiscoTSP001-142.2.64.51)
000016210| 2009/05/17 08:57:26.153| 001| SdlSig    |
CtiHeartbeat                          | ready                         |
CTIHandler(1,200,16,15)         | CTIHandler(1,200,16,15)         |
(1,200,15,1).1511-(*:*)                 | [R:NP - HP: 0, NP: 0, LP: 0, VLP:
0, LZP: 0 DBP: 0]
000016211| 2009/05/17 08:57:26.338| 001| SdlSig    |
CtiServerHeartbeatTimer               | ready                         |
CTIHandler(1,200,16,15)         | SdlTimerService(1,200,3,1)      |
(1,200,15,1).1450-(*:*)                 | [R:HP - HP: 0, NP: 0, LP: 0, VLP:
0, LZP: 0 DBP: 0]
000016212| 2009/05/17 08:57:26.338| 001| AppInfo
|                                       |                               |
CTIHandler(1,200,16,15)         |
|                                         | [CTI-APP]
[CTIHandler::OutputCtiMessage      ]     CTI   Server Heartbeat   (
application name=CiscoTSP001-142.2.64.51)
000016213| 2009/05/17 08:57:26.338| 001| AppInfo
|                                       |                               |
CTIHandler(1,200,16,15)         |
|                                         | Send CTI  Server Heartbeat
000016214| 2009/05/17 08:57:26.592| 001| SdlSig    |
CtiClientHeartbeatTimer               | ready                         |
CTIHandler(1,200,16,15)         | SdlTimerService(1,200,3,1)      |
(1,200,15,1).1450-(*:*)                 | [R:HP - HP: 0, NP: 0, LP: 0, VLP:
0, LZP: 0 DBP: 0]
000016215| 2009/05/17 08:57:56.132| 001| AppInfo
|                                       |                               |
CTIHandler(1,200,16,15)         |
|                                         | [CTI-APP]
[CTIHandler::processIncomingMessage]     CTI   Client Heartbeat   (
application name=CiscoTSP001-142.2.64.51)
000016216| 2009/05/17 08:57:56.132| 001| SdlSig    |
CtiHeartbeat                          | ready                         |
CTIHandler(1,200,16,15)         | CTIHandler(1,200,16,15)         |
(1,200,15,1).1512-(*:*)                 | [R:NP - HP: 0, NP: 0, LP: 0, VLP:
0, LZP: 0 DBP: 0]
000016217| 2009/05/17 08:57:56.349| 001| SdlSig    |
CtiServerHeartbeatTimer               | ready                         |
CTIHandler(1,200,16,15)         | SdlTimerService(1,200,3,1)      |
(1,200,15,1).1450-(*:*)                 | [R:HP - HP: 0, NP: 0, LP: 0, VLP:
0, LZP: 0 DBP: 0]
000016218| 2009/05/17 08:57:56.350| 001| AppInfo
|                                       |                               |
CTIHandler(1,200,16,15)         |
|                                         | [CTI-APP]
[CTIHandler::OutputCtiMessage      ]     CTI   Server Heartbeat   (
application name=CiscoTSP001-142.2.64.51)
000016219| 2009/05/17 08:57:56.350| 001| AppInfo
|                                       |                               |
CTIHandler(1,200,16,15)         |
|                                         | Send CTI  Server Heartbeat
000016220| 2009/05/17 08:57:56.598| 001| SdlSig    |
CtiClientHeartbeatTimer               | ready                         |
CTIHandler(1,200,16,15)         | SdlTimerService(1,200,3,1)      |
(1,200,15,1).1450-(*:*)                 | [R:HP - HP: 0, NP: 0, LP: 0, VLP:
0, LZP: 0 DBP: 0]
000016221| 2009/05/17 08:58:26.133| 001| AppInfo
|                                       |                               |
CTIHandler(1,200,16,15)         |
|                                         | [CTI-APP]
[CTIHandler::processIncomingMessage]     CTI   Client Heartbeat   (
application name=CiscoTSP001-142.2.64.51)
000016222| 2009/05/17 08:58:26.133| 001| SdlSig    |
CtiHeartbeat                          | ready                         |
CTIHandler(1,200,16,15)         | CTIHandler(1,200,16,15)         |
(1,200,15,1).1513-(*:*)                 | [R:NP - HP: 0, NP: 0, LP: 0, VLP:
0, LZP: 0 DBP: 0]
000016223| 2009/05/17 08:58:26.358| 001| SdlSig    |
CtiServerHeartbeatTimer               | ready                         |
CTIHandler(1,200,16,15)         | SdlTimerService(1,200,3,1)      |
(1,200,15,1).1450-(*:*)                 | [R:HP - HP: 0, NP: 0, LP: 0, VLP:
0, LZP: 0 DBP: 0]
000016224| 2009/05/17 08:58:26.358| 001| AppInfo
|                                       |                               |
CTIHandler(1,200,16,15)         |
|                                         | [CTI-APP]
[CTIHandler::OutputCtiMessage      ]     CTI   Server Heartbeat   (
application name=CiscoTSP001-142.2.64.51)
000016225| 2009/05/17 08:58:26.358| 001| AppInfo
|                                       |                               |
CTIHandler(1,200,16,15)         |
|                                         | Send CTI  Server Heartbeat
000016226| 2009/05/17 08:58:26.614|

ARC logs:
==================================================================

17/5/2009 18:45:53.187|R|XML>Reply, ET=304, TR=, OS=2002, RT=, RE=, RD=
17/5/2009 18:45:53.187|R|XML>DeviceStatus, Received, "2002", Capability=N,
Service=N, MAC=SEP888888888888
17/5/2009 18:45:53.187|M|"DeviceFeatureNE","RouterEvent","2002""
17/5/2009 18:45:53.187|R|XML>Reply, ET=304, TR=, OS=2002, RT=, RE=, RD=
17/5/2009 18:45:53.187|R|XML>DeviceStatus, Received, "2002", Capability=N,
Service=N, MAC=SEP888888888888
17/5/2009 18:45:53.187|M|"DeviceFeatureNE","RouterEvent","2002""
17/5/2009 18:45:53.187|R|XML>Reply, ET=304, TR=, OS=3002, RT=, RE=, RD=
17/5/2009 18:45:53.187|R|XML>DeviceStatus, Received, "3002", Capability=Y,
Service=N, MAC=SEP888888888888
17/5/2009 18:45:53.187|M|"DeviceFeatureNE","RouterEvent","3002""
17/5/2009 18:45:53.187|R|XML>Reply, ET=304, TR=, OS=2002, RT=, RE=, RD=
17/5/2009 18:45:53.187|R|XML>DeviceStatus, Received, "2002", Capability=N,
Service=N, MAC=SEP888888888888
17/5/2009 18:45:53.187|M|"DeviceFeatureNE","RouterEvent","2002""
17/5/2009 18:45:53.187|R|XML>Reply, ET=304, TR=, OS=3002, RT=, RE=, RD=
17/5/2009 18:45:53.187|R|XML>DeviceStatus, Received, "3002", Capability=Y,
Service=N, MAC=SEP888888888888
17/5/2009 18:45:53.187|M|"DeviceFeatureNE","RouterEvent","3002""
17/5/2009 18:45:53.187|R|"TimerEvent","Event Relay [41]"
17/5/2009 18:45:53.187|R|CT>"CTCE","Invalid","I:13","S:0","E:0 [0x00000000]"

17/5/2009
18:45:53.187|R|CT>"CTEE","SEVT_MONITOR","E:10","2002","SNo:SEP888888888888"
17/5/2009 18:45:53.187|R|CT>"CTEE","SEVT_MONITOR","2002","State:Y"
17/5/2009 18:45:53.187|M|"DeviceFeatureNE","RouterEvent","2002""
17/5/2009
18:45:53.187|R|CT>"TQueryForward","TQueryForward(Request)","2002","TQueryForward('2002')"

17/5/2009
18:45:53.187|R|CT>"TQueryForward","TQueryForward(Response)","2002","TQueryForward()
= SUCCESS"
17/5/2009
18:45:53.187|R|CT>"CTEE","SEVT_DEVICE","E:97","2002","SNo:SEP888888888888"
17/5/2009 18:45:53.187|R|CT>"CTEE","SEVT_DEVICE","EEVT_OUTOFSERVICE","2002"
17/5/2009 18:45:53.187|R|"DeviceFailureEvent","2002"
17/5/2009
18:45:53.187|R|"TIEnv.CTLAShutdownDP","Generate(teShutdown)","Devices.OutOfService:1","EventDelay:30000"

17/5/2009
18:45:53.187|R|CT>"CTEE","SEVT_MONITOR","E:10","2002","SNo:SEP888888888888"
17/5/2009
18:45:53.187|R|CT>"CTEE","SEVT_DEVICE","E:97","2002","SNo:SEP888888888888"
17/5/2009 18:45:53.187|R|CT>"CTEE","SEVT_DEVICE","EEVT_OUTOFSERVICE","2002"
17/5/2009 18:45:53.187|R|"DeviceFailureEvent","2002"
17/5/2009
18:45:53.187|R|"TIEnv.CTLAShutdownDP","Already(teShutdown)","Devices.OutOfService:1"

17/5/2009 18:45:53.187|R|"teMonitorBLFDevices","EventNotify"
17/5/2009
18:45:53.187|R|CT>"MonitorDevice","MonitorStartDevice(Request)","3002","MonitorStartDevice('3002','',MT_MONITOR)"

17/5/2009
18:45:53.187|R|CT>"MonitorDevice","MonitorStartDevice(Response)","3002","MonitorStartDevice()
= FAILURE(15)"
17/5/2009
18:45:53.187|R|"TimerEvent","Recycle","Generate(teMonitorBLFDevices)","CurrentIndex:1","BLFDevicesCount:2"

17/5/2009 18:45:53.203|R|"TimerEvent","Event Relay [41]"
17/5/2009 18:45:53.203|R|"teMonitorBLFDevices","EventNotify"
17/5/2009 18:45:53.203|R|"teMonitorBLFDevices","No More BLF Device Left to
be Monitored","CurrentIndex:2","BLFDevicesCount:2"
17/5/2009 18:45:53.203|R|CT>"CTCE","Invalid","I:15","S:0","E:0 [0x00000000]"

17/5/2009
18:45:53.203|R|CT>"CTEE","SEVT_MONITOR","E:10","3002","SNo:SEP888888888888"
17/5/2009 18:45:53.203|R|CT>"CTEE","SEVT_MONITOR","3002","State:Y"
17/5/2009 18:45:53.203|M|"DeviceFeatureNE","RouterEvent","3002""
17/5/2009
18:45:53.203|R|CT>"TQueryForward","TQueryForward(Request)","3002","TQueryForward('3002')"

17/5/2009
18:45:53.203|R|CT>"TQueryForward","TQueryForward(Response)","3002","TQueryForward()
= SUCCESS"
17/5/2009
18:45:53.203|R|CT>"CTEE","SEVT_DEVICE","E:97","3002","SNo:SEP888888888888"
17/5/2009 18:45:53.203|R|CT>"CTEE","SEVT_DEVICE","EEVT_OUTOFSERVICE","3002"
17/5/2009 18:45:53.203|R|"DeviceFailureEvent","3002"
17/5/2009 18:45:56.656|M|"InitializeICDSystemLog","Initialize","Success"
17/5/2009
18:46:23.203|R|"TimerEvent","teShutdown","Status:Y","RT:1","CTLF:Y","GCTLS:4""

17/5/2009
18:46:23.203|R|"TIEnv.CTLAShutdownDP","Error(Event.Iteration)","Devices.OutOfService:1"

17/5/2009
18:46:23.203|R|"TIEnv.CTLAShutdownDP","AutoRestartOnDeviceFail.Disabled","Devices.OutOfService:1","EventDelay:30000"

17/5/2009
18:46:23.203|R|"TIEnv.CTLAShutdownDP","Error(NoGatewayDevices)","Shutdown.Imminent","gctlsFail"

17/5/2009
18:46:23.203|M|"CTServerStatusNE","RouterEvent","CTServerStatus","1","1",""
17/5/2009 18:46:23.203|M|"CTServerStatusNE","Server Shutdown\Restarted","CT
Link Error"
17/5/2009 18:46:23.234|M|"StopEMSCalendarClient","Started"
17/5/2009 18:46:23.234|CL|"StopEMSClient","Current Client
State:EMSUnknown","Last Client State:EMSUnknown"
17/5/2009 18:46:23.234|CL|"DisconnectRecovery: Started "
17/5/2009 18:46:23.234|CL|"DisconnectRecovery: End "
17/5/2009 18:46:23.234|CL|"StopEMSClient","Success"
17/5/2009 18:46:23.234|M|"StopEMSCalendarClient","Success"
17/5/2009 18:46:23.234|M|"StopEMSCalendarClient","Shutdown","Started"
17/5/2009 18:46:23.234|M|"StopEMSCalendarClient","Shutdown","Success"
17/5/2009 18:46:23.234|M|"PMS>ShutdownPresenceManager","Status=pssStopped"
17/5/2009 18:46:23.234|M|"ShutdownServices","Shutdown","Started"
17/5/2009 18:46:23.234|M|"VoiceServerClient","ShutdownMonitor","Success"
17/5/2009 18:46:23.234|R|"TIEnv","Destructor","Terminating event
services..."
17/5/2009
18:46:23.234|R|"FilterPriorityRoutingExecution.RouteManager.Stopped"
17/5/2009 18:46:23.234|R|XML>Status, mssRunning -> mssShutdownREQ
17/5/2009 18:46:23.234|R|XML>Reply, ET=301, TR=, OS=, RT=, RE=, RD=
17/5/2009 18:46:23.234|R|XML>Status, mssShutdownREQ -> mssShutdown
17/5/2009 18:46:23.234|R|XML>Reply, ET=304, TR=, OS=2002, RT=, RE=, RD=
17/5/2009 18:46:23.234|R|XML>DeviceStatus, Received, "2002", Capability=N,
Service=N, MAC=SEP888888888888
17/5/2009 18:46:23.234|M|"DeviceFeatureNE","RouterEvent","2002""
17/5/2009 18:46:23.234|R|XML>Reply, ET=304, TR=, OS=3002, RT=, RE=, RD=
17/5/2009 18:46:23.234|R|XML>DeviceStatus, Received, "3002", Capability=Y,
Service=N, MAC=SEP888888888888
17/5/2009 18:46:23.234|M|"DeviceFeatureNE","RouterEvent","3002""
17/5/2009 18:46:23.234|R|XML>Reply, ET=301, TR=, OS=, RT=, RE=, RD=
17/5/2009 18:46:23.234|R|XML>Status, mssShutdown -> mssStopped
17/5/2009 18:46:23.234|R|XML>Failover
17/5/2009 18:46:23.234|R|XML>FailoverDevices
17/5/2009 18:46:25.250|R|XML>TPGShutDown=0[0x00000000]
17/5/2009 18:46:25.250|R|"ICD Routing Thread","Shutdown Started"
17/5/2009 18:46:25.250|R|"ICD Routing Thread","Shutdown CT Calls"
17/5/2009 18:46:25.250|R|"ICD Routing Thread","Shutdown CT Link"
17/5/2009
18:46:25.250|R|CT>"ICDRouter","TLogOutServer","TLogOutServer(Request)","TLogOutServer()"

17/5/2009
18:46:26.937|R|CT>"ICDRouter","TLogOutServer","TLogOutServer(Response)","TLogOutServer()
= 0"
17/5/2009 18:46:27.031|R|"ICD Routing Thread","Shutdown Complete"
17/5/2009 18:46:27.031|R|"TIEnv","Destructor","Terminating callbacks..."
17/5/2009 18:46:27.031|R|"TIEnv","Destructor","Terminating event objects..."

17/5/2009 18:46:27.031|M|"ICDRouter","Shutdown","Success"
17/5/2009 18:46:27.046|M|"ICDCommunications","Shutdown","Success"
17/5/2009 18:46:27.046|M|"ShutdownDBObjects","Shutdown","Start"
17/5/2009 18:46:27.046|M|"ShutdownDBObjects","Shutdown","Terminate Call
Objects..."
17/5/2009 18:46:27.046|M|"ShutdownDBObjects","Shutdown","Terminate Agent
Objects..."
17/5/2009
18:46:27.046|M|"ShutdownDBConnectionObject","Shutdown","SID:","CLF:10","CLF:1"

17/5/2009 18:46:27.046|M|"ShutdownDBObjects","Shutdown","Complete"
17/5/2009 18:46:27.046|M|"ICDDataBase","ShutdownDBObjects","Success"
17/5/2009 18:46:27.046|M|"ICDDataBase","Shutdown","Success"
17/5/2009 18:46:27.046|M|"RecordInterface","Shutdown","Success"
17/5/2009 18:46:27.046|M|"ICDDataBase","Shutdown","Success"
17/5/2009 18:46:27.046|M|"COM>SetCOMEnvironment.CoUninitialize"
17/5/2009 18:46:27.046|M|"ShutdownServices","Shutdown","Completed"
17/5/2009 18:46:27.046|M|"ShutdownVoiceServices","Shutdown","Started"
17/5/2009 18:46:27.046|M|"VoiceServerClient","Shutdown","Success"
17/5/2009 18:46:27.046|M|"ShutdownVoiceServices","Shutdown","Completed"
17/5/2009 18:46:27.046|M|"Voice Process Error
Logging","Uninitialize","Disabled","Ok"
-- 
Smile, you'll save someone else's day!
Frog
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://puck.nether.net/pipermail/cisco-voip/attachments/20090517/0b88d160/attachment.html>


More information about the cisco-voip mailing list