38915818: Aug 12 12:21:01.663 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SERVER-70-1-outboundMsgQ] com.cisco.wf.subsystems.ctiserver.CSOutboundMsgProcessor OutboundMsgProcessor: got message: { HEARTBEAT_CONF }
38915819: Aug 12 12:21:01.663 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SERVER-70-1-outboundMsgQ] com.cisco.wf.subsystems.ctiserver.CSOutboundMsgProcessor OutboundMessageprocessor : sending msg : { length=-1 type=HEARTBEAT_CONF,invokeId=4130640 to socket: Socket[addr=10.3.176.21,port=56362,localport=12028]
38915820: Aug 12 12:21:01.664 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SOCKET-102-4387458-PROCESS_CTI_MGR_RESPONSE] com.cisco.wf.subsystems.ctiserver.MsgHandler MsgHandler : Sent : { HEARTBEAT_CONF to Socket[addr=10.3.176.21,port=56362,localport=12028]  } 
38915821: Aug 12 12:21:02.234 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SERVER-70-1-outboundMsgQ] com.cisco.wf.subsystems.ctiserver.CSOutboundMsgProcessor OutboundMsgProcessor: got message: { HEARTBEAT_CONF }
38915822: Aug 12 12:21:02.234 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SERVER-70-1-outboundMsgQ] com.cisco.wf.subsystems.ctiserver.CSOutboundMsgProcessor OutboundMessageprocessor : sending msg : { length=-1 type=HEARTBEAT_CONF,invokeId=4126060 to socket: Socket[addr=10.3.176.22,port=58248,localport=12028]
38915823: Aug 12 12:21:02.234 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SOCKET-199-1449448-PROCESS_CTI_MGR_RESPONSE] com.cisco.wf.subsystems.ctiserver.MsgHandler MsgHandler : Sent : { HEARTBEAT_CONF to Socket[addr=10.3.176.22,port=58248,localport=12028]  } 
38915824: Aug 12 12:21:02.436 AZT %MIVR-SS_TEL-7-UNK: [MIVR_SS_TEL_PORT_QEXE_9837-173-654-TERM_CONN_EV:9837] com.cisco.executor.impl.ExecutorStubImpl.RequestImpl CallID:30929 MediaId:46875/2 Task:25000040774, TerminalConnection to Terminal: CTI_9837 is HELD
38915825: Aug 12 12:21:02.440 AZT %MIVR-SS_TEL-7-UNK: [(P1-10.3.176.11) EventThread] com.cisco.wf.subsystems.jtapi.TAPIPortGroup.Port.InCallObserverImpl.ConsultCallObserver OrigCall=CallID:30929 MediaId:46875/2 Task:25000040774, ConsultEvent= ConsultCallActive
38915826: Aug 12 12:21:02.440 AZT %MIVR-SS_TEL-7-UNK: [(P1-10.3.176.11) EventThread] com.cisco.wf.subsystems.jtapi.TAPIPortGroup.Port.InCallObserverImpl.ConsultCallObserver OrigCall=CallID:30929 MediaId:46875/2 Task:25000040774, ConsultEvent= ConnCreatedEv 9837::1
38915827: Aug 12 12:21:02.440 AZT %MIVR-SS_TEL-7-UNK: [(P1-10.3.176.11) EventThread] com.cisco.wf.subsystems.jtapi.TAPIPortGroup.Port.InCallObserverImpl.ConsultCallObserver OrigCall=CallID:30929 MediaId:46875/2 Task:25000040774, ConsultEvent= ConnConnectedEv 9837::1
38915828: Aug 12 12:21:02.440 AZT %MIVR-SS_TEL-7-UNK: [(P1-10.3.176.11) EventThread] com.cisco.wf.subsystems.jtapi.TAPIPortGroup.Port.InCallObserverImpl.ConsultCallObserver OrigCall=CallID:30929 MediaId:46875/2 Task:25000040774, ConsultEvent= CallCtlConnInitiatedEv 9837::1
38915829: Aug 12 12:21:02.440 AZT %MIVR-SS_TEL-7-UNK: [(P1-10.3.176.11) EventThread] com.cisco.wf.subsystems.jtapi.TAPIPortGroup.Port.InCallObserverImpl.ConsultCallObserver OrigCall=CallID:30929 MediaId:46875/2 Task:25000040774, ConsultEvent= TermConnCreatedEv CTI_9837
38915830: Aug 12 12:21:02.440 AZT %MIVR-SS_TEL-7-UNK: [(P1-10.3.176.11) EventThread] com.cisco.wf.subsystems.jtapi.TAPIPortGroup.Port.InCallObserverImpl.ConsultCallObserver OrigCall=CallID:30929 MediaId:46875/2 Task:25000040774, ConsultEvent= TermConnActiveEv CTI_9837
38915831: Aug 12 12:21:02.440 AZT %MIVR-SS_TEL-7-UNK: [(P1-10.3.176.11) EventThread] com.cisco.wf.subsystems.jtapi.TAPIPortGroup.Port.InCallObserverImpl.ConsultCallObserver OrigCall=CallID:30929 MediaId:46875/2 Task:25000040774, ConsultEvent= CallCtlTermConnTalkingEv CTI_9837
38915832: Aug 12 12:21:02.440 AZT %MIVR-SS_TEL-7-UNK: [MIVR_SS_TEL_TPG_EXE-40-113382-CALL_EVENT_LOG:9837] com.cisco.executor.impl.ExecutorStubImpl.RequestImpl CallID:30929 MediaId:46875/2 Task:25000040774 Got CallCtlConnDialingEv 9837::1,  events on the AddressCallObserver.
38915833: Aug 12 12:21:02.440 AZT %MIVR-SS_TEL-7-UNK: [(P1-10.3.176.11) EventThread] com.cisco.wf.subsystems.jtapi.TAPIPortGroup.Port.InCallObserverImpl.ConsultCallObserver OrigCall=CallID:30929 MediaId:46875/2 Task:25000040774, ConsultEvent= CallCtlConnDialingEv 9837::1
38915834: Aug 12 12:21:02.452 AZT %MIVR-SS_TEL-7-UNK: [MIVR_SS_TEL_TPG_EXE-40-113386-CALL_EVENT_LOG:9837] com.cisco.executor.impl.ExecutorStubImpl.RequestImpl CallID:30929 MediaId:46875/2 Task:25000040774 Got CallCtlConnEstablishedEv 9837::1,  events on the AddressCallObserver.
38915835: Aug 12 12:21:02.452 AZT %MIVR-SS_TEL-7-UNK: [(P1-10.3.176.11) EventThread] com.cisco.wf.subsystems.jtapi.TAPIPortGroup.Port.InCallObserverImpl.ConsultCallObserver OrigCall=CallID:30929 MediaId:46875/2 Task:25000040774, ConsultEvent= CallCtlConnEstablishedEv 9837::1
38915836: Aug 12 12:21:02.456 AZT %MIVR-SS_RM-7-UNK: [ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@16c9310)] com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver processCiscoCallInProgressEv: cause100
38915837: Aug 12 12:21:02.456 AZT %MIVR-SS_RM-7-UNK: [ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@16c9310)] com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver processCiscoCallCtlConnOfferedEv: cause100
38915838: Aug 12 12:21:02.456 AZT %MIVR-SS_RM-7-UNK: [ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@16c9310)] com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver processCiscoCallCtlConnOfferedEv: getCiscoFeatureReason: 12
38915839: Aug 12 12:21:02.456 AZT %MIVR-SS_RM-7-UNK: [ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@16c9310)] com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver processCiscoCallCtlConnOfferedEv: calledAddress: YYYY
38915840: Aug 12 12:21:02.456 AZT %MIVR-SS_RM-7-UNK: [ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@16c9310)] com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver processCiscoCallCtlConnOfferedEv: callingAddress: 9837
38915841: Aug 12 12:21:02.457 AZT %MIVR-SS_RM-7-UNK: [ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@16c9310)] com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver processCiscoCallCtlConnOfferedEv: callID: 10695/1
38915842: Aug 12 12:21:02.457 AZT %MIVR-SS_CM-7-UNK: [ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@16c9310)] com.cisco.wf.subsystems.rmcm.ContactMgr ContactMgr.getRmCmContact(10695/1) returns null
38915843: Aug 12 12:21:02.457 AZT %MIVR-SS_RM-7-UNK: [ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@16c9310)] com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver processCiscoCallCtlConnOfferedEv: callContact null
38915844: Aug 12 12:21:02.457 AZT %MIVR-SS_RM-7-UNK: [ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@16c9310)] com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver processCiscoCallCtlConnOfferedEv: resID: YYYY connState: 49
38915845: Aug 12 12:21:02.457 AZT %MIVR-SS_RM-7-UNK: [ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@16c9310)] com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver processCiscoCallCtlConnOfferedEv: resID: YYYY connState: 51
38915846: Aug 12 12:21:02.457 AZT %MIVR-SS_RM-7-UNK: [ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@16c9310)] com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver processCiscoCallCtlConnOfferedEv: resourceId: YYYY
38915847: Aug 12 12:21:02.457 AZT %MIVR-SS_RM-7-UNK: [ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@16c9310)] com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver processCiscoCallCtlConnOfferedEv: Resource: Rsrc Name:agent ID:agent IAQ Extn:YYYY
38915848: Aug 12 12:21:02.457 AZT %MIVR-SS_RM-7-UNK: [ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@16c9310)] com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver RIMgrAddressCallObserver startPCAnnouncement  for currentCalledAddr:YYYY
38915849: Aug 12 12:21:02.457 AZT %MIVR-SS_RM-7-UNK: [ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@16c9310)] com.cisco.wf.subsystems.rmcm.jtapi.PersistentConnection PersistentConnection::getRDTerminal check for active remote destination for :YYYY
38915850: Aug 12 12:21:02.457 AZT %MIVR-SS_RM-7-UNK: [ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@16c9310)] com.cisco.wf.subsystems.rmcm.jtapi.PersistentConnection PersistentConnection::getRDTerminal address does not have terminal with active remote destinationYYYY
38915851: Aug 12 12:21:02.457 AZT %MIVR-SS_RM-7-UNK: [ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@16c9310)] com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver Protocol Call Ref = null
38915852: Aug 12 12:21:02.457 AZT %MIVR-SS_CM-7-UNK: [ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@16c9310)] com.cisco.wf.subsystems.rmcm.ContactMgr ContactMgr.getRmCmContact(10695/1) returns null
38915853: Aug 12 12:21:02.457 AZT %MIVR-SS_RM-7-UNK: [ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@16c9310)] com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver RIMgrAddressCallObserver: CallCtlConnEstablishedEv received for call:16787911 [10695/1], address 9837, calling party 9837, and called party YYYY
38915854: Aug 12 12:21:02.457 AZT %MIVR-SS_RM-7-UNK: [ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@16c9310)] com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver RIMgrAddressCallObserver: CallCtlConnEstablishedEv received for call 16787911 [10695/1] and agent null being ignored because orig isn't a logged in agent
38915855: Aug 12 12:21:02.512 AZT %MIVR-SS_TEL-7-UNK: [(P1-10.3.176.11) EventThread] com.cisco.wf.subsystems.jtapi.TAPIPortGroup.Port.InCallObserverImpl CallID:30929 MediaId:46875/2 Task:25000040774, CallCtlConnFailed, Inbound call, callctl cause:109, [9837::1/(P1-jtapi_1) GCID=(2,46875)->ACTIVE]->FAILED
38915856: Aug 12 12:21:02.512 AZT %MIVR-SS_TEL-7-UNK: [MIVR_SS_TEL_PORT_QEXE_9837-173-654-CONN_EV:30929] com.cisco.executor.impl.ExecutorStubImpl.RequestImpl CallID:30929 MediaId:46875/2 Task:25000040774, CallCtlConnFailed, Inbound call, call being abandoned
38915857: Aug 12 12:21:02.512 AZT %MIVR-SS_TEL-7-UNK: [MIVR_SS_TEL_PORT_QEXE_9837-173-654-CONN_EV:30929] com.cisco.wf.subsystems.jtapi.CallImpl CallID:30929 MediaId:46875/2 is removed from call session mapping in Session[id=001-0x165a13648,parent=null,active=true,state=SESSION_IN_USE,time=1597219602197], result:true
38915858: Aug 12 12:21:02.512 AZT %MIVR-SS_TEL-7-UNK: [MIVR_SS_TEL_PORT_QEXE_9837-173-654-CONN_EV:30929] com.cisco.wf.subsystems.jtapi.CallImpl Call.abandoned() JTAPICallContact[id=30929,implId=46875/2,state=STATE_ABANDONED_IDX,inbound=true,App name=AM01,task=25000040774,session=6000031304,seq num=0,cn=9990,dn=9990,cgn=XXXX,ani=null,dnis=null,clid=null,atype=DIRECT,lrd=null,ocn=9990,route=RP[num=9990],OrigProtocolCallRef=000000000000B71B02CCFE1E00000000,DestProtocolCallRef=null,TP=9837]
38915859: Aug 12 12:21:02.518 AZT %MIVR-SS_TEL-7-UNK: [MIVR_SS_TEL_PORT_QEXE_9837-173-654-CONN_EV:30929] com.cisco.wf.subsystems.jtapi.TAPIPortGroup.Port.InCallObserverImpl CallID:30929 MediaId:46875/2 Task:25000040774, released TP[type=Cisco CTI Port,id=23,implId=9837,active=false,state=IDLE] from 9990, and releasing udpPort 28136
38915860: Aug 12 12:21:02.518 AZT %MIVR-SS_TEL-7-UNK: [MIVR_SS_TEL_TPG_EXE-40-113398-CALL_EVENT_LOG:9837] com.cisco.executor.impl.ExecutorStubImpl.RequestImpl  Got TermConnDroppedEv CTI_9837, CallCtlTermConnDroppedEv CTI_9837, ConnDisconnectedEv 9837::1, CallCtlConnDisconnectedEv 9837::1, CallInvalidEv,  events on the AddressCallObserver.
38915861: Aug 12 12:21:02.519 AZT %MIVR-SS_TEL-7-UNK: [MIVR_SS_TEL_PORT_QEXE_9837-173-654-CONN_EV:30929] com.cisco.wf.subsystems.jtapi.TAPIPortGroup.Port.InCallObserverImpl CallID:30929 MediaId:46875/2 Task:25000040774 com.cisco.jtapi.TermObservationEndedEvImpl received
38915862: Aug 12 12:21:02.519 AZT %MIVR-SS_TEL-7-UNK: [(P1-10.3.176.11) EventThread] com.cisco.wf.subsystems.jtapi.TAPIPortGroup.Port.InCallObserverImpl.ConsultCallObserver OrigCall=CallID:30929 MediaId:46875/2 Task:25000040774, ConsultEvent= TermConnDroppedEv CTI_9837
38915863: Aug 12 12:21:02.519 AZT %MIVR-SS_TEL-7-UNK: [(P1-10.3.176.11) EventThread] com.cisco.wf.subsystems.jtapi.TAPIPortGroup.Port.InCallObserverImpl.ConsultCallObserver OrigCall=CallID:30929 MediaId:46875/2 Task:25000040774, ConsultEvent= CallCtlTermConnDroppedEv CTI_9837
38915864: Aug 12 12:21:02.519 AZT %MIVR-SS_TEL-7-UNK: [(P1-10.3.176.11) EventThread] com.cisco.wf.subsystems.jtapi.TAPIPortGroup.Port.InCallObserverImpl.ConsultCallObserver OrigCall=CallID:30929 MediaId:46875/2 Task:25000040774, ConsultEvent= ConnDisconnectedEv 9837::1
38915865: Aug 12 12:21:02.519 AZT %MIVR-SS_TEL-7-UNK: [(P1-10.3.176.11) EventThread] com.cisco.wf.subsystems.jtapi.TAPIPortGroup.Port.InCallObserverImpl.ConsultCallObserver OrigCall=CallID:30929 MediaId:46875/2 Task:25000040774, ConsultEvent= CallCtlConnDisconnectedEv 9837::1
38915866: Aug 12 12:21:02.519 AZT %MIVR-SS_TEL-7-UNK: [(P1-10.3.176.11) EventThread] com.cisco.wf.subsystems.jtapi.TAPIPortGroup.Port.InCallObserverImpl.ConsultCallObserver OrigCall=CallID:30929 MediaId:46875/2 Task:25000040774, ConsultEvent= CallInvalidEv
38915867: Aug 12 12:21:02.519 AZT %MIVR-SS_TEL-7-UNK: [MIVR_SS_TEL_TPG_EXE-40-113380-CALL_EVENT_LOG:9837] com.cisco.executor.impl.ExecutorStubImpl.RequestImpl  Got CallObservationEndedEv,  events on the AddressCallObserver.
38915868: Aug 12 12:21:02.519 AZT %MIVR-SS_TEL-7-UNK: [MIVR_ENG_TASKS-31-42-TASK:0x5d21e5946_199_prod_08.08.2020.aef] com.cisco.wf.subsystems.jtapi.TAPIPortGroup.Port.InCallObserverImpl CallID:30929 MediaId:46875/2 Task:25000040774 consultWithoutMedia gets CiscoJtapiException: 0x0(CTIERR_UNSPECIFIED)::Unspecified error
38915869: Aug 12 12:21:02.519 AZT %MIVR-SS_TEL-7-UNK: [MIVR_SS_TEL_TPG_EXE-40-113387-CONSULT_CALL_OBS:YYYY] com.cisco.executor.impl.ExecutorStubImpl.RequestImpl CallID:30929 MediaId:46875/2 Task:25000040774, ConsultCallObserver():Received msg from original call WF Execution Exception
38915870: Aug 12 12:21:02.519 AZT %MIVR-SS_TEL-7-UNK: [MIVR_SS_TEL_PORT_QEXE_9837-173-654-TERM_CONN_EV:9837] com.cisco.executor.impl.ExecutorStubImpl.RequestImpl CallID:30929 MediaId:46875/2 Task:25000040774 gets TermConnDroppedEv, meta code:132, cause code:100
38915871: Aug 12 12:21:02.519 AZT %MIVR-SS_TEL-7-UNK: [MIVR_ENG_TASKS-31-42-TASK:0x5d21e5946_199_prod_08.08.2020.aef] com.cisco.wf.subsystems.jtapi.TAPIPortGroup.Port.InCallObserverImpl.ConsultCallObserver OrigCall=CallID:30929 MediaId:46875/2 Task:25000040774, ConsultEvent= CallObservationEndedEv
38915872: Aug 12 12:21:02.519 AZT %MIVR-SS_TEL-7-UNK: [MIVR_SS_TEL_PORT_QEXE_9837-173-654-TERM_CONN_EV:9837] com.cisco.executor.impl.ExecutorStubImpl.RequestImpl CallID:30929 MediaId:46875/2 Task:25000040774, TerminalConnection to Terminal: CTI_9837 is DROPPED, 46875/2
38915873: Aug 12 12:21:02.519 AZT %MIVR-SS_TEL-7-UNK: [MIVR_ENG_TASKS-31-42-TASK:0x5d21e5946_199_prod_08.08.2020.aef] com.cisco.wf.subsystems.jtapi.TAPIPortGroup.Port.InCallObserverImpl CallID:30929 MediaId:46875/2 Task:25000040774, Original call dropped
38915874: Aug 12 12:21:02.519 AZT %MIVR-SS_TEL-7-UNK: [MIVR_ENG_TASKS-31-42-TASK:0x5d21e5946_199_prod_08.08.2020.aef] com.cisco.wf.subsystems.jtapi.TAPIPortGroup.Port.InCallObserverImpl Consult call drop failed for: 10695/1exception: Did not meet pre-conditions -- call must be ACTIVE and at least one TerminalConnection must be Active
38915875: Aug 12 12:21:02.519 AZT %MIVR-SS_CM-7-UNK: [MIVR_ENG_TASKS-31-42-TASK:0x5d21e5946_199_prod_08.08.2020.aef] com.cisco.wf.subsystems.rmcm.CallContact ConnectingFlag is set false, call contact: 46875/2
38915876: Aug 12 12:21:02.519 AZT %MIVR-SS_TEL-7-UNK: [MIVR_SS_TEL_PORT_QEXE_9837-173-654-TERM_CONN_EV:YYYY] com.cisco.executor.impl.ExecutorStubImpl.RequestImpl CallID:30929 MediaId:46875/2 Task:25000040774, ConsultCallObserver DROPPED, term=9837
38915877: Aug 12 12:21:02.626 AZT %MIVR-SS_RM-7-UNK: [ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@16c9310)] com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver RIMgrAddressCallObserver: CallInvalidEv received for call:16787911 [10695/1]
38915878: Aug 12 12:21:02.626 AZT %MIVR-SS_CM-7-UNK: [ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@16c9310)] com.cisco.wf.subsystems.rmcm.ContactMgr ContactMgr.getRmCmContact(10695/1) returns null
38915879: Aug 12 12:21:02.626 AZT %MIVR-SS_RM-7-UNK: [ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@16c9310)] com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver processCallInvalidEv: getCiscoFeatureReason: 12 isBlindTransferToUnmonitoredExtn: null isBlindTransferred: null
38915880: Aug 12 12:21:02.626 AZT %MIVR-SS_RM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.RsrcMgrMsgProcessor Processing msg: SessionInvalidMsg (ID:10695/1)
38915881: Aug 12 12:21:02.627 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.ContactMgr ContactMgr.getRmCmContact(10695/1) returns null
38915882: Aug 12 12:21:02.627 AZT %MIVR-SS_RM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.RsrcMgrMsgProcessor RsrcMgrMsgProcessor: SessionInvalidMsg received for contactID:10695/1] being ignored because RmCm contact is null
38915883: Aug 12 12:21:03.443 AZT %MIVR-SS_TEL-7-UNK: [MIVR_SS_TEL_PORT_QEXE_9836-175-668-TERM_CONN_EV:9836] com.cisco.executor.impl.ExecutorStubImpl.RequestImpl CallID:31050 MediaId:47051/2 Task:25000040921 Digit received: 2
38915884: Aug 12 12:21:03.443 AZT %MIVR-SS_CM-7-UNK: [EventQueue.DispatchThread-0-22] com.cisco.wf.subsystems.rmcm.ContactMgr ContactMgr.getRmCmContact(47051/2) returns 33601483 [47051/2]
38915885: Aug 12 12:21:03.443 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_CM_ContactMgrMsgProcessor-69-0-MIVR] com.cisco.wf.subsystems.rmcm.ContactMgrMsgProcessor Processing msg: class com.cisco.wf.subsystems.rmcm.msgs.CMFireContactDataChangedEventReqMsg Contact31050
38915886: Aug 12 12:21:03.443 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_CM_ContactMgrMsgProcessor-69-0-MIVR] com.cisco.wf.subsystems.rmcm.ContactEventsGenerator  IEF Contact ID: [47051/2] 33601483 ContactEventsGenerator: Posting the ContactDataChangedEvent agent null contact type IAQ session 6000031425 session seq num 0
38915887: Aug 12 12:21:03.443 AZT %MIVR-ICD_CTI-7-UNK: [EventQueue.DispatchThread-0-0] com.cisco.wf.subsystems.ctiserver.CRACTIEventHandler EventHandler: posting {CALL_DATA_UPDATE_EVENT: Socket:Socket: null monitoredDeviceDN:null, connectionCallID: 33601483, newConnectionCallID: 33601483, callType: 1, connectionDeviceID: , newConnectionDeviceID: , ani: null, callerEnteredDigits: null, accountNumber: null, wrapupData: , callVar1: null, callVar2: null, callVar3: Məlumat, callVar4: null, callVar5: null, callVar6: null, callVar7: null, callVar8: null, callVar9: null, callVar10: null, user.layout: custom1 } to outboundQ
38915888: Aug 12 12:21:03.443 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SERVER-70-1-outboundMsgQ] com.cisco.wf.subsystems.ctiserver.CSOutboundMsgProcessor OutboundMsgProcessor: got message: { CALL_DATA_UPDATE_EVENT }
38915889: Aug 12 12:21:03.444 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SERVER-70-1-outboundMsgQ] com.cisco.wf.subsystems.ctiserver.CSOutboundMsgProcessor OutboundMessageprocessor : sending msg : { length=-1 type=CALL_DATA_UPDATE_EVENT, monitorID: 0, peripheralID: 1, peripheralType: 21, numCTIClients: 0, numNamedVars: 0, numNamedArrays: 0, callType: 1, connectionDeviceIDType: 0, connectionCallID: 33601483, newConnectionDeviceIDType: 0, newConnectionCallID: 33601483, calledPartyDisposition: 0, campaignID: 0, queryRuleID: 0, connectionDeviceID: , newConnectionDeviceID: , customerPhoneNumber: , customerAccountNumber: null, ani: null, callerEnteredDigits: null, wrapupData: , callVar1: null, callVar2: null, callVar3: Məlumat, callVar4: null, callVar5: null, callVar6: null, callVar7: null, callVar8: null, callVar9: null, callVar10: null, user.layout: custom1 to various sockets } 
38915890: Aug 12 12:21:03.444 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SOCKET-199-1449449-PROCESS_CTI_MGR_EVENT] com.cisco.wf.subsystems.ctiserver.MsgHandler MsgHandler : Sent : { CALL_DATA_UPDATE_EVENT to Socket[addr=10.3.176.22,port=58248,localport=12028]  CID: 33601483 } 
38915891: Aug 12 12:21:03.444 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SOCKET-102-4387459-PROCESS_CTI_MGR_EVENT] com.cisco.wf.subsystems.ctiserver.MsgHandler MsgHandler : Sent : { CALL_DATA_UPDATE_EVENT to Socket[addr=10.3.176.21,port=56362,localport=12028]  CID: 33601483 } 
38915892: Aug 12 12:21:03.663 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SERVER-70-1-outboundMsgQ] com.cisco.wf.subsystems.ctiserver.CSOutboundMsgProcessor OutboundMsgProcessor: got message: { HEARTBEAT_CONF }
38915893: Aug 12 12:21:03.663 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SERVER-70-1-outboundMsgQ] com.cisco.wf.subsystems.ctiserver.CSOutboundMsgProcessor OutboundMessageprocessor : sending msg : { length=-1 type=HEARTBEAT_CONF,invokeId=4130641 to socket: Socket[addr=10.3.176.21,port=56362,localport=12028]
38915894: Aug 12 12:21:03.663 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SOCKET-102-4387460-PROCESS_CTI_MGR_RESPONSE] com.cisco.wf.subsystems.ctiserver.MsgHandler MsgHandler : Sent : { HEARTBEAT_CONF to Socket[addr=10.3.176.21,port=56362,localport=12028]  } 
38915895: Aug 12 12:21:04.233 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SERVER-70-1-outboundMsgQ] com.cisco.wf.subsystems.ctiserver.CSOutboundMsgProcessor OutboundMsgProcessor: got message: { HEARTBEAT_CONF }
38915896: Aug 12 12:21:04.234 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SERVER-70-1-outboundMsgQ] com.cisco.wf.subsystems.ctiserver.CSOutboundMsgProcessor OutboundMessageprocessor : sending msg : { length=-1 type=HEARTBEAT_CONF,invokeId=4126061 to socket: Socket[addr=10.3.176.22,port=58248,localport=12028]
38915897: Aug 12 12:21:04.234 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SOCKET-199-1449450-PROCESS_CTI_MGR_RESPONSE] com.cisco.wf.subsystems.ctiserver.MsgHandler MsgHandler : Sent : { HEARTBEAT_CONF to Socket[addr=10.3.176.22,port=58248,localport=12028]  } 
38915898: Aug 12 12:21:04.513 AZT %MIVR-LIB_EVENT-5-TOO_LONG_IN_QUEUE: [MIVR_CONTACT_MGR_CLOCK-9-0-FINAL_EVENT_30929] com.cisco.manager.impl.ManagerManagerImpl.EventLoggerImpl Event queue time exceeded: Event=com.cisco.call.CallEvent[CALL_DISCONNECTED,state=CALL_DISCONNECTED,isRemote=true,task=AppTask[id=0x5d21e5946,time=1597219602219,state=ENDED,active=false,aborting=null,app=App[name=AM01,type=Cisco Script Application,id=0,desc=AM01,enabled=true,max=46,valid=true,cfg=[ApplicationConfig[schema=ApplicationConfig,time=2020-08-08 19:18:11.0,recordId=6,desc=AM01,name=AM01,type=Cisco Script Application,id=0,enabled=true,sessions=46,surveyName=,dispatchId=,script=SCRIPT[199_prod_08.08.2020.aef],defaultScript=,surveyScript=,vars=null,defaultVars=null,surveyVars=null]]],trigger=ContactApplicationTrigger[time=1597219602219,locale=en_US,cfg=JTAPITriggerConfig[schema=ApplicationTriggerConfig,time=2020-07-13 21:28:33.0,recordId=2,desc=Cisco JTAPI Trigger,name=9990,type=Cisco JTAPI Trigger,appName=AM01,enabled=true,sessions=46,idleTimeout=5000,locale=en_US,parms={},taskGroups=[],controlClass=class com.cisco.call.CallControlChannel,controlGroupId=2,contactGroups=[GroupInfo[class=com.cisco.dialog.DialogChannel,id=0]],dn=9990,redirectCSS=default,cmDeviceName=CTI_RP,cmDeviceInvalid=false,cmDescription=CTI_RP,cmDevicePoolUUID={1B1B9EB6-7803-11D3-BDF0-00108302EAD1},cmDevicePoolName=Default,cmCallingSearchSpaceUUID=,cmCallingSearchSpaceName=None,cmLocationUUID={29C5C1C4-8871-4D1E-8394-0B9181E8C54D},cmLocationName=Hub_None,cmPartitionUUID=,cmPartitionName=None,cmVoiceMailProfileUUID=,cmVoiceMailProfileName=None,cmCallPickUpGroupUUID=,cmCallPickUpGroupName=,cmDisplay=,cmExternalPhNumMask=,cmFwdBusyVM=false,cmFwdBusyDest=,cmFwdBusyCSSUUID=,cmFwdBusyCSSName=None,cmAlertingNameAscii=,cmPresenceGroupUUID=ad243d17-98b4-4118-8feb-5ff2e1b781ac,cmPresenceGroupName=Standard Presence group,campaignID=-1],contact=JTAPICallContact[id=30929,implId=46875/2,state=STATE_ABANDONED_IDX,inbound=true,App name=AM01,task=25000040774,session=6000031304,seq num=0,cn=9990,dn=9990,cgn=XXXX,ani=null,dnis=null,clid=null,atype=DIRECT,lrd=null,ocn=9990,route=RP[num=9990],OrigProtocolCallRef=000000000000B71B02CCFE1E00000000,DestProtocolCallRef=null,TP=9837]],task=com.cisco.wfframework.engine.core.WFEngineWorkflowDebugTask@1d43f62,default=null],isRemote=true,contactImplId=46875/2,lastContactImplId=46875/2,session=Session[id=001-0x165a13648,parent=null,active=true,state=SESSION_IN_USE,time=1597219602197],lastSession=Session[id=001-0x165a13648,parent=null,active=true,state=SESSION_IN_USE,time=1597219602197],contactSeqNum=0,lastContactSeqNum=0] on JTAPICallContact[id=30929,implId=46875/2,state=STATE_ABANDONED_IDX,inbound=true,App name=AM01,task=25000040774,session=6000031304,seq num=0,cn=9990,dn=9990,cgn=XXXX,ani=null,dnis=null,clid=null,atype=DIRECT,lrd=null,ocn=9990,route=RP[num=9990],OrigProtocolCallRef=000000000000B71B02CCFE1E00000000,DestProtocolCallRef=null,TP=9837] at Wed Aug 12 12:21:02 AZT 2020,Queue Time=2001
38915899: Aug 12 12:21:04.514 AZT %MIVR-SS_CM-7-UNK: [MIVR_CONTACT_MGR_CLOCK-9-0-FINAL_EVENT_30929] com.cisco.wf.subsystems.rmcm.ICDContactAdapter ICDContactAdapter 30929 : ContactTerminated received for App FW contact 30929, iefSourceContact is 33601307 [46875/2] (23076)
38915900: Aug 12 12:21:04.514 AZT %MIVR-SS_RM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.RsrcMgrMsgProcessor Processing msg: SessionTerminatedMsg (Rsrc:null ID:46875/2 Type:IAQ Cause:INVALID Abort Exception:null)
38915901: Aug 12 12:21:04.514 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.ContactMgr ContactMgr.getRmCmContact(46875/2) returns 33601307 [46875/2]
38915902: Aug 12 12:21:04.514 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.ContactMgr ContactMgr.removeContactResourceTuple(46875/2) removing resource 9837
38915903: Aug 12 12:21:04.514 AZT %MIVR-SS_RM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.RsrcMgr RsrcMgr.removeContactFromCTIPort, port: 9837
38915904: Aug 12 12:21:04.514 AZT %MIVR-SS_TEL-7-UNK: [MIVR_SS_RTR_EVENTS-37-0-CONTACT_TERMINATED] com.cisco.wf.subsystems.jtapi.CallImpl TAPIPortGroup::CallImpl:getReportingPropeorty - Get Property : 1
38915905: Aug 12 12:21:04.514 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.ContactMgr ContactMgr.getRmCmContact(46875/2) returns 33601307 [46875/2]
38915906: Aug 12 12:21:04.514 AZT %MIVR-SS_TEL-7-UNK: [MIVR_SS_RTR_EVENTS-37-0-CONTACT_TERMINATED] com.cisco.wf.subsystems.jtapi.CallImpl TAPIPortGroup::CallImpl:getReportingPropeorty - IVR OB Call. Found "OBIVRCALL" in the contact context : false
38915907: Aug 12 12:21:04.514 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.CallContact RmCm contact 33601307[46875/2] (23076) .removeConnectedResource(9837)
38915908: Aug 12 12:21:04.514 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.CallContact RmCm contact 33601307[46875/2] (23076) .dequeueAll(CALLER_DROPPED)
38915909: Aug 12 12:21:04.514 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_CM_ContactMgrMsgProcessor-69-0-MIVR] com.cisco.wf.subsystems.rmcm.ContactMgrMsgProcessor Processing msg: class com.cisco.wf.subsystems.rmcm.msgs.CMFireRsrcDisconnectedEventReqMsg Contact30929
38915910: Aug 12 12:21:04.514 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.CallContact RmCm contact 33601307[46875/2] (23076) .setAllocatedResource(null)
38915911: Aug 12 12:21:04.514 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.CTIPort rsrc is:agent in CTIPort 0 .processSessionTerminatedMsg() for App FW contact 30929, iefSourceContact is 33601307 [46875/2] (23076)
38915912: Aug 12 12:21:04.514 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.CTIPort rsrc is null in ICDContactAdapter 0 .cancelSessionForContact(33601307 [46875/2])
38915913: Aug 12 12:21:04.514 AZT %MIVR-SS_RM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.RsrcMgrMsgProcessor Processing msg: SessionCancelledMsg (Rsrc:46875/2 Cause:SESSION_CANCELLED_CAUSE_CONTACT_DROPPED)
38915914: Aug 12 12:21:04.514 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_CM_ContactMgrMsgProcessor-69-0-MIVR] com.cisco.wf.subsystems.rmcm.ContactEventsGenerator  IEF Contact ID: [46875/2] 33601307 ContactEventsGenerator: Posting the ContactRsrcDisconnectedEvent Resource 9837 contact type IAQ session 6000031304 session seq num 0
38915915: Aug 12 12:21:04.514 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.ContactMgr ContactMgr.getRmCmContact(46875/2) returns 33601307 [46875/2]
38915916: Aug 12 12:21:04.514 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_CM_ContactMgrMsgProcessor-69-0-MIVR] com.cisco.wf.subsystems.rmcm.ContactMgrMsgProcessor Processing msg: class com.cisco.wf.subsystems.rmcm.msgs.CMFireRsrcDisconnectedEventReqMsg Contact30929
38915917: Aug 12 12:21:04.514 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_CM_ContactMgrMsgProcessor-69-0-MIVR] com.cisco.wf.subsystems.rmcm.ContactEventsGenerator  IEF Contact ID: [46875/2] 33601307 ContactEventsGenerator: Posting the ContactRsrcDisconnectedEvent Resource Rsrc Name:agent ID:agent IAQ Extn:YYYY contact type PRIMARY CONSULT session 6000031304 session seq num 0
38915918: Aug 12 12:21:04.514 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_CM_ContactMgrMsgProcessor-69-0-MIVR] com.cisco.wf.subsystems.rmcm.ContactMgrMsgProcessor Processing msg: class com.cisco.wf.subsystems.rmcm.msgs.CMFireContactDisconnectedEventReqMsg Contact30929
38915919: Aug 12 12:21:04.514 AZT %MIVR-SS_CM-7-UNK: [EventQueue.DispatchThread-0-5] com.cisco.wf.wxm.WXMSurveyHandler contactRsrcDisconnected- contactID:33,601,307, resource:9837
38915920: Aug 12 12:21:04.514 AZT %MIVR-SS_CM-7-UNK: [EventQueue.DispatchThread-0-5] com.cisco.wf.wxm.WXMSurveyHandler contactRsrcDisconnected - not processing further as the object isn't of type Resource
38915921: Aug 12 12:21:04.514 AZT %MIVR-SS_RM-7-UNK: [EventQueue.DispatchThread-0-5] com.cisco.wf.subsystems.rmcm.jtapi.JtapiRIMgr getAllDevicesForCall():noOfCalls=3
38915922: Aug 12 12:21:04.514 AZT %MIVR-SS_RM-7-UNK: [EventQueue.DispatchThread-0-5] com.cisco.wf.subsystems.rmcm.jtapi.JtapiRIMgr getAllDevicesForCall():monCall is null
38915923: Aug 12 12:21:04.514 AZT %MIVR-ICD_CTI-7-UNK: [EventQueue.DispatchThread-0-5] com.cisco.wf.subsystems.ctiserver.CRACTIEventHandler EventHandler: posting {CALL_CONNECTION_CLEARED_EVENT: Socket:Socket: null monitoredDeviceDN:9837, connectionCallID: 33601307, monitoredDeviceDN: 9837, releasingDeviceID: 9837, releasingDeviceType: 73 } to outboundQ
38915924: Aug 12 12:21:04.515 AZT %MIVR-SS_CM-7-UNK: [EventQueue.DispatchThread-0-5] com.cisco.wf.wxm.WXMSurveyHandler contactRsrcDisconnected- contactID:33,601,307, resource:Rsrc Name:agent ID:agent IAQ Extn:YYYY
38915925: Aug 12 12:21:04.515 AZT %MIVR-SS_RM-7-UNK: [EventQueue.DispatchThread-0-5] com.cisco.wf.subsystems.rmcm.util.RmCmUtils The parentContactID of 33601307 is computed as 33601307 with depth 0
38915926: Aug 12 12:21:04.515 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SERVER-70-1-outboundMsgQ] com.cisco.wf.subsystems.ctiserver.CSOutboundMsgProcessor OutboundMsgProcessor: got message: { CALL_CONNECTION_CLEARED_EVENT }
38915927: Aug 12 12:21:04.515 AZT %MIVR-SS_CM-7-UNK: [EventQueue.DispatchThread-0-5] com.cisco.wf.wxm.WXMSurveyHandler Updating already existing prefillsBuilder with new agent details for contactID: 33601307
38915928: Aug 12 12:21:04.515 AZT %MIVR-SS_CM-7-UNK: [EventQueue.DispatchThread-0-5] com.cisco.wf.wxm.WXMSurveyHandler prefillsBuilder doesn't exist for contactId: 33601307
38915929: Aug 12 12:21:04.515 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SERVER-70-1-outboundMsgQ] com.cisco.wf.subsystems.ctiserver.CSOutboundMsgProcessor OutboundMessageprocessor : sending msg : { length=-1 type=CALL_CONNECTION_CLEARED_EVENT, monitorID: 0, peripheralID: 1, peripheralType: 21, connectionDeviceIDType: 0, connectionCallID: 33601307, releasingDeviceType: 73, localConnectionState: 65535, eventCause: 28, monitoredDeviceDN: 9837, releasingDeviceID: 9837, remasks: [] to various sockets } 
38915930: Aug 12 12:21:04.515 AZT %MIVR-SS_TEL-7-UNK: [EventQueue.DispatchThread-0-5] com.cisco.wf.subsystems.jtapi.TAPIPortGroup ServiceAddressObserver#getRoute, Trying to find route point with wild card, CTI Route Point: YYYY
38915931: Aug 12 12:21:04.515 AZT %MIVR-SS_RM-7-UNK: [EventQueue.DispatchThread-0-5] com.cisco.wf.subsystems.rmcm.RsrcMgr isCTIRoutePoint, addr: YYYY:false
38915932: Aug 12 12:21:04.515 AZT %MIVR-SS_RM-7-UNK: [EventQueue.DispatchThread-0-5] com.cisco.wf.subsystems.rmcm.jtapi.JtapiRIMgr getAllDevicesForCall():noOfCalls=3
38915933: Aug 12 12:21:04.515 AZT %MIVR-SS_RM-7-UNK: [EventQueue.DispatchThread-0-5] com.cisco.wf.subsystems.rmcm.jtapi.JtapiRIMgr getAllDevicesForCall():monCall is null
38915934: Aug 12 12:21:04.515 AZT %MIVR-ICD_CTI-7-UNK: [EventQueue.DispatchThread-0-5] com.cisco.wf.subsystems.ctiserver.CRACTIEventHandler EventHandler: posting {CALL_CONNECTION_CLEARED_EVENT: Socket:Socket: null monitoredDeviceDN:YYYY, connectionCallID: 33601307, monitoredDeviceDN: YYYY, releasingDeviceID: YYYY, releasingDeviceType: 76 } to outboundQ
38915935: Aug 12 12:21:04.515 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.ContactMgr ContactMgr.getRmCmContact(46875/2) returns 33601307 [46875/2]
38915936: Aug 12 12:21:04.515 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SOCKET-102-4387461-PROCESS_CTI_MGR_EVENT] com.cisco.wf.subsystems.ctiserver.MsgHandler MsgHandler : Sent : { CALL_CONNECTION_CLEARED_EVENT to Socket[addr=10.3.176.21,port=56362,localport=12028]  CID: 33601307 } 
38915937: Aug 12 12:21:04.515 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.ContactMgr ContactMgr.getRmCmContact(46875/2) returns 33601307 [46875/2]
38915938: Aug 12 12:21:04.515 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.ContactMgr ContactMgr.getRmCmContact(46875/2) returns 33601307 [46875/2]
38915939: Aug 12 12:21:04.515 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.CallContact RmCm contact 33601307[46875/2] (23076) .removeConnectedResource(agent)
38915940: Aug 12 12:21:04.515 AZT %MIVR-SS_RM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.Resource Agent agent .removeAssociatedContact(Contact:46875/2
38915941: Aug 12 12:21:04.515 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.CallContact RmCm contact 33601307[46875/2] (23076) .setAllocatedResource(null)
38915942: Aug 12 12:21:04.515 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.CallContact RmCm contact 33601307[46875/2] (23076) .setIaqState(QUEUED_WAITING) from QUEUED_ALLOCATED
38915943: Aug 12 12:21:04.515 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.CallContact RmCm contact 33601307[46875/2] (23076) .setIaqState(NOT_IN_QUEUE) from QUEUED_WAITING
38915944: Aug 12 12:21:04.515 AZT %MIVR-SS_RM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.Resource Rsrc: agent New State:AVAILABLE Old State:RESERVED Reason code:0
38915945: Aug 12 12:21:04.515 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SOCKET-199-1449451-PROCESS_CTI_MGR_EVENT] com.cisco.wf.subsystems.ctiserver.MsgHandler MsgHandler : Sent : { CALL_CONNECTION_CLEARED_EVENT to Socket[addr=10.3.176.22,port=58248,localport=12028]  CID: 33601307 } 
38915946: Aug 12 12:21:04.515 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.CallContact RmCm contact 33601307[46875/2] (23076) .sessionCancelledExecuted
38915947: Aug 12 12:21:04.515 AZT %MIVR-SS_ROUTEANDQUEUE-7-UNK: [MediaStateChangeNotifier] com.cisco.wf.subsystems.routeandqueue.RouteAndQueueSubsystemLogger Recvd. notification about media state change for AgentId: agent
38915948: Aug 12 12:21:04.515 AZT %MIVR-SS_ROUTEANDQUEUE-7-UNK: [MediaStateChangeNotifier] com.cisco.wf.subsystems.routeandqueue.RouteAndQueueSubsystemLogger agent: agent id is not known to Subsystem RouteAndQueue.
38915949: Aug 12 12:21:04.515 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SERVER-70-1-outboundMsgQ] com.cisco.wf.subsystems.ctiserver.CSOutboundMsgProcessor OutboundMsgProcessor: got message: { CALL_CONNECTION_CLEARED_EVENT }
38915950: Aug 12 12:21:04.515 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SERVER-70-1-outboundMsgQ] com.cisco.wf.subsystems.ctiserver.CSOutboundMsgProcessor OutboundMessageprocessor : sending msg : { length=-1 type=CALL_CONNECTION_CLEARED_EVENT, monitorID: 0, peripheralID: 1, peripheralType: 21, connectionDeviceIDType: 0, connectionCallID: 33601307, releasingDeviceType: 76, localConnectionState: 65535, eventCause: 65535, monitoredDeviceDN: YYYY, releasingDeviceID: YYYY, remasks: [] to various sockets } 
38915951: Aug 12 12:21:04.516 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SOCKET-102-4387462-PROCESS_CTI_MGR_EVENT] com.cisco.wf.subsystems.ctiserver.MsgHandler MsgHandler : Sent : { CALL_CONNECTION_CLEARED_EVENT to Socket[addr=10.3.176.21,port=56362,localport=12028]  CID: 33601307 } 
38915952: Aug 12 12:21:04.516 AZT %MIVR-ICD_CTI-7-UNK: [EventQueue.DispatchThread-0-11] com.cisco.wf.subsystems.ctiserver.CRACTIEventHandler EventHandler: posting {AGENT_STATE_EVENT: Socket:Socket: null monitoredDeviceDN:YYYY, agentDN:YYYY, agentID:agent, monitorID = 0, stateDuration = 0, agentstate = AVAILABLE, eventreasoncode = 0, agentID = agent, agentExtension = YYYY, agentID_Long = agent, duration = -1 } to outboundQ
38915953: Aug 12 12:21:04.516 AZT %MIVR-SS_OB-7-UNK: [MIVR_SS_OB_OutboundMgrMsgProcessor-52-0-OutboundMgrMsgProcessor] com.cisco.wf.subsystems.outbound.OutboundMgrMsgProcessor OutboundRsrcStateChangedMsg:Processing msg: OutboundRsrcStateChangedMsg (Rsrc:agent, state:3, csqID:-1) 
38915954: Aug 12 12:21:04.516 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SOCKET-199-1449452-PROCESS_CTI_MGR_EVENT] com.cisco.wf.subsystems.ctiserver.MsgHandler MsgHandler : Sent : { CALL_CONNECTION_CLEARED_EVENT to Socket[addr=10.3.176.22,port=58248,localport=12028]  CID: 33601307 } 
38915955: Aug 12 12:21:04.516 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.CallContact RmCm contact 33601307[46875/2] (23076) .setProcessSessionCancelledExecuted(true)
38915956: Aug 12 12:21:04.516 AZT %MIVR-SS_RM-7-UNK: [MediaStateChangeNotifier] com.cisco.wf.subsystems.rmcm.RmCmAgentMediaStateListener RmCmAgentMediaStateListener: AgentId: agent. Posting to RM Queue for processing.
38915957: Aug 12 12:21:04.516 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_CM_ContactMgrMsgProcessor-69-0-MIVR] com.cisco.wf.subsystems.rmcm.ContactEventsGenerator ContactEventsGenerator: session is null in RsrcMgr.cleanup  and App FW contact 30929, so not removing mapping ID
38915958: Aug 12 12:21:04.516 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_CM_ContactMgrMsgProcessor-69-0-MIVR] com.cisco.wf.subsystems.rmcm.ContactMgr ContactMgr.removeRmCmContact(46875/2)
38915959: Aug 12 12:21:04.516 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_CM_ContactMgrMsgProcessor-69-0-MIVR] com.cisco.wf.subsystems.rmcm.ContactEventsGenerator  IEF Contact ID: [46875/2] 33601307 ContactEventsGenerator: Posting the ContactDisconnectedEvent contact type IAQ, session 6000031304, session seq num 0, rejReason 1, abortReason null
38915960: Aug 12 12:21:04.516 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_CM_ContactMgrMsgProcessor-69-0-MIVR] com.cisco.wf.subsystems.rmcm.ContactMgrMsgProcessor Processing msg: class com.cisco.wf.subsystems.rmcm.msgs.DequeueContactReqMsg Contact30929
38915961: Aug 12 12:21:04.517 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_CM_ContactMgrMsgProcessor-69-0-MIVR] com.cisco.wf.subsystems.rmcm.CallContact RmCm contact 33601307[46875/2] (23076) .setIaqState(NOT_IN_QUEUE) from NOT_IN_QUEUE
38915962: Aug 12 12:21:04.517 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_CM_ContactMgrMsgProcessor-69-0-MIVR] com.cisco.wf.subsystems.rmcm.CallContact RmCm contact 33601307[46875/2] (23076) .setIaqState(NOT_IN_QUEUE) from NOT_IN_QUEUE
38915963: Aug 12 12:21:04.517 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SERVER-70-1-outboundMsgQ] com.cisco.wf.subsystems.ctiserver.CSOutboundMsgProcessor OutboundMsgProcessor: got message: { AGENT_STATE_EVENT }
38915964: Aug 12 12:21:04.517 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_CM_ContactMgrMsgProcessor-69-0-MIVR] com.cisco.wf.subsystems.rmcm.ContactEventsGenerator  IEF Contact ID: [46875/2] 33601307 ContactEventsGenerator: Posting the ContactDequeuedAllEvent reason code CALLER_DROPPED contact type IAQ session 6000031304 session seq num 0
38915965: Aug 12 12:21:04.517 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SERVER-70-1-outboundMsgQ] com.cisco.wf.subsystems.ctiserver.CSOutboundMsgProcessor OutboundMessageprocessor : sending msg : { length=-1 type=AGENT_STATE_EVENT, monitorID=0, peripheralID=1, sessionID=0, peripheralType=21, skillGroupState=3, stateDuration=0, skillGroupNumber=-1, skillGroupID=-1, skillGroupPriority=0, agentstate=AVAILABLE, eventReasonCode=0, MRDid=1, numTasks=0, agentMode=0, maxTaskLimit=0, ICMAgentID=0, agentAvailabilityStatus=1, numFltSkillGroups=0, CTIClientSignature=null, agentID=agent, agentExtension=YYYY, agentInstrument=null, agentID_Long=agent, duration=-1, nextAgentState=-1 Remask=CRACTIRemaskField [reqMaskInstrument=YYYY, reqMaskExtension=YYYY, reqMaskCallID=0, reqMaskFlags=[0, 0, 0, 0, 0, 0, 0, 1], reqCallMask=[0, 0, 0, 0, 0, 0, 0, 1], reqAgentMask=[0, 0, 0, 0, 0, 0, 0, 6]] to various sockets } 
38915966: Aug 12 12:21:04.517 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_CM_ContactMgrMsgProcessor-69-0-MIVR] com.cisco.wf.subsystems.rmcm.CallContact conferenceQueued flag is: false call contact: 46875/2
38915967: Aug 12 12:21:04.517 AZT %MIVR-SS_CM-7-UNK: [EventQueue.DispatchThread-0-9] com.cisco.wf.wxm.WXMSurveyHandler contactDisconnected - contactID: 33601307
38915968: Aug 12 12:21:04.517 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SOCKET-199-1449453-PROCESS_CTI_MGR_EVENT] com.cisco.wf.subsystems.ctiserver.MsgHandler MsgHandler : Sent : { AGENT_STATE_EVENT to Socket[addr=10.3.176.22,port=58248,localport=12028]  } 
38915969: Aug 12 12:21:04.517 AZT %MIVR-SS_RM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.RsrcMgrMsgProcessor Rsrc: agent Contacts:
38915970: Aug 12 12:21:04.517 AZT %MIVR-SS_RM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.RsrcMgrMsgProcessor Rsrc: agent PrevState: AVAILABLE PrevStateSet:false
38915971: Aug 12 12:21:04.517 AZT %MIVR-SS_RM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.ESD Rsrcs in ARL of ESD Qeza: 
38915972: Aug 12 12:21:04.517 AZT %MIVR-SS_RM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.ESD Rsrcs in ARL of ESD Melumat: 
38915973: Aug 12 12:21:04.517 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SOCKET-102-4387463-PROCESS_CTI_MGR_EVENT] com.cisco.wf.subsystems.ctiserver.MsgHandler MsgHandler : Sent : { AGENT_STATE_EVENT to Socket[addr=10.3.176.21,port=56362,localport=12028]  } 
38915974: Aug 12 12:21:04.517 AZT %MIVR-SS_RM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.RsrcMgrMsgProcessor Processing msg: com.cisco.wf.subsystems.rmcm.msgs.MediaStateChangeMsg@19ce8b5[agentId=agent,id=106]
38915975: Aug 12 12:21:04.517 AZT %MIVR-SS_RM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.RsrcMgr offerAgentToArls: Agent Id: agent
38915976: Aug 12 12:21:04.517 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.ContactMgr ContactMgr.offerResource(agent)
38915977: Aug 12 12:21:04.517 AZT %MIVR-SS_RM-7-UNK: [MIVR_SS_OB_OutboundMgrMsgProcessor-52-0-OutboundMgrMsgProcessor] com.cisco.wf.subsystems.rmcm.RsrcMgr RsrsMgr:removeFromlicensedWorkingAgentsList():: removed from List : false
38915978: Aug 12 12:21:04.517 AZT %MIVR-SS_RM-7-UNK: [EventQueue.DispatchThread-0-9] com.cisco.wf.subsystems.rmcm.util.RmCmUtils The parentContactID of 33601307 is computed as 33601307 with depth 0
38915979: Aug 12 12:21:04.518 AZT %MIVR-SS_CM-7-UNK: [EventQueue.DispatchThread-0-9] com.cisco.wf.wxm.WXMSurveyHandler contactDisconnected - couldn't find prefillsBuilder to be disptached to WXM for contact:33601307
38915980: Aug 12 12:21:04.518 AZT %MIVR-SS_RM-7-UNK: [EventQueue.DispatchThread-0-9] com.cisco.wf.subsystems.rmcm.util.RmCmUtils The parentContactID of 33601307 is computed as 33601307 with depth 0
38915981: Aug 12 12:21:04.519 AZT %MIVR-SS_RM-7-UNK: [EventQueue.DispatchThread-0-9] com.cisco.wf.subsystems.rmcm.jtapi.JtapiRIMgr getAllDevicesForCall():noOfCalls=3
38915982: Aug 12 12:21:04.519 AZT %MIVR-SS_RM-7-UNK: [EventQueue.DispatchThread-0-9] com.cisco.wf.subsystems.rmcm.jtapi.JtapiRIMgr getAllDevicesForCall():monCall is null
38915983: Aug 12 12:21:04.519 AZT %MIVR-ICD_CTI-7-UNK: [EventQueue.DispatchThread-0-9] com.cisco.wf.subsystems.ctiserver.CRACTIEventHandler EventHandler: posting {CALL_CLEARED_EVENT: Socket:Socket: null monitoredDeviceDN:, connectionCallID: 33601307, localConnectionState: 0, eventCause: 65535, connectionDeviceID:  } to outboundQ
38915984: Aug 12 12:21:04.519 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_CM_ContactMgrMsgProcessor-69-0-MIVR] com.cisco.wf.subsystems.rmcm.ContactMgrMsgProcessor Processing msg: OfferRsrcReqMsg (Rsrc:agent)
38915985: Aug 12 12:21:04.519 AZT %MIVR-SS_RM-7-UNK: [MIVR_SS_CM_ContactMgrMsgProcessor-69-0-MIVR] com.cisco.wf.subsystems.rmcm.ESD ESD 1 .getHighestPriorityContact() returning 33601377 [46945/2]
38915986: Aug 12 12:21:04.519 AZT %MIVR-SS_RM-7-UNK: [MIVR_SS_CM_ContactMgrMsgProcessor-69-0-MIVR] com.cisco.wf.subsystems.rmcm.ESD ESD 2 .getHighestPriorityContact() returning 16787886 [10670/1]
38915987: Aug 12 12:21:04.519 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SERVER-70-1-outboundMsgQ] com.cisco.wf.subsystems.ctiserver.CSOutboundMsgProcessor OutboundMsgProcessor: got message: { CALL_CLEARED_EVENT }
38915988: Aug 12 12:21:04.519 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_CM_ContactMgrMsgProcessor-69-0-MIVR] com.cisco.wf.subsystems.rmcm.CallContact RmCm contact 16787886[10670/1] (23086) .setAllocatedResource(agent)
38915989: Aug 12 12:21:04.519 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SERVER-70-1-outboundMsgQ] com.cisco.wf.subsystems.ctiserver.CSOutboundMsgProcessor OutboundMessageprocessor : sending msg : { length=-1 type=CALL_CLEARED_EVENT, monitorID: 0, peripheralID: 1, peripheralType: 21, connectionDeviceIDType: 0, ConnectionCallID: 33601307, localConnectionState: 0, eventCause: 65535, connectionDeviceID: , remasks: [] to various sockets } 
38915990: Aug 12 12:21:04.519 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.ContactMgr returning 16787886 [10670/1] CSQ:Name:Melumat ID:2 from ContactMgr.offerResource(agent)
38915991: Aug 12 12:21:04.519 AZT %MIVR-SS_RM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.Resource Agent agent .addAssociatedContact(Contact:RmCm contact 16787886[10670/1] (23086)
38915992: Aug 12 12:21:04.519 AZT %MIVR-SS_CM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.Contact RmCm contact 16787886[10670/1] (23086) .addAssociatedResource(agent)
38915993: Aug 12 12:21:04.520 AZT %MIVR-SS_RM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.Resource Resource.updateContactExtTypeMap()the context map is null
38915994: Aug 12 12:21:04.520 AZT %MIVR-SS_RM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.util.RmCmUtils The parentContactID of 16787886 is computed as 16787886 with depth 0
38915995: Aug 12 12:21:04.520 AZT %MIVR-SS_ROUTEANDQUEUE-7-UNK: [MediaStateChangeNotifier] com.cisco.wf.subsystems.routeandqueue.RouteAndQueueSubsystemLogger Recvd. notification about media state change for AgentId: agent
38915996: Aug 12 12:21:04.520 AZT %MIVR-SS_ROUTEANDQUEUE-7-UNK: [MediaStateChangeNotifier] com.cisco.wf.subsystems.routeandqueue.RouteAndQueueSubsystemLogger agent: agent id is not known to Subsystem RouteAndQueue.
38915997: Aug 12 12:21:04.520 AZT %MIVR-SS_TEL-7-UNK: [EventQueue.DispatchThread-0-9] com.cisco.wf.subsystems.jtapi.TAPIPortGroup ServiceAddressObserver#getRoute, Trying to find route point with wild card, CTI Route Point: XXXX
38915998: Aug 12 12:21:04.520 AZT %MIVR-SS_RM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.Resource Rsrc: agent New State:RESERVED Old State:AVAILABLE Reason code:0
38915999: Aug 12 12:21:04.520 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SOCKET-199-1449454-PROCESS_CTI_MGR_EVENT] com.cisco.wf.subsystems.ctiserver.MsgHandler MsgHandler : Sent : { CALL_CLEARED_EVENT to Socket[addr=10.3.176.22,port=58248,localport=12028]  CID: 33601307 } 
38916000: Aug 12 12:21:04.520 AZT %MIVR-SS_RM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.Agent Task: 25000040789 Ready to interrupt the Application task. [Resource = Rsrc Name:agent ID:agent IAQ Extn:YYYY, Selected Esd = 2, Contact = 10670/1]
38916001: Aug 12 12:21:04.520 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SOCKET-102-4387464-PROCESS_CTI_MGR_EVENT] com.cisco.wf.subsystems.ctiserver.MsgHandler MsgHandler : Sent : { CALL_CLEARED_EVENT to Socket[addr=10.3.176.21,port=56362,localport=12028]  CID: 33601307 } 
38916002: Aug 12 12:21:04.521 AZT %MIVR-SS_RM-7-UNK: [EventQueue.DispatchThread-0-9] com.cisco.wf.subsystems.rmcm.RsrcMgr isCTIRoutePoint, addr: XXXX:false
38916003: Aug 12 12:21:04.521 AZT %MIVR-SS_RM-7-UNK: [EventQueue.DispatchThread-0-9] com.cisco.wf.subsystems.rmcm.RsrcMgr isCTIRoutePoint, addr: 9990:true
38916004: Aug 12 12:21:04.521 AZT %MIVR-ICD_CTI-7-UNK: [EventQueue.DispatchThread-0-9] com.cisco.wf.subsystems.ctiserver.CRACTIEventHandler EventHandler: posting {END_CALL_EVENT: Socket:Socket: null monitoredDeviceDN:null, connectionCallID: 33601307 } to outboundQ
38916005: Aug 12 12:21:04.521 AZT %MIVR-SS_RM-7-UNK: [MIVR_SS_RM_INTERRUPT-80-6891-INTERRUPT_RSRC_AVAIL] com.cisco.executor.impl.ExecutorStubImpl.RequestImpl Task: 25000040789 Start interrupting the task.
38916006: Aug 12 12:21:04.522 AZT %MIVR-SS_RM-7-UNK: [MediaStateChangeNotifier] com.cisco.wf.subsystems.rmcm.RmCmAgentMediaStateListener RmCmAgentMediaStateListener: AgentId: agent. Posting to RM Queue for processing.
38916007: Aug 12 12:21:04.522 AZT %MIVR-ICD_CTI-7-UNK: [EventQueue.DispatchThread-0-7] com.cisco.wf.subsystems.ctiserver.CRACTIEventHandler EventHandler: posting {AGENT_STATE_EVENT: Socket:Socket: null monitoredDeviceDN:YYYY, agentDN:YYYY, agentID:agent, monitorID = 0, stateDuration = 0, agentstate = RESERVED, eventreasoncode = 0, agentID = agent, agentExtension = YYYY, agentID_Long = agent, duration = -1 } to outboundQ
38916008: Aug 12 12:21:04.522 AZT %MIVR-SS_OB-7-UNK: [MIVR_SS_OB_OutboundMgrMsgProcessor-52-0-OutboundMgrMsgProcessor] com.cisco.wf.subsystems.outbound.OutboundMgrMsgProcessor OutboundRsrcStateChangedMsg:Processing msg: OutboundRsrcStateChangedMsg (Rsrc:agent, state:9, csqID:2) 
38916009: Aug 12 12:21:04.522 AZT %MIVR-SS_RM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.RsrcMgrMsgProcessor Processing msg: com.cisco.wf.subsystems.rmcm.msgs.MediaStateChangeMsg@4ce068[agentId=agent,id=106]
38916010: Aug 12 12:21:04.522 AZT %MIVR-SS_RM-7-UNK: [MIVR_SS_RM_RmMsgProcessor-85-0-RmMsgProcessor] com.cisco.wf.subsystems.rmcm.RsrcMgr removeAgentFromArls: Removing agent from ARLsagent
38916011: Aug 12 12:21:04.522 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SERVER-70-1-outboundMsgQ] com.cisco.wf.subsystems.ctiserver.CSOutboundMsgProcessor OutboundMsgProcessor: got message: { END_CALL_EVENT }
38916012: Aug 12 12:21:04.522 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SERVER-70-1-outboundMsgQ] com.cisco.wf.subsystems.ctiserver.CSOutboundMsgProcessor OutboundMessageprocessor : sending msg : { length=-1 type=END_CALL_EVENT, monitorID: 0, peripheralID: 1, peripheralType: 21, connectionDeviceIDType: 0, connectionCallID: 33601307, connectionDeviceID:  to various sockets } 
38916013: Aug 12 12:21:04.522 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SERVER-70-1-outboundMsgQ] com.cisco.wf.subsystems.ctiserver.CSOutboundMsgProcessor OutboundMsgProcessor: got message: { AGENT_STATE_EVENT }
38916014: Aug 12 12:21:04.523 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SOCKET-199-1449455-PROCESS_CTI_MGR_EVENT] com.cisco.wf.subsystems.ctiserver.MsgHandler MsgHandler : Sent : { END_CALL_EVENT to Socket[addr=10.3.176.22,port=58248,localport=12028]  CID: 33601307 } 
38916015: Aug 12 12:21:04.523 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SOCKET-102-4387465-PROCESS_CTI_MGR_EVENT] com.cisco.wf.subsystems.ctiserver.MsgHandler MsgHandler : Sent : { END_CALL_EVENT to Socket[addr=10.3.176.21,port=56362,localport=12028]  CID: 33601307 } 
38916016: Aug 12 12:21:04.523 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SERVER-70-1-outboundMsgQ] com.cisco.wf.subsystems.ctiserver.CSOutboundMsgProcessor OutboundMessageprocessor : sending msg : { length=-1 type=AGENT_STATE_EVENT, monitorID=0, peripheralID=1, sessionID=0, peripheralType=21, skillGroupState=8, stateDuration=0, skillGroupNumber=2, skillGroupID=-1, skillGroupPriority=0, agentstate=RESERVED, eventReasonCode=0, MRDid=1, numTasks=0, agentMode=0, maxTaskLimit=0, ICMAgentID=0, agentAvailabilityStatus=1, numFltSkillGroups=0, CTIClientSignature=null, agentID=agent, agentExtension=YYYY, agentInstrument=null, agentID_Long=agent, duration=-1, nextAgentState=-1 Remask=CRACTIRemaskField [reqMaskInstrument=YYYY, reqMaskExtension=YYYY, reqMaskCallID=0, reqMaskFlags=[0, 0, 0, 0, 0, 0, 0, 1], reqCallMask=[0, 0, 0, 0, 0, 0, 0, 1], reqAgentMask=[0, 0, 0, 0, 0, 0, 0, 0]] to various sockets } 
38916017: Aug 12 12:21:04.523 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SOCKET-102-4387466-PROCESS_CTI_MGR_EVENT] com.cisco.wf.subsystems.ctiserver.MsgHandler MsgHandler : Sent : { AGENT_STATE_EVENT to Socket[addr=10.3.176.21,port=56362,localport=12028]  } 
38916018: Aug 12 12:21:04.523 AZT %MIVR-ICD_CTI-7-UNK: [MIVR_ICD_CTI_SOCKET-199-1449456-PROCESS_CTI_MGR_EVENT] com.cisco.wf.subsystems.ctiserver.MsgHandler MsgHandler : Sent : { AGENT_STATE_EVENT to Socket[addr=10.3.176.22,port=58248,localport=12028]  } 
38916019: Aug 12 12:21:04.728 AZT %MIVR-SS_CM-7-UNK: [MIVR_ENG_TASKS-31-30-TASK:0x5d21e5955_199_prod_08.08.2020.aef] com.cisco.wf.subsystems.rmcm.CallContact ConnectingFlag is set true, call contact: 10670/1
38916020: Aug 12 12:21:04.730 AZT %MIVR-SS_RM-7-UNK: [MIVR_SS_RM_INTERRUPT-80-6891-INTERRUPT_RSRC_AVAIL] com.cisco.executor.impl.ExecutorStubImpl.RequestImpl Task: 25000040789 Interrupting the task has been successful.