Cisco Unified Application Environment Developer Forums

« Back to Etch

RE: Platform Exception on JtapiStartMonitoring

Combination View Flat View Tree View
Threads [ Previous | Next ]
Platform Exception on JtapiStartMonitoring
jtapi jtapi jtapistartmonitoring recording
Answer
8/19/09 10:07 PM
Hi,
      we are developing an etch based voice recording application which will do  JTAPIStartMonitoring for recording a monitored extension. we are getting platform exception while we try to do the JTAPI start Monitoring API. I wonder if some settings need to be done additionally in CUAE and CUCM.  It seems that our CTI port got initiated with the JTAPI call , but after that platform excdption is raised. ( E JTP Call CtiPort2:1000082 encountered an error (PlatformException): <no description>) . please find the logs attached as below for further analysis.
 
21:50:04.968 V JTP Got 20(IncomingCall) message from v6.1.
21:50:04.968 V JTP Field: 5(DeviceName) = SEP001CC0314499
21:50:04.968 V JTP Field: 6(DeviceType) = 6
21:50:04.968 V JTP Field: 40(CallId) = rWS8YgxrRPv9lNJh
21:50:04.984 V JTP Field: 41(To) = 1037
21:50:04.984 V JTP Field: 42(From) = 1038
21:50:04.984 V JTP Field: 43(OriginalTo) = 1037
21:50:04.984 V JTP Field: 53(CallControlCause) = NORMAL
21:50:04.984 V JTP Field: 66(JTapiCallId) = 8122/1
21:50:04.984 I JTP Added JTapi call 1000081<->rWS8YgxrRPv9lNJh
21:50:04.984 I JTP Sending third-party IncomingCall for: 1000081
21:50:04.984 V JTP Got 99(Ringing) message from v6.1.
21:50:04.984 V JTP Field: 5(DeviceName) = SEP001CC0314499
21:50:04.984 V JTP Field: 6(DeviceType) = 6
21:50:04.984 V JTP Field: 40(CallId) = rWS8YgxrRPv9lNJh
21:50:04.984 V JTP Field: 41(To) = 1037
21:50:04.984 V JTP Field: 42(From) = 1038
21:50:04.984 V JTP Field: 43(OriginalTo) = 1037
21:50:04.984 V JTP Field: 66(JTapiCallId) = 8122/1
21:50:06.843 V JTP Got 31(CallEstablished) message from v6.1.
21:50:06.843 V JTP Field: 5(DeviceName) = SEP001CC0314499
21:50:06.843 V JTP Field: 6(DeviceType) = 6
21:50:06.843 V JTP Field: 40(CallId) = rWS8YgxrRPv9lNJh
21:50:06.843 V JTP Field: 41(To) = 1037
21:50:06.843 V JTP Field: 42(From) = 1038
21:50:06.843 V JTP Field: 43(OriginalTo) = 1037
21:50:06.843 V JTP Field: 46(Cause) = normal
21:50:06.843 V JTP Field: 53(CallControlCause) = NORMAL
21:50:06.843 V JTP Field: 66(JTapiCallId) = 8122/1
21:50:06.843 I JTP Sending CallActive for call: 1000081
21:50:06.890 V JTP Dir number: 2051
21:50:06.890 V JTP Selected Device from DevicePool: MonitoredCtiPools, CtiPort2, 2051
21:50:06.890 I JTP Added JTapi call 1000082<->1000082
21:50:06.890 V JTP Sent StartMonitoring. stackCallId:1000082, monInitiatorDeviceName:CtiPort2, monInitiatorFrom:2051, monTargetDeviceName:SEP001CC0314499, monTargetTo:1037, monTargetCallId:rWS8YgxrRPv9lNJh
21:50:06.890 V JTP StartMonitoring request sent, waiting for response from stack...CallId:1000082, ListenerDeviceNameCtiPort2
21:50:06.937 V JTP Got 35(InitiatedCall) message from v6.1.
21:50:06.937 V JTP Field: 5(DeviceName) = CtiPort2
21:50:06.937 V JTP Field: 6(DeviceType) = 6
21:50:06.937 V JTP Field: 40(CallId) = 1000082
21:50:06.937 V JTP Field: 42(From) = 2051
21:50:06.937 V JTP Field: 53(CallControlCause) = NORMAL
21:50:06.937 V JTP Field: 66(JTapiCallId) = 8123/1
21:50:06.937 V JTP PropOnCallInitiated - entry StackCallId 1000082 : DevName CtiPort2
21:50:06.937 I JTP Sending third-party CallInitiated for: 1000082
21:50:06.937 V JTP Got 0(Error) message from v6.1.
21:50:06.937 V JTP Field: 0(FailReason) = 16
21:50:06.937 V JTP Field: 5(DeviceName) = CtiPort2
21:50:06.937 V JTP Field: 7(MessageType) = 52
21:50:06.937 V JTP Field: 40(CallId) = 1000082
21:50:06.937 V JTP Updating transaction results1000082
21:50:06.953 V JTP Releasing lock on device CtiPort2
21:50:06.953 V JTP Got response to StartMonitoring message; released the lock: CallId:1000082
21:50:06.953 E JTP Call CtiPort2:1000082 encountered an error (PlatformException): <no description>
21:50:06.953 V JTP Got 31(CallEstablished) message from v6.1.
21:50:06.953 V JTP Field: 5(DeviceName) = CtiPort2
21:50:06.953 V JTP Field: 6(DeviceType) = 6
21:50:06.953 V JTP Field: 40(CallId) = 1000082
21:50:06.953 V JTP Field: 41(To) = 1037
21:50:06.953 V JTP Field: 42(From) = 2051
21:50:06.953 V JTP Field: 43(OriginalTo) = 1037
21:50:06.953 V JTP Field: 46(Cause) = normal
21:50:06.953 V JTP Field: 53(CallControlCause) = NORMAL
21:50:06.968 V JTP Field: 66(JTapiCallId) = 8123/1
21:50:06.968 V JTP Got 25(Hangup) message from v6.1.
21:50:06.968 V JTP Field: 5(DeviceName) = CtiPort2
21:50:06.968 V JTP Field: 6(DeviceType) = 6
21:50:06.968 V JTP Field: 40(CallId) = 1000082
21:50:06.968 V JTP Field: 41(To) = 1037
21:50:06.968 V JTP Field: 42(From) = 2051
21:50:06.968 V JTP Field: 43(OriginalTo) = 1037
21:50:06.968 V JTP Field: 46(Cause) = NORMAL
21:50:06.968 V JTP Field: 53(CallControlCause) = NORMAL
21:50:06.968 V JTP Field: 66(JTapiCallId) = 8123/1
21:50:06.968 V JTP Could not find callInfo for stackCallId 1000082; CallControlCause NORMAL
21:50:06.968 I JTP Metreos.Providers.JTapi.JTapiCallInitiated event was not handled
21:51:02.875 I CDL Device list cache refresh starting.
21:51:09.671 I CDL Device list cache refresh complete.
21:51:42.109 V JTP Got 25(Hangup) message from v6.1.
21:51:42.109 V JTP Field: 5(DeviceName) = SEP001CC0314499
21:51:42.109 V JTP Field: 6(DeviceType) = 6
21:51:42.109 V JTP Field: 40(CallId) = rWS8YgxrRPv9lNJh
21:51:42.109 V JTP Field: 41(To) = 1037
21:51:42.109 V JTP Field: 42(From) = 1038
21:51:42.109 V JTP Field: 43(OriginalTo) = 1037
21:51:42.109 V JTP Field: 46(Cause) = NORMAL
21:51:42.109 V JTP Field: 53(CallControlCause) = NORMAL
21:51:42.109 V JTP Field: 66(JTapiCallId) = 8122/1
21:51:42.109 V JTP About to remove call: 1000081
21:51:42.109 I JTP Sending third-party HangupCall for '1000081' (cause=NORMAL)
21:51:42.109 V JTP Removing call and informing app(s) : 1000081
21:51:42.109 I JTP Sending third-party Hangup for '1000081' (cause=NORMAL) (ccCause=NORMAL)
21:51:42.125 I JTP Metreos.Providers.JTapi.JTapiHangupCall event was not handled
21:51:42.125 I JTP Metreos.Providers.JTapi.JTapiHangup event was not handled
 
Thanks and Regards
Abdul Rasheed.K.A
 
Flag

RE: Platform Exception on JtapiStartMonitoring
Answer
8/20/09 9:35 AM as a reply to Abdul Rasheed.
Hi Abdul
 
Can you share the JTAPI service logs(in verbose mode) as well please.
Which CUCM version are you using?
You will probably have an exception 'com.cisco.jtapi.PlatformExceptionImpl' in those logs. Will need to have a look at those logs.
This exception generally occurs when your JTAPI Provider can't connect to the CUCM JTAPI service
 
Just check the following as well :
 
a. Your phone has an application user(in CUCM)
b. The username and password in your code are correct and same as those of the  'Application User'
 
Please share the JTAPI service logs as well.
 
 
 
Thanks
Piyush
Flag

RE: Platform Exception on JtapiStartMonitoring
Answer
8/20/09 3:42 PM as a reply to Piyush Mathur.
Dear Piyush,

Thanks for your reply.

Please find the following information.
CUCM Version: 6.1
The Exception is ¿2009:08:20::15:01:16.015: Info: JTapiServer @ IpcConnection@127.0.0.1:1145: sending message: Error{ FailReason = (i) PlatformException, MessageType = (i) StartMonitoring, CallId = (s) 1000113, DeviceName = (s) CtiPort1 }¿
The Phone has an application user and is added in the monitored device pool of CUAE  and status is Enabled Running.
Did you mention about the username and password of  server.registerApplication ?We gave as server.registerApplication("EtchSample", "Default", "administrator", "<password>");
The applications is connected to the cuae server and when an incoming call comes it triggers.In the csllActive event JTapiStartmonitoring called.After that platform Exception Comes.

By Application user , do you mean that a user in CUAE or in CUCM. ? Right now we put the CUAE administrator in our code as mentioned above.
 
JTAPI service logs(in verbose mode) Is attached below
 
2009:08:20::15:01:12.812: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: delivering 5 call events
2009:08:20::15:01:12.812: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: delivering CallActiveEv
2009:08:20::15:01:12.812: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: CallActiveEv, cause = NEW_CALL, metaCode = CALL_STARTING, callId = 8155/1, callState = active
2009:08:20::15:01:12.812: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: # AM::addCallMonitor - Add CM - CallId: 8155/1 size: 0
2009:08:20::15:01:12.812: Info: metreos.service.jtapi.CallMonitorMap@18f5824: CMMap::add - Adding CallMonitor CID lFZvvZdFJlJ9PkdM | Size 0
2009:08:20::15:01:12.812: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: added call INACTIVE/IDLE lFZvvZdFJlJ9PkdM 8155/1
2009:08:20::15:01:12.812: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: delivering ConnCreatedEv
2009:08:20::15:01:12.812: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: ignoring ConnCreatedEv
2009:08:20::15:01:12.812: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: delivering ConnInProgressEv
2009:08:20::15:01:12.812: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: ignoring ConnInProgressEv
2009:08:20::15:01:12.812: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: delivering CallCtlConnOfferedEv
2009:08:20::15:01:12.812: Info: offered call ALERTING/RINGING lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499: delivering CallCtlConnOfferedEv
2009:08:20::15:01:12.812: Info: offered call ALERTING/RINGING lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499: CallCtlConnOfferedEv, cause = NORMAL, metaCode = CALL_STARTING, callId = 8155/1, callState = active, conn.getAddress = 1037, conn.getCallControlState = OFFERED, conn.getConnectionID = 19449653/1, conn.getReason = DIRECTCALL, conn.getState = INPROGRESS, callControlCause = NORMAL, calledAddress = 1037, callingAddress = 1039, callingTerminal = SEP0090F51F55E1, lastRedirectedAddress = null
2009:08:20::15:01:12.812: Info: offered call ALERTING/RINGING lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499: xsetupAddrs connAddr=1037, called (ev=1037, c=1037, cCur=1037, cMod=1037), calling (ev=1039, c=1039, cCur=1039, cMod=1039), redir (evLast=null, cLast=null)
2009:08:20::15:01:12.812: Info: offered call ALERTING/RINGING lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499: connState changed from INACTIVE to OFFERED
2009:08:20::15:01:12.812: Info: offered call ALERTING/RINGING lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499: sending message: IncomingCall{ DeviceType = (i) MonitoredDevice, DeviceName = (s) SEP001CC0314499, CallControlCause = (s) NORMAL, CallId = (s) lFZvvZdFJlJ9PkdM, From = (s) 1039, To = (s) 1037, OriginalTo = (s) 1037, *unknownMessageField=66* = (s) 8155/1 }
2009:08:20::15:01:12.812: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: delivering ConnCreatedEv
2009:08:20::15:01:12.812: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: *** ignoring event about address: ConnCreatedEv, cause = NORMAL, metaCode = CALL_STARTING, callId = 8155/1, callState = active, conn.getAddress = 1039, conn.getCallControlState = IDLE, conn.getConnectionID = 0/0, conn.getReason = DIRECTCALL, conn.getState = IDLE
2009:08:20::15:01:12.812: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: ignoring ConnCreatedEv
2009:08:20::15:01:12.812: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: done delivering 5 call events
2009:08:20::15:01:12.812: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: delivering 2 call events
2009:08:20::15:01:12.812: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: delivering ConnConnectedEv
2009:08:20::15:01:12.812: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: *** ignoring event about address: ConnConnectedEv, cause = NORMAL, metaCode = CALL_PROGRESS, callId = 8155/1, callState = active, conn.getAddress = 1039, conn.getCallControlState = ESTABLISHED, conn.getConnectionID = 0/0, conn.getReason = DIRECTCALL, conn.getState = CONNECTED
2009:08:20::15:01:12.812: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: ignoring ConnConnectedEv
2009:08:20::15:01:12.812: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: delivering CallCtlConnEstablishedEv
2009:08:20::15:01:12.812: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: *** ignoring event about address: CallCtlConnEstablishedEv, cause = NORMAL, metaCode = CALL_PROGRESS, callId = 8155/1, callState = active, conn.getAddress = 1039, conn.getCallControlState = ESTABLISHED, conn.getConnectionID = 0/0, conn.getReason = DIRECTCALL, conn.getState = CONNECTED
2009:08:20::15:01:12.812: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: ignoring CallCtlConnEstablishedEv
2009:08:20::15:01:12.812: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: done delivering 2 call events
2009:08:20::15:01:12.812: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: delivering 5 call events
2009:08:20::15:01:12.812: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: delivering ConnAlertingEv
2009:08:20::15:01:12.812: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: ignoring ConnAlertingEv
2009:08:20::15:01:12.812: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: delivering CallCtlConnAlertingEv
2009:08:20::15:01:12.812: Info: offered call ALERTING/RINGING lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499: delivering CallCtlConnAlertingEv
2009:08:20::15:01:12.812: Info: offered call ALERTING/RINGING lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499: CallCtlConnAlertingEv, cause = NORMAL, metaCode = CALL_PROGRESS, callId = 8155/1, callState = active, conn.getAddress = 1037, conn.getCallControlState = ALERTING, conn.getConnectionID = 19449653/1, conn.getReason = DIRECTCALL, conn.getState = ALERTING, callControlCause = NORMAL, calledAddress = 1037, callingAddress = 1039, callingTerminal = SEP0090F51F55E1, lastRedirectedAddress = null
2009:08:20::15:01:12.812: Info: offered call ALERTING/RINGING lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499: xsetupAddrs connAddr=1037, called (ev=1037, c=1037, cCur=1037, cMod=1037), calling (ev=1039, c=1039, cCur=1039, cMod=1039), redir (evLast=null, cLast=null)
2009:08:20::15:01:12.812: Info: offered call ALERTING/RINGING lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499: connState changed from OFFERED to ALERTING
2009:08:20::15:01:12.812: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: delivering TermConnCreatedEv SEP001CC0314499
2009:08:20::15:01:12.812: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: ignoring TermConnCreatedEv SEP001CC0314499
2009:08:20::15:01:12.812: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: delivering TermConnRingingEv SEP001CC0314499
2009:08:20::15:01:12.812: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: ignoring TermConnRingingEv SEP001CC0314499
2009:08:20::15:01:12.812: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: delivering CallCtlTermConnRingingEvImpl SEP001CC0314499
2009:08:20::15:01:12.812: Info: offered call ALERTING/RINGING lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499: delivering CallCtlTermConnRingingEvImpl SEP001CC0314499
2009:08:20::15:01:12.812: Info: offered call ALERTING/RINGING lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499: CallCtlTermConnRingingEv, cause = NORMAL, metaCode = CALL_PROGRESS, callId = 8155/1, callState = active, termConn.getCallControlState = RINGING, termConn.getState = RINGING, termConn.getTerminal = SEP001CC0314499, conn.getAddress = 1037, conn.getCallControlState = ALERTING, conn.getConnectionID = 19449653/1, conn.getReason = DIRECTCALL, conn.getState = ALERTING, callControlCause = NORMAL, calledAddress = 1037, callingAddress = 1039, callingTerminal = SEP0090F51F55E1, lastRedirectedAddress = null
2009:08:20::15:01:12.812: Info: offered call ALERTING/RINGING lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499: no dtmf detection, media not available
2009:08:20::15:01:12.812: Info: mon-dev( SEP001CC0314499 ): addCallMonitor, connId=19449653, monitor=offered call ALERTING/IDLE lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499, oldMonitor=null, active=true
2009:08:20::15:01:12.812: Info: offered call ALERTING/RINGING lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499: termConnState changed from IDLE to RINGING
2009:08:20::15:01:12.812: Info: offered call ALERTING/RINGING lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499: sending message: RingingCall{ DeviceType = (i) MonitoredDevice, DeviceName = (s) SEP001CC0314499, CallId = (s) lFZvvZdFJlJ9PkdM, From = (s) 1039, To = (s) 1037, OriginalTo = (s) 1037, *unknownMessageField=66* = (s) 8155/1 }
2009:08:20::15:01:12.812: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: done delivering 5 call events
2009:08:20::15:01:15.937: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: delivering 4 call events
2009:08:20::15:01:15.937: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: delivering ConnConnectedEv
2009:08:20::15:01:15.937: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: ignoring ConnConnectedEv
2009:08:20::15:01:15.937: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: delivering CallCtlConnEstablishedEv
2009:08:20::15:01:15.937: Info: offered call ESTABLISHED/RINGING lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499: delivering CallCtlConnEstablishedEv
2009:08:20::15:01:15.937: Info: offered call ESTABLISHED/RINGING lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499: CallCtlConnEstablishedEv, cause = NORMAL, metaCode = CALL_PROGRESS, callId = 8155/1, callState = active, conn.getAddress = 1037, conn.getCallControlState = ESTABLISHED, conn.getConnectionID = 19449653/1, conn.getReason = DIRECTCALL, conn.getState = CONNECTED, callControlCause = NORMAL, calledAddress = 1037, callingAddress = 1039, callingTerminal = SEP0090F51F55E1, lastRedirectedAddress = null
2009:08:20::15:01:15.937: Info: offered call ESTABLISHED/RINGING lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499: xsetupAddrs connAddr=1037, called (ev=1037, c=1037, cCur=1037, cMod=1037), calling (ev=1039, c=1039, cCur=1039, cMod=1039), redir (evLast=null, cLast=null)
2009:08:20::15:01:15.937: Info: offered call ESTABLISHED/TALKING lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499: connState changed from ALERTING to ESTABLISHED
2009:08:20::15:01:15.937: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: delivering TermConnActiveEv SEP001CC0314499
2009:08:20::15:01:15.937: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: ignoring TermConnActiveEv SEP001CC0314499
2009:08:20::15:01:15.937: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: delivering CallCtlTermConnTalkingEv SEP001CC0314499
2009:08:20::15:01:15.937: Info: offered call ESTABLISHED/TALKING lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499: delivering CallCtlTermConnTalkingEv SEP001CC0314499
2009:08:20::15:01:15.937: Info: offered call ESTABLISHED/TALKING lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499: CallCtlTermConnTalkingEv, cause = NORMAL, metaCode = CALL_PROGRESS, callId = 8155/1, callState = active, termConn.getCallControlState = TALKING, termConn.getState = ACTIVE, termConn.getTerminal = SEP001CC0314499, conn.getAddress = 1037, conn.getCallControlState = ESTABLISHED, conn.getConnectionID = 19449653/1, conn.getReason = DIRECTCALL, conn.getState = CONNECTED, callControlCause = NORMAL, calledAddress = 1037, callingAddress = 1039, callingTerminal = SEP0090F51F55E1, lastRedirectedAddress = null
2009:08:20::15:01:15.937: Info: offered call ESTABLISHED/TALKING lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499: no dtmf detection, media not available
2009:08:20::15:01:15.937: Info: mon-dev( SEP001CC0314499 ): addCallMonitor, connId=19449653, monitor=offered call ESTABLISHED/RINGING lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499, oldMonitor=offered call ESTABLISHED/RINGING lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499, active=true
2009:08:20::15:01:15.937: Info: offered call ESTABLISHED/TALKING lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499: termConnState changed from RINGING to TALKING
2009:08:20::15:01:15.937: Info: offered call ESTABLISHED/TALKING lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499: sending message: EstablishedCall{ DeviceType = (i) MonitoredDevice, DeviceName = (s) SEP001CC0314499, Cause = (s) normal, CallControlCause = (s) NORMAL, CallId = (s) lFZvvZdFJlJ9PkdM, From = (s) 1039, To = (s) 1037, OriginalTo = (s) 1037, *unknownMessageField=66* = (s) 8155/1 }
2009:08:20::15:01:15.937: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: done delivering 4 call events
2009:08:20::15:01:15.937: Info: mon-dev( SEP001CC0314499 ): -T- Device::terminalChangedEvent: Received Event: <- CiscoRTPInputStartedEv
2009:08:20::15:01:15.937: Info: offered call ESTABLISHED/TALKING lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499: CiscoRTPInputStartedEv, cause = NORMAL, metaCode = UNKNOWN, terminal = SEP001CC0314499, localAddress = 192.168.0.24, localPort = 24610, payloadType = 4, packetSize = 20, hasLogicalChannel = false
2009:08:20::15:01:15.953: Info: mon-dev( SEP001CC0314499 ): -T- Device::terminalChangedEvent: Received Event: <- CiscoRTPOutputStartedEv
2009:08:20::15:01:15.953: Info: offered call ESTABLISHED/TALKING lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499: CiscoRTPOutputStartedEv, cause = NORMAL, metaCode = UNKNOWN, terminal = SEP001CC0314499, remoteAddress = 192.168.0.102, remotePort = 24608, payloadType = 4, packetSize = 20, hasLogicalChannel = false, silent = false
2009:08:20::15:01:15.984: Info: JTapiServer @ IpcConnection@127.0.0.1:1145: received message: StartMonitoring{ DeviceName = (s) CtiPort1, RxIP = (s) 192.168.0.102, RxPort = (i) 8740, CallId = (s) 1000113, To = (s) 1037, From = (s) 2050, MonTargetDeviceName = (s) SEP001CC0314499, PlayToneMode = (i) 0, MonTargetCallId = (s) lFZvvZdFJlJ9PkdM }
2009:08:20::15:01:15.984: Info: JTapiServer @ IpcConnection@127.0.0.1:1145: StartMonitoring Monitor Initiator device = CtiPort1
2009:08:20::15:01:15.984: Info: JTapiServer @ IpcConnection@127.0.0.1:1145: StartMonitoring Monitor Target device = mon-dev( SEP001CC0314499 )
2009:08:20::15:01:15.984: Info: mon-dev( CtiPort1 ): Start monitoring: Initiator Device: CtiPort1 Target Device: SEP001CC0314499
2009:08:20::15:01:15.984: Info: mon-dev( CtiPort1 ): Got Target terminal : SEP001CC0314499
2009:08:20::15:01:15.984: Info: mon-dev( CtiPort1 ): No. of connections : 1
2009:08:20::15:01:15.984: Info: mon-dev( CtiPort1 ): StartMonitoring: Found Call 8155/1 on SEP001CC0314499
2009:08:20::15:01:16.000: Info: address( 2050@CtiPort1 ) [metreos.service.jtapi.AddressMonitor@14b5f4a]: delivering 7 call events
2009:08:20::15:01:16.000: Info: address( 2050@CtiPort1 ) [metreos.service.jtapi.AddressMonitor@14b5f4a]: delivering CallActiveEv
2009:08:20::15:01:16.000: Info: address( 2050@CtiPort1 ) [metreos.service.jtapi.AddressMonitor@14b5f4a]: CallActiveEv, cause = NEW_CALL, metaCode = CALL_STARTING, callId = 8156/1, callState = active
2009:08:20::15:01:16.000: Info: address( 2050@CtiPort1 ) [metreos.service.jtapi.AddressMonitor@14b5f4a]: # AM::addCallMonitor - Add CM - CallId: 8156/1 size: 1
2009:08:20::15:01:16.000: Info: metreos.service.jtapi.CallMonitorMap@18f5824: CMMap::add - Adding CallMonitor CID 8lY3t7HbL6ypVZ2Q | Size 1
2009:08:20::15:01:16.000: Info: address( 2050@CtiPort1 ) [metreos.service.jtapi.AddressMonitor@14b5f4a]: added call INACTIVE/IDLE 8lY3t7HbL6ypVZ2Q 8156/1
2009:08:20::15:01:16.000: Info: address( 2050@CtiPort1 ) [metreos.service.jtapi.AddressMonitor@14b5f4a]: delivering ConnCreatedEv
2009:08:20::15:01:16.000: Info: address( 2050@CtiPort1 ) [metreos.service.jtapi.AddressMonitor@14b5f4a]: ignoring ConnCreatedEv
2009:08:20::15:01:16.000: Info: address( 2050@CtiPort1 ) [metreos.service.jtapi.AddressMonitor@14b5f4a]: delivering ConnConnectedEv
2009:08:20::15:01:16.000: Info: address( 2050@CtiPort1 ) [metreos.service.jtapi.AddressMonitor@14b5f4a]: ignoring ConnConnectedEv
2009:08:20::15:01:16.000: Info: address( 2050@CtiPort1 ) [metreos.service.jtapi.AddressMonitor@14b5f4a]: delivering CallCtlConnInitiatedEv
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: delivering CallCtlConnInitiatedEv
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: CallCtlConnInitiatedEv, cause = NORMAL, metaCode = CALL_STARTING, callId = 8156/1, callState = active, conn.getAddress = 2050, conn.getCallControlState = INITIATED, conn.getConnectionID = 19449654/1, conn.getReason = DIRECTCALL, conn.getState = CONNECTED, callControlCause = NORMAL, calledAddress = null, callingAddress = 2050, callingTerminal = null, lastRedirectedAddress = null
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: xsetupAddrs connAddr=2050, called (ev=null, c=null, cCur=null, cMod=null), calling (ev=2050, c=2050, cCur=2050, cMod=2050), redir (evLast=null, cLast=null)
2009:08:20::15:01:16.000: Info: metreos.service.jtapi.CallMonitorMap@18f5824: rename 8lY3t7HbL6ypVZ2Q -> 1000113
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: setRTPIpPort to /192.168.0.102/8740, callSetRTPParams true, fake false
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: setRTPParams but no handle or no media:
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: ===== CallActiveEv, cause = SNAPSHOT, metaCode = SNAPSHOT, callId = 8156/1, callState = active
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: -C- callChangedEvent: Received Event: <- *unknownTermEv*(101)
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: ===== ConnCreatedEv, cause = SNAPSHOT, metaCode = SNAPSHOT, callId = 8156/1, callState = active
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: -C- callChangedEvent: Received Event: <- *unknownTermEv*(106)
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: ===== ConnConnectedEv, cause = SNAPSHOT, metaCode = SNAPSHOT, callId = 8156/1, callState = active
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: -C- callChangedEvent: Received Event: <- *unknownTermEv*(105)
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: ===== CallCtlConnInitiatedEv, cause = SNAPSHOT, metaCode = SNAPSHOT, callId = 8156/1, callState = active, conn.getAddress = 2050, conn.getCallControlState = INITIATED, conn.getConnectionID = 19449654/1, conn.getReason = DIRECTCALL, conn.getState = CONNECTED, callControlCause = SNAPSHOT, calledAddress = null, callingAddress = 2050, callingTerminal = null, lastRedirectedAddress = null
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: -C- callChangedEvent: Received Event: <- *unknownTermEv*(208)
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: ===== TermConnCreatedEv, cause = SNAPSHOT, metaCode = SNAPSHOT, callId = 8156/1, callState = active
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: -C- callChangedEvent: Received Event: <- *unknownTermEv*(116)
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: ===== TermConnActiveEv, cause = SNAPSHOT, metaCode = SNAPSHOT, callId = 8156/1, callState = active
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: -C- callChangedEvent: Received Event: <- *unknownTermEv*(115)
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: ===== CallCtlTermConnTalkingEv, cause = SNAPSHOT, metaCode = SNAPSHOT, callId = 8156/1, callState = active, termConn.getCallControlState = TALKING, termConn.getState = ACTIVE, termConn.getTerminal = CtiPort1, conn.getAddress = 2050, conn.getCallControlState = INITIATED, conn.getConnectionID = 19449654/1, conn.getReason = DIRECTCALL, conn.getState = CONNECTED, callControlCause = SNAPSHOT, calledAddress = null, callingAddress = 2050, callingTerminal = null, lastRedirectedAddress = null
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: -C- callChangedEvent: Received Event: <- *unknownTermEv*(219)
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: connState changed from INACTIVE to INITIATED
2009:08:20::15:01:16.000: Info: address( 2050@CtiPort1 ) [metreos.service.jtapi.AddressMonitor@14b5f4a]: delivering TermConnCreatedEv CtiPort1
2009:08:20::15:01:16.000: Info: address( 2050@CtiPort1 ) [metreos.service.jtapi.AddressMonitor@14b5f4a]: ignoring TermConnCreatedEv CtiPort1
2009:08:20::15:01:16.000: Info: address( 2050@CtiPort1 ) [metreos.service.jtapi.AddressMonitor@14b5f4a]: delivering TermConnActiveEv CtiPort1
2009:08:20::15:01:16.000: Info: address( 2050@CtiPort1 ) [metreos.service.jtapi.AddressMonitor@14b5f4a]: ignoring TermConnActiveEv CtiPort1
2009:08:20::15:01:16.000: Info: address( 2050@CtiPort1 ) [metreos.service.jtapi.AddressMonitor@14b5f4a]: delivering CallCtlTermConnTalkingEv CtiPort1
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: delivering CallCtlTermConnTalkingEv CtiPort1
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: CallCtlTermConnTalkingEv, cause = NORMAL, metaCode = CALL_STARTING, callId = 8156/1, callState = active, termConn.getCallControlState = TALKING, termConn.getState = ACTIVE, termConn.getTerminal = CtiPort1, conn.getAddress = 2050, conn.getCallControlState = INITIATED, conn.getConnectionID = 19449654/1, conn.getReason = DIRECTCALL, conn.getState = CONNECTED, callControlCause = NORMAL, calledAddress = null, callingAddress = 2050, callingTerminal = null, lastRedirectedAddress = null
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: no dtmf detection, media not available
2009:08:20::15:01:16.000: Info: mon-dev( CtiPort1 ): addCallMonitor, connId=19449654, monitor=initiated call INITIATED/IDLE 1000113 8156/1 2050 CtiPort1, oldMonitor=null, active=true
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: termConnState changed from IDLE to TALKING
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: sending message: InitiatedCall{ DeviceType = (i) MonitoredDevice, DeviceName = (s) CtiPort1, CallControlCause = (s) NORMAL, CallId = (s) 1000113, From = (s) 2050, *unknownMessageField=66* = (s) 8156/1 }
2009:08:20::15:01:16.000: Info: address( 2050@CtiPort1 ) [metreos.service.jtapi.AddressMonitor@14b5f4a]: done delivering 7 call events
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: ===== CallActiveEv, cause = NEW_CALL, metaCode = CALL_STARTING, callId = 8156/1, callState = active
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: -C- callChangedEvent: Received Event: <- *unknownTermEv*(101)
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: ===== ConnCreatedEv, cause = NORMAL, metaCode = CALL_STARTING, callId = 8156/1, callState = active
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: -C- callChangedEvent: Received Event: <- *unknownTermEv*(106)
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: ===== ConnConnectedEv, cause = NORMAL, metaCode = CALL_STARTING, callId = 8156/1, callState = active
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: -C- callChangedEvent: Received Event: <- *unknownTermEv*(105)
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: ===== CallCtlConnInitiatedEv, cause = NORMAL, metaCode = CALL_STARTING, callId = 8156/1, callState = active, conn.getAddress = 2050, conn.getCallControlState = INITIATED, conn.getConnectionID = 19449654/1, conn.getReason = DIRECTCALL, conn.getState = CONNECTED, callControlCause = NORMAL, calledAddress = null, callingAddress = 2050, callingTerminal = null, lastRedirectedAddress = null
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: -C- callChangedEvent: Received Event: <- *unknownTermEv*(208)
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: ===== TermConnCreatedEv, cause = 0, metaCode = CALL_STARTING, callId = 8156/1, callState = active
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: -C- callChangedEvent: Received Event: <- *unknownTermEv*(116)
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: ===== TermConnActiveEv, cause = NORMAL, metaCode = CALL_STARTING, callId = 8156/1, callState = active
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: -C- callChangedEvent: Received Event: <- *unknownTermEv*(115)
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: ===== CallCtlTermConnTalkingEv, cause = NORMAL, metaCode = CALL_STARTING, callId = 8156/1, callState = active, termConn.getCallControlState = TALKING, termConn.getState = ACTIVE, termConn.getTerminal = CtiPort1, conn.getAddress = 2050, conn.getCallControlState = INITIATED, conn.getConnectionID = 19449654/1, conn.getReason = DIRECTCALL, conn.getState = CONNECTED, callControlCause = NORMAL, calledAddress = null, callingAddress = 2050, callingTerminal = null, lastRedirectedAddress = null
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: -C- callChangedEvent: Received Event: <- *unknownTermEv*(219)
2009:08:20::15:01:16.000: Info: address( 2050@CtiPort1 ) [metreos.service.jtapi.AddressMonitor@14b5f4a]: delivering 1 call events
2009:08:20::15:01:16.000: Info: address( 2050@CtiPort1 ) [metreos.service.jtapi.AddressMonitor@14b5f4a]: delivering CallCtlConnDialingEv
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: delivering CallCtlConnDialingEv
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: CallCtlConnDialingEv, cause = NORMAL, metaCode = CALL_PROGRESS, callId = 8156/1, callState = active, conn.getAddress = 2050, conn.getCallControlState = DIALING, conn.getConnectionID = 19449654/1, conn.getReason = DIRECTCALL, conn.getState = CONNECTED, callControlCause = NORMAL, calledAddress = null, callingAddress = 2050, callingTerminal = null, lastRedirectedAddress = null
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: xsetupAddrs connAddr=2050, called (ev=null, c=null, cCur=null, cMod=null), calling (ev=2050, c=2050, cCur=2050, cMod=2050), redir (evLast=null, cLast=null)
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: connState changed from INITIATED to DIALING
2009:08:20::15:01:16.000: Info: address( 2050@CtiPort1 ) [metreos.service.jtapi.AddressMonitor@14b5f4a]: done delivering 1 call events
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: ===== CallCtlConnDialingEv, cause = NORMAL, metaCode = CALL_PROGRESS, callId = 8156/1, callState = active, conn.getAddress = 2050, conn.getCallControlState = DIALING, conn.getConnectionID = 19449654/1, conn.getReason = DIRECTCALL, conn.getState = CONNECTED, callControlCause = NORMAL, calledAddress = null, callingAddress = 2050, callingTerminal = null, lastRedirectedAddress = null
2009:08:20::15:01:16.000: Info: initiated call DIALING/TALKING 1000113 8156/1 2050 CtiPort1: -C- callChangedEvent: Received Event: <- *unknownTermEv*(204)
2009:08:20::15:01:16.015: Info: mon-dev( CtiPort1 ): Failed to start monitoring on terminal : SEP001CC0314499
2009:08:20::15:01:16.015: Info: JTapiServer @ IpcConnection@127.0.0.1:1145: sending message: Error{ FailReason = (i) PlatformException, MessageType = (i) StartMonitoring, CallId = (s) 1000113, DeviceName = (s) CtiPort1 }
2009:08:20::15:01:16.015: Info: address( 2050@CtiPort1 ) [metreos.service.jtapi.AddressMonitor@14b5f4a]: delivering 1 call events
2009:08:20::15:01:16.015: Info: address( 2050@CtiPort1 ) [metreos.service.jtapi.AddressMonitor@14b5f4a]: delivering CallCtlConnEstablishedEv
2009:08:20::15:01:16.015: Info: initiated call ESTABLISHED/TALKING 1000113 8156/1 2050 CtiPort1: delivering CallCtlConnEstablishedEv
2009:08:20::15:01:16.015: Info: initiated call ESTABLISHED/TALKING 1000113 8156/1 2050 CtiPort1: CallCtlConnEstablishedEv, cause = NORMAL, metaCode = CALL_PROGRESS, callId = 8156/1, callState = active, conn.getAddress = 2050, conn.getCallControlState = ESTABLISHED, conn.getConnectionID = 19449654/1, conn.getReason = DIRECTCALL, conn.getState = CONNECTED, callControlCause = NORMAL, calledAddress = 1037, callingAddress = 2050, callingTerminal = null, lastRedirectedAddress = null
2009:08:20::15:01:16.015: Info: initiated call ESTABLISHED/TALKING 1000113 8156/1 2050 CtiPort1: xsetupAddrs connAddr=2050, called (ev=1037, c=1037, cCur=1037, cMod=1037), calling (ev=2050, c=2050, cCur=2050, cMod=2050), redir (evLast=null, cLast=null)
2009:08:20::15:01:16.015: Info: initiated call ESTABLISHED/TALKING 1000113 8156/1 2050 CtiPort1: connState changed from DIALING to ESTABLISHED
2009:08:20::15:01:16.015: Info: initiated call ESTABLISHED/TALKING 1000113 8156/1 2050 CtiPort1: sending message: EstablishedCall{ DeviceType = (i) MonitoredDevice, DeviceName = (s) CtiPort1, Cause = (s) normal, CallControlCause = (s) NORMAL, CallId = (s) 1000113, From = (s) 2050, To = (s) 1037, OriginalTo = (s) 1037, *unknownMessageField=66* = (s) 8156/1 }
2009:08:20::15:01:16.015: Info: address( 2050@CtiPort1 ) [metreos.service.jtapi.AddressMonitor@14b5f4a]: done delivering 1 call events
2009:08:20::15:01:16.015: Info: initiated call DISCONNECTED/TALKING 1000113 8156/1 2050 CtiPort1: ===== CallCtlConnEstablishedEv, cause = NORMAL, metaCode = CALL_PROGRESS, callId = 8156/1, callState = active, conn.getAddress = 2050, conn.getCallControlState = ESTABLISHED, conn.getConnectionID = 19449654/1, conn.getReason = DIRECTCALL, conn.getState = CONNECTED, callControlCause = NORMAL, calledAddress = 1037, callingAddress = 2050, callingTerminal = null, lastRedirectedAddress = null
2009:08:20::15:01:16.015: Info: initiated call DISCONNECTED/TALKING 1000113 8156/1 2050 CtiPort1: ===== address 2050 NOT alerting
2009:08:20::15:01:16.015: Info: initiated call DISCONNECTED/TALKING 1000113 8156/1 2050 CtiPort1: -C- callChangedEvent: Received Event: <- *unknownTermEv*(206)
2009:08:20::15:01:16.015: Info: address( 2050@CtiPort1 ) [metreos.service.jtapi.AddressMonitor@14b5f4a]: delivering 2 call events
2009:08:20::15:01:16.015: Info: address( 2050@CtiPort1 ) [metreos.service.jtapi.AddressMonitor@14b5f4a]: delivering ConnFailedEv
2009:08:20::15:01:16.015: Info: address( 2050@CtiPort1 ) [metreos.service.jtapi.AddressMonitor@14b5f4a]: ignoring ConnFailedEv
2009:08:20::15:01:16.015: Info: address( 2050@CtiPort1 ) [metreos.service.jtapi.AddressMonitor@14b5f4a]: delivering CallCtlConnFailedEv
2009:08:20::15:01:16.015: Info: initiated call DISCONNECTED/TALKING 1000113 8156/1 2050 CtiPort1: delivering CallCtlConnFailedEv
2009:08:20::15:01:16.015: Info: initiated call DISCONNECTED/TALKING 1000113 8156/1 2050 CtiPort1: CallCtlConnFailedEv, cause = NORMAL, metaCode = CALL_PROGRESS, callId = 8156/1, callState = active, conn.getAddress = 2050, conn.getCallControlState = FAILED, conn.getConnectionID = 19449654/1, conn.getReason = DIRECTCALL, conn.getState = FAILED, callControlCause = NORMAL, calledAddress = 1037, callingAddress = 2050, callingTerminal = CtiPort1, lastRedirectedAddress = null
2009:08:20::15:01:16.015: Info: initiated call DISCONNECTED/TALKING 1000113 8156/1 2050 CtiPort1: ---- callCtlConnFailed: Changin state to Disconnected
2009:08:20::15:01:16.015: Info: initiated call DISCONNECTED/TALKING 1000113 8156/1 2050 CtiPort1: connState changed from ESTABLISHED to DISCONNECTED
2009:08:20::15:01:16.015: Info: initiated call DISCONNECTED/TALKING 1000113 8156/1 2050 CtiPort1: Sending Hangup Call from jtapi service
2009:08:20::15:01:16.015: Info: initiated call DISCONNECTED/TALKING 1000113 8156/1 2050 CtiPort1: sending message: HangupCall{ DeviceType = (i) MonitoredDevice, DeviceName = (s) CtiPort1, Cause = (s) NORMAL, CallControlCause = (s) NORMAL, CallId = (s) 1000113, From = (s) 2050, To = (s) 1037, OriginalTo = (s) 1037, *unknownMessageField=66* = (s) 8156/1 }
2009:08:20::15:01:16.031: Info: address( 2050@CtiPort1 ) [metreos.service.jtapi.AddressMonitor@14b5f4a]: done delivering 2 call events
2009:08:20::15:01:16.031: Info: initiated call DISCONNECTED/TALKING 1000113 8156/1 2050 CtiPort1: ===== ConnFailedEv, cause = NORMAL, metaCode = CALL_PROGRESS, callId = 8156/1, callState = active
2009:08:20::15:01:16.031: Info: initiated call DISCONNECTED/TALKING 1000113 8156/1 2050 CtiPort1: -C- callChangedEvent: Received Event: <- *unknownTermEv*(108)
2009:08:20::15:01:16.031: Info: initiated call DISCONNECTED/TALKING 1000113 8156/1 2050 CtiPort1: ===== CallCtlConnFailedEv, cause = NORMAL, metaCode = CALL_PROGRESS, callId = 8156/1, callState = active, conn.getAddress = 2050, conn.getCallControlState = FAILED, conn.getConnectionID = 19449654/1, conn.getReason = DIRECTCALL, conn.getState = FAILED, callControlCause = NORMAL, calledAddress = 1037, callingAddress = 2050, callingTerminal = CtiPort1, lastRedirectedAddress = null
2009:08:20::15:01:16.031: Info: initiated call DISCONNECTED/TALKING 1000113 8156/1 2050 CtiPort1: -C- callChangedEvent: Received Event: <- *unknownTermEv*(207)
2009:08:20::15:01:20.875: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: delivering 2 call events
2009:08:20::15:01:20.875: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: delivering ConnDisconnectedEv
2009:08:20::15:01:20.875: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: *** ignoring event about address: ConnDisconnectedEv, cause = NORMAL, metaCode = CALL_REMOVING_PARTY, callId = 8155/1, callState = active, conn.getAddress = 1039, conn.getCallControlState = DISCONNECTED, conn.getConnectionID = 0/0, conn.getReason = DIRECTCALL, conn.getState = DISCONNECTED
2009:08:20::15:01:20.875: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: ignoring ConnDisconnectedEv
2009:08:20::15:01:20.875: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: delivering CallCtlConnDisconnectedEv
2009:08:20::15:01:20.875: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: *** ignoring event about address: CallCtlConnDisconnectedEv, cause = NORMAL, metaCode = CALL_REMOVING_PARTY, callId = 8155/1, callState = active, conn.getAddress = 1039, conn.getCallControlState = DISCONNECTED, conn.getConnectionID = 0/0, conn.getReason = DIRECTCALL, conn.getState = DISCONNECTED
2009:08:20::15:01:20.875: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: ignoring CallCtlConnDisconnectedEv
2009:08:20::15:01:20.875: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: done delivering 2 call events
2009:08:20::15:01:20.875: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: delivering 5 call events
2009:08:20::15:01:20.875: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: delivering TermConnDroppedEv SEP001CC0314499
2009:08:20::15:01:20.875: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: ignoring TermConnDroppedEv SEP001CC0314499
2009:08:20::15:01:20.875: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: delivering CallCtlTermConnDroppedEv SEP001CC0314499
2009:08:20::15:01:20.875: Info: offered call DISCONNECTED/DROPPED lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499: delivering CallCtlTermConnDroppedEv SEP001CC0314499
2009:08:20::15:01:20.875: Info: offered call DISCONNECTED/DROPPED lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499: CallCtlTermConnDroppedEv, cause = NORMAL, metaCode = CALL_ENDING, callId = 8155/1, callState = invalid, termConn.getCallControlState = DROPPED, termConn.getState = DROPPED, termConn.getTerminal = SEP001CC0314499, conn.getAddress = 1037, conn.getCallControlState = DISCONNECTED, conn.getConnectionID = 19449653/1, conn.getReason = DIRECTCALL, conn.getState = DISCONNECTED, callControlCause = NORMAL, calledAddress = 1037, callingAddress = 1039, callingTerminal = SEP0090F51F55E1, lastRedirectedAddress = null
2009:08:20::15:01:20.875: Info: offered call DISCONNECTED/DROPPED lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499: # CM::callCtlTermConnDropped - About to remove CM
2009:08:20::15:01:20.875: Info: mon-dev( SEP001CC0314499 ): removeCallMonitor, connId=19449653, monitor=offered call ESTABLISHED/TALKING lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499
2009:08:20::15:01:20.875: Info: offered call DISCONNECTED/DROPPED lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499: termConnState changed from TALKING to DROPPED
2009:08:20::15:01:20.875: Info: offered call DISCONNECTED/DROPPED lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499: Sending Hangup Call from jtapi service
2009:08:20::15:01:20.875: Info: offered call DISCONNECTED/DROPPED lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499: sending message: HangupCall{ DeviceType = (i) MonitoredDevice, DeviceName = (s) SEP001CC0314499, Cause = (s) NORMAL, CallControlCause = (s) NORMAL, CallId = (s) lFZvvZdFJlJ9PkdM, From = (s) 1039, To = (s) 1037, OriginalTo = (s) 1037, *unknownMessageField=66* = (s) 8155/1 }
2009:08:20::15:01:20.875: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: delivering ConnDisconnectedEv
2009:08:20::15:01:20.875: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: ignoring ConnDisconnectedEv
2009:08:20::15:01:20.875: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: delivering CallCtlConnDisconnectedEv
2009:08:20::15:01:20.875: Info: offered call DISCONNECTED/DROPPED lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499: delivering CallCtlConnDisconnectedEv
2009:08:20::15:01:20.875: Info: offered call DISCONNECTED/DROPPED lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499: CallCtlConnDisconnectedEv, cause = NORMAL, metaCode = CALL_ENDING, callId = 8155/1, callState = invalid, conn.getAddress = 1037, conn.getCallControlState = DISCONNECTED, conn.getConnectionID = 19449653/1, conn.getReason = DIRECTCALL, conn.getState = DISCONNECTED, callControlCause = NORMAL, calledAddress = 1037, callingAddress = 1039, callingTerminal = SEP0090F51F55E1, lastRedirectedAddress = null

2009:08:20::15:01:20.875: Info: offered call DISCONNECTED/DROPPED lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499: connState changed from ESTABLISHED to DISCONNECTED
2009:08:20::15:01:20.875: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: delivering CallInvalidEv
2009:08:20::15:01:20.875: Info: offered call DISCONNECTED/DROPPED lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499: cleanup
2009:08:20::15:01:20.875: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: done delivering 5 call events
2009:08:20::15:01:20.875: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: delivering 1 call events
2009:08:20::15:01:20.875: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: delivering CallObservationEndedEv
2009:08:20::15:01:20.875: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: # AM::removeCallMonitor - Removed CM CallId: 8155/1 size: 2
2009:08:20::15:01:20.875: Info: (P1-192.168.0.117) EventThread: {8155/1=offered call DISCONNECTED/DROPPED lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499}
2009:08:20::15:01:20.875: Info: metreos.service.jtapi.CallMonitorMap@18f5824: # CMMap::remove - Removing CallMonitor CID lFZvvZdFJlJ9PkdM | Size 2
2009:08:20::15:01:20.875: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: removed call offered call DISCONNECTED/DROPPED lFZvvZdFJlJ9PkdM 8155/1 1037 SEP001CC0314499
2009:08:20::15:01:20.875: Info: address( 1037@SEP001CC0314499 ) [metreos.service.jtapi.AddressMonitor@f18e8e]: done delivering 1 call events
2009:08:20::15:01:20.890: Info: mon-dev( SEP001CC0314499 ): -T- Device::terminalChangedEvent: Received Event: <- CiscoRTPInputStoppedEv
2009:08:20::15:01:20.890: Info: mon-dev( SEP001CC0314499 ): *** ignored CiscoRTPInputStoppedEv
2009:08:20::15:01:20.890: Info: mon-dev( SEP001CC0314499 ): -T- Device::terminalChangedEvent: Received Event: <- CiscoRTPOutputStoppedEv
2009:08:20::15:01:20.890: Info: mon-dev( SEP001CC0314499 ): *** ignored CiscoRTPOutputStoppedEv


Thanks and Regards
Rasheed
Flag

RE: Platform Exception on JtapiStartMonitoring
Answer
8/21/09 10:34 AM as a reply to Abdul Rasheed.
Hi Abdul
 
Thanks for sharing the logs. Looks like
there was some exception at the underlying JTAPI level while trying to
start monitoring the call.
I need one more log file from you.
 
Can you please share this log file : "C:\Program Files\Cisco Systems\Unified Application Environment\Framework\1.0\wrapper.log".
In that file we will have to look for an exception trace.
 
That should help us nail down the exact problem.
 
Are both your phone and CTI port in the monitored device pool ?
 
Will it possible for you to try this application by using a Route Point instead of a CTI port ?
If you are making  a call from your CTI port and then using the same to monitor, I don't think that will be possible in which case you would have to use a route point.
 
 
Thanks
Piyush
Flag

RE: Platform Exception on JtapiStartMonitoring
Answer
8/21/09 11:01 AM as a reply to Piyush Mathur.
Hi Piyush,
 
Our phone and CTI ports are in two seperate Monitored device pools . Both of them showing status Enabled Running. I think CTI port is not being used for making calls. i am not sure what exactly you mean?
 
Following are the logs which you have requested .
 
INFO   | jvm 1    | 2009/08/21 11:11:33 | com.cisco.jtapi.PlatformExceptionImpl: Monitor request failed with cause:com.cisco.cti.client.CCNException
INFO   | jvm 1    | 2009/08/21 11:11:33 |     at com.cisco.jtapi.CallImpl.startMonitor(CTQF)
INFO   | jvm 1    | 2009/08/21 11:11:33 |     at metreos.jtapi.apiAdapterLayer.VerDevice.startMonitoring(Unknown Source)
INFO   | jvm 1    | 2009/08/21 11:11:33 |     at metreos.service.jtapi.Device.startMonitoring(Unknown Source)
INFO   | jvm 1    | 2009/08/21 11:11:33 |     at metreos.service.jtapi.JTapiServer.startMonitoring(Unknown Source)
INFO   | jvm 1    | 2009/08/21 11:11:33 |     at metreos.service.jtapi.JTapiServer.doReceived(Unknown Source)
INFO   | jvm 1    | 2009/08/21 11:11:33 |     at metreos.service.jtapi.JTapiServer$3.doit(Unknown Source)
INFO   | jvm 1    | 2009/08/21 11:11:33 |     at metreos.util.TodoManager.run(TodoManager.java:93)
INFO   | jvm 1    | 2009/08/21 11:11:33 |     at java.lang.Thread.run(Unknown Source)
 
Thanks and Regards
Abdul Rasheed
 
 
 
 
Flag

RE: Platform Exception on JtapiStartMonitoring
Answer
8/21/09 12:50 PM as a reply to Abdul Rasheed.
Hi Abdul
 
Have you enabled the 'Built In Bridge' on the phone you want to monitor ? You have to change it's state from 'Default' to On.
 
Sadly, the logs turned out inconclusive.
 
Can you please try the application with the Built In Bridge set to on.Meanwhile I will try to reproduce the problem locally.
 
Thanks
Piyush
Flag

RE: Platform Exception on JtapiStartMonitoring
Answer
8/21/09 2:27 PM as a reply to Piyush Mathur.
Hi Piyush,
               Yes we already enabled the Built in bridge for the phone which we are monitoring. But no change, we are really confused now.emoticon(   .
 
Regards
Rasheed
 
Flag

RE: Platform Exception on JtapiStartMonitoring
Answer
8/22/09 3:29 PM as a reply to Abdul Rasheed.
 
Hi Piyush,
 
The issue is solved.   we were using IP communicator softphone  for our
testing. The version of IP communicator was 2.x and is very old, we
upgraded it to version 7.x and then it started working. Thanks a lot
for your valuable support.
 
THanks and Regards
Rasheed
 
Flag

RE: Platform Exception on JtapiStartMonitoring
Answer
8/24/09 8:32 AM as a reply to Abdul Rasheed.
Great to hear that Abdul.
 
Thanks for the info.
 
Regards
Piyush
Flag