Hi,
I would like to modify the topic of my query to "cti transfer failing from IVR application". As i have already mentioned, agent transfer is failing from IVR. When checking IVR logs it only shows
<BLIND CALL FAILED> ddLastException is : | ddLastException:stacktrace : java.io.IOException: Server returned HTTP response code: 500 for URL:
http://10.144.141.34:8080/aesconnector/BlindCall?callee%3D7003%26uui%3D+%7C%7CLoan+acct%2CDemat%26NPS%7CHIN%7C%7C1%7C1%26uuiAsAscii%3Dtrue%26holdAddress%3D4111%26extension%3D1108&k=E%2B6JYNjQH5UtPdHsLS6amiLQX2KCUEJHsSuU047D3lA%3D
The URL mentions aesconnector applicaion. Does it mean there some problem trying to access the application which will be used to transfer the call details? Please find below IVR logs:
Line 68508: 03/09/2013 15:03:58:371 INFO - 4BED3FC326D9EF14ABDABDF4923A9845:/IDBI_IVR : Using SCESession 4BED3FC326D9EF14ABDABDF4923A9845:/IDBI_IVR servlet : S92000_AgentTransfer-S_AgentTransfer
Line 68508: 03/09/2013 15:03:58:371 INFO - 4BED3FC326D9EF14ABDABDF4923A9845:/IDBI_IVR : Using SCESession 4BED3FC326D9EF14ABDABDF4923A9845:/IDBI_IVR servlet : S92000_AgentTransfer-S_AgentTransfer
Line 68853: 03/09/2013 15:04:09:136 INFO - 4BED3FC326D9EF14ABDABDF4923A9845:/IDBI_IVR : Using SCESession 4BED3FC326D9EF14ABDABDF4923A9845:/IDBI_IVR servlet : S92000_AgentTransfer-BlindCall
Line 68853: 03/09/2013 15:04:09:136 INFO - 4BED3FC326D9EF14ABDABDF4923A9845:/IDBI_IVR : Using SCESession 4BED3FC326D9EF14ABDABDF4923A9845:/IDBI_IVR servlet : S92000_AgentTransfer-BlindCall
Line 68854: 03/09/2013 15:04:09:136 DEBUG - 4BED3FC326D9EF14ABDABDF4923A9845:/IDBI_IVR : session id:avpDC3-2013246093246-13 | IDBI_IVR:S92000_AgentTransfer-BlindCall | Destination Vdn = | C_transferVdn:destination : 7003
Line 68855: 03/09/2013 15:04:09:136 DEBUG - 4BED3FC326D9EF14ABDABDF4923A9845:/IDBI_IVR : VariableUtil.getVariableValue: Variable value is 7003
Line 68856: 03/09/2013 15:04:09:136 DEBUG - 4BED3FC326D9EF14ABDABDF4923A9845:/IDBI_IVR : VariableUtil.getVariableValue: Variable value is ||Loan acct,Demat&NPS|HIN||1|1
Line 68857: 03/09/2013 15:04:09:136 DEBUG - 4BED3FC326D9EF14ABDABDF4923A9845:/IDBI_IVR : CTICommand.execute: encrypted value is E+6JYNjQH5UtPdHsLS6amiLQX2KCUEJHsSuU047D3lA=
Line 68858: 03/09/2013 15:04:09:136 DEBUG - 4BED3FC326D9EF14ABDABDF4923A9845:/IDBI_IVR : VariableUtil.getVariableValue: Variable value is 4111
Line 68859: 03/09/2013 15:04:09:136 DEBUG - 4BED3FC326D9EF14ABDABDF4923A9845:/IDBI_IVR : CTICommand.execute: request to manager is
http://10.144.141.34:8080/aesconnector/BlindCall?callee%3D7003%26uui%3D+%7C%7CLoan+acct%2CDemat%26NPS%7CHIN%7C%7C1%7C1%26uuiAsAscii%3Dtrue%26holdAddress%3D4111%26extension%3D1108&k=E%2B6JYNjQH5UtPdHsLS6amiLQX2KCUEJHsSuU047D3lA%3D
Line 68860: 03/09/2013 15:04:09:136 DEBUG - 4BED3FC326D9EF14ABDABDF4923A9845:/IDBI_IVR : CTICommand.execute: setting session cookie to 4BED3FC326D9EF14ABDABDF4923A9845:/IDBI_IVR
Line 68861: 03/09/2013 15:04:09:136 INFO - 4BED3FC326D9EF14ABDABDF4923A9845:/IDBI_IVR : Capturing exception [java.io.IOException]. Message [Server returned HTTP response code: 500 for URL:
http://10.144.141.34:8080/aesconnector/BlindCall?callee%3D7003%26uui%3D+%7C%7CLoan+acct%2CDemat%26NPS%7CHIN%7C%7C1%7C1%26uuiAsAscii%3Dtrue%26holdAddress%3D4111%26extension%3D1108&k=E%2B6JYNjQH5UtPdHsLS6amiLQX2KCUEJHsSuU047D3lA%3D]
Line 68862: 03/09/2013 15:04:09:136 INFO - 4BED3FC326D9EF14ABDABDF4923A9845:/IDBI_IVR : Assigning [CTI failure] to [C_ivrMaster:uui]
Line 68863: 03/09/2013 15:04:09:136 DEBUG - 4BED3FC326D9EF14ABDABDF4923A9845:/IDBI_IVR : session id:avpDC3-2013246093246-13 | IDBI_IVR:S92000_AgentTransfer-BlindCall | <BLIND CALL FAILED> ddLastException is : | ddLastException:stacktrace : java.io.IOException: Server returned HTTP response code: 500 for URL:
http://10.144.141.34:8080/aesconnector/BlindCall?callee%3D7003%26uui%3D+%7C%7CLoan+acct%2CDemat%26NPS%7CHIN%7C%7C1%7C1%26uuiAsAscii%3Dtrue%26holdAddress%3D4111%26extension%3D1108&k=E%2B6JYNjQH5UtPdHsLS6amiLQX2KCUEJHsSuU047D3lA%3D
Line 68887: 03/09/2013 15:04:09:136 INFO - 4BED3FC326D9EF14ABDABDF4923A9845:/IDBI_IVR : Using SCESession 4BED3FC326D9EF14ABDABDF4923A9845:/IDBI_IVR servlet : S92000_AgentTransfer-BlindTranfer
Line 68887: 03/09/2013 15:04:09:136 INFO - 4BED3FC326D9EF14ABDABDF4923A9845:/IDBI_IVR : Using SCESession 4BED3FC326D9EF14ABDABDF4923A9845:/IDBI_IVR servlet : S92000_AgentTransfer-BlindTranfer
Line 68888: 03/09/2013 15:04:09:308 INFO - 4BED3FC326D9EF14ABDABDF4923A9845:/IDBI_IVR : Using SCESession 4BED3FC326D9EF14ABDABDF4923A9845:/IDBI_IVR servlet : S92000_AgentTransfer-SetNextState
Line 68888: 03/09/2013 15:04:09:308 INFO - 4BED3FC326D9EF14ABDABDF4923A9845:/IDBI_IVR : Using SCESession 4BED3FC326D9EF14ABDABDF4923A9845:/IDBI_IVR servlet : S92000_AgentTransfer-SetNextState
Below are aesconnector logs:
03/09/2013 15:03:57:311 DEBUG - CTICallObserver.callChangedEvent: Number of events on extension 1085 is 10
03/09/2013 15:03:57:311 DEBUG - CTICallObserver.callChangedEvent 1085: Event: 0 is 101 for call 4125 Provider:AES
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent1085: got Connection Active Event
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent 1085: Event: 1 is 106 for call 4125 Provider:AES
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent1085: got Connection Created Event for id:4125
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent1085: Saving most recent call created:4125
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.addCall 1085: call id is 4125
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.addCall 1085: ucid is 01001041251378200835
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.addCall 1085: setting 4125 to state: initiated
03/09/2013 15:03:57:326 DEBUG - CallInfoData.CallInfoData:1085: callid 4125
03/09/2013 15:03:57:326 DEBUG - CallInfoData.CallInfoData:1085: ani 9925662006
03/09/2013 15:03:57:326 DEBUG - CallInfoData.CallInfoData:1085: dnis 4000
03/09/2013 15:03:57:326 DEBUG - CallInfoData.CallInfoData:1085: ucid 01001041251378200835
03/09/2013 15:03:57:326 DEBUG - CallInfoData.CallInfoData:1085: uui
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.addCall 1085: added call to list
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.addCall 1085:cache is:false
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent 1085: Event: 2 is 105 for call 4125 Provider:AES
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent1085: got Connection Connected Event
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent 1085: Event: 3 is 206 for call 4125 Provider:AES
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent:1085: got Call Control Connection Established Event
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent:1085: found 2 connections
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent:1085: state of connection 0 is 51
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent:1085: state of connection 1 is 51
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent:1085: established both connections of call
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.updateCallState 1085: setting call:4125 to state:established
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent 1085: Event: 4 is 106 for call 4125 Provider:AES
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent1085: got Connection Created Event for id:4125
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent1085: Saving most recent call created:4125
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.addCall 1085: call id is 4125 is already added, returning call data
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent 1085: Event: 5 is 104 for call 4125 Provider:AES
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent1085: got Connection Alerting Event
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent 1085: Event: 6 is 203 for call 4125 Provider:AES
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent:1085: got Call Control alerting Event
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.updateCallState 1085: setting call:4125 to state:ringing
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent 1085: Event: 7 is 116 for call 4125 Provider:AES
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent1085: got Terminal Connection Created Event
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent 1085: Event: 8 is 119 for call 4125 Provider:AES
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent1085: got Terminal Connection Ringing Event
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent 1085: Event: 9 is 218 for call 4125 Provider:AES
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent1085: got Call Control Terminal Ringing Event
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent: Number of events on extension 1085 is 4
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent 1085: Event: 0 is 105 for call 4125 Provider:AES
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent1085: got Connection Connected Event
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent 1085: Event: 1 is 206 for call 4125 Provider:AES
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent:1085: got Call Control Connection Established Event
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent:1085: found 2 connections
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent:1085: state of connection 0 is 51
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent:1085: state of connection 1 is 51
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent:1085: established both connections of call
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.updateCallState 1085: setting call:4125 to state:established
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent 1085: Event: 2 is 115 for call 4125 Provider:AES
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent1085: got Terminal Connection Active Event
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent 1085: Event: 3 is 219 for call 4125 Provider:AES
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent:1085: got Terminal Connection Talking Event
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent:1085: found 2 connections
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent:1085: state of connection 0 is 51
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent:1085: state of connection 1 is 51
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.callChangedEvent:1085: established both connections of call
03/09/2013 15:03:57:326 DEBUG - CTICallObserver.updateCallState 1085: setting call:4125 to state:established
03/09/2013 15:03:57:435 DEBUG - CTICallObserver.addCallToCache:caching call:4125
03/09/2013 15:03:57:435 DEBUG - CTICallObserver.getCallInfo: app is interested in call, setting MRCR:4125
03/09/2013 15:03:57:435 DEBUG - CallInfo.doGetCallInfo: Got call info for extension 1085
03/09/2013 15:04:02:677 DEBUG - ProviderMonitor: Checking:AES for availability
03/09/2013 15:04:09:152 DEBUG - CTICallObserver.callChangedEvent: Number of events on extension 1108 is 1
03/09/2013 15:04:09:152 DEBUG - CTICallObserver.callChangedEvent 1108: Event: 0 is 216 for call 4111 Provider:AES
03/09/2013 15:04:09:152 DEBUG - CTICallObserver.callChangedEvent:1108: got Terminal Connection Held Event
03/09/2013 15:04:09:152 DEBUG - CTICallObserver.updateCallState 1108: setting call:4111 to state:held
03/09/2013 15:04:09:152 DEBUG - CTICallObserver.updateCallState 1108: setting cached call:4111 to state:held
03/09/2013 15:04:09:152 DEBUG - CTICallObserver.callChangedEvent: Number of events on extension 1108 is 7
03/09/2013 15:04:09:152 DEBUG - CTICallObserver.callChangedEvent 1108: Event: 0 is 101 for call 4126 Provider:AES
03/09/2013 15:04:09:152 DEBUG - CTICallObserver.callChangedEvent1108: got Connection Active Event
03/09/2013 15:04:09:152 DEBUG - CTICallObserver.callChangedEvent 1108: Event: 1 is 106 for call 4126 Provider:AES
03/09/2013 15:04:09:152 DEBUG - CTICallObserver.callChangedEvent1108: got Connection Created Event for id:4126
03/09/2013 15:04:09:152 DEBUG - CTICallObserver.callChangedEvent1108: Saving most recent call created:4126
03/09/2013 15:04:09:152 DEBUG - CTICallObserver.addCall 1108: call id is 4126
03/09/2013 15:04:09:152 DEBUG - CTICallObserver.addCall 1108: ucid is 01001041261378200849
03/09/2013 15:04:09:152 DEBUG - CTICallObserver.addCall 1108: setting 4126 to state: initiated
03/09/2013 15:04:09:152 DEBUG - CallInfoData.CallInfoData:1108: callid 4126
03/09/2013 15:04:09:152 DEBUG - CallInfoData.CallInfoData:1108: ani
03/09/2013 15:04:09:152 DEBUG - CallInfoData.CallInfoData:1108: dnis
03/09/2013 15:04:09:152 DEBUG - CallInfoData.CallInfoData:1108: ucid 01001041261378200849
03/09/2013 15:04:09:152 DEBUG - CallInfoData.CallInfoData:1108: uui
03/09/2013 15:04:09:152 DEBUG - CTICallObserver.addCall 1108: added call to list
03/09/2013 15:04:09:152 DEBUG - CTICallObserver.addCall 1108:cache is:true
03/09/2013 15:04:09:152 DEBUG - CTICallObserver.addCallToCache:caching call:4126
03/09/2013 15:04:09:152 DEBUG - CTICallObserver.callChangedEvent 1108: Event: 2 is 105 for call 4126 Provider:AES
03/09/2013 15:04:09:152 DEBUG - CTICallObserver.callChangedEvent1108: got Connection Connected Event
03/09/2013 15:04:09:152 DEBUG - CTICallObserver.callChangedEvent 1108: Event: 3 is 208 for call 4126 Provider:AES
03/09/2013 15:04:09:152 DEBUG - CTICallObserver.callChangedEvent:1108: got an unknown event 208
03/09/2013 15:04:09:152 DEBUG - CTICallObserver.callChangedEvent 1108: Event: 4 is 116 for call 4126 Provider:AES
03/09/2013 15:04:09:152 DEBUG - CTICallObserver.callChangedEvent1108: got Terminal Connection Created Event
03/09/2013 15:04:09:152 DEBUG - CTICallObserver.callChangedEvent 1108: Event: 5 is 115 for call 4126 Provider:AES
03/09/2013 15:04:09:152 DEBUG - CTICallObserver.callChangedEvent1108: got Terminal Connection Active Event
03/09/2013 15:04:09:152 DEBUG - CTICallObserver.callChangedEvent 1108: Event: 6 is 219 for call 4126 Provider:AES
03/09/2013 15:04:09:152 DEBUG - CTICallObserver.callChangedEvent:1108: got Terminal Connection Talking Event
03/09/2013 15:04:09:152 DEBUG - CTICallObserver.callChangedEvent:1108: found 1 connections
03/09/2013 15:04:09:152 DEBUG - CTICallObserver.callChangedEvent:1108: Found connections object, but not 2 active connections. May be using FastConnect
03/09/2013 15:04:09:245 DEBUG - CTICallObserver.callChangedEvent: Number of events on extension 1108 is 1
03/09/2013 15:04:09:245 DEBUG - CTICallObserver.callChangedEvent 1108: Event: 0 is 206 for call 4126 Provider:AES
03/09/2013 15:04:09:245 DEBUG - CTICallObserver.callChangedEvent:1108: got Call Control Connection Established Event
03/09/2013 15:04:09:245 DEBUG - CTICallObserver.callChangedEvent:1108: found 1 connections
03/09/2013 15:04:09:245 DEBUG - CTICallObserver.callChangedEvent:1108: Found connections object, but not 2 active connections. May be using FastConnect
03/09/2013 15:04:09:261 DEBUG - CTICallObserver.callChangedEvent: Number of events on extension 1108 is 8
03/09/2013 15:04:09:261 DEBUG - CTICallObserver.callChangedEvent 1108: Event: 0 is 107 for call 4111 Provider:AES
03/09/2013 15:04:09:261 DEBUG - CTICallObserver.callChangedEvent:1108: got Connection Disconnected Event 107
03/09/2013 15:04:09:261 DEBUG - CTICallObserver.callChangedEvent 1108: Event: 1 is 205 for call 4111 Provider:AES
03/09/2013 15:04:09:261 DEBUG - CTICallObserver.callChangedEvent:1108: got Connection Disconnected Event 205
03/09/2013 15:04:09:261 DEBUG - CTICallObserver.callChangedEvent 1108: Event: 2 is 117 for call 4111 Provider:AES
03/09/2013 15:04:09:261 DEBUG - CTICallObserver.callChangedEvent:1108: got an unknown event 117
03/09/2013 15:04:09:261 DEBUG - CTICallObserver.callChangedEvent 1108: Event: 3 is 215 for call 4111 Provider:AES
03/09/2013 15:04:09:261 DEBUG - CTICallObserver.callChangedEvent:1108: got an unknown event 215
03/09/2013 15:04:09:261 DEBUG - CTICallObserver.callChangedEvent 1108: Event: 4 is 107 for call 4111 Provider:AES
03/09/2013 15:04:09:261 DEBUG - CTICallObserver.callChangedEvent:1108: got Connection Disconnected Event 107
03/09/2013 15:04:09:261 DEBUG - CTICallObserver.callChangedEvent 1108: Event: 5 is 205 for call 4111 Provider:AES
03/09/2013 15:04:09:261 DEBUG - CTICallObserver.callChangedEvent:1108: got Connection Disconnected Event 205
03/09/2013 15:04:09:261 DEBUG - CTICallObserver.callChangedEvent 1108: Event: 6 is 102 for call 4111 Provider:AES
03/09/2013 15:04:09:261 DEBUG - CTICallObserver.callChangedEvent:1108: got an unknown event 102
03/09/2013 15:04:09:261 DEBUG - CTICallObserver.callChangedEvent 1108: Event: 7 is 103 for call 4111 Provider:AES
03/09/2013 15:04:09:261 DEBUG - CTICallObserver.callChangedEvent:1108: got Call Observation Ended Event
03/09/2013 15:04:09:261 DEBUG - CTICallObserver.removeCall:clearing MRCR:4111 from ext:1108
03/09/2013 15:04:09:261 DEBUG - CTICallObserver.removeCall:removing call:4111 from ext:1108
03/09/2013 15:04:09:261 DEBUG - CTICallObserver.callChangedEvent: Number of events on extension 1108 is 8
03/09/2013 15:04:09:261 DEBUG - CTICallObserver.callChangedEvent 1108: Event: 0 is 117 for call 4126 Provider:AES
03/09/2013 15:04:09:261 DEBUG - CTICallObserver.callChangedEvent:1108: got an unknown event 117
03/09/2013 15:04:09:261 DEBUG - CTICallObserver.callChangedEvent 1108: Event: 1 is 215 for call 4126 Provider:AES
03/09/2013 15:04:09:261 DEBUG - CTICallObserver.callChangedEvent:1108: got an unknown event 215
03/09/2013 15:04:09:261 DEBUG - CTICallObserver.callChangedEvent 1108: Event: 2 is 107 for call 4126 Provider:AES
03/09/2013 15:04:09:261 DEBUG - CTICallObserver.callChangedEvent:1108: got Connection Disconnected Event 107
03/09/2013 15:04:09:261 DEBUG - CTICallObserver.callChangedEvent 1108: Event: 3 is 205 for call 4126 Provider:AES
03/09/2013 15:04:09:261 DEBUG - CTICallObserver.callChangedEvent:1108: got Connection Disconnected Event 205
03/09/2013 15:04:09:261 DEBUG - CTICallObserver.callChangedEvent 1108: Event: 4 is 106 for call 4126 Provider:AES
03/09/2013 15:04:09:261 DEBUG - CTICallObserver.callChangedEvent1108: got Connection Created Event for id:4126
03/09/2013 15:04:09:261 DEBUG - CTICallObserver.callChangedEvent1108: Saving most recent call created:4126
03/09/2013 15:04:09:261 DEBUG - CTICallObserver.addCall 1108: call id is 4126 is already added, returning call data
03/09/2013 15:04:09:261 DEBUG - CTICallObserver.callChangedEvent 1108: Event: 5 is 105 for call 4126 Provider:AES
03/09/2013 15:04:09:261 DEBUG - CTICallObserver.callChangedEvent1108: got Connection Connected Event
03/09/2013 15:04:09:261 DEBUG - CTICallObserver.callChangedEvent 1108: Event: 6 is 206 for call 4126 Provider:AES
03/09/2013 15:04:09:261 DEBUG - CTICallObserver.callChangedEvent:1108: got Call Control Connection Established Event
03/09/2013 15:04:09:323 DEBUG - Ending call on extension 1108
03/09/2013 15:04:09:323 DEBUG - CTICallObserver.removeAllCalls: Callid: id passed from app on extension:1108 is 4111 MRCR: MRCC:4126
03/09/2013 15:04:09:323 DEBUG - CTICallObserver.removeAllCalls: Callid: in conf, no id or MRCC exists on extension:1108. Will only remove this callid.
03/09/2013 15:04:09:323 DEBUG - CTICallObserver.removeAllCallsFromCache: Callid: in conf, no id or MRCC exists on extension:1108. Remove all calls except MRCC and MRCA from cachedCalls q of size:2
03/09/2013 15:04:09:354 DEBUG - CTICallObserver.callChangedEvent:1108: found 1 connections
03/09/2013 15:04:09:354 DEBUG - CTICallObserver.callChangedEvent:1108: Found connections object, but not 2 active connections. May be using FastConnect
03/09/2013 15:04:09:354 DEBUG - CTICallObserver.callChangedEvent 1108: Event: 7 is 103 for call 4126 Provider:AES
03/09/2013 15:04:09:354 DEBUG - CTICallObserver.callChangedEvent:1108: got Call Observation Ended Event
03/09/2013 15:04:09:354 DEBUG - CTICallObserver.removeCall:clearing MRCC:4126 from ext:1108
03/09/2013 15:04:09:354 DEBUG - CTICallObserver.removeCall:removing call:4126 from ext:1108
03/09/2013 15:04:13:348 DEBUG - CTICallObserver.callChangedEvent: Number of events on extension 1023 is 10
03/09/2013 15:04:13:348 DEBUG - CTICallObserver.callChangedEvent 1023: Event: 0 is 101 for call 4127 Provider:AES
03/09/2013 15:04:13:348 DEBUG - CTICallObserver.callChangedEvent1023: got Connection Active Event
03/09/2013 15:04:13:348 DEBUG - CTICallObserver.callChangedEvent 1023: Event: 1 is 106 for call 4127 Provider:AES
03/09/2013 15:04:13:348 DEBUG - CTICallObserver.callChangedEvent1023: got Connection Created Event for id:4127
03/09/2013 15:04:13:348 DEBUG - CTICallObserver.callChangedEvent1023: Saving most recent call created:4127
03/09/2013 15:04:13:348 DEBUG - CTICallObserver.addCall 1023: call id is 4127
03/09/2013 15:04:13:348 DEBUG - CTICallObserver.addCall 1023: ucid is 01001041271378200851
03/09/2013 15:04:13:348 DEBUG - CTICallObserver.addCall 1023: setting 4127 to state: initiated
03/09/2013 15:04:13:348 DEBUG - CallInfoData.CallInfoData:1023: callid 4127
03/09/2013 15:04:13:364 DEBUG - CallInfoData.CallInfoData:1023: ani 2225237448
03/09/2013 15:04:13:364 DEBUG - CallInfoData.CallInfoData:1023: dnis 4000
03/09/2013 15:04:13:364 DEBUG - CallInfoData.CallInfoData:1023: ucid 01001041271378200851
03/09/2013 15:04:13:364 DEBUG - CallInfoData.CallInfoData:1023: uui
03/09/2013 15:04:13:364 DEBUG - CTICallObserver.addCall 1023: added call to list
03/09/2013 15:04:13:364 DEBUG - CTICallObserver.addCall 1023:cache is:false
03/09/2013 15:04:13:364 DEBUG - CTICallObserver.callChangedEvent 1023: Event: 2 is 105 for call 4127 Provider:AES
03/09/2013 15:04:13:364 DEBUG - CTICallObserver.callChangedEvent1023: got Connection Connected Event
03/09/2013 15:04:13:364 DEBUG - CTICallObserver.callChangedEvent 1023: Event: 3 is 206 for call 4127 Provider:AES
03/09/2013 15:04:13:364 DEBUG - CTICallObserver.callChangedEvent:1023: got Call Control Connection Established Event
03/09/2013 15:04:13:364 DEBUG - CTICallObserver.callChangedEvent:1023: found 2 connections
03/09/2013 15:04:13:364 DEBUG - CTICallObserver.callChangedEvent:1023: state of connection 0 is 51
03/09/2013 15:04:13:364 DEBUG - CTICallObserver.callChangedEvent:1023: state of connection 1 is 50
03/09/2013 15:04:13:364 DEBUG - CTICallObserver.callChangedEvent:1023: Call is not fully established
03/09/2013 15:04:13:364 DEBUG - CTICallObserver.callChangedEvent 1023: Event: 4 is 106 for call 4127 Provider:AES
03/09/2013 15:04:13:364 DEBUG - CTICallObserver.callChangedEvent1023: got Connection Created Event for id:4127
03/09/2013 15:04:13:364 DEBUG - CTICallObserver.callChangedEvent1023: Saving most recent call created:4127
03/09/2013 15:04:13:364 DEBUG - CTICallObserver.addCall 1023: call id is 4127 is already added, returning call data
03/09/2013 15:04:13:364 DEBUG - CTICallObserver.callChangedEvent 1023: Event: 5 is 104 for call 4127 Provider:AES
03/09/2013 15:04:13:364 DEBUG - CTICallObserver.callChangedEvent1023: got Connection Alerting Event
03/09/2013 15:04:13:364 DEBUG - CTICallObserver.callChangedEvent 1023: Event: 6 is 203 for call 4127 Provider:AES
03/09/2013 15:04:13:364 DEBUG - CTICallObserver.callChangedEvent:1023: got Call Control alerting Event
03/09/2013 15:04:13:364 DEBUG - CTICallObserver.updateCallState 1023: setting call:4127 to state:ringing
03/09/2013 15:04:13:364 DEBUG - CTICallObserver.callChangedEvent 1023: Event: 7 is 116 for call 4127 Provider:AES
03/09/2013 15:04:13:364 DEBUG - CTICallObserver.callChangedEvent1023: got Terminal Connection Created Event
03/09/2013 15:04:13:364 DEBUG - CTICallObserver.callChangedEvent 1023: Event: 8 is 119 for call 4127 Provider:AES
03/09/2013 15:04:13:364 DEBUG - CTICallObserver.callChangedEvent1023: got Terminal Connection Ringing Event
03/09/2013 15:04:13:364 DEBUG - CTICallObserver.callChangedEvent 1023: Event: 9 is 218 for call 4127 Provider:AES
03/09/2013 15:04:13:364 DEBUG - CTICallObserver.callChangedEvent1023: got Call Control Terminal Ringing Event
03/09/2013 15:04:13:879 DEBUG - CTICallObserver.callChangedEvent: Number of events on extension 1023 is 4
03/09/2013 15:04:13:879 DEBUG - CTICallObserver.callChangedEvent 1023: Event: 0 is 105 for call 4127 Provider:AES
03/09/2013 15:04:13:879 DEBUG - CTICallObserver.callChangedEvent1023: got Connection Connected Event
03/09/2013 15:04:13:879 DEBUG - CTICallObserver.callChangedEvent 1023: Event: 1 is 206 for call 4127 Provider:AES
03/09/2013 15:04:13:879 DEBUG - CTICallObserver.callChangedEvent:1023: got Call Control Connection Established Event
03/09/2013 15:04:13:879 DEBUG - CTICallObserver.callChangedEvent:1023: found 2 connections
03/09/2013 15:04:13:879 DEBUG - CTICallObserver.callChangedEvent:1023: state of connection 0 is 51
03/09/2013 15:04:13:879 DEBUG - CTICallObserver.callChangedEvent:1023: state of connection 1 is 51
03/09/2013 15:04:13:879 DEBUG - CTICallObserver.callChangedEvent:1023: established both connections of call
03/09/2013 15:04:13:879 DEBUG - CTICallObserver.updateCallState 1023: setting call:4127 to state:established
03/09/2013 15:04:13:879 DEBUG - CTICallObserver.callChangedEvent 1023: Event: 2 is 115 for call 4127 Provider:AES
03/09/2013 15:04:13:879 DEBUG - CTICallObserver.callChangedEvent1023: got Terminal Connection Active Event
03/09/2013 15:04:13:879 DEBUG - CTICallObserver.callChangedEvent 1023: Event: 3 is 219 for call 4127 Provider:AES
03/09/2013 15:04:13:879 DEBUG - CTICallObserver.callChangedEvent:1023: got Terminal Connection Talking Event
03/09/2013 15:04:13:879 DEBUG - CTICallObserver.callChangedEvent:1023: found 2 connections
03/09/2013 15:04:13:879 DEBUG - CTICallObserver.callChangedEvent:1023: state of connection 0 is 51
03/09/2013 15:04:13:879 DEBUG - CTICallObserver.callChangedEvent:1023: state of connection 1 is 51
03/09/2013 15:04:13:879 DEBUG - CTICallObserver.callChangedEvent:1023: established both connections of call
03/09/2013 15:04:13:879 DEBUG - CTICallObserver.updateCallState 1023: setting call:4127 to state:established
03/09/2013 15:04:13:957 DEBUG - CTICallObserver.addCallToCache:caching call:4127
03/09/2013 15:04:13:957 DEBUG - CTICallObserver.getCallInfo: app is interested in call, setting MRCR:4127
03/09/2013 15:04:13:957 DEBUG - CallInfo.doGetCallInfo: Got call info for extension 1023
03/09/2013 15:04:14:175 DEBUG - Hold.doHold:1010 putting on hold ext:1010
03/09/2013 15:04:14:191 DEBUG - CTICallObserver.addApplicationObserver: adding an app observer to extension:1010
03/09/2013 15:04:14:191 DEBUG - Hold.doHold 1010: Placing terminal connection 0 on hold...
03/09/2013 15:04:14:191 DEBUG - Hold.doHold 1010: Waiting....
03/09/2013 15:04:14:237 DEBUG - CTICallObserver.callChangedEvent: Number of events on extension 1010 is 1
03/09/2013 15:04:14:237 DEBUG - CTICallObserver.callChangedEvent 1010: Event: 0 is 216 for call 4113 Provider:AES
03/09/2013 15:04:14:237 DEBUG - CTICallObserver.callChangedEvent:1010: got Terminal Connection Held Event
03/09/2013 15:04:14:237 DEBUG - CTICallObserver.updateCallState 1010: setting call:4113 to state:held
03/09/2013 15:04:14:237 DEBUG - CTICallObserver.updateCallState 1010: setting cached call:4113 to state:held
03/09/2013 15:04:14:237 DEBUG - CTICallObserver.notifyApplicationObserver: Notifying 1010 about call event
03/09/2013 15:04:14:237 DEBUG - Hold.doHold 1010: Terminal connection 0 placed on hold
03/09/2013 15:04:14:237 DEBUG - Hold.doHold 1010: Done placing connections on hold
03/09/2013 15:04:14:237 DEBUG - CTICallObserver.removeApplicationObserver: removing an app observer from extension:1010
03/09/2013 15:04:14:237 DEBUG - CTICallObserver.addApplicationObserver: adding an app observer to extension:1010
03/09/2013 15:04:14:237 DEBUG - CTICallOperation.makeCall: Attempting to Connect from 1010 to 7001...
03/09/2013 15:04:14:237 DEBUG - CTICallOperation.makeCall 1010: Connecting with UUI ||Main Menu|ENG||1|1...
03/09/2013 15:04:14:237 DEBUG - CTICallOperation.makeCall 1010: UUI is ASCII:true
03/09/2013 15:04:14:237 DEBUG - CTICallOperation.makeCall: Using FastConnect
03/09/2013 15:04:14:284 DEBUG - CTICallOperation.makeCall 1010: Returned from connect call...
03/09/2013 15:04:14:284 DEBUG - CTICallOperation.makeCall 1010: new call id is 4128
03/09/2013 15:04:14:284 DEBUG - CTICallOperation.makeCall 1010: Call id is active
03/09/2013 15:04:14:284 DEBUG - CTICallOperation.makeCall 1010: Call is connected
03/09/2013 15:04:14:284 DEBUG - CTICallOperation.makeCall 1010: blind call -- Waiting on mutex
03/09/2013 15:04:14:284 DEBUG - CTICallObserver.callChangedEvent: Number of events on extension 1010 is 7
03/09/2013 15:04:14:284 DEBUG - CTICallObserver.callChangedEvent 1010: Event: 0 is 101 for call 4128 Provider:AES
03/09/2013 15:04:14:284 DEBUG - CTICallObserver.callChangedEvent1010: got Connection Active Event
03/09/2013 15:04:14:284 DEBUG - CTICallObserver.callChangedEvent 1010: Event: 1 is 106 for call 4128 Provider:AES
03/09/2013 15:04:14:284 DEBUG - CTICallObserver.callChangedEvent1010: got Connection Created Event for id:4128
03/09/2013 15:04:14:284 DEBUG - CTICallObserver.callChangedEvent1010: Saving most recent call created:4128
03/09/2013 15:04:14:284 DEBUG - CTICallObserver.addCall 1010: call id is 4128
03/09/2013 15:04:14:284 DEBUG - CTICallObserver.addCall 1010: ucid is 01001041281378200854
03/09/2013 15:04:14:284 DEBUG - CTICallObserver.addCall 1010: setting 4128 to state: initiated
03/09/2013 15:04:14:284 DEBUG - CallInfoData.CallInfoData:1010: callid 4128
03/09/2013 15:04:14:284 DEBUG - CallInfoData.CallInfoData:1010: ani 1010
03/09/2013 15:04:14:284 DEBUG - CallInfoData.CallInfoData:1010: dnis 7001
03/09/2013 15:04:14:284 DEBUG - CallInfoData.CallInfoData:1010: ucid 01001041281378200854
03/09/2013 15:04:14:284 DEBUG - CallInfoData.CallInfoData:1010: uui
03/09/2013 15:04:14:284 DEBUG - CTICallObserver.addCall 1010: added call to list
03/09/2013 15:04:14:284 DEBUG - CTICallObserver.addCall 1010:cache is:true
03/09/2013 15:04:14:284 DEBUG - CTICallObserver.addCallToCache:caching call:4128
03/09/2013 15:04:14:284 DEBUG - CTICallObserver.notifyApplicationObserver: Notifying 1010 about call event
03/09/2013 15:04:14:284 DEBUG - CTICallOperation.applicationCallEvent 1010: call is active, may transfer now
03/09/2013 15:04:14:284 DEBUG - Mutex.setWait: wait is false
03/09/2013 15:04:14:284 DEBUG - CTICallOperation.makeCall 1010: Thread on mutex + resumed
03/09/2013 15:04:14:284 DEBUG - CTICallObserver.callChangedEvent 1010: Event: 2 is 105 for call 4128 Provider:AES
03/09/2013 15:04:14:284 DEBUG - CTICallObserver.callChangedEvent1010: got Connection Connected Event
03/09/2013 15:04:14:300 DEBUG - CTICallObserver.removeApplicationObserver: removing an app observer from extension:1010
03/09/2013 15:04:14:300 DEBUG - Transfer.doMerge: Performing transfer...
03/09/2013 15:04:14:300 DEBUG - Transfer.doMerge:1010 active callid is 4128 held call id is 4113
03/09/2013 15:04:14:300 DEBUG - CTICallObserver.callChangedEvent 1010: Event: 3 is 208 for call 4128 Provider:AES
03/09/2013 15:04:14:300 DEBUG - CTICallObserver.callChangedEvent:1010: got an unknown event 208
03/09/2013 15:04:14:300 DEBUG - CTICallObserver.callChangedEvent 1010: Event: 4 is 116 for call 4128 Provider:AES
03/09/2013 15:04:14:300 DEBUG - CTICallObserver.callChangedEvent1010: got Terminal Connection Created Event
03/09/2013 15:04:14:300 DEBUG - CTICallObserver.callChangedEvent 1010: Event: 5 is 115 for call 4128 Provider:AES
03/09/2013 15:04:14:300 DEBUG - CTICallObserver.callChangedEvent1010: got Terminal Connection Active Event
03/09/2013 15:04:14:300 DEBUG - CTICallObserver.callChangedEvent 1010: Event: 6 is 219 for call 4128 Provider:AES
03/09/2013 15:04:14:300 DEBUG - CTICallObserver.callChangedEvent:1010: got Terminal Connection Talking Event
03/09/2013 15:04:14:300 DEBUG - CTICallObserver.callChangedEvent:1010: found 1 connections
03/09/2013 15:04:14:300 DEBUG - CTICallObserver.callChangedEvent:1010: Found connections object, but not 2 active connections. May be using FastConnect
03/09/2013 15:04:14:300 DEBUG - CTICallObserver.callChangedEvent: Number of events on extension 1010 is 1
03/09/2013 15:04:14:300 DEBUG - CTICallObserver.callChangedEvent 1010: Event: 0 is 206 for call 4128 Provider:AES
03/09/2013 15:04:14:300 DEBUG - CTICallObserver.callChangedEvent:1010: got Call Control Connection Established Event
03/09/2013 15:04:14:300 DEBUG - CTICallObserver.callChangedEvent:1010: found 1 connections
03/09/2013 15:04:14:300 DEBUG - CTICallObserver.callChangedEvent:1010: Found connections object, but not 2 active connections. May be using FastConnect
03/09/2013 15:04:14:393 DEBUG - Transfer.doMerge:1010 number of connections is 2
03/09/2013 15:04:14:393 DEBUG - Transfer.doMerge:1010 found held call 4113
03/09/2013 15:04:14:393 DEBUG - Transfer.doMerge:1010 found active call 4128
03/09/2013 15:04:14:393 DEBUG - Transfer.doMerge:1010 about to transfer the call
03/09/2013 15:04:14:440 DEBUG - CTICallObserver.callChangedEvent: Number of events on extension 1010 is 8
03/09/2013 15:04:14:440 DEBUG - CTICallObserver.callChangedEvent 1010: Event: 0 is 107 for call 4113 Provider:AES
03/09/2013 15:04:14:440 DEBUG - CTICallObserver.callChangedEvent:1010: got Connection Disconnected Event 107
03/09/2013 15:04:14:440 DEBUG - CTICallObserver.callChangedEvent 1010: Event: 1 is 205 for call 4113 Provider:AES
03/09/2013 15:04:14:440 DEBUG - CTICallObserver.callChangedEvent:1010: got Connection Disconnected Event 205
03/09/2013 15:04:14:440 DEBUG - CTICallObserver.callChangedEvent 1010: Event: 2 is 117 for call 4113 Provider:AES
03/09/2013 15:04:14:440 DEBUG - CTICallObserver.callChangedEvent:1010: got an unknown event 117
03/09/2013 15:04:14:440 DEBUG - CTICallObserver.callChangedEvent 1010: Event: 3 is 215 for call 4113 Provider:AES
03/09/2013 15:04:14:440 DEBUG - CTICallObserver.callChangedEvent:1010: got an unknown event 215
03/09/2013 15:04:14:440 DEBUG - CTICallObserver.callChangedEvent 1010: Event: 4 is 107 for call 4113 Provider:AES
03/09/2013 15:04:14:440 DEBUG - CTICallObserver.callChangedEvent:1010: got Connection Disconnected Event 107
03/09/2013 15:04:14:440 DEBUG - CTICallObserver.callChangedEvent 1010: Event: 5 is 205 for call 4113 Provider:AES
03/09/2013 15:04:14:440 DEBUG - CTICallObserver.callChangedEvent:1010: got Connection Disconnected Event 205
03/09/2013 15:04:14:440 DEBUG - CTICallObserver.callChangedEvent 1010: Event: 6 is 102 for call 4113 Provider:AES
03/09/2013 15:04:14:440 DEBUG - CTICallObserver.callChangedEvent:1010: got an unknown event 102
03/09/2013 15:04:14:440 DEBUG - CTICallObserver.callChangedEvent 1010: Event: 7 is 103 for call 4113 Provider:AES
03/09/2013 15:04:14:440 DEBUG - CTICallObserver.callChangedEvent:1010: got Call Observation Ended Event
03/09/2013 15:04:14:440 DEBUG - Transfer.doMerge:1010 transfer has just returned
03/09/2013 15:04:14:440 DEBUG - getObserversFromCallId: gathering observers... done waiting on sync list size of list is 140
03/09/2013 15:04:14:440 DEBUG - CTICallObserver.removeCall:clearing MRCR:4113 from ext:1010
03/09/2013 15:04:14:440 DEBUG - CTICallObserver.removeCall:removing call:4113 from ext:1010
03/09/2013 15:04:14:456 DEBUG - CTICallObserver.callChangedEvent: Number of events on extension 1010 is 7
03/09/2013 15:04:14:456 DEBUG - CTICallObserver.callChangedEvent 1010: Event: 0 is 117 for call 4128 Provider:AES
03/09/2013 15:04:14:456 DEBUG - CTICallObserver.callChangedEvent:1010: got an unknown event 117
03/09/2013 15:04:14:456 DEBUG - CTICallObserver.callChangedEvent 1010: Event: 1 is 215 for call 4128 Provider:AES
03/09/2013 15:04:14:456 DEBUG - CTICallObserver.callChangedEvent:1010: got an unknown event 215
03/09/2013 15:04:14:456 DEBUG - CTICallObserver.callChangedEvent 1010: Event: 2 is 107 for call 4128 Provider:AES
03/09/2013 15:04:14:456 DEBUG - CTICallObserver.callChangedEvent:1010: got Connection Disconnected Event 107
03/09/2013 15:04:14:456 DEBUG - CTICallObserver.callChangedEvent 1010: Event: 3 is 205 for call 4128 Provider:AES
03/09/2013 15:04:14:456 DEBUG - CTICallObserver.callChangedEvent:1010: got Connection Disconnected Event 205
03/09/2013 15:04:14:456 DEBUG - CTICallObserver.callChangedEvent 1010: Event: 4 is 106 for call 4128 Provider:AES
03/09/2013 15:04:14:456 DEBUG - CTICallObserver.callChangedEvent1010: got Connection Created Event for id:4128
03/09/2013 15:04:14:456 DEBUG - CTICallObserver.callChangedEvent1010: Saving most recent call created:4128
03/09/2013 15:04:14:456 DEBUG - CTICallObserver.addCall 1010: call id is 4128 is already added, returning call data
03/09/2013 15:04:14:456 DEBUG - CTICallObserver.callChangedEvent 1010: Event: 5 is 105 for call 4128 Provider:AES
03/09/2013 15:04:14:456 DEBUG - CTICallObserver.callChangedEvent1010: got Connection Connected Event
03/09/2013 15:04:14:456 DEBUG - CTICallObserver.callChangedEvent 1010: Event: 6 is 206 for call 4128 Provider:AES
03/09/2013 15:04:14:456 DEBUG - CTICallObserver.callChangedEvent:1010: got Call Control Connection Established Event
03/09/2013 15:04:14:456 DEBUG - getObserversFromCallId: done gathering observers
03/09/2013 15:04:14:456 DEBUG - CTICallObserver.updateCallState 1010: setting call:4113 to state:transferred
03/09/2013 15:04:14:456 DEBUG - CTICallObserver.updateCallState 1010: setting cached call:4113 to state:transferred
03/09/2013 15:04:14:456 DEBUG - Transfer.doMerge:1010 Updated call info to state:transferred
03/09/2013 15:04:14:487 DEBUG - Ending call on extension 1010
03/09/2013 15:04:14:487 DEBUG - CTICallObserver.removeAllCalls: Callid: id passed from app on extension:1010 is 4113 MRCR: MRCC:4128
03/09/2013 15:04:14:487 DEBUG - CTICallObserver.removeAllCalls: Callid: in conf, no id or MRCC exists on extension:1010. Will only remove this callid.
03/09/2013 15:04:14:487 DEBUG - CTICallObserver.removeAllCallsFromCache: Callid: in conf, no id or MRCC exists on extension:1010. Remove all calls except MRCC and MRCA from cachedCalls q of size:3
03/09/2013 15:04:14:549 DEBUG - CTICallObserver.callChangedEvent:1010: found 1 connections
03/09/2013 15:04:14:549 DEBUG - CTICallObserver.callChangedEvent:1010: Found connections object, but not 2 active connections. May be using FastConnect
03/09/2013 15:04:14:549 DEBUG - CTICallObserver.callChangedEvent: Number of events on extension 1010 is 1
03/09/2013 15:04:14:549 DEBUG - CTICallObserver.callChangedEvent 1010: Event: 0 is 103 for call 4128 Provider:AES
03/09/2013 15:04:14:549 DEBUG - CTICallObserver.callChangedEvent:1010: got Call Observation Ended Event
03/09/2013 15:04:14:549 DEBUG - CTICallObserver.removeCall:clearing MRCC:4128 from ext:1010
03/09/2013 15:04:14:549 DEBUG - CTICallObserver.removeCall:removing call:4128 from ext:1010
Please suggest.