Cisco Support Community
cancel
Showing results for 
Search instead for 
Did you mean: 
Announcements

Welcome to Cisco Support Community. We would love to have your feedback.

For an introduction to the new site, click here. And see here for current known issues.

New Member

Outbound dialer issues errors in passing contacts to agents

Hi,


We are currently experiencing an issue with our outbound dialer.

We are seeing the following behaviour.

1) Start a new Campaign

2) Agents start to receive contacts and makes calls

3) slowly agents are not being sent calls but waiting for other agents to be placed in a work or not ready state and using the longest waiting agent option which is set on the CSQ

This process continues to repeat.


We are running CAD from 2 Citrix servers.

I have seen the following message in the logs and am unsure whether it is the root cause, we have 40 outbound seats enabled however.

5783400: Jan 10 15:09:40.228 EST %MIVR-SS_OB-7-UNK:Dialer:processOutboundCSQIterationMsg() #active%:5.0, #active%-working:2, #lic_left:0, #contacts:3

5783401: Jan 10 15:09:41.228 EST %MIVR-SS_OB-7-UNK:OutboundMgrMsgProcessor:Processing msg: OutboundCSQIterationMsg (CsqID:9)

5783402: Jan 10 15:09:41.228 EST %MIVR-SS_OB-7-UNK:OutboundContactInfo:getNumberContactsToCall() for csqID:9 :3

5783403: Jan 10 15:09:41.228 EST %MIVR-SS_OB-7-UNK:Dialer:processOutboundCSQIterationMsg() #active%:5.0, #active%-working:2, #lic_left:0, #contacts:3

5783159: Jan 10 15:09:33.322 EST %MIVR-SS_OB-7-UNK:Dialer:agentAccepts()
5783160: Jan 10 15:09:33.322 EST %MIVR-SS_OB-7-UNK:Dialer:getFakeID() realCallID:2965426/1
5783161: Jan 10 15:09:33.322 EST %MIVR-ICD_CTI-7-UNK:OutboundMessageprocessor : sending msg : { length=-1 type=CALL_DATA_UPDATE_EVENT, monitorID: 0, peripheralID: 1, peripheralType: 21, numCTIClients: 0, numNamedVars: 1, numNamedArrays: 0, callType: 18, connectionDeviceIDType: 0, connectionCallID: 19742642, newConnectionDeviceIDType: 0, newConnectionCallID: 19742642, calledPartyDisposition: 0, campaignID: 0, queryRuleID: 0, connectionDeviceID: 84610, newConnectionDeviceID: 84610, customerPhoneNumber: , customerAccountNumber: , ani: null, callerEnteredDigits: null, wrapupData: null, callVar1: null, callVar2: null, callVar3: null, callVar4: null, callVar5: null, callVar6: null, callVar7: null, callVar8: null, callVar9: null, callVar10: null, BAResponse: Accept to various sockets }
5783162: Jan 10 15:09:33.322 EST %MIVR-SS_CM-7-UNK:ContactMgr.getRmCmContact(19742642) returns 19742642 [2965426/1]
5783163: Jan 10 15:09:33.322 EST %MIVR-SS_RM-7-UNK:Processing msg: OutboundDropRsrcFromRealAndReservationCallReqMsg (Rsrc:0290084610)  callId: 2965425/1 realCallAtCad: false
5783164: Jan 10 15:09:33.322 EST %MIVR-SS_OB-7-UNK:Dialer:set selected esd to Name:ASEC1112 2010 and 2011 Lapsed ID:9
5783165: Jan 10 15:09:33.322 EST %MIVR-SS_RM-7-UNK:RsrcMgrMsgProcessor: process OutboundDropRsrcFromRealAndReservationCallReqMsg. msg : OutboundDropRsrcFromRealAndReservationCallReqMsg (Rsrc:0290084610)  callId: 2965425/1 realCallAtCad: false
5783166: Jan 10 15:09:33.322 EST %MIVR-SS_OB-7-UNK:Dialer:posting OutboundPlaceCallReqMsg - rsrcID=0290084610, phoneNumber=00394583403, icdCall=2965426/1
5783167: Jan 10 15:09:33.322 EST %MIVR-LIB_EVENT-7-POSTING_EVENT:: Event=com.cisco.call.CallDataEvent[ALLCALLDATA_CHANGED: for 2965426/1 name = script id, value = |name = script config, value = |name = ani, value = |name = ced, value = |name = callvariable1, value = |name = callvariable2, value = |name = callvariable3, value = |name = callvariable4, value = |name = callvariable5, value = |name = callvariable6, value = |name = callvariable7, value = |name = callvariable8, value = |name = callvariable9, value = |name = callvariable10, value = |name = account no, value = |name = wrapup data, value = ] on name = script id, value = |name = script config, value = |name = ani, value = |name = ced, value = |name = callvariable1, value = |name = callvariable2, value = |name = callvariable3, value = |name = callvariable4, value = |name = callvariable5, value = |name = callvariable6, value = |name = callvariable7, value = |name = callvariable8, value = |name = callvariable9, value = |name = callvariable10, value = |name = account no, value = |name = wrapup data, value =
5783168: Jan 10 15:09:33.322 EST %MIVR-SS_RM-7-UNK:RsrcMgrMsgProcessor: process processOutboundDropRsrcFromRealAndReservationCallReq. msg : OutboundDropRsrcFromRealAndReservationCallReqMsg (Rsrc:0290084610)  callId: 2965425/1 realCallAtCad: false
5783169: Jan 10 15:09:33.322 EST %MIVR-SS_CM-7-UNK:ContactMgr.getRmCmContact(19742641) returns 19742642 [2965426/1]
5783170: Jan 10 15:09:33.322 EST %MIVR-SS_CM-7-UNK:ContactMgr.removeRmCmContact(2965425/1)
5783171: Jan 10 15:09:33.322 EST %MIVR-SS_CM-7-UNK:Processing msg: class com.cisco.wf.subsystems.rmcm.msgs.CMFireRsrcDisconnectedEventReqMsg Contact5007
5783172: Jan 10 15:09:33.322 EST %MIVR-SS_CM-7-UNK:RmCm contact 19742642[2965426/1] (5007) .removeConnectedResource(0290084610)
5783173: Jan 10 15:09:33.322 EST %MIVR-SS_CM-7-UNK: IEF Contact ID: [2965426/1] 19742642 ContactEventsGenerator: Posting the ContactRsrcDisconnectedEvent Resource Rsrc Name:Charles Mesiku ID:0290084610 IAQ Extn:84610 contact type IAQ session 15000004999 session seq num 0
5783174: Jan 10 15:09:33.322 EST %MIVR-SS_RM-7-UNK:Processing msg: OutboundPlaceCallReqMsg (Rsrc: 0290084610 dialNum: 00394583403 icd call: com.cisco.wf.subsystems.rmcm.jtapi.ICDJtapiCall@116859dtimeZone: 660)
5783175: Jan 10 15:09:33.322 EST %MIVR-SS_RM-7-UNK:RsrcMgrMsgProcessor: process OutboundPlaceCallReqMsg.
5783176: Jan 10 15:09:33.322 EST %MIVR-SS_CM-7-UNK:ContactMgr.getRmCmContact(19742642) returns 19742642 [2965426/1]
5783177: Jan 10 15:09:33.322 EST %MIVR-SS_RM-7-UNK:RsrcMgrMsgProcessor: process OutboundPlaceCallReqMsg.rsrcId: 0290084610 icdcallid: 19742642 diaNum : 00394583403 agentInstrument: 84610
5783178: Jan 10 15:09:33.322 EST %MIVR-SS_RM-7-UNK:Agent 0290084610 .addAssociatedContact(Contact:RmCm contact 19742642[2965426/1] (5007)
5783179: Jan 10 15:09:33.322 EST %MIVR-SS_CM-7-UNK:RmCm contact 19742642[2965426/1] (5007) .addAssociatedResource(0290084610)
5783180: Jan 10 15:09:33.322 EST %MIVR-SS_RM-7-UNK:isCTIRoutePoint, addr: 84610:false
5783181: Jan 10 15:09:33.322 EST %MIVR-ICD_CTI-7-UNK:EventHandler: posting {CALL_CONNECTION_CLEARED_EVENT: Socket:Socket: null monitoredDeviceDN:84610, connectionCallID: 19742641, monitoredDeviceDN: 84610, releasingDeviceID: 84610, releasingDeviceType: 76 } to outboundQ
5783182: Jan 10 15:09:33.322 EST %MIVR-SS_CM-7-UNK:ContactMgr.getRmCmContact(2965426/1) returns 19742642 [2965426/1]
5783183: Jan 10 15:09:33.322 EST %MIVR-SS_CM-7-UNK:ContactMgr.getRmCmContact(2965426/1) returns 19742642 [2965426/1]
5783184: Jan 10 15:09:33.322 EST %MIVR-SS_CM-7-UNK:ContactMgr.getRmCmContact(2965426/1) returns 19742642 [2965426/1]
5783185: Jan 10 15:09:33.322 EST %MIVR-SS_OB-7-UNK:OutboundMgrMsgProcessor:Processing msg: OutboundContactRsrcDisconnectedMsg (Rsrc:Rsrc Name:Charles Mesiku ID:0290084610 IAQ Extn:84610)
5783186: Jan 10 15:09:33.322 EST %MIVR-ICD_CTI-7-UNK:OutboundMsgProcessor: got message: { BEGIN_CALL_EVENT }
5783187: Jan 10 15:09:33.322 EST %MIVR-ICD_CTI-7-UNK:MsgHandler : Sent : { CALL_DATA_UPDATE_EVENT to Socket[addr=10.1.5.148,port=64662,localport=42027]  CID: 19742642 }
5783188: Jan 10 15:09:33.322 EST %MIVR-ICD_CTI-7-UNK:CSOutboundMsgProcessor: got an event message:{ length=-1 type=BEGIN_CALL_EVENT, monitorID: 0, peripheralID: 1, peripheralType: 21, numCTIClients: 0, numNamedVars: 10, numNamedArrays: 0, callType: 17, connectionDeviceIDType: 0, connectionCallID: 19742642, calledPartyDisposition: 0, connectionDeviceID: 84610, ani: null, dnis: null, dialedNumber: null, callerEnteredDigits: null, callVar1: null, callVar2: null, callVar3: null, callVar4: null, callVar5: null, callVar6: null, callVar7: null, callVar8: null, callVar9: null, callVar10: null, wrapupData: null }
5783189: Jan 10 15:09:33.322 EST %MIVR-ICD_CTI-7-UNK:OutboundMessageprocessor : sending msg : { length=-1 type=BEGIN_CALL_EVENT, monitorID: 0, peripheralID: 1, peripheralType: 21, numCTIClients: 0, numNamedVars: 10, numNamedArrays: 0, callType: 17, connectionDeviceIDType: 0, connectionCallID: 19742642, calledPartyDisposition: 0, connectionDeviceID: 84610, ani: null, dnis: null, dialedNumber: null, callerEnteredDigits: null, callVar1: null, callVar2: null, callVar3: null, callVar4: null, callVar5: null, callVar6: null, callVar7: null, callVar8: null, callVar9: null, callVar10: null, wrapupData: null to various sockets }
5783190: Jan 10 15:09:33.322 EST %MIVR-ICD_CTI-7-UNK:MsgHandler : Sent : { CALL_DATA_UPDATE_EVENT to Socket[addr=10.128.1.200,port=49417,localport=42027]  CID: 19742642 }
5783191: Jan 10 15:09:33.322 EST %MIVR-LIB_EVENT-7-POSTING_EVENT:: Event=com.cisco.wf.subsystems.events.ContactDataChangedEvent[] on com.cisco.wf.subsystems.rmcm.CallContact@1f6718d at Tue Jan 10 15:09:33 EST 2012
5783192: Jan 10 15:09:33.322 EST %MIVR-SS_CM-7-UNK:Processing msg: class com.cisco.wf.subsystems.rmcm.msgs.CMFireContactDataChangedEventReqMsg Contact5007
5783193: Jan 10 15:09:33.322 EST %MIVR-SS_OB-7-UNK:Dialer:getFakeID() realCallID:2965426/1
5783194: Jan 10 15:09:33.322 EST %MIVR-SS_OB-7-UNK:Dialer:processOutboundContactRsrcDisconnectedMsg() rsrcID:0290084610, ACMICallType:17
5783195: Jan 10 15:09:33.322 EST %MIVR-SS_CM-7-UNK: IEF Contact ID: [2965426/1] 19742642 ContactEventsGenerator: Posting the ContactDataChangedEvent agent 0290084610 contact type IAQ session 15000004999 session seq num 0
5783196: Jan 10 15:09:33.322 EST %MIVR-SS_CM-7-UNK:Processing msg: class com.cisco.wf.subsystems.rmcm.msgs.CMFireContactDataChangedEventReqMsg Contact5007
5783197: Jan 10 15:09:33.322 EST %MIVR-SS_OB-7-UNK:OutboundMgrMsgProcessor:Processing msg: OutboundContactDataChangedMsg (Rsrc:Rsrc Name:Charles Mesiku ID:0290084610 IAQ Extn:84610)
5783198: Jan 10 15:09:33.322 EST %MIVR-SS_OB-7-UNK:Dialer:processOutboundContactDataChangedMsg() rsrc:Rsrc Name:Charles Mesiku ID:0290084610 IAQ Extn:84610, callID:2965426/1
5783199: Jan 10 15:09:33.322 EST %MIVR-SS_OB-7-UNK:Dialer:processOutboundContactDataChangedMsg() BAResponse:Accept
5783200: Jan 10 15:09:33.322 EST %MIVR-SS_OB-7-UNK:Dialer:agentAccepts()
5783201: Jan 10 15:09:33.322 EST %MIVR-ICD_CTI-7-UNK:EventHandler: posting {CALL_DATA_UPDATE_EVENT: Socket:Socket: null monitoredDeviceDN:null, connectionCallID: 19742642, newConnectionCallID: 19742642, callType: 17, connectionDeviceID: 84610, newConnectionDeviceID: 84610, ani: null, callerEnteredDigits: null, accountNumber: , wrapupData: null, callVar1: null, callVar2: null, callVar3: null, callVar4: null, callVar5: null, callVar6: null, callVar7: null, callVar8: null, callVar9: null, callVar10: null, BACampaign: Catalogue Request  2012, BATimeZone: +00660, BACampaignID: 13, BAResponse: Accept, BADialedListID: 26211, BABuddyName: Jenni,Sharp, BAStatus: CO, user.layout: OODefault, BACustomerNumber: 00394583403, BAAccountNumber: 189640 } to outboundQ
5783202: Jan 10 15:09:33.322 EST %MIVR-SS_OB-7-UNK:Dialer:getFakeID() realCallID:2965426/1
5783203: Jan 10 15:09:33.322 EST %MIVR-SS_OB-7-UNK:Dialer:agentAccepts() - already handled the accept
5783204: Jan 10 15:09:33.322 EST %MIVR-SS_CM-7-UNK: IEF Contact ID: [2965426/1] 19742642 ContactEventsGenerator: Posting the ContactDataChangedEvent agent 0290084610 contact type IAQ session 15000004999 session seq num 0
5783205: Jan 10 15:09:33.322 EST %MIVR-SS_CM-7-UNK:Processing msg: class com.cisco.wf.subsystems.rmcm.msgs.CMFireContactDataChangedEventReqMsg Contact5007
5783206: Jan 10 15:09:33.322 EST %MIVR-SS_CM-7-UNK: IEF Contact ID: [2965426/1] 19742642 ContactEventsGenerator: Posting the ContactDataChangedEvent agent 0290084610 contact type IAQ session 15000004999 session seq num 0
5783207: Jan 10 15:09:33.322 EST %MIVR-SS_CM-7-UNK:Processing msg: class com.cisco.wf.subsystems.rmcm.msgs.CMFireContactDataChangedEventReqMsg Contact5007
5783208: Jan 10 15:09:33.322 EST %MIVR-SS_CM-7-UNK: IEF Contact ID: [2965426/1] 19742642 ContactEventsGenerator: Posting the ContactDataChangedEvent agent 0290084610 contact type IAQ session 15000004999 session seq num 0
5783209: Jan 10 15:09:33.322 EST %MIVR-ICD_CTI-7-UNK:OutboundMsgProcessor: got message: { CALL_CONNECTION_CLEARED_EVENT }
5783210: Jan 10 15:09:33.322 EST %MIVR-ICD_CTI-7-UNK:CSOutboundMsgProcessor: got an event message:{ length=-1 type=CALL_CONNECTION_CLEARED_EVENT, monitorID: 0, peripheralID: 1, peripheralType: 21, connectionDeviceIDType: 0, connectionCallID: 19742641, releasingDeviceType: 76, localConnectionState: 65535, eventCause: 65535, monitoredDeviceDN: 84610, releasingDeviceID: 84610 }
5783211: Jan 10 15:09:33.322 EST %MIVR-ICD_CTI-7-UNK:OutboundMessageprocessor : sending msg : { length=-1 type=CALL_CONNECTION_CLEARED_EVENT, monitorID: 0, peripheralID: 1, peripheralType: 21, connectionDeviceIDType: 0, connectionCallID: 19742641, releasingDeviceType: 76, localConnectionState: 65535, eventCause: 65535, monitoredDeviceDN: 84610, releasingDeviceID: 84610 to various sockets }
5783212: Jan 10 15:09:33.322 EST %MIVR-ICD_CTI-7-UNK:MsgHandler : Sent : { BEGIN_CALL_EVENT to Socket[addr=10.1.5.148,port=64662,localport=42027]  CID: 19742642 }
5783213: Jan 10 15:09:33.322 EST %MIVR-ICD_CTI-7-UNK:EventHandler: posting {CALL_DATA_UPDATE_EVENT: Socket:Socket: null monitoredDeviceDN:null, connectionCallID: 19742642, newConnectionCallID: 19742642, callType: 17, connectionDeviceID: 84610, newConnectionDeviceID: 84610, ani: null, callerEnteredDigits: null, accountNumber: null, wrapupData: null, callVar1: null, callVar2: null, callVar3: null, callVar4: null, callVar5: null, callVar6: null, callVar7: null, callVar8: null, callVar9: null, callVar10: null, BAStatus: CO } to outboundQ
5783214: Jan 10 15:09:33.322 EST %MIVR-SS_OB-7-UNK:OutboundMgrMsgProcessor:Processing msg: OutboundContactDataChangedMsg (Rsrc:Rsrc Name:Charles Mesiku ID:0290084610 IAQ Extn:84610)
5783215: Jan 10 15:09:33.322 EST %MIVR-SS_OB-7-UNK:Dialer:processOutboundContactDataChangedMsg() rsrc:Rsrc Name:Charles Mesiku ID:0290084610 IAQ Extn:84610, callID:2965426/1
5783216: Jan 10 15:09:33.322 EST %MIVR-SS_OB-7-UNK:OutboundMgrMsgProcessor:Processing msg: OutboundContactDataChangedMsg (Rsrc:Rsrc Name:Charles Mesiku ID:0290084610 IAQ Extn:84610)
5783217: Jan 10 15:09:33.322 EST %MIVR-SS_OB-7-UNK:Dialer:processOutboundContactDataChangedMsg() rsrc:Rsrc Name:Charles Mesiku ID:0290084610 IAQ Extn:84610, callID:2965426/1
5783218: Jan 10 15:09:33.322 EST %MIVR-ICD_CTI-7-UNK:EventHandler: posting {CALL_DATA_UPDATE_EVENT: Socket:Socket: null monitoredDeviceDN:null, connectionCallID: 19742642, newConnectionCallID: 19742642, callType: 17, connectionDeviceID: 84610, newConnectionDeviceID: 84610, ani: null, callerEnteredDigits: null, accountNumber: null, wrapupData: null, callVar1: null, callVar2: null, callVar3: null, callVar4: null, callVar5: null, callVar6: null, callVar7: null, callVar8: null, callVar9: null, callVar10: null, BATimeZone: +00660 } to outboundQ
5783219: Jan 10 15:09:33.322 EST %MIVR-ICD_CTI-7-UNK:EventHandler: posting {CALL_DATA_UPDATE_EVENT: Socket:Socket: null monitoredDeviceDN:null, connectionCallID: 19742642, newConnectionCallID: 19742642, callType: 17, connectionDeviceID: 84610, newConnectionDeviceID: 84610, ani: null, callerEnteredDigits: null, accountNumber: null, wrapupData: null, callVar1: null, callVar2: null, callVar3: null, callVar4: null, callVar5: null, callVar6: null, callVar7: null, callVar8: null, callVar9: null, callVar10: null, BACustomerNumber: 00394583403 } to outboundQ
5783220: Jan 10 15:09:33.322 EST %MIVR-SS_OB-7-UNK:OutboundMgrMsgProcessor:Processing msg: OutboundContactDataChangedMsg (Rsrc:Rsrc Name:Charles Mesiku ID:0290084610 IAQ Extn:84610)
5783221: Jan 10 15:09:33.322 EST %MIVR-SS_OB-7-UNK:Dialer:processOutboundContactDataChangedMsg() rsrc:Rsrc Name:Charles Mesiku ID:0290084610 IAQ Extn:84610, callID:2965426/1
5783222: Jan 10 15:09:33.322 EST %MIVR-ICD_CTI-7-UNK:MsgHandler : Sent : { BEGIN_CALL_EVENT to Socket[addr=10.128.1.200,port=49417,localport=42027]  CID: 19742642 }
5783223: Jan 10 15:09:33.322 EST %MIVR-ICD_CTI-7-UNK:MsgHandler : Sent : { CALL_CONNECTION_CLEARED_EVENT to Socket[addr=10.128.1.200,port=49417,localport=42027]  CID: 19742641 }
5783224: Jan 10 15:09:33.322 EST %MIVR-LIB_EVENT-7-POSTING_EVENT:: Event=com.cisco.wf.subsystems.events.OutboundBAResponseEvent[] on java.lang.Object@11a9732 at Tue Jan 10 15:09:33 EST 2012
5783225: Jan 10 15:09:33.322 EST %MIVR-LIB_EVENT-7-POSTING_EVENT:: Event=com.cisco.wf.subsystems.events.ContactRsrcDisconnectedEvent[] on com.cisco.wf.subsystems.rmcm.CallContact@1f6718d at Tue Jan 10 15:09:33 EST 2012
5783226: Jan 10 15:09:33.322 EST %MIVR-LIB_EVENT-7-POSTING_EVENT:: Event=com.cisco.call.CallDataEvent[CALLDATA_ECC_CHANGED: for 2965426/1 name = BAStatus, value = CO] on name = BAStatus, value = CO
5783227: Jan 10 15:09:33.322 EST %MIVR-LIB_EVENT-7-POSTING_EVENT:: Event=com.cisco.call.CallDataEvent[CALLDATA_ECC_CHANGED: for 2965426/1 name = BATimeZone, value = +00660] on name = BATimeZone, value = +00660
5783228: Jan 10 15:09:33.322 EST %MIVR-LIB_EVENT-7-POSTING_EVENT:: Event=com.cisco.call.CallDataEvent[CALLDATA_ECC_CHANGED: for 2965426/1 name = BACustomerNumber, value = 00394583403] on name = BACustomerNumber, value = 00394583403
5783229: Jan 10 15:09:33.322 EST %MIVR-LIB_EVENT-7-POSTING_EVENT:: Event=com.cisco.wf.subsystems.events.ContactDataChangedEvent[] on com.cisco.wf.subsystems.rmcm.CallContact@1f6718d at Tue Jan 10 15:09:33 EST 2012
5783230: Jan 10 15:09:33.322 EST %MIVR-LIB_EVENT-7-POSTING_EVENT:: Event=com.cisco.wf.subsystems.events.ContactDataChangedEvent[] on com.cisco.wf.subsystems.rmcm.CallContact@1f6718d at Tue Jan 10 15:09:33 EST 2012
5783231: Jan 10 15:09:33.322 EST %MIVR-LIB_EVENT-7-POSTING_EVENT:: Event=com.cisco.wf.subsystems.events.ContactDataChangedEvent[] on com.cisco.wf.subsystems.rmcm.CallContact@1f6718d at Tue Jan 10 15:09:33 EST 2012
5783232: Jan 10 15:09:33.322 EST %MIVR-LIB_EVENT-7-POSTING_EVENT:: Event=com.cisco.wf.subsystems.events.ContactDataChangedEvent[] on com.cisco.wf.subsystems.rmcm.CallContact@1f6718d at Tue Jan 10 15:09:33 EST 2012
5783233: Jan 10 15:09:33.322 EST %MIVR-ICD_CTI-7-UNK:MsgHandler : Sent : { CALL_DATA_UPDATE_EVENT to Socket[addr=10.128.1.200,port=49433,localport=42027]  CID: 19742642 }
5783234: Jan 10 15:09:33.322 EST %MIVR-ICD_CTI-7-UNK:MsgHandler : Sent : { CALL_CONNECTION_CLEARED_EVENT to Socket[addr=10.1.5.148,port=64662,localport=42027]  CID: 19742641 }
5783235: Jan 10 15:09:33.322 EST %MIVR-ICD_CTI-7-UNK:MsgHandler : Sent : { BEGIN_CALL_EVENT to Socket[addr=10.128.1.200,port=49433,localport=42027]  CID: 19742642 }
5783236: Jan 10 15:09:33.322 EST %MIVR-ICD_CTI-7-UNK:MsgHandler : Sent : { CALL_CONNECTION_CLEARED_EVENT to Socket[addr=10.128.1.200,port=49433,localport=42027]  CID: 19742641 }
5783237: Jan 10 15:09:33.337 EST %MIVR-ICD_CTI-7-UNK:OutboundMsgProcessor: got message: { CALL_CLEARED_EVENT }
5783238: Jan 10 15:09:33.337 EST %MIVR-ICD_CTI-7-UNK:CSOutboundMsgProcessor: got an event message:{ length=-1 type=CALL_CLEARED_EVENT, monitorID: 0, peripheralID: 1, peripheralType: 21, connectionDeviceIDType: 0, ConnectionCallID: 19742641, localConnectionState: 0, eventCause: 65535, connectionDeviceID: 84610 }
5783239: Jan 10 15:09:33.337 EST %MIVR-ICD_CTI-7-UNK:OutboundMessageprocessor : sending msg : { length=-1 type=CALL_CLEARED_EVENT, monitorID: 0, peripheralID: 1, peripheralType: 21, connectionDeviceIDType: 0, ConnectionCallID: 19742641, localConnectionState: 0, eventCause: 65535, connectionDeviceID: 84610 to various sockets }
5783240: Jan 10 15:09:33.337 EST %MIVR-ICD_CTI-7-UNK:OutboundMsgProcessor: got message: { END_CALL_EVENT }
5783241: Jan 10 15:09:33.337 EST %MIVR-ICD_CTI-7-UNK:CSOutboundMsgProcessor: got an event message:{ length=-1 type=END_CALL_EVENT, monitorID: 0, peripheralID: 1, peripheralType: 21, connectionDeviceIDType: 0, connectionCallID: 19742641, connectionDeviceID:  }
5783242: Jan 10 15:09:33.337 EST %MIVR-ICD_CTI-7-UNK:OutboundMessageprocessor : sending msg : { length=-1 type=END_CALL_EVENT, monitorID: 0, peripheralID: 1, peripheralType: 21, connectionDeviceIDType: 0, connectionCallID: 19742641, connectionDeviceID:  to various sockets }
5783243: Jan 10 15:09:33.337 EST %MIVR-ICD_CTI-7-UNK:MsgHandler : Sent : { CALL_CLEARED_EVENT to Socket[addr=10.128.1.200,port=49433,localport=42027]  CID: 19742641 }
5783244: Jan 10 15:09:33.337 EST %MIVR-ICD_CTI-7-UNK:OutboundMsgProcessor: got message: { CALL_DATA_UPDATE_EVENT }
5783245: Jan 10 15:09:33.337 EST %MIVR-ICD_CTI-7-UNK:MsgHandler : Sent : { END_CALL_EVENT to Socket[addr=10.128.1.200,port=49433,localport=42027]  CID: 19742641 }
5783246: Jan 10 15:09:33.337 EST %MIVR-ICD_CTI-7-UNK:CSOutboundMsgProcessor: got an event message:{ length=-1 type=CALL_DATA_UPDATE_EVENT, monitorID: 0, peripheralID: 1, peripheralType: 21, numCTIClients: 0, numNamedVars: 10, numNamedArrays: 0, callType: 17, connectionDeviceIDType: 0, connectionCallID: 19742642, newConnectionDeviceIDType: 0, newConnectionCallID: 19742642, calledPartyDisposition: 0, campaignID: 0, queryRuleID: 0, connectionDeviceID: 84610, newConnectionDeviceID: 84610, customerPhoneNumber: , customerAccountNumber: , ani: null, callerEnteredDigits: null, wrapupData: null, callVar1: null, callVar2: null, callVar3: null, callVar4: null, callVar5: null, callVar6: null, callVar7: null, callVar8: null, callVar9: null, callVar10: null, BACampaign: Catalogue Request  2012, BATimeZone: +00660, BACampaignID: 13, BAResponse: Accept, BADialedListID: 26211, BABuddyName: Jenni,Sharp, BAStatus: CO, user.layout: OODefault, BACustomerNumber: 00394583403, BAAccountNumber: 189640 }
5783247: Jan 10 15:09:33.337 EST %MIVR-ICD_CTI-7-UNK:OutboundMessageprocessor : sending msg : { length=-1 type=CALL_DATA_UPDATE_EVENT, monitorID: 0, peripheralID: 1, peripheralType: 21, numCTIClients: 0, numNamedVars: 10, numNamedArrays: 0, callType: 17, connectionDeviceIDType: 0, connectionCallID: 19742642, newConnectionDeviceIDType: 0, newConnectionCallID: 19742642, calledPartyDisposition: 0, campaignID: 0, queryRuleID: 0, connectionDeviceID: 84610, newConnectionDeviceID: 84610, customerPhoneNumber: , customerAccountNumber: , ani: null, callerEnteredDigits: null, wrapupData: null, callVar1: null, callVar2: null, callVar3: null, callVar4: null, callVar5: null, callVar6: null, callVar7: null, callVar8: null, callVar9: null, callVar10: null, BACampaign: Catalogue Request  2012, BATimeZone: +00660, BACampaignID: 13, BAResponse: Accept, BADialedListID: 26211, BABuddyName: Jenni,Sharp, BAStatus: CO, user.layout: OODefault, BACustomerNumber: 00394583403, BAAccountNumber: 189640 to various sockets }
5783248: Jan 10 15:09:33.337 EST %MIVR-ICD_CTI-7-UNK:OutboundMsgProcessor: got message: { CALL_DATA_UPDATE_EVENT }
5783249: Jan 10 15:09:33.337 EST %MIVR-ICD_CTI-7-UNK:CSOutboundMsgProcessor: got an event message:{ length=-1 type=CALL_DATA_UPDATE_EVENT, monitorID: 0, peripheralID: 1, peripheralType: 21, numCTIClients: 0, numNamedVars: 1, numNamedArrays: 0, callType: 17, connectionDeviceIDType: 0, connectionCallID: 19742642, newConnectionDeviceIDType: 0, newConnectionCallID: 19742642, calledPartyDisposition: 0, campaignID: 0, queryRuleID: 0, connectionDeviceID: 84610, newConnectionDeviceID: 84610, customerPhoneNumber: , customerAccountNumber: null, ani: null, callerEnteredDigits: null, wrapupData: null, callVar1: null, callVar2: null, callVar3: null, callVar4: null, callVar5: null, callVar6: null, callVar7: null, callVar8: null, callVar9: null, callVar10: null, BAStatus: CO }
5783250: Jan 10 15:09:33.337 EST %MIVR-ICD_CTI-7-UNK:MsgHandler : Sent : { CALL_DATA_UPDATE_EVENT to Socket[addr=10.1.5.148,port=64662,localport=42027]  CID: 19742642 }
5783251: Jan 10 15:09:33.337 EST %MIVR-ICD_CTI-7-UNK:MsgHandler : Sent : { END_CALL_EVENT to Socket[addr=10.128.1.200,port=49417,localport=42027]  CID: 19742641 }
5783252: Jan 10 15:09:33.337 EST %MIVR-ICD_CTI-7-UNK:MsgHandler : Sent : { CALL_DATA_UPDATE_EVENT to Socket[addr=10.128.1.200,port=49417,localport=42027]  CID: 19742642 }
5783253: Jan 10 15:09:33.337 EST %MIVR-ICD_CTI-7-UNK:MsgHandler : Sent : { CALL_DATA_UPDATE_EVENT to Socket[addr=10.128.1.200,port=49433,localport=42027]  CID: 19742642 }
5783254: Jan 10 15:09:33.337 EST %MIVR-ICD_CTI-7-UNK:OutboundMessageprocessor : sending msg : { length=-1 type=CALL_DATA_UPDATE_EVENT, monitorID: 0, peripheralID: 1, peripheralType: 21, numCTIClients: 0, numNamedVars: 1, numNamedArrays: 0, callType: 17, connectionDeviceIDType: 0, connectionCallID: 19742642, newConnectionDeviceIDType: 0, newConnectionCallID: 19742642, calledPartyDisposition: 0, campaignID: 0, queryRuleID: 0, connectionDeviceID: 84610, newConnectionDeviceID: 84610, customerPhoneNumber: , customerAccountNumber: null, ani: null, callerEnteredDigits: null, wrapupData: null, callVar1: null, callVar2: null, callVar3: null, callVar4: null, callVar5: null, callVar6: null, callVar7: null, callVar8: null, callVar9: null, callVar10: null, BAStatus: CO to various sockets }
5783255: Jan 10 15:09:33.337 EST %MIVR-ICD_CTI-7-UNK:OutboundMsgProcessor: got message: { CALL_DATA_UPDATE_EVENT }
5783256: Jan 10 15:09:33.337 EST %MIVR-ICD_CTI-7-UNK:MsgHandler : Sent : { CALL_DATA_UPDATE_EVENT to Socket[addr=10.128.1.200,port=49433,localport=42027]  CID: 19742642 }
5783257: Jan 10 15:09:33.337 EST %MIVR-ICD_CTI-7-UNK:CSOutboundMsgProcessor: got an event message:{ length=-1 type=CALL_DATA_UPDATE_EVENT, monitorID: 0, peripheralID: 1, peripheralType: 21, numCTIClients: 0, numNamedVars: 1, numNamedArrays: 0, callType: 17, connectionDeviceIDType: 0, connectionCallID: 19742642, newConnectionDeviceIDType: 0, newConnectionCallID: 19742642, calledPartyDisposition: 0, campaignID: 0, queryRuleID: 0, connectionDeviceID: 84610, newConnectionDeviceID: 84610, customerPhoneNumber: , customerAccountNumber: null, ani: null, callerEnteredDigits: null, wrapupData: null, callVar1: null, callVar2: null, callVar3: null, callVar4: null, callVar5: null, callVar6: null, callVar7: null, callVar8: null, callVar9: null, callVar10: null, BATimeZone: +00660 }
5783258: Jan 10 15:09:33.337 EST %MIVR-ICD_CTI-7-UNK:OutboundMessageprocessor : sending msg : { length=-1 type=CALL_DATA_UPDATE_EVENT, monitorID: 0, peripheralID: 1, peripheralType: 21, numCTIClients: 0, numNamedVars: 1, numNamedArrays: 0, callType: 17, connectionDeviceIDType: 0, connectionCallID: 19742642, newConnectionDeviceIDType: 0, newConnectionCallID: 19742642, calledPartyDisposition: 0, campaignID: 0, queryRuleID: 0, connectionDeviceID: 84610, newConnectionDeviceID: 84610, customerPhoneNumber: , customerAccountNumber: null, ani: null, callerEnteredDigits: null, wrapupData: null, callVar1: null, callVar2: null, callVar3: null, callVar4: null, callVar5: null, callVar6: null, callVar7: null, callVar8: null, callVar9: null, callVar10: null, BATimeZone: +00660 to various sockets }
5783259: Jan 10 15:09:33.337 EST %MIVR-ICD_CTI-7-UNK:OutboundMsgProcessor: got message: { CALL_DATA_UPDATE_EVENT }
5783260: Jan 10 15:09:33.337 EST %MIVR-ICD_CTI-7-UNK:MsgHandler : Sent : { CALL_DATA_UPDATE_EVENT to Socket[addr=10.128.1.200,port=49433,localport=42027]  CID: 19742642 }
5783261: Jan 10 15:09:33.337 EST %MIVR-ICD_CTI-7-UNK:MsgHandler : Sent : { CALL_DATA_UPDATE_EVENT to Socket[addr=10.1.5.148,port=64662,localport=42027]  CID: 19742642 }
5783262: Jan 10 15:09:33.337 EST %MIVR-ICD_CTI-7-UNK:MsgHandler : Sent : { CALL_DATA_UPDATE_EVENT to Socket[addr=10.1.5.148,port=64662,localport=42027]  CID: 19742642 }
5783263: Jan 10 15:09:33.337 EST %MIVR-ICD_CTI-7-UNK:MsgHandler : Sent : { CALL_DATA_UPDATE_EVENT to Socket[addr=10.128.1.200,port=49417,localport=42027]  CID: 19742642 }
5783264: Jan 10 15:09:33.337 EST %MIVR-ICD_CTI-7-UNK:MsgHandler : Sent : { CALL_DATA_UPDATE_EVENT to Socket[addr=10.128.1.200,port=49417,localport=42027]  CID: 19742642 }
5783265: Jan 10 15:09:33.337 EST %MIVR-ICD_CTI-7-UNK:CSOutboundMsgProcessor: got an event message:{ length=-1 type=CALL_DATA_UPDATE_EVENT, monitorID: 0, peripheralID: 1, peripheralType: 21, numCTIClients: 0, numNamedVars: 1, numNamedArrays: 0, callType: 17, connectionDeviceIDType: 0, connectionCallID: 19742642, newConnectionDeviceIDType: 0, newConnectionCallID: 19742642, calledPartyDisposition: 0, campaignID: 0, queryRuleID: 0, connectionDeviceID: 84610, newConnectionDeviceID: 84610, customerPhoneNumber: , customerAccountNumber: null, ani: null, callerEnteredDigits: null, wrapupData: null, callVar1: null, callVar2: null, callVar3: null, callVar4: null, callVar5: null, callVar6: null, callVar7: null, callVar8: null, callVar9: null, callVar10: null, BACustomerNumber: 00394583403 }
5783266: Jan 10 15:09:33.337 EST %MIVR-ICD_CTI-7-UNK:OutboundMessageprocessor : sending msg : { length=-1 type=CALL_DATA_UPDATE_EVENT, monitorID: 0, peripheralID: 1, peripheralType: 21, numCTIClients: 0, numNamedVars: 1, numNamedArrays: 0, callType: 17, connectionDeviceIDType: 0, connectionCallID: 19742642, newConnectionDeviceIDType: 0, newConnectionCallID: 19742642, calledPartyDisposition: 0, campaignID: 0, queryRuleID: 0, connectionDeviceID: 84610, newConnectionDeviceID: 84610, customerPhoneNumber: , customerAccountNumber: null, ani: null, callerEnteredDigits: null, wrapupData: null, callVar1: null, callVar2: null, callVar3: null, callVar4: null, callVar5: null, callVar6: null, callVar7: null, callVar8: null, callVar9: null, callVar10: null, BACustomerNumber: 00394583403 to various sockets }
5783267: Jan 10 15:09:33.337 EST %MIVR-ICD_CTI-7-UNK:MsgHandler : Sent : { CALL_DATA_UPDATE_EVENT to Socket[addr=10.128.1.200,port=49433,localport=42027]  CID: 19742642 }
5783268: Jan 10 15:09:33.337 EST %MIVR-ICD_CTI-7-UNK:MsgHandler : Sent : { CALL_DATA_UPDATE_EVENT to Socket[addr=10.1.5.148,port=64662,localport=42027]  CID: 19742642 }
5783269: Jan 10 15:09:33.337 EST %MIVR-ICD_CTI-7-UNK:MsgHandler : Sent : { CALL_DATA_UPDATE_EVENT to Socket[addr=10.128.1.200,port=49417,localport=42027]  CID: 19742642 }
5783270: Jan 10 15:09:33.337 EST %MIVR-SS_RM-7-UNK:RIMgrAddressCallObserver: CallCtlConnInitiatedEv received for call 19742642 [2965426/1] and agent 0290084610
5783271: Jan 10 15:09:33.337 EST %MIVR-SS_CM-7-UNK:ContactMgr.getRmCmContact(2965426/1) returns 19742642 [2965426/1]
5783272: Jan 10 15:09:33.337 EST %MIVR-SS_RM-7-UNK:classifyCall: 2965426/1 84610
5783273: Jan 10 15:09:33.337 EST %MIVR-SS_RM-7-UNK:RmCm contact 19742642 [2965426/1] has internal type IAQ, contact type: IAQ for agent 0290084610
5783274: Jan 10 15:09:33.337 EST %MIVR-SS_RM-7-UNK:classifyCall 2965426/1 Rsrc: 0290084610 CallingAddr: 84610 LRAddr:null Type:IAQ(Normal)
5783275: Jan 10 15:09:33.337 EST %MIVR-SS_RM-7-UNK:RIMgrAddressCallObserver: TermConnActiveEv received for call 19742642 [2965426/1] and agent 0290084610
5783276: Jan 10 15:09:33.337 EST %MIVR-SS_CM-7-UNK:ContactMgr.getRmCmContact(2965426/1) returns 19742642 [2965426/1]
5783277: Jan 10 15:09:33.337 EST %MIVR-SS_RM-7-UNK:classifyCall: 2965426/1 84610
5783278: Jan 10 15:09:33.337 EST %MIVR-SS_RM-7-UNK:RmCm contact 19742642 [2965426/1] has internal type IAQ, contact type: IAQ for agent 0290084610
5783279: Jan 10 15:09:33.337 EST %MIVR-SS_RM-7-UNK:classifyCall 2965426/1 Rsrc: 0290084610 CallingAddr: 84610 LRAddr:null Type:IAQ(Normal)
5783280: Jan 10 15:09:33.337 EST %MIVR-SS_RM-7-UNK:RIMgrAddressCallObserver: CallCtlTermConnTalkingEv received for call 19742642 [2965426/1] and agent 0290084610
5783281: Jan 10 15:09:33.337 EST %MIVR-SS_CM-7-UNK:ContactMgr.getRmCmContact(2965426/1) returns 19742642 [2965426/1]
5783282: Jan 10 15:09:33.337 EST %MIVR-SS_RM-7-UNK:classifyCall: 2965426/1 84610
5783283: Jan 10 15:09:33.337 EST %MIVR-SS_RM-7-UNK:RmCm contact 19742642 [2965426/1] has internal type IAQ, contact type: IAQ for agent 0290084610
5783284: Jan 10 15:09:33.337 EST %MIVR-SS_RM-7-UNK:classifyCall 2965426/1 Rsrc: 0290084610 CallingAddr: 84610 LRAddr:null Type:IAQ(Normal)
5783285: Jan 10 15:09:33.353 EST %MIVR-SS_RM-7-UNK:Processing msg: SessionInitiatedMsg (Rsrc:0290084610 ID:2965426/1 Type:IAQ)
5783286: Jan 10 15:09:33.353 EST %MIVR-SS_RM-7-UNK:checkContacts(), agent's Reserved duration:6125 Threshold:20000
5783287: Jan 10 15:09:33.353 EST %MIVR-SS_CM-7-UNK:ContactMgr.getRmCmContact(2965426/1) returns 19742642 [2965426/1]
5783288: Jan 10 15:09:33.353 EST %MIVR-SS_RM-7-UNK:RIMgrAddressCallObserver: CallCtlConnEstablishedEv received for call:19742642 [2965426/1], address 84610, calling party 84610, and called party 0394583403
5783289: Jan 10 15:09:33.353 EST %MIVR-SS_RM-7-UNK:Rsrc: 0290084610 New State:IN_SESSION_PENDING_WORK Old State:RESERVED Reason code:0
5783290: Jan 10 15:09:33.353 EST %MIVR-SS_CM-7-UNK:ContactMgr.getRmCmContact(2965426/1) returns 19742642 [2965426/1]
5783291: Jan 10 15:09:33.353 EST %MIVR-SS_RM-7-UNK:classifyCall: 2965426/1 84610
5783292: Jan 10 15:09:33.353 EST %MIVR-SS_RM-7-UNK:RmCm contact 19742642 [2965426/1] has internal type IAQ, contact type: IAQ for agent 0290084610
5783293: Jan 10 15:09:33.353 EST %MIVR-SS_RM-7-UNK:Rsrc: 0290084610 Contacts:Contact ID:19742642 [2965426/1] Contact Class:IAQ App Contact Id:5007
5783294: Jan 10 15:09:33.353 EST %MIVR-SS_RM-7-UNK:classifyCall 2965426/1 Rsrc: 0290084610 CallingAddr: 84610 LRAddr:null Type:IAQ(Normal)
5783295: Jan 10 15:09:33.353 EST %MIVR-SS_RM-7-UNK:Rsrc: 0290084610 PrevState: AVAILABLE PrevStateSet:false
5783296: Jan 10 15:09:33.353 EST %MIVR-SS_RM-7-UNK:Rsrcs in ARL of ESD ASEC1112 2010 and 2011 Lapsed: (ASEC1112 2010 and 2011 Lapsed 0290084624 Order:16)(ASEC1112 2010 and 2011 Lapsed 0290084627 Order:20)
5783297: Jan 10 15:09:33.353 EST %MIVR-ICD_CTI-7-UNK:EventHandler: posting {AGENT_STATE_EVENT: Socket:Socket: null monitoredDeviceDN:84610, agentDN:84610, agentID:0290084610, monitorID = 0, stateDuration = 0, agentstate = IN_SESSION_PENDING_WORK, eventreasoncode = 0, agentID = 0290084610, agentExtension = 84610, agentID_Long = 0290084610 } to outboundQ
5783298: Jan 10 15:09:33.353 EST %MIVR-SS_RM-7-UNK:Processing msg: SessionActiveMsg (Rsrc:0290084610 ID:2965426/1 Type:IAQ)
5783299: Jan 10 15:09:33.353 EST %MIVR-SS_CM-7-UNK:ContactMgr.getRmCmContact(2965426/1) returns 19742642 [2965426/1]
5783300: Jan 10 15:09:33.353 EST %MIVR-SS_CM-7-UNK:ContactMgr.getRmCmContact(2965426/1) returns 19742642 [2965426/1]
5783301: Jan 10 15:09:33.353 EST %MIVR-SS_OB-7-UNK:OutboundRsrcStateChangedMsg:Processing msg: OutboundRsrcStateChangedMsg (Rsrc:0290084610, state:7)
5783302: Jan 10 15:09:33.353 EST %MIVR-SS_CM-7-UNK:ContactMgr.getRmCmContact(2965426/1) returns 19742642 [2965426/1]
5783303: Jan 10 15:09:33.353 EST %MIVR-SS_OB-7-UNK:Dialer:processOutboundRsrcStateChangedMsg() for 0290084610, state:7
5783304: Jan 10 15:09:33.353 EST %MIVR-SS_RM-7-UNK:SessionActiveMsg being ignored because contact 5007 is already connected to agent 0290084610
5783305: Jan 10 15:09:33.353 EST %MIVR-SS_CM-7-UNK:Processing msg: class com.cisco.wf.subsystems.rmcm.msgs.CMFireContactServiceInitiatedEventReqMsg Contact5007
5783306: Jan 10 15:09:33.353 EST %MIVR-SS_RM-7-UNK:Rsrc: 0290084610 Contacts:Contact ID:19742642 [2965426/1] Contact Class:IAQ App Contact Id:5007
5783307: Jan 10 15:09:33.353 EST %MIVR-SS_CM-7-UNK: IEF Contact ID: [2965426/1] 19742642 ContactEventsGenerator: Posting the ContactServiceInitiatedEvent Resource Rsrc Name:Charles Mesiku ID:0290084610 IAQ Extn:84610 contact type IAQ session 15000004999 session seq num 0
5783308: Jan 10 15:09:33.353 EST %MIVR-SS_RM-7-UNK:Rsrc: 0290084610 PrevState: AVAILABLE PrevStateSet:false
5783309: Jan 10 15:09:33.353 EST %MIVR-SS_RM-7-UNK:Rsrcs in ARL of ESD ASEC1112 2010 and 2011 Lapsed: (ASEC1112 2010 and 2011 Lapsed 0290084624 Order:16)(ASEC1112 2010 and 2011 Lapsed 0290084627 Order:20)
5783310: Jan 10 15:09:33.353 EST %MIVR-SS_RM-7-UNK:Processing msg: SessionTalkingMsg (Rsrc:0290084610 ID:2965426/1 Type:IAQ cause:DEFAULT)
5783311: Jan 10 15:09:33.353 EST %MIVR-ICD_CTI-7-UNK:EventHandler: posting {CALL_SERVICE_INITIATED_EVENT: Socket:Socket: null monitoredDeviceDN:0, monitorID: 0, serviceNumber: -1, skillGroupNumber: 9, connectionCallID: 19742642, connectionDeviceID: 84610, callingDeviceID: 84610 } to outboundQ
5783312: Jan 10 15:09:33.353 EST %MIVR-SS_CM-7-UNK:ContactMgr.getRmCmContact(2965426/1) returns 19742642 [2965426/1]
5783313: Jan 10 15:09:33.353 EST %MIVR-SS_RM-7-UNK:Rsrc: 0290084610 Contacts:Contact ID:19742642 [2965426/1] Contact Class:IAQ App Contact Id:5007
5783314: Jan 10 15:09:33.353 EST %MIVR-SS_RM-7-UNK:Rsrc: 0290084610 PrevState: AVAILABLE PrevStateSet:false
5783315: Jan 10 15:09:33.353 EST %MIVR-SS_RM-7-UNK:Rsrcs in ARL of ESD ASEC1112 2010 and 2011 Lapsed: (ASEC1112 2010 and 2011 Lapsed 0290084624 Order:16)(ASEC1112 2010 and 2011 Lapsed 0290084627 Order:20)
5783316: Jan 10 15:09:33.353 EST %MIVR-SS_RM-7-UNK:Processing msg: OutboundSessionEstablishedMsg (Rsrc:0290084610 ID:2965426/1)
5783317: Jan 10 15:09:33.353 EST %MIVR-LIB_EVENT-7-POSTING_EVENT:: Event=com.cisco.wf.subsystems.events.RsrcStateChangedEvent[] on Rsrc Name:Charles Mesiku ID:0290084610 IAQ Extn:84610 at Tue Jan 10 15:09:33 EST 2012
5783318: Jan 10 15:09:33.353 EST %MIVR-SS_RM-7-UNK:Agent 0290084610 monitoring contacts:
5783319: Jan 10 15:09:33.353 EST %MIVR-SS_CM-7-UNK:ContactMgr.getRmCmContact(2965426/1) returns 19742642 [2965426/1]
5783320: Jan 10 15:09:33.353 EST %MIVR-LIB_EVENT-7-POSTING_EVENT:: Event=com.cisco.wf.subsystems.events.ContactServiceInitiatedEvent[] on com.cisco.wf.subsystems.rmcm.CallContact@1f6718d at Tue Jan 10 15:09:33 EST 2012
5783321: Jan 10 15:09:33.353 EST %MIVR-ICD_CTI-7-UNK:OutboundMsgProcessor: got message: { AGENT_STATE_EVENT }
5783322: Jan 10 15:09:33.353 EST %MIVR-SS_CM-7-UNK:Processing msg: class com.cisco.wf.subsystems.rmcm.msgs.CMFireContactOriginatedEventReqMsg Contact5007
5783323: Jan 10 15:09:33.353 EST %MIVR-SS_RM-7-UNK:Processing msg: OutboundSessionOfferedMsg (Rsrc:0290084610 ID:2965426/1 Type:IAQ)
5783324: Jan 10 15:09:33.353 EST %MIVR-SS_CM-7-UNK: IEF Contact ID: [2965426/1] 19742642 ContactEventsGenerator: Posting the ContactOriginatedEvent Resource Rsrc Name:Charles Mesiku ID:0290084610 IAQ Extn:84610 contact type IAQ session 15000004999 session seq num 0
5783325: Jan 10 15:09:33.353 EST %MIVR-SS_RM-7-UNK:isCTIRoutePoint, addr: 0394583403:false
5783326: Jan 10 15:09:33.353 EST %MIVR-SS_CM-7-UNK:ContactMgr.getRmCmContact(2965426/1) returns 19742642 [2965426/1]
5783327: Jan 10 15:09:33.353 EST %MIVR-SS_RM-7-UNK:isCTIRoutePoint, addr: 0394583403:false
5783328: Jan 10 15:09:33.353 EST %MIVR-SS_RM-7-UNK:Agent processOutboundSessionOfferedMsg null 0290084610 being ignored because it isn't for non-IAQ call
5783329: Jan 10 15:09:33.353 EST %MIVR-ICD_CTI-7-UNK:CSOutboundMsgProcessor: got an event message:{ length=-1 type=AGENT_STATE_EVENT, monitorID=0, peripheralID=1, sessionID=0, peripheralType=21, skillGroupState=4, stateDuration=0, skillGroupNumber=-1, skillGroupID=-1, skillGroupPriority=0, agentstate=TALKING, eventReasonCode=0, MRDid=1, numTasks=0, agentMode=0, maxTaskLimit=0, ICMAgentID=0, agentAvailabilityStatus=1, numFltSkillGroups=0, CTIClientSignature=null, agentID=0290084610, agentExtension=84610, agentInstrument=null, agentID_Long=0290084610, duration=-1, nextAgentState=5 }
5783330: Jan 10 15:09:33.353 EST %MIVR-ICD_CTI-3-Called Address in CALL_ORIGINATED_EVENT -- CRACTIEvenHandler :0394583403:Undefined mnemonic 'Called Address in CALL_ORIGINATED_EVENT -- CRACTIEvenHandler :0394583403'
5783331: Jan 10 15:09:33.353 EST %MIVR-SS_RM-7-UNK:isCTIRoutePoint, addr: 84610:false
5783332: Jan 10 15:09:33.353 EST %MIVR-SS_RM-7-UNK:isCTIRoutePoint, addr: 0394583403:false
5783333: Jan 10 15:09:33.353 EST %MIVR-ICD_CTI-7-UNK:EventHandler: posting {CALL_ORIGINATED_EVENT: Socket:Socket: null monitoredDeviceDN:0, monitorID: 0, serviceNumber: -1, skillGroupNumber: 9, connectionCallID: 19742642, connectionDeviceID: 84610, callingDeviceID: 84610, calledDeviceID: 0394583403, calledDeviceType: 0, callingDeviceType: 76 } to outboundQ
5783334: Jan 10 15:09:33.353 EST %MIVR-LIB_EVENT-7-POSTING_EVENT:: Event=com.cisco.wf.subsystems.events.ContactOriginatedEvent[] on com.cisco.wf.subsystems.rmcm.CallContact@1f6718d at Tue Jan 10 15:09:33 EST 2012
5783335: Jan 10 15:09:33.353 EST %MIVR-ICD_CTI-7-UNK:OutboundMsgProcessor got AGENT_STATE_EVENT after updating clientInfoTable: Socket to SocketInfo:
: Socket[addr=10.128.1.200,port=49421,localport=42027], SocketInfo:ServicesGranted:794102 idleTimeout:120000 timeLastMsgRcvd:Tue Jan 10 15:09:17 EST 2012 executor:com.cisco.executor.QueuedExecutor@eca93c outputstream:com.cisco.Ged188.io.CRACTIOutputStream@15ad0c6 sessionValid:true Client ID:10.128.1.200
: Socket[addr=10.1.5.146,port=56512,localport=42027], SocketInfo:ServicesGranted:7 idleTimeout:120000 timeLastMsgRcvd:Tue Jan 10 15:09:11 EST 2012 executor:com.cisco.executor.QueuedExecutor@4f0b53 outputstream:com.cisco.Ged188.io.CRACTIOutputStream@170785a sessionValid:true Client ID:0290084619
: Socket[addr=10.1.5.146,port=56613,localport=42027], SocketInfo:ServicesGranted:7 idleTimeout:120000 timeLastMsgRcvd:Tue Jan 10 15:09:07 EST 2012 executor:com.cisco.executor.QueuedExecutor@e97c34 outputstream:com.cisco.Ged188.io.CRACTIOutputStream@d944a5 sessionValid:true Client ID:0290084614
: Socket[addr=10.128.1.200,port=49433,localport=42027], SocketInfo:ServicesGranted:262198 idleTimeout:120000 timeLastMsgRcvd:Tue Jan 10 15:09:33 EST 2012 executor:com.cisco.executor.QueuedExecutor@100078c outputstream:com.cisco.Ged188.io.CRACTIOutputStream@ef7efe sessionValid:true Client ID:Enterprise Server
: Socket[addr=10.1.5.148,port=64067,localport=42027], SocketInfo:ServicesGranted:7 idleTimeout:120000 timeLastMsgRcvd:Tue Jan 10 15:09:27 EST 2012 executor:com.cisco.executor.QueuedExecutor@21220e outputstream:com.cisco.Ged188.io.CRACTIOutputStream@142f87d sessionValid:true Client ID:0290084627
: Socket[addr=10.1.5.148,port=64697,localport=42027], SocketInfo:ServicesGranted:7 idleTimeout:120000 timeLastMsgRcvd:Tue Jan 10 15:09:32 EST 2012 executor:com.cisco.executor.QueuedExecutor@316aa3 outputstream:com.cisco.Ged188.io.CRACTIOutputStream@503308 sessionValid:true Client ID:0290084657
: Socket[addr=10.1.5.148,port=64353,localport=42027], SocketInfo:ServicesGranted:7 idleTimeout:120000 timeLastMsgRcvd:Tue Jan 10 15:09:21 EST 2012 executor:com.cisco.executor.QueuedExecutor@11c8dd9 outputstream:com.cisco.Ged188.io.CRACTIOutputStream@1a4c815 sessionValid:true Client ID:0290084607
: Socket[addr=10.1.5.148,port=64662,localport=42027], SocketInfo:ServicesGranted:7 idleTimeout:120000 timeLastMsgRcvd:Tue Jan 10 15:09:31 EST 2012 executor:com.cisco.executor.QueuedExecutor@1784e00 outputstream:com.cisco.Ged188.io.CRACTIOutputStream@148e2c1 sessionValid:true Client ID:0290084610
: Socket[addr=10.128.1.200,port=49417,localport=42027], SocketInfo:ServicesGranted:22 idleTimeout:120000 timeLastMsgRcvd:Tue Jan 10 15:09:16 EST 2012 executor:com.cisco.executor.QueuedExecutor@12a642 outputstream:com.cisco.Ged188.io.CRACTIOutputStream@615f19 sessionValid:true Client ID:10.128.1.200IPPASvr
: Socket[addr=10.1.5.148,port=64382,localport=42027], SocketInfo:ServicesGranted:7 idleTimeout:120000 timeLastMsgRcvd:Tue Jan 10 15:09:29 EST 2012 executor:com.cisco.executor.QueuedExecutor@fdc45b outputstream:com.cisco.Ged188.io.CRACTIOutputStream@6634ec sessionValid:true Client ID:0290084624
Socket To AgentInfo
Socket[addr=10.128.1.200,port=49421,localport=42027], Socket[addr=10.1.5.146,port=56512,localport=42027], AgentID:0290084619 , AgentDN:84619
Socket[addr=10.1.5.146,port=56613,localport=42027], AgentID:0290084614 , AgentDN:84614
Socket[addr=10.128.1.200,port=49433,localport=42027], Socket[addr=10.1.5.148,port=64067,localport=42027], AgentID:0290084627 , AgentDN:84627
Socket[addr=10.1.5.148,port=64697,localport=42027], AgentID:0290084657 , AgentDN:84657
Socket[addr=10.1.5.148,port=64353,localport=42027], AgentID:0290084607 , AgentDN:84607
Socket[addr=10.1.5.148,port=64662,localport=42027], AgentID:0290084610 , AgentDN:84610
Socket[addr=10.128.1.200,port=49417,localport=42027], Socket[addr=10.1.5.148,port=64382,localport=42027], AgentID:0290084624 , AgentDN:84624
Mask To Sockets
Mask: 128 - Sockets: Socket[addr=10.128.1.200,port=49421,localport=42027],
Mask: 16 - Sockets: Socket[addr=10.128.1.200,port=49417,localport=42027],
Socket[addr=10.128.1.200,port=49421,localport=42027],
Socket[addr=10.128.1.200,port=49433,localport=42027],
Mask: 262144 - Sockets: Socket[addr=10.128.1.200,port=49421,localport=42027],
Socket[addr=10.128.1.200,port=49433,localport=42027],
Mask: 1 - Sockets: Socket[addr=10.1.5.148,port=64067,localport=42027],
Socket[addr=10.1.5.148,port=64353,localport=42027],
Socket[addr=10.1.5.148,port=64382,localport=42027],
Socket[addr=10.1.5.146,port=56512,localport=42027],
Socket[addr=10.1.5.146,port=56613,localport=42027],
Socket[addr=10.1.5.148,port=64662,localport=42027],
Socket[addr=10.1.5.148,port=64697,localport=42027],

5783336: Jan 10 15:09:33.353 EST %MIVR-ICD_CTI-7-UNK:OutboundMessageprocessor : sending msg : { length=-1 type=AGENT_STATE_EVENT, monitorID=0, peripheralID=1, sessionID=0, peripheralType=21, skillGroupState=4, stateDuration=0, skillGroupNumber=-1, skillGroupID=-1, skillGroupPriority=0, agentstate=TALKING, eventReasonCode=0, MRDid=1, numTasks=0, agentMode=0, maxTaskLimit=0, ICMAgentID=0, agentAvailabilityStatus=1, numFltSkillGroups=0, CTIClientSignature=null, agentID=0290084610, agentExtension=84610, agentInstrument=null, agentID_Long=0290084610, duration=-1, nextAgentState=5 to various sockets }
5783337: Jan 10 15:09:33.368 EST %MIVR-ICD_CTI-7-UNK:MsgHandler : Sent : { AGENT_STATE_EVENT to Socket[addr=10.128.1.200,port=49433,localport=42027]  }
5783338: Jan 10 15:09:33.368 EST %MIVR-ICD_CTI-7-UNK:OutboundMsgProcessor: got message: { CALL_SERVICE_INITIATED_EVENT }
5783339: Jan 10 15:09:33.368 EST %MIVR-ICD_CTI-7-UNK:CSOutboundMsgProcessor: got an event message:{ length=-1 type=CALL_SERVICE_INITIATED_EVENT, monitorID: 0, peripheralID: 1, peripheralType: 21, connectionDeviceIDType: 0, connectionCallID: 19742642, lineHandle: 65535, lineType: 3, serviceNumber: -1, serviceID: -1, skillGroupNumber: 9, skillGroupID: -1, skillGroupPriority: 0, callingDeviceType: 76, localConnectionState: 1, eventCause: 65535, connectionDeviceID: 84610, callingDeviceID: 84610 }
5783340: Jan 10 15:09:33.368 EST %MIVR-ICD_CTI-7-UNK:MsgHandler : Sent : { AGENT_STATE_EVENT to Socket[addr=10.128.1.200,port=49417,localport=42027]  }
5783341: Jan 10 15:09:33.368 EST %MIVR-ICD_CTI-7-UNK:OutboundMessageprocessor : sending msg : { length=-1 type=CALL_SERVICE_INITIATED_EVENT, monitorID: 0, peripheralID: 1, peripheralType: 21, connectionDeviceIDType: 0, connectionCallID: 19742642, lineHandle: 65535, lineType: 3, serviceNumber: -1, serviceID: -1, skillGroupNumber: 9, skillGroupID: -1, skillGroupPriority: 0, callingDeviceType: 76, localConnectionState: 1, eventCause: 65535, connectionDeviceID: 84610, callingDeviceID: 84610 to various sockets }
5783342: Jan 10 15:09:33.368 EST %MIVR-ICD_CTI-7-UNK:MsgHandler : Sent : { CALL_SERVICE_INITIATED_EVENT to Socket[addr=10.128.1.200,port=49433,localport=42027]  CID: 19742642 }
5783343: Jan 10 15:09:33.368 EST %MIVR-ICD_CTI-7-UNK:MsgHandler : Sent : { AGENT_STATE_EVENT to Socket[addr=10.1.5.148,port=64662,localport=42027]  }
5783344: Jan 10 15:09:33.368 EST %MIVR-ICD_CTI-7-UNK:OutboundMsgProcessor: got message: { CALL_ORIGINATED_EVENT }
5783345: Jan 10 15:09:33.368 EST %MIVR-ICD_CTI-7-UNK:MsgHandler : Sent : { CALL_SERVICE_INITIATED_EVENT to Socket[addr=10.1.5.148,port=64662,localport=42027]  CID: 19742642 }
5783346: Jan 10 15:09:33.368 EST %MIVR-ICD_CTI-7-UNK:CSOutboundMsgProcessor: got an event message:{ length=-1 type=CALL_ORIGINATED_EVENT, monitorID: 0, peripheralID: 1, peripheralType: 21, connectionDeviceIDType: 0, connectionCallID: 19742642, lineHandle: 65535, lineType: 7, serviceNumber: -1, serviceID: -1, skillGroupNumber: 9, skillGroupID: -1, skillGroupPriority: 0, callingDeviceType: 76, calledDeviceType: 0, localConnectionState: 1, eventCause: 65535, connectionDeviceID: 84610, callingDeviceID: 84610, calledDeviceID: 0394583403 }
5783347: Jan 10 15:09:33.368 EST %MIVR-ICD_CTI-7-UNK:OutboundMessageprocessor : sending msg : { length=-1 type=CALL_ORIGINATED_EVENT, monitorID: 0, peripheralID: 1, peripheralType: 21, connectionDeviceIDType: 0, connectionCallID: 19742642, lineHandle: 65535, lineType: 7, serviceNumber: -1, serviceID: -1, skillGroupNumber: 9, skillGroupID: -1, skillGroupPriority: 0, callingDeviceType: 76, calledDeviceType: 0, localConnectionState: 1, eventCause: 65535, connectionDeviceID: 84610, callingDeviceID: 84610, calledDeviceID: 0394583403 to various sockets }
5783348: Jan 10 15:09:33.368 EST %MIVR-ICD_CTI-7-UNK:MsgHandler : Sent : { CALL_SERVICE_INITIATED_EVENT to Socket[addr=10.128.1.200,port=49417,localport=42027]  CID: 19742642 }
5783349: Jan 10 15:09:33.368 EST %MIVR-ICD_CTI-7-UNK:MsgHandler : Sent : { CALL_ORIGINATED_EVENT to Socket[addr=10.128.1.200,port=49433,localport=42027]  CID: 19742642 }
5783350: Jan 10 15:09:33.368 EST %MIVR-ICD_CTI-7-UNK:MsgHandler : Sent : { CALL_ORIGINATED_EVENT to Socket[addr=10.128.1.200,port=49417,localport=42027]  CID: 19742642 }
5783351: Jan 10 15:09:33.368 EST %MIVR-ICD_CTI-7-UNK:MsgHandler : Sent : { CALL_ORIGINATED_EVENT to Socket[addr=10.1.5.148,port=64662,localport=42027]  CID: 19742642 }
5783352: Jan 10 15:09:34.228 EST %MIVR-SS_OB-7-UNK:OutboundMgrMsgProcessor:Processing msg: OutboundCSQIterationMsg (CsqID:9)
5783353: Jan 10 15:09:34.228 EST %MIVR-SS_OB-7-UNK:OutboundContactInfo:getNumberContactsToCall() for csqID:9 :3
5783354: Jan 10 15:09:34.228 EST %MIVR-SS_OB-7-UNK:Dialer:processOutboundCSQIterationMsg() #active%:6.0, #active%-working:3, #lic_left:0, #contacts:3
5783355: Jan 10 15:09:35.228 EST %MIVR-SS_OB-7-UNK:OutboundMgrMsgProcessor:Processing msg: OutboundCSQIterationMsg (CsqID:9)
5783356: Jan 10 15:09:35.228 EST %MIVR-SS_OB-7-UNK:OutboundContactInfo:getNumberContactsToCall() for csqID:9 :3
5783357: Jan 10 15:09:35.228 EST %MIVR-SS_OB-7-UNK:Dialer:processOutboundCSQIterationMsg() #active%:6.0, #active%-working:3, #lic_left:0, #contacts:3
5783358: Jan 10 15:09:35.478 EST %MIVR-ICD_CTI-7-UNK:Received message SNAPSHOT_DEVICE_REQ
Message dump, excluding msg header (Length = 18 bytes):
0 0 0 C 0 0 0 1 0 L 6 6 8 4 6 5 7 0
5783359: Jan 10 15:09:35.478 EST %MIVR-ICD_CTI-7-UNK:ClientConnMgr: Processing msg on socket:Socket[addr=10.1.5.148,port=64697,localport=42027] Msg is {length=18 type=SNAPSHOT_DEVICE_REQ,invokeId=67,peripheralID =1,deviceIDType =76,agentInstrument =84657 }
5783360: Jan 10 15:09:35.478 EST %MIVR-ICD_CTI-7-UNK:InboundMsgProcessor : Processing msg: { msg: length=18 type=SNAPSHOT_DEVICE_REQ,invokeId=67,peripheralID =1,deviceIDType =76,agentInstrument =84657 socket: Socket[addr=10.1.5.148,port=64697,localport=42027] }
5783361: Jan 10 15:09:35.478 EST %MIVR-ICD_CTI-7-UNK:CSInboundMsgProcessor processing : SNAPSHOT_DEVICE_REQ
5783362: Jan 10 15:09:35.478 EST %MIVR-ICD_CTI-7-UNK:Socket info: ServicesGranted:7 idleTimeout:120000 timeLastMsgRcvd:Tue Jan 10 15:09:35 EST 2012 executor:com.cisco.executor.QueuedExecutor@316aa3 outputstream:com.cisco.Ged188.io.CRACTIOutputStream@503308 sessionValid:true Client ID:0290084657
5783363: Jan 10 15:09:35.478 EST %MIVR-ICD_CTI-7-UNK:InboundMsgProcessor : posting msg: { CTISnapshotDeviceReqMsg CTIMgrTPCCReqMsg Socket:Socket[addr=10.1.5.148,port=64697,localport=42027] invokeID:67, peripheralID: 1, deviceIDType: 76, agentInstrument: 84657 } to rm MsgQ
5783364: Jan 10 15:09:35.478 EST %MIVR-SS_RM-7-UNK:Processing msg: CTISnapshotDeviceReqMsg CTIMgrTPCCReqMsg Socket:Socket[addr=10.1.5.148,port=64697,localport=42027] invokeID:67, peripheralID: 1, deviceIDType: 76, agentInstrument: 84657
5783365: Jan 10 15:09:35.478 EST %MIVR-SS_RM-7-UNK:Invoked SnapshotDeviceReqMsgHandler.handle() : Input Message: CTISnapshotDeviceReqMsg CTIMgrTPCCReqMsg Socket:Socket[addr=10.1.5.148,port=64697,localport=42027] invokeID:67, peripheralID: 1, deviceIDType: 76, agentInstrument: 84657
5783366: Jan 10 15:09:35.478 EST %MIVR-ICD_CTI-7-UNK:OutboundMsgProcessor: got message: { SNAPSHOT_DEVICE_CONF }
5783367: Jan 10 15:09:35.478 EST %MIVR-ICD_CTI-7-UNK:CSOutboundMsgProcessor: got a conf message and sending it on { Socket[addr=10.1.5.148,port=64697,localport=42027] msg: 85 and invokeid: 67 }
5783368: Jan 10 15:09:35.478 EST %MIVR-ICD_CTI-7-UNK:OutboundMessageprocessor : sending msg : { length=-1 type=SNAPSHOT_DEVICE_CONF,invokeId=67 numCalls=0 to socket: Socket[addr=10.1.5.148,port=64697,localport=42027] }
5783369: Jan 10 15:09:35.478 EST %MIVR-ICD_CTI-7-UNK:OutboundMessageprocessor : Q size after posting msg : { length=-1 type=SNAPSHOT_DEVICE_CONF,invokeId=67 numCalls=0} to socket: Socket[addr=10.1.5.148,port=64697,localport=42027] is : 0
5783370: Jan 10 15:09:35.478 EST %MIVR-ICD_CTI-7-UNK:MsgHandler : Sent : { SNAPSHOT_DEVICE_CONF to Socket[addr=10.1.5.148,port=64697,localport=42027]  }
5783371: Jan 10 15:09:35.525 EST %MIVR-SS_OB-7-UNK:Dialer:setNumberActiveRsrcs() csqID:9, activeRsrcs:5
5783372: Jan 10 15:09:35.525 EST %MIVR-SS_OB-7-UNK:ActiveResourcesEstimator set stats for csqID:9, numActive:5
5783373: Jan 10 15:09:36.228 EST %MIVR-SS_OB-7-UNK:OutboundMgrMsgProcessor:Processing msg: OutboundCSQIterationMsg (CsqID:9)
5783374: Jan 10 15:09:36.228 EST %MIVR-SS_OB-7-UNK:OutboundContactInfo:getNumberContactsToCall() for csqID:9 :3
5783375: Jan 10 15:09:36.228 EST %MIVR-SS_OB-7-UNK:Dialer:processOutboundCSQIterationMsg() #active%:5.0, #active%-working:2, #lic_left:0, #contacts:3
5783376: Jan 10 15:09:36.556 EST %MIVR-LIB_EVENT-5-TOO_LONG_IN_QUEUE:Event queue time exceeded: Event=com.cisco.call.CallEvent[CALL_DISCONNECTED,state=CALL_DISCONNECTEDtreatment=NONE,isRemote=false,contactImplId=2965424/1,lastContactImplId=2965424/1,session=Session[id=001-0x37e11e986,parent=null,active=true,state=SESSION_IDLE,time=1326168567228],lastSession=Session[id=001-0x37e11e986,parent=null,active=true,state=SESSION_IDLE,time=1326168567228],contactSeqNum=0,lastContactSeqNum=0,treatment=NONE] on CallContact[id=5006,type=Cisco Agent Call,implId=2965424/1,active=false,state=CALL_DISCONNECTED,inbound=false,handled=false,locale=en_US,aborting=false,app=null,task=null,session=Session[id=001-0x37e11e986,parent=null,active=true,state=SESSION_IDLE,time=1326168567228],seqNum=0,time=1326168567228,cn=null,dn=null,cgn=null,ani=null,dnis=null,clid=null,atype=UNKNOWN,lrd=null,ocn=null,odn=null,uui=null,aniii=null,ced=null] at Tue Jan 10 15:09:31 EST 2012,Queue Time=5000
5783377: Jan 10 15:09:36.556 EST %MIVR-SS_CM-7-UNK:ICDContactAdapter 5006 : ContactTerminated received for App FW contact 5006, iefSourceContact is 19742640 [2965424/1] (5006)
5783378: Jan 10 15:09:36.556 EST %MIVR-LIB_EVENT-7-POSTING_EVENT:: Event=com.cisco.call.CallEvent[CALL_DISCONNECTED,state=CALL_DISCONNECTEDtreatment=NONE,isRemote=false,contactImplId=2965424/1,lastContactImplId=2965424/1,session=Session[id=001-0x37e11e986,parent=null,active=true,state=SESSION_IDLE,time=1326168567228],lastSession=Session[id=001-0x37e11e986,parent=null,active=true,state=SESSION_IDLE,time=1326168567228],contactSeqNum=0,lastContactSeqNum=0,treatment=NONE] on CallContact[id=5006,type=Cisco Agent Call,implId=2965424/1,active=false,state=CALL_DISCONNECTED,inbound=false,handled=false,locale=en_US,aborting=false,app=null,task=null,session=Session[id=001-0x37e11e986,parent=null,active=true,state=SESSION_IDLE,time=1326168567228],seqNum=0,time=1326168567228,cn=null,dn=null,cgn=null,ani=null,dnis=null,clid=null,atype=UNKNOWN,lrd=null,ocn=null,odn=null,uui=null,aniii=null,ced=null] at Tue Jan 10 15:09:31 EST 2012
5783379: Jan 10 15:09:37.228 EST %MIVR-SS_OB-7-UNK:OutboundMgrMsgProcessor:Processing msg: OutboundCSQIterationMsg (CsqID:9)
5783380: Jan 10 15:09:37.228 EST %MIVR-SS_OB-7-UNK:OutboundContactInfo:getNumberContactsToCall() for csqID:9 :3
5783381: Jan 10 15:09:37.228 EST %MIVR-SS_OB-7-UNK:Dialer:processOutboundCSQIterationMsg() #active%:5.0, #active%-working:2, #lic_left:0, #contacts:3
5783382: Jan 10 15:09:37.681 EST %MIVR-ICD_CTI-7-UNK:Received message HEARTBEAT_REQ
Message dump, excluding msg header (Length = 4 bytes):
0 0 0 12
5783383: Jan 10 15:09:37.681 EST %MIVR-ICD_CTI-7-UNK:ClientConnMgr: Processing msg on socket:Socket[addr=10.1.5.146,port=56613,localport=42027] Msg is {length=4 type=HEARTBEAT_REQ,invokeId=18 }
5783384: Jan 10 15:09:37.681 EST %MIVR-ICD_CTI-7-UNK:InboundMsgProcessor : Processing msg: { msg: length=4 type=HEARTBEAT_REQ,invokeId=18 socket: Socket[addr=10.1.5.146,port=56613,localport=42027] }
5783385: Jan 10 15:09:37.681 EST %MIVR-ICD_CTI-7-UNK:InboundMsgProcessor : posting HB conf: { HEARTBEAT_CONF Socket:Socket[addr=10.1.5.146,port=56613,localport=42027] invokeID:18 }
5783386: Jan 10 15:09:37.681 EST %MIVR-ICD_CTI-7-UNK:OutboundMsgProcessor: got message: { HEARTBEAT_CONF }
5783387: Jan 10 15:09:37.681 EST %MIVR-ICD_CTI-7-UNK:CSOutboundMsgProcessor: got a conf message and sending it on { Socket[addr=10.1.5.146,port=56613,localport=42027] msg: 6 and invokeid: 18 }
5783388: Jan 10 15:09:37.681 EST %MIVR-ICD_CTI-7-UNK:OutboundMessageprocessor : sending msg : { length=-1 type=HEARTBEAT_CONF,invokeId=18 to socket: Socket[addr=10.1.5.146,port=56613,localport=42027] }
5783389: Jan 10 15:09:37.681 EST %MIVR-ICD_CTI-7-UNK:OutboundMessageprocessor : Q size after posting msg : { length=-1 type=HEARTBEAT_CONF,invokeId=18} to socket: Socket[addr=10.1.5.146,port=56613,localport=42027] is : 0
5783390: Jan 10 15:09:37.681 EST %MIVR-ICD_CTI-7-UNK:MsgHandler : Sent : { HEARTBEAT_CONF to Socket[addr=10.1.5.146,port=56613,localport=42027]  }
5783391: Jan 10 15:09:38.228 EST %MIVR-SS_OB-7-UNK:OutboundMgrMsgProcessor:Processing msg: OutboundCSQIterationMsg (CsqID:9)
5783392: Jan 10 15:09:38.228 EST %MIVR-SS_OB-7-UNK:OutboundContactInfo:getNumberContactsToCall() for csqID:9 :3
5783393: Jan 10 15:09:38.228 EST %MIVR-SS_OB-7-UNK:Dialer:processOutboundCSQIterationMsg() #active%:5.0, #active%-working:2, #lic_left:0, #contacts:3
5783394: Jan 10 15:09:39.228 EST %MIVR-SS_OB-7-UNK:OutboundMgrMsgProcessor:Processing msg: OutboundCSQIterationMsg (CsqID:9)
5783395: Jan 10 15:09:39.228 EST %MIVR-SS_OB-7-UNK:OutboundContactInfo:getNumberContactsToCall() for csqID:9 :3
5783396: Jan 10 15:09:39.228 EST %MIVR-SS_OB-7-UNK:Dialer:processOutboundCSQIterationMsg() #active%:5.0, #active%-working:2, #lic_left:0, #contacts:3
5783397: Jan 10 15:09:39.447 EST %MIVR-LIB_AXL-7-UNK:Keep-alive successful 1f44b84[TLS_RSA_WITH_AES_128_CBC_SHA: Socket[addr=10.128.1.11/10.128.1.11,port=8443,localport=49624]]
5783398: Jan 10 15:09:40.228 EST %MIVR-SS_OB-7-UNK:OutboundMgrMsgProcessor:Processing msg: OutboundCSQIterationMsg (CsqID:9)
5783399: Jan 10 15:09:40.228 EST %MIVR-SS_OB-7-UNK:OutboundContactInfo:getNumberContactsToCall() for csqID:9 :3
5783400: Jan 10 15:09:40.228 EST %MIVR-SS_OB-7-UNK:Dialer:processOutboundCSQIterationMsg() #active%:5.0, #active%-working:2, #lic_left:0, #contacts:3
5783401: Jan 10 15:09:41.228 EST %MIVR-SS_OB-7-UNK:OutboundMgrMsgProcessor:Processing msg: OutboundCSQIterationMsg (CsqID:9)
5783402: Jan 10 15:09:41.228 EST %MIVR-SS_OB-7-UNK:OutboundContactInfo:getNumberContactsToCall() for csqID:9 :3
5783403: Jan 10 15:09:41.228 EST %MIVR-SS_OB-7-UNK:Dialer:processOutboundCSQIterationMsg() #active%:5.0, #active%-working:2, #lic_left:0, #contacts:3

5783400: Jan 10 15:09:40.228 EST %MIVR-SS_OB-7-UNK:Dialer:processOutboundCSQIterationMsg() #active%:5.0, #active%-working:2, #lic_left:0, #contacts:3

5783401: Jan 10 15:09:41.228 EST %MIVR-SS_OB-7-UNK:OutboundMgrMsgProcessor:Processing msg: OutboundCSQIterationMsg (CsqID:9)

5783402: Jan 10 15:09:41.228 EST %MIVR-SS_OB-7-UNK:OutboundContactInfo:getNumberContactsToCall() for csqID:9 :3

5783403: Jan 10 15:09:41.228 EST %MIVR-SS_OB-7-UNK:Dialer:processOutboundCSQIterationMsg() #active%:5.0, #active%-working:2, #lic_left:0, #contacts:3

Please see below extract from MIVR logs:

1067
Views
0
Helpful
0
Replies
CreatePlease login to create content