Hello all
I made a cti screen pop that works fine with other customers just is a copy paste from other customers but here doesnt work
My principal problem is that in the screen pop i cant receive the uui. Where is working i am using cticonector and here use the aesconnector the value that i want to see in the log is CTICallOperation.makeCall 50227: Connecting with UUI 555278760020130731172959...
i have the log from a customer where is working
31/07/2013 00:08:19:997 INFO - 20FDDA49B0707F80A3CB12931F684F3A:/ADT_V3h : Using SCESession 20FDDA49B0707F80A3CB12931F684F3A:/ADT_V3h servlet : AnnTansfCTI
31/07/2013 00:08:23:927 INFO - 20FDDA49B0707F80A3CB12931F684F3A:/ADT_V3h : Using SCESession 20FDDA49B0707F80A3CB12931F684F3A:/ADT_V3h servlet : ctti
31/07/2013 00:08:23:928 INFO - 20FDDA49B0707F80A3CB12931F684F3A:/ADT_V3h : Using SCESession 20FDDA49B0707F80A3CB12931F684F3A:/ADT_V3h servlet : cti_log
31/07/2013 00:08:23:929 DEBUG - 20FDDA49B0707F80A3CB12931F684F3A:/ADT_V3h : VariableUtil.getVariableValue: Variable value is 2158
31/07/2013 00:08:23:930 DEBUG - 20FDDA49B0707F80A3CB12931F684F3A:/ADT_V3h : VariableUtil.getVariableValue: Variable value is 2267463520130731240819
31/07/2013 00:08:23:930 DEBUG - 20FDDA49B0707F80A3CB12931F684F3A:/ADT_V3h : CTICommand.execute: encrypted value is M0103o8f5Trp6tzvgXl0wlFagpc8zizQ+bNVCQ0+OgQ=
31/07/2013 00:08:23:930 DEBUG - 20FDDA49B0707F80A3CB12931F684F3A:/ADT_V3h : VariableUtil.getVariableValue: Variable value is 3191
31/07/2013 00:08:23:930 DEBUG - 20FDDA49B0707F80A3CB12931F684F3A:/ADT_V3h : CTICommand.execute: request to manager is
http://10.44.202.53:7080/cticonnector/BlindCall?callee%3D2158%26uui%3D2267463520130731240819%26uuiAsAscii%3Dtrue%26holdAddress%3D3191%26extension%3D8958&k=M0103o8f5Trp6tzvgXl0wlFagpc8zizQ%2BbNVCQ0%2BOgQ%3D
31/07/2013 00:08:23:931 DEBUG - 20FDDA49B0707F80A3CB12931F684F3A:/ADT_V3h : CTICommand.execute: setting session cookie to 20FDDA49B0707F80A3CB12931F684F3A:/ADT_V3h
31/07/2013 00:08:24:916 DEBUG - 20FDDA49B0707F80A3CB12931F684F3A:/ADT_V3h : CTICommand.execute: callinfo from Manager is callid=3191&ani=5522674635&dnis=2150&extension=8958&ucID=00000000000000000000&uui= &state=transferred
31/07/2013 00:08:24:916 DEBUG - 20FDDA49B0707F80A3CB12931F684F3A:/ADT_V3h : CTICommand.processCallInfoResults: rawResponse: callid=3191&ani=5522674635&dnis=2150&extension=8958&ucID=00000000000000000000&uui=+&state=transferred
31/07/2013 00:08:24:917 DEBUG - 20FDDA49B0707F80A3CB12931F684F3A:/ADT_V3h : CallInfo.doGetCallInfo: Populating call info variable values...
31/07/2013 00:08:24:917 DEBUG - 20FDDA49B0707F80A3CB12931F684F3A:/ADT_V3h : CTICommand.setCallInfoVariableValues: Call ID 3191
31/07/2013 00:08:24:917 DEBUG - 20FDDA49B0707F80A3CB12931F684F3A:/ADT_V3h : CTICommand.setCallInfoVariableValues: Calling # (ANI) 5522674635
31/07/2013 00:08:24:917 DEBUG - 20FDDA49B0707F80A3CB12931F684F3A:/ADT_V3h : CTICommand.setCallInfoVariableValues: Called # (DNIS) 2150
31/07/2013 00:08:24:917 DEBUG - 20FDDA49B0707F80A3CB12931F684F3A:/ADT_V3h : CTICommand.setCallInfoVariableValues: Ext 8958
31/07/2013 00:08:24:918 DEBUG - 20FDDA49B0707F80A3CB12931F684F3A:/ADT_V3h : CTICommand.setCallInfoVariableValues: UCID 00000000000000000000
31/07/2013 00:08:24:918 DEBUG - 20FDDA49B0707F80A3CB12931F684F3A:/ADT_V3h : CTICommand.setCallInfoVariableValues: UUI
31/07/2013 00:08:24:918 DEBUG - 20FDDA49B0707F80A3CB12931F684F3A:/ADT_V3h : CTICommand.setCallInfoVariableValues: State transferred
31/07/2013 00:08:24:918 INFO - 20FDDA49B0707F80A3CB12931F684F3A:/ADT_V3h : Using SCESession 20FDDA49B0707F80A3CB12931F684F3A:/ADT_V3h servlet : Exit
31/07/2013 00:08:24:919 DEBUG - 20FDDA49B0707F80A3CB12931F684F3A:/ADT_V3h : CTIEnd.execute:8958 callid is 3191
31/07/2013 00:08:24:919 DEBUG - 20FDDA49B0707F80A3CB12931F684F3A:/ADT_V3h : CTICommand.execute: encrypted value is M0103o8f5Trp6tzvgXl0wgAtUR2LzNX6z8RFIOwnJxo=
31/07/2013 00:08:24:919 DEBUG - 20FDDA49B0707F80A3CB12931F684F3A:/ADT_V3h : CTICommand.execute: request to manager is
http://10.44.202.53:7080/cticonnector/End?extension%3D8958%26callid%3D3191&k=M0103o8f5Trp6tzvgXl0wgAtUR2LzNX6z8RFIOwnJxo%3D
31/07/2013 00:08:24:920 DEBUG - 20FDDA49B0707F80A3CB12931F684F3A:/ADT_V3h : CTICommand.execute: setting session cookie to 20FDDA49B0707F80A3CB12931F684F3A:/ADT_V3h
31/07/2013 00:08:24:929 DEBUG - 20FDDA49B0707F80A3CB12931F684F3A:/ADT_V3h : CTICommand.execute: callinfo from Manager is
31/07/2013 00:08:24:931 INFO - 20FDDA49B0707F80A3CB12931F684F3A:/ADT_V3h : Removing session from AvayaSessionTermination Servlet
31/07/2013 00:08:24:932 INFO - 20FDDA49B0707F80A3CB12931F684F3A:/ADT_V3h : Termination URL reply : <?xml version="1.0" encoding="UTF-8"?>
<vxml version="2.1" xmlns="http://www.w3.org/2001/vxml" xml:lang="en-us">
</vxml>
And this is the log that is fail because my screen pop doesnt work
31/07/2013 17:30:53:308 DEBUG - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : CTICommand.execute: encrypted value is a1jGIGb8bRpXjOa+wOCm93mk9o0R7CDxwbk4RMtGaIk=
31/07/2013 17:30:53:308 DEBUG - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : CTICommand.execute: request to manager is
http://172.17.1.208:8080/aesconnector/CallInfoInitialCall?extension%3D50227&k=a1jGIGb8bRpXjOa%2BwOCm93mk9o0R7CDxwbk4RMtGaIk%3D
31/07/2013 17:30:53:308 DEBUG - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : CTICommand.execute: setting session cookie to 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6
31/07/2013 17:30:53:308 DEBUG - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : CTICommand.execute: callinfo from Manager is callid=8482&ani=5552787600&dnis=5221&extension=50227&ucID=00001084821375309953&uui= &state=established
31/07/2013 17:30:53:308 DEBUG - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : CTICommand.processCallInfoResults: rawResponse: callid=8482&ani=5552787600&dnis=5221&extension=50227&ucID=00001084821375309953&uui=+&state=established
31/07/2013 17:30:53:308 DEBUG - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : CallInfo.doGetCallInfo: Populating call info variable values...
31/07/2013 17:30:53:308 DEBUG - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : CTICommand.setCallInfoVariableValues: Call ID 8482
31/07/2013 17:30:53:308 DEBUG - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : CTICommand.setCallInfoVariableValues: Calling # (ANI) 5552787600
31/07/2013 17:30:53:308 DEBUG - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : CTICommand.setCallInfoVariableValues: Called # (DNIS) 5221
31/07/2013 17:30:53:308 DEBUG - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : CTICommand.setCallInfoVariableValues: Ext 50227
31/07/2013 17:30:53:308 DEBUG - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : CTICommand.setCallInfoVariableValues: UCID 00001084821375309953
31/07/2013 17:30:53:308 DEBUG - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : CTICommand.setCallInfoVariableValues: UUI
31/07/2013 17:30:53:308 DEBUG - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : CTICommand.setCallInfoVariableValues: State established
31/07/2013 17:30:53:308 INFO - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : Using SCESession 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 servlet : transferir
31/07/2013 17:30:58:839 INFO - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : Using SCESession 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 servlet : CtiTransf
31/07/2013 17:30:58:854 DEBUG - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : VariableUtil.getVariableValue: Variable value is 55442
31/07/2013 17:30:58:854 DEBUG - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : VariableUtil.getVariableValue: Variable value is 555278760020130731172959
31/07/2013 17:30:58:854 DEBUG - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : CTICommand.execute: encrypted value is a1jGIGb8bRpXjOa+wOCm9+xAN4uw8LfjqnB9vFRhVfM=
31/07/2013 17:30:58:854 DEBUG - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : VariableUtil.getVariableValue: Variable value is 8482
31/07/2013 17:30:58:854 DEBUG - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : CTICommand.execute: request to manager is
http://172.17.1.208:8080/aesconnector/BlindCall?callee%3D55442%26uui%3D555278760020130731172959%26uuiAsAscii%3Dtrue%26holdAddress%3D8482%26extension%3D50227&k=a1jGIGb8bRpXjOa%2BwOCm9%2BxAN4uw8LfjqnB9vFRhVfM%3D
31/07/2013 17:30:58:854 DEBUG - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : CTICommand.execute: setting session cookie to 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6
31/07/2013 17:30:59:245 DEBUG - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : CTICommand.execute: callinfo from Manager is callid=8482&ani=5552787600&dnis=5221&extension=50227&ucID=00001084821375309953&uui= &state=transferred
31/07/2013 17:30:59:245 DEBUG - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : CTICommand.processCallInfoResults: rawResponse: callid=8482&ani=5552787600&dnis=5221&extension=50227&ucID=00001084821375309953&uui=+&state=transferred
31/07/2013 17:30:59:245 DEBUG - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : CallInfo.doGetCallInfo: Populating call info variable values...
31/07/2013 17:30:59:245 DEBUG - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : CTICommand.setCallInfoVariableValues: Call ID 8482
31/07/2013 17:30:59:245 DEBUG - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : CTICommand.setCallInfoVariableValues: Calling # (ANI) 5552787600
31/07/2013 17:30:59:245 DEBUG - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : CTICommand.setCallInfoVariableValues: Called # (DNIS) 5221
31/07/2013 17:30:59:245 DEBUG - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : CTICommand.setCallInfoVariableValues: Ext 50227
31/07/2013 17:30:59:245 DEBUG - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : CTICommand.setCallInfoVariableValues: UCID 00001084821375309953
31/07/2013 17:30:59:245 DEBUG - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : CTICommand.setCallInfoVariableValues: UUI
31/07/2013 17:30:59:245 DEBUG - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : CTICommand.setCallInfoVariableValues: State transferred
31/07/2013 17:30:59:245 INFO - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : Using SCESession 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 servlet : Transferencia
31/07/2013 17:30:59:245 INFO - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : Using SCESession 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 servlet : ErrSistemas
31/07/2013 17:30:59:245 INFO - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : Assigning [Transferencias:ErrSistemas] to [VDN]
31/07/2013 17:30:59:245 DEBUG - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : Admin VariableField:getStringValue(): return string value for Transferencias:ErrSistemas
31/07/2013 17:30:59:245 DEBUG - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : get param for app: Famsa
31/07/2013 17:30:59:245 INFO - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : Using SCESession 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 servlet : Transferencia
31/07/2013 17:30:59:260 INFO - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : Using SCESession 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 servlet : Fin
31/07/2013 17:30:59:260 DEBUG - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : CTIEnd.execute:50227 callid is 8482
31/07/2013 17:30:59:260 DEBUG - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : CTICommand.execute: encrypted value is a1jGIGb8bRpXjOa+wOCm93XTAEAkfD9LIChK5z7KY40=
31/07/2013 17:30:59:260 DEBUG - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : CTICommand.execute: request to manager is
http://172.17.1.208:8080/aesconnector/End?extension%3D50227%26callid%3D8482&k=a1jGIGb8bRpXjOa%2BwOCm93XTAEAkfD9LIChK5z7KY40%3D
31/07/2013 17:30:59:260 DEBUG - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : CTICommand.execute: setting session cookie to 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6
31/07/2013 17:30:59:260 DEBUG - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : CTICommand.execute: callinfo from Manager is
31/07/2013 17:30:59:260 INFO - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : Removing session from AvayaSessionTermination Servlet
31/07/2013 17:30:59:260 INFO - 1AEC590C51262F00E848EC017A68C1D0:/Famsa_V6 : Termination URL reply : <?xml version="1.0" encoding="UTF-8"?>
<vxml version="2.1" xmlns="http://www.w3.org/2001/vxml" xml:lang="en-us">
</vxml>
this the log of the aesconnector
31/07/2013 17:29:27:256 DEBUG - ProviderMonitor: Checking:cti for availability
31/07/2013 17:29:45:801 DEBUG - ProviderMonitor: Checking:cti for availability
31/07/2013 17:29:58:518 DEBUG - CTICallObserver.callChangedEvent: Number of events on extension 50227 is 10
31/07/2013 17:29:58:518 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 0 is 101 for call 8482 Provider:cti
31/07/2013 17:29:58:518 DEBUG - CTICallObserver.callChangedEvent50227: got Connection Active Event
31/07/2013 17:29:58:518 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 1 is 106 for call 8482 Provider:cti
31/07/2013 17:29:58:518 DEBUG - CTICallObserver.callChangedEvent50227: got Connection Created Event for id:8482
31/07/2013 17:29:58:518 DEBUG - CTICallObserver.callChangedEvent50227: Saving most recent call created:8482
31/07/2013 17:29:58:518 DEBUG - CTICallObserver.addCall 50227: call id is 8482
31/07/2013 17:29:58:518 DEBUG - CTICallObserver.addCall 50227: ucid is 00001084821375309953
31/07/2013 17:29:58:518 DEBUG - CTICallObserver.addCall 50227: setting 8482 to state: initiated
31/07/2013 17:29:58:518 DEBUG - CallInfoData.CallInfoData:50227: callid 8482
31/07/2013 17:29:58:518 DEBUG - CallInfoData.CallInfoData:50227: ani 5552787600
31/07/2013 17:29:58:518 DEBUG - CallInfoData.CallInfoData:50227: dnis 5221
31/07/2013 17:29:58:518 DEBUG - CallInfoData.CallInfoData:50227: ucid 00001084821375309953
31/07/2013 17:29:58:518 DEBUG - CallInfoData.CallInfoData:50227: uui
31/07/2013 17:29:58:518 DEBUG - CTICallObserver.addCall 50227: added call to list
31/07/2013 17:29:58:518 DEBUG - CTICallObserver.addCall 50227:cache is:false
31/07/2013 17:29:58:518 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 2 is 105 for call 8482 Provider:cti
31/07/2013 17:29:58:518 DEBUG - CTICallObserver.callChangedEvent50227: got Connection Connected Event
31/07/2013 17:29:58:518 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 3 is 206 for call 8482 Provider:cti
31/07/2013 17:29:58:518 DEBUG - CTICallObserver.callChangedEvent:50227: got Call Control Connection Established Event
31/07/2013 17:29:58:518 DEBUG - CTICallObserver.callChangedEvent:50227: found 2 connections
31/07/2013 17:29:58:518 DEBUG - CTICallObserver.callChangedEvent:50227: state of connection 0 is 51
31/07/2013 17:29:58:518 DEBUG - CTICallObserver.callChangedEvent:50227: state of connection 1 is 50
31/07/2013 17:29:58:518 DEBUG - CTICallObserver.callChangedEvent:50227: Call is not fully established
31/07/2013 17:29:58:518 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 4 is 106 for call 8482 Provider:cti
31/07/2013 17:29:58:518 DEBUG - CTICallObserver.callChangedEvent50227: got Connection Created Event for id:8482
31/07/2013 17:29:58:518 DEBUG - CTICallObserver.callChangedEvent50227: Saving most recent call created:8482
31/07/2013 17:29:58:518 DEBUG - CTICallObserver.addCall 50227: call id is 8482 is already added, returning call data
31/07/2013 17:29:58:518 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 5 is 104 for call 8482 Provider:cti
31/07/2013 17:29:58:518 DEBUG - CTICallObserver.callChangedEvent50227: got Connection Alerting Event
31/07/2013 17:29:58:518 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 6 is 203 for call 8482 Provider:cti
31/07/2013 17:29:58:518 DEBUG - CTICallObserver.callChangedEvent:50227: got Call Control alerting Event
31/07/2013 17:29:58:518 DEBUG - CTICallObserver.updateCallState 50227: setting call:8482 to state:ringing
31/07/2013 17:29:58:518 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 7 is 116 for call 8482 Provider:cti
31/07/2013 17:29:58:518 DEBUG - CTICallObserver.callChangedEvent50227: got Terminal Connection Created Event
31/07/2013 17:29:58:518 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 8 is 119 for call 8482 Provider:cti
31/07/2013 17:29:58:518 DEBUG - CTICallObserver.callChangedEvent50227: got Terminal Connection Ringing Event
31/07/2013 17:29:58:518 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 9 is 218 for call 8482 Provider:cti
31/07/2013 17:29:58:518 DEBUG - CTICallObserver.callChangedEvent50227: got Call Control Terminal Ringing Event
31/07/2013 17:29:59:049 DEBUG - CTICallObserver.callChangedEvent: Number of events on extension 50227 is 4
31/07/2013 17:29:59:049 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 0 is 105 for call 8482 Provider:cti
31/07/2013 17:29:59:049 DEBUG - CTICallObserver.callChangedEvent50227: got Connection Connected Event
31/07/2013 17:29:59:049 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 1 is 206 for call 8482 Provider:cti
31/07/2013 17:29:59:049 DEBUG - CTICallObserver.callChangedEvent:50227: got Call Control Connection Established Event
31/07/2013 17:29:59:049 DEBUG - CTICallObserver.callChangedEvent:50227: found 2 connections
31/07/2013 17:29:59:049 DEBUG - CTICallObserver.callChangedEvent:50227: state of connection 0 is 51
31/07/2013 17:29:59:049 DEBUG - CTICallObserver.callChangedEvent:50227: state of connection 1 is 51
31/07/2013 17:29:59:049 DEBUG - CTICallObserver.callChangedEvent:50227: established both connections of call
31/07/2013 17:29:59:049 DEBUG - CTICallObserver.updateCallState 50227: setting call:8482 to state:established
31/07/2013 17:29:59:049 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 2 is 115 for call 8482 Provider:cti
31/07/2013 17:29:59:049 DEBUG - CTICallObserver.callChangedEvent50227: got Terminal Connection Active Event
31/07/2013 17:29:59:049 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 3 is 219 for call 8482 Provider:cti
31/07/2013 17:29:59:049 DEBUG - CTICallObserver.callChangedEvent:50227: got Terminal Connection Talking Event
31/07/2013 17:29:59:049 DEBUG - CTICallObserver.callChangedEvent:50227: found 2 connections
31/07/2013 17:29:59:049 DEBUG - CTICallObserver.callChangedEvent:50227: state of connection 0 is 51
31/07/2013 17:29:59:049 DEBUG - CTICallObserver.callChangedEvent:50227: state of connection 1 is 51
31/07/2013 17:29:59:049 DEBUG - CTICallObserver.callChangedEvent:50227: established both connections of call
31/07/2013 17:29:59:049 DEBUG - CTICallObserver.updateCallState 50227: setting call:8482 to state:established
31/07/2013 17:29:59:111 DEBUG - CTICallObserver.addCallToCache:caching call:8482
31/07/2013 17:29:59:111 DEBUG - CTICallObserver.getCallInfo: app is interested in call, setting MRCR:8482
31/07/2013 17:29:59:111 DEBUG - CallInfo.doGetCallInfo: Got call info for extension 50227
31/07/2013 17:30:04:361 DEBUG - ProviderMonitor: Checking:cti for availability
31/07/2013 17:30:22:890 DEBUG - ProviderMonitor: Checking:cti for availability
31/07/2013 17:30:41:419 DEBUG - ProviderMonitor: Checking:cti for availability
31/07/2013 17:30:53:308 DEBUG - CTICallObserver.getCallInfo: app is interested in call, setting MRCR:8482
31/07/2013 17:30:53:308 DEBUG - CallInfo.doGetCallInfo: Got call info for extension 50227
31/07/2013 17:30:58:854 DEBUG - Hold.doHold:50227 putting on hold ext:50227
31/07/2013 17:30:58:901 DEBUG - CTICallObserver.addApplicationObserver: adding an app observer to extension:50227
31/07/2013 17:30:58:901 DEBUG - Hold.doHold 50227: Placing terminal connection 0 on hold...
31/07/2013 17:30:58:901 DEBUG - Hold.doHold 50227: Waiting....
31/07/2013 17:30:58:948 DEBUG - CTICallObserver.callChangedEvent: Number of events on extension 50227 is 1
31/07/2013 17:30:58:948 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 0 is 216 for call 8482 Provider:cti
31/07/2013 17:30:58:948 DEBUG - CTICallObserver.callChangedEvent:50227: got Terminal Connection Held Event
31/07/2013 17:30:58:948 DEBUG - CTICallObserver.updateCallState 50227: setting call:8482 to state:held
31/07/2013 17:30:58:948 DEBUG - CTICallObserver.updateCallState 50227: setting cached call:8482 to state:held
31/07/2013 17:30:58:948 DEBUG - CTICallObserver.notifyApplicationObserver: Notifying 50227 about call event
31/07/2013 17:30:58:948 DEBUG - Hold.doHold 50227: Terminal connection 0 placed on hold
31/07/2013 17:30:58:948 DEBUG - Hold.doHold 50227: Done placing connections on hold
31/07/2013 17:30:58:948 DEBUG - CTICallObserver.removeApplicationObserver: removing an app observer from extension:50227
31/07/2013 17:30:58:964 DEBUG - CTICallObserver.addApplicationObserver: adding an app observer to extension:50227
31/07/2013 17:30:58:964 DEBUG - CTICallOperation.makeCall: Attempting to Connect from 50227 to 55442...
31/07/2013 17:30:58:964 DEBUG - CTICallOperation.makeCall 50227: Connecting with UUI 555278760020130731172959...
31/07/2013 17:30:58:964 DEBUG - CTICallOperation.makeCall 50227: UUI is ASCII:true
31/07/2013 17:30:58:964 DEBUG - CTICallOperation.makeCall: Using FastConnect
31/07/2013 17:30:59:057 DEBUG - CTICallOperation.makeCall 50227: Returned from connect call...
31/07/2013 17:30:59:057 DEBUG - CTICallOperation.makeCall 50227: new call id is 8564
31/07/2013 17:30:59:057 DEBUG - CTICallOperation.makeCall 50227: Call id is active
31/07/2013 17:30:59:057 DEBUG - CTICallOperation.makeCall 50227: Call state is 48
31/07/2013 17:30:59:057 DEBUG - CTICallOperation.makeCall 50227: blind call -- Waiting on mutex
31/07/2013 17:30:59:057 DEBUG - CTICallObserver.callChangedEvent: Number of events on extension 50227 is 7
31/07/2013 17:30:59:057 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 0 is 101 for call 8564 Provider:cti
31/07/2013 17:30:59:057 DEBUG - CTICallObserver.callChangedEvent50227: got Connection Active Event
31/07/2013 17:30:59:057 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 1 is 106 for call 8564 Provider:cti
31/07/2013 17:30:59:057 DEBUG - CTICallObserver.callChangedEvent50227: got Connection Created Event for id:8564
31/07/2013 17:30:59:057 DEBUG - CTICallObserver.callChangedEvent50227: Saving most recent call created:8564
31/07/2013 17:30:59:057 DEBUG - CTICallObserver.addCall 50227: call id is 8564
31/07/2013 17:30:59:057 DEBUG - CTICallObserver.addCall 50227: ucid is 00001085641375310025
31/07/2013 17:30:59:057 DEBUG - CTICallObserver.addCall 50227: setting 8564 to state: initiated
31/07/2013 17:30:59:057 DEBUG - CallInfoData.CallInfoData:50227: callid 8564
31/07/2013 17:30:59:057 DEBUG - CallInfoData.CallInfoData:50227: ani 50227
31/07/2013 17:30:59:057 DEBUG - CallInfoData.CallInfoData:50227: dnis 55442
31/07/2013 17:30:59:057 DEBUG - CallInfoData.CallInfoData:50227: ucid 00001085641375310025
31/07/2013 17:30:59:057 DEBUG - CallInfoData.CallInfoData:50227: uui
31/07/2013 17:30:59:073 DEBUG - CTICallObserver.addCall 50227: added call to list
31/07/2013 17:30:59:073 DEBUG - CTICallObserver.addCall 50227:cache is:true
31/07/2013 17:30:59:073 DEBUG - CTICallObserver.addCallToCache:caching call:8564
31/07/2013 17:30:59:073 DEBUG - CTICallObserver.notifyApplicationObserver: Notifying 50227 about call event
31/07/2013 17:30:59:073 DEBUG - CTICallOperation.applicationCallEvent 50227: call is active, may transfer now
31/07/2013 17:30:59:073 DEBUG - Mutex.setWait: wait is false
31/07/2013 17:30:59:073 DEBUG - CTICallOperation.makeCall 50227: Thread on mutex + resumed
31/07/2013 17:30:59:073 DEBUG - CTICallObserver.removeApplicationObserver: removing an app observer from extension:50227
31/07/2013 17:30:59:073 DEBUG - Transfer.doMerge: Performing transfer...
31/07/2013 17:30:59:073 DEBUG - Transfer.doMerge:50227 active callid is 8564 held call id is 8482
31/07/2013 17:30:59:073 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 2 is 105 for call 8564 Provider:cti
31/07/2013 17:30:59:073 DEBUG - CTICallObserver.callChangedEvent50227: got Connection Connected Event
31/07/2013 17:30:59:073 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 3 is 208 for call 8564 Provider:cti
31/07/2013 17:30:59:073 DEBUG - CTICallObserver.callChangedEvent:50227: got an unknown event 208
31/07/2013 17:30:59:073 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 4 is 116 for call 8564 Provider:cti
31/07/2013 17:30:59:073 DEBUG - CTICallObserver.callChangedEvent50227: got Terminal Connection Created Event
31/07/2013 17:30:59:073 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 5 is 115 for call 8564 Provider:cti
31/07/2013 17:30:59:073 DEBUG - CTICallObserver.callChangedEvent50227: got Terminal Connection Active Event
31/07/2013 17:30:59:073 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 6 is 219 for call 8564 Provider:cti
31/07/2013 17:30:59:073 DEBUG - CTICallObserver.callChangedEvent:50227: got Terminal Connection Talking Event
31/07/2013 17:30:59:073 DEBUG - CTICallObserver.callChangedEvent:50227: found 1 connections
31/07/2013 17:30:59:073 DEBUG - CTICallObserver.callChangedEvent:50227: Found connections object, but not 2 active connections. May be using FastConnect
31/07/2013 17:30:59:073 DEBUG - CTICallObserver.callChangedEvent: Number of events on extension 50227 is 1
31/07/2013 17:30:59:073 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 0 is 206 for call 8564 Provider:cti
31/07/2013 17:30:59:073 DEBUG - CTICallObserver.callChangedEvent:50227: got Call Control Connection Established Event
31/07/2013 17:30:59:073 DEBUG - CTICallObserver.callChangedEvent:50227: found 1 connections
31/07/2013 17:30:59:073 DEBUG - CTICallObserver.callChangedEvent:50227: Found connections object, but not 2 active connections. May be using FastConnect
31/07/2013 17:30:59:104 DEBUG - CTICallObserver.callChangedEvent: Number of events on extension 50227 is 3
31/07/2013 17:30:59:104 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 0 is 106 for call 8564 Provider:cti
31/07/2013 17:30:59:104 DEBUG - CTICallObserver.callChangedEvent50227: got Connection Created Event for id:8564
31/07/2013 17:30:59:104 DEBUG - CTICallObserver.callChangedEvent50227: Saving most recent call created:8564
31/07/2013 17:30:59:104 DEBUG - CTICallObserver.addCall 50227: call id is 8564 is already added, returning call data
31/07/2013 17:30:59:104 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 1 is 109 for call 8564 Provider:cti
31/07/2013 17:30:59:104 DEBUG - CTICallObserver.callChangedEvent:50227: got an unknown event 109
31/07/2013 17:30:59:104 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 2 is 212 for call 8564 Provider:cti
31/07/2013 17:30:59:104 DEBUG - CTICallObserver.callChangedEvent:50227: got Call Control queued Event
31/07/2013 17:30:59:104 DEBUG - CTICallObserver.updateCallState 50227: setting call:8564 to state:queued
31/07/2013 17:30:59:104 DEBUG - CTICallObserver.updateCallState 50227: setting cached call:8564 to state:queued
31/07/2013 17:30:59:167 DEBUG - Transfer.doMerge:50227 number of connections is 2
31/07/2013 17:30:59:167 DEBUG - Transfer.doMerge:50227 found held call 8482
31/07/2013 17:30:59:167 DEBUG - Transfer.doMerge:50227 found active call 8564
31/07/2013 17:30:59:167 DEBUG - Transfer.doMerge:50227 about to transfer the call
31/07/2013 17:30:59:229 DEBUG - Transfer.doMerge:50227 transfer has just returned
31/07/2013 17:30:59:229 DEBUG - getObserversFromCallId: gathering observers... done waiting on sync list size of list is 96
31/07/2013 17:30:59:229 DEBUG - CTICallObserver.callChangedEvent: Number of events on extension 50227 is 15
31/07/2013 17:30:59:229 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 0 is 107 for call 8482 Provider:cti
31/07/2013 17:30:59:229 DEBUG - CTICallObserver.callChangedEvent:50227: got Connection Disconnected Event 107
31/07/2013 17:30:59:229 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 1 is 205 for call 8482 Provider:cti
31/07/2013 17:30:59:229 DEBUG - CTICallObserver.callChangedEvent:50227: got Connection Disconnected Event 205
31/07/2013 17:30:59:229 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 2 is 117 for call 8482 Provider:cti
31/07/2013 17:30:59:229 DEBUG - CTICallObserver.callChangedEvent:50227: got an unknown event 117
31/07/2013 17:30:59:229 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 3 is 215 for call 8482 Provider:cti
31/07/2013 17:30:59:229 DEBUG - CTICallObserver.callChangedEvent:50227: got an unknown event 215
31/07/2013 17:30:59:229 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 4 is 107 for call 8482 Provider:cti
31/07/2013 17:30:59:229 DEBUG - CTICallObserver.callChangedEvent:50227: got Connection Disconnected Event 107
31/07/2013 17:30:59:229 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 5 is 205 for call 8482 Provider:cti
31/07/2013 17:30:59:229 DEBUG - CTICallObserver.callChangedEvent:50227: got Connection Disconnected Event 205
31/07/2013 17:30:59:229 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 6 is 102 for call 8482 Provider:cti
31/07/2013 17:30:59:229 DEBUG - CTICallObserver.callChangedEvent:50227: got an unknown event 102
31/07/2013 17:30:59:229 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 7 is 103 for call 8482 Provider:cti
31/07/2013 17:30:59:229 DEBUG - CTICallObserver.callChangedEvent:50227: got Call Observation Ended Event
31/07/2013 17:30:59:229 DEBUG - CTICallObserver.removeCall:clearing MRCR:8482 from ext:50227
31/07/2013 17:30:59:229 DEBUG - CTICallObserver.removeCall:removing call:8482 from ext:50227
31/07/2013 17:30:59:229 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 8 is 117 for call 8564 Provider:cti
31/07/2013 17:30:59:229 DEBUG - CTICallObserver.callChangedEvent:50227: got an unknown event 117
31/07/2013 17:30:59:229 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 9 is 215 for call 8564 Provider:cti
31/07/2013 17:30:59:229 DEBUG - CTICallObserver.callChangedEvent:50227: got an unknown event 215
31/07/2013 17:30:59:229 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 10 is 107 for call 8564 Provider:cti
31/07/2013 17:30:59:229 DEBUG - CTICallObserver.callChangedEvent:50227: got Connection Disconnected Event but during an alert or after queue. Probably calling a VDN
31/07/2013 17:30:59:229 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 11 is 205 for call 8564 Provider:cti
31/07/2013 17:30:59:229 DEBUG - CTICallObserver.callChangedEvent:50227: got Connection Disconnected Event but during an alert or after queue. Probably calling a VDN
31/07/2013 17:30:59:229 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 12 is 106 for call 8564 Provider:cti
31/07/2013 17:30:59:229 DEBUG - CTICallObserver.callChangedEvent50227: got Connection Created Event for id:8564
31/07/2013 17:30:59:229 DEBUG - CTICallObserver.callChangedEvent50227: Saving most recent call created:8564
31/07/2013 17:30:59:229 DEBUG - CTICallObserver.addCall 50227: call id is 8564 is already added, returning call data
31/07/2013 17:30:59:229 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 13 is 105 for call 8564 Provider:cti
31/07/2013 17:30:59:229 DEBUG - CTICallObserver.callChangedEvent50227: got Connection Connected Event
31/07/2013 17:30:59:229 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 14 is 206 for call 8564 Provider:cti
31/07/2013 17:30:59:229 DEBUG - CTICallObserver.callChangedEvent:50227: got Call Control Connection Established Event
31/07/2013 17:30:59:229 DEBUG - getObserversFromCallId: done gathering observers
31/07/2013 17:30:59:229 DEBUG - CTICallObserver.updateCallState 50227: setting call:8482 to state:transferred
31/07/2013 17:30:59:229 DEBUG - CTICallObserver.updateCallState 50227: setting cached call:8482 to state:transferred
31/07/2013 17:30:59:229 DEBUG - Transfer.doMerge:50227 Updated call info to state:transferred
31/07/2013 17:30:59:260 DEBUG - Ending call on extension 50227
31/07/2013 17:30:59:260 DEBUG - CTICallObserver.removeAllCalls: Callid: id passed from app on extension:50227 is 8482 MRCR: MRCC:8564
31/07/2013 17:30:59:260 DEBUG - CTICallObserver.removeAllCalls: Callid: in conf, no id or MRCC exists on extension:50227. Will only remove this callid.
31/07/2013 17:30:59:260 DEBUG - CTICallObserver.removeAllCallsFromCache: Callid: in conf, no id or MRCC exists on extension:50227. Remove all calls except MRCC and MRCA from cachedCalls q of size:2
31/07/2013 17:30:59:338 DEBUG - CTICallObserver.callChangedEvent:50227: found 2 connections
31/07/2013 17:30:59:432 DEBUG - CTICallObserver.callChangedEvent:50227: state of connection 0 is 54
31/07/2013 17:30:59:651 DEBUG - CTICallObserver.callChangedEvent:50227: state of connection 1 is 51
31/07/2013 17:30:59:760 DEBUG - CTICallObserver.callChangedEvent:50227: Call is not fully established
31/07/2013 17:30:59:760 DEBUG - CTICallObserver.callChangedEvent: Number of events on extension 50227 is 1
31/07/2013 17:30:59:760 DEBUG - CTICallObserver.callChangedEvent 50227: Event: 0 is 103 for call 8564 Provider:cti
31/07/2013 17:30:59:760 DEBUG - CTICallObserver.callChangedEvent:50227: got Call Observation Ended Event
31/07/2013 17:30:59:760 DEBUG - CTICallObserver.removeCall:clearing MRCC:8564 from ext:50227
31/07/2013 17:30:59:760 DEBUG - CTICallObserver.removeCall:removing call:8564 from ext:50227
31/07/2013 17:30:59:932 DEBUG - ProviderMonitor: Checking:cti for availability
31/07/2013 17:31:18:477 DEBUG - ProviderMonitor: Checking:cti for availability
31/07/2013 17:31:37:006 DEBUG - ProviderMonitor: Checking:cti for availability
Can you give me any directions about how resolve this issue?
regards