Demasiadas llamadas abortadas en UCCX ver 11.6

[vladimirromero]

Que tal Team, 

Se presenta mucho el problema en llamadas abortadas en un UCCX 11.6, se anexan Script y lineas de logs  donde se muestran los errores constantes:

Date: Jun 11 16:01:33
Machine Name: CCPub
Severity: Error
App ID: Cisco Unified CCX Engine
Message: : 3619: CCPUB.xxx: Jun 11 2021 21:01:33.988 UTC : %UC_SS_TEL-3-WAIT_FOR_CALL_AND_MEDIA_ACTIVE_FAILED: %[UNKNOWN_PARAMTYPE:Time Lapse=5000][allCallIds=CallID:52529 MediaId:6703714/3 Task:42000312864][ClusterID=][NodeID=CCPUB.xxx]: Waiting for call and media active failed

Date: Jun 11 16:01:33
Machine Name: CCPUB.xxx
Severity: Error
App ID: Cisco Unified CCX Engine
Message: : 3620: CCPUB.xxx: Jun 11 2021 21:01:33.998 UTC : %UC_APP_MGR-3-TASK_ABORTED: %[app.trigger=ContactApplicationTrigger[time=1623445288978,locale=es_MX,cfg=JTAPITriggerConfig[schema=ApplicationTriggerConfig,time=2020-05-20 ][exception=com.cisco.channel.ChannelExecutionException: Contact id: 52529, Channel id: 15, Reason: UNCAUGHT_EXCEPTION, CallID:52529 MediaId:][UNKNOWN_PARAMTYPE:Task id=42000312864][app=App[name=Ped_CCX,type=Cisco Script Application,id=6,desc=Ped_CCX,enabled=true,max=25,valid=true,cfg=[Applicatio][task.class=class com.cisco.app.impl.WFWorkflowAppDebugTaskWrapper][ClusterID=][NodeID=CCPUB.xxx]: Application task aborted

Si alguien me puede apoyar por favor se los agradeceria...

[Jonas Fraga]

Hello @vladimirromero 

Not sure the root cause, but with a log from CUCM and UCCX will be better to understand who and when the call are missed.

Sounds like UCCX just receive a media failed connection from CUCM and then drop the call, but it doesn't show us if the call are dropped first on CUCM, UCCX, VG, PSTN...

Can you collect SIP trace information from CUCM, UCCX and VG with disconnect cause?

As far as I understand on your print the call are missed when select resource step are pointed, right?

[vladimirromero]

Thanks Jonas,

I got this part of logs from the UCCX and the CUCM

UCCX:

231820806: Jun 14 17:16:48.338 CDT %MIVR-ENG-7-UNK:[MIVR_ENG_TASKS-31-22-TASK:0x9c76ab9cd_Ags GN/Pedidos_UCCX_GNAgs_SIN_PROMO.aef] WFTracingLogImpl: Notifying Debug Task Aborted
231820807: Jun 14 17:16:48.338 CDT %MIVR-ENG-7-UNK:[MIVR_ENG_TASKS-31-22] ExecutorStubImpl: Executor trace: Action=CMD_DONE,Executor id=TASKS,Mnemonic=TASK:0x9c76ab9cd_Ags GN/Pedidos_UCCX_GNAgs_SIN_PROMO.aef,Executor type=null,Thread=MIVR_ENG_TASKS-31-22,Thread priority=5,Original Thread=null,Original thread priority=null,Time=1,838,Exception=null
231820808: Jun 14 17:16:48.338 CDT %MIVR-SS_TEL-7-UNK:[MIVR_SS_TEL_PORT_QEXE_8302913-66-1419-TERM_CONN_EV:8302913] InCallObserverImpl: CallID:29172 MediaId:4503309/4 Task:42000366029 com.cisco.jtapi.TermObservationEndedEvImpl received
231820809: Jun 14 17:16:48.338 CDT %MIVR-SS_TEL-7-UNK:[MIVR_SS_TEL_PORT_QEXE_8302913-66-1419-CALL_EV:8302913] RequestImpl: CallID:29172 MediaId:4503309/4 Task:42000366029, got TransferEnd event, finalCall: (P1-admtelipdc_1) GCID=(4,4503309)->INVALID, consultCall: (P1-admtelipdc_1) GCID=(4,4503309)->INVALID, success: true, transferStart: false
231820810: Jun 14 17:16:48.338 CDT %MIVR-SS_TEL-7-UNK:[MIVR_SS_TEL_PORT_QEXE_8302913-66-1419-CALL_EV:8302913] RequestImpl: CallID:29172 MediaId:4503309/4 Task:42000366029, got TransferEnd event, finalCall: (P1-admtelipdc_1) GCID=(4,4503309)->INVALID, consultCall: (P1-admtelipdc_1) GCID=(4,4503309)->INVALID, success: true, transferStart: false
231820811: Jun 14 17:16:48.338 CDT %MIVR-SS_TEL-7-UNK:[MIVR_SS_TEL_TPG_EXE-40-122587-CALL_TRANSFER_START:8302913] RequestImpl: CallID:? MediaId:4503309/4, got CiscoTransferStartEv event on the AddressCallObserver, InCallObserver is null. Port.this.transferStart = true, notProcessedEventList = [CiscoTransferStartEv]
231820812: Jun 14 17:16:48.338 CDT %MIVR-SS_TEL-7-UNK:[MIVR_SS_RTR_EVENTS-37-0-CONTACT_HANDLED] CallImpl: TAPIPortGroup::CallImpl:getReportingPropeorty - Get Property : 1
231820813: Jun 14 17:16:48.338 CDT %MIVR-SS_TEL-7-UNK:[MIVR_SS_RTR_EVENTS-37-0-CONTACT_HANDLED] CallImpl: TAPIPortGroup::CallImpl:getReportingPropeorty - IVR OB Call. Found "OBIVRCALL" in the contact context : false
231820814: Jun 14 17:16:48.338 CDT %MIVR-SS_TEL-7-UNK:[MIVR_SS_RTR_EVENTS-37-0-CONTACT_CONNECTED] CallImpl: TAPIPortGroup::CallImpl:getReportingPropeorty - Get Property : 1
231820815: Jun 14 17:16:48.338 CDT %MIVR-SS_TEL-7-UNK:[MIVR_SS_RTR_EVENTS-37-0-CONTACT_CONNECTED] CallImpl: TAPIPortGroup::CallImpl:getReportingPropeorty - IVR OB Call. Found "OBIVRCALL" in the contact context : false
231820816: Jun 14 17:16:48.338 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] RIMgrAddressCallObserver: RIMgrAddressCallObserver.callChangedEvent():
CiscoCallChangedEv 1530135/1
231820817: Jun 14 17:16:48.338 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] RIMgrAddressCallObserver: Exiting callChangedEvent
231820818: Jun 14 17:16:48.338 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] RIMgrAddressCallObserver: RIMgrAddressCallObserver.callChangedEvent():
Unknown event. eventID:105:4503309/4
CallCtlConnEstablishedEv 4503309/4
Unknown event. eventID:116:4503309/4
TermConnActiveEv 4503309/4
CallCtlTermConnTalkingEv 4503309/4
231820819: Jun 14 17:16:48.338 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] RIMgrAddressCallObserver: RIMgrAddressCallObserver startPCAnnouncement for currentCalledAddr:8302227
231820820: Jun 14 17:16:48.338 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] PersistentConnection: PersistentConnection::getRDTerminal check for active remote destination for :8302227
231820821: Jun 14 17:16:48.338 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] PersistentConnection: PersistentConnection::getRDTerminal address does not have terminal with active remote destination8302227
231820822: Jun 14 17:16:48.338 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] RIMgrAddressCallObserver: Called Address in processCallCtlConnEstablishedEv :8302227
231820823: Jun 14 17:16:48.338 CDT %MIVR-SS_CM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] ContactMgr: ContactMgr.getRmCmContact(4503309/4) returns 71612173 [4503309/4]
231820824: Jun 14 17:16:48.338 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] RIMgrAddressCallObserver: RIMgrAddressCallObserver: CallCtlConnEstablishedEv received for call:71612173 [4503309/4], address 8302227, calling party 8302913, and called party 8302227
231820825: Jun 14 17:16:48.338 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] RIMgrAddressCallObserver: Called Address returned from JTAPI :8302227
231820826: Jun 14 17:16:48.338 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] RIMgrAddressCallObserver: Current Called Address returned from JTAPI :8302227
231820827: Jun 14 17:16:48.338 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] RIMgrAddressCallObserver: RIMgrAddressCallObserver: CallCtlConnEstablishedEv received for call 71612173 [4503309/4] and agent null being ignored because orig isn't a logged in agent
231820828: Jun 14 17:16:48.338 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] RIMgrAddressCallObserver: processTermConnActiveEv featureReason:2
231820829: Jun 14 17:16:48.339 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] RIMgrAddressCallObserver: RIMgrAddressCallObserver: TermConnActiveEv received for call 71612173 [4503309/4] and agent amrodriguez
231820830: Jun 14 17:16:48.339 CDT %MIVR-SS_CM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] ContactMgr: ContactMgr.getRmCmContact(4503309/4) returns 71612173 [4503309/4]
231820831: Jun 14 17:16:48.339 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] RIMgrAddressCallObserver: classifyCall: 4503309/4 8302227
231820832: Jun 14 17:16:48.339 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] RIMgrAddressCallObserver: RmCm contact 71612173 [4503309/4] has internal type IAQ, contact type: IAQ for agent amrodriguez
231820833: Jun 14 17:16:48.339 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] Resource: Resource.getIAQContact(String)- consultCallId isnull
231820834: Jun 14 17:16:48.339 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] Resource: Resource.getIAQContact(String) - consultCallid is null and ContactClass is IAQ_CONTACT
231820835: Jun 14 17:16:48.339 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] RIMgrAddressCallObserver: classifyCall 4503309/4 Rsrc: amrodriguez CallingAddr: 8302913 LRAddr:8302913 Type:IAQ(Normal)
231820836: Jun 14 17:16:48.339 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] RIMgrAddressCallObserver: RIMgrAddressCallObserver: TermConnActiveEv set CallEventData of EventReasonCode for call 71612173 [4503309/4] and agent amrodriguez and connection state of calledAddress(8302227) is 51
231820837: Jun 14 17:16:48.339 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] RIMgrAddressCallObserver: Called Address returned from JTAPI :8302227
231820838: Jun 14 17:16:48.339 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] RIMgrAddressCallObserver: Current Called Address returned from JTAPI :8302227
231820839: Jun 14 17:16:48.339 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] RIMgrAddressCallObserver: RIMgrAddressCallObserver: TermConnActiveEv, CallID 71612173[4503309/4] and Rsrc: amrodriguez; CiscoCall.getCurrentCallingAddress returned: 4491521222
231820840: Jun 14 17:16:48.339 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] RIMgrAddressCallObserver: Just before processCallCtlConnInitiatedEv-Outbound/Outgoing getUniqueID for:SEPBC16F5FBD0E7
231820841: Jun 14 17:16:48.339 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] RIMgrAddressCallObserver: Protocol Call Ref = 000000000044B70D0477C42600000000
231820842: Jun 14 17:16:48.339 CDT %MIVR-SS_CM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] ContactMgr: ContactMgr.getRmCmContact(4503309/4) returns 71612173 [4503309/4]
231820843: Jun 14 17:16:48.339 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] RIMgrAddressCallObserver: RIMgrAddressCallObserver: CallCtlTermConnTalkingEv received for call 71612173 [4503309/4] and agent amrodriguez
231820844: Jun 14 17:16:48.339 CDT %MIVR-SS_CM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] ContactMgr: ContactMgr.getRmCmContact(4503309/4) returns 71612173 [4503309/4]
231820845: Jun 14 17:16:48.339 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] RIMgrAddressCallObserver: classifyCall: 4503309/4 8302227
231820846: Jun 14 17:16:48.339 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] RIMgrAddressCallObserver: RmCm contact 71612173 [4503309/4] has internal type IAQ, contact type: IAQ for agent amrodriguez
231820847: Jun 14 17:16:48.339 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] Resource: Resource.getIAQContact(String)- consultCallId isnull
231820848: Jun 14 17:16:48.339 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] Resource: Resource.getIAQContact(String) - consultCallid is null and ContactClass is IAQ_CONTACT
231820849: Jun 14 17:16:48.339 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] RIMgrAddressCallObserver: classifyCall 4503309/4 Rsrc: amrodriguez CallingAddr: 8302913 LRAddr:8302913 Type:IAQ(Normal)
231820850: Jun 14 17:16:48.339 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] RIMgrAddressCallObserver: Called Address returned from JTAPI :8302227
231820851: Jun 14 17:16:48.339 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] RIMgrAddressCallObserver: Current Called Address returned from JTAPI :8302227
231820852: Jun 14 17:16:48.339 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] RIMgrAddressCallObserver: Exiting callChangedEvent
231820853: Jun 14 17:16:48.339 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] RIMgrAddressCallObserver: RIMgrAddressCallObserver.callChangedEvent():
Unknown event. eventID:105:4503309/4
CallCtlConnEstablishedEv 4503309/4
231820854: Jun 14 17:16:48.339 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] RIMgrAddressCallObserver: RIMgrAddressCallObserver startPCAnnouncement for currentCalledAddr:8302227
231820855: Jun 14 17:16:48.339 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] PersistentConnection: PersistentConnection::getRDTerminal check for active remote destination for :8302227
231820856: Jun 14 17:16:48.339 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] PersistentConnection: PersistentConnection::getRDTerminal address does not have terminal with active remote destination8302227
231820857: Jun 14 17:16:48.339 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] RIMgrAddressCallObserver: Called Address in processCallCtlConnEstablishedEv :8302227
231820858: Jun 14 17:16:48.339 CDT %MIVR-SS_CM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] ContactMgr: ContactMgr.getRmCmContact(4503309/4) returns 71612173 [4503309/4]
231820859: Jun 14 17:16:48.339 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] RIMgrAddressCallObserver: RIMgrAddressCallObserver: CallCtlConnEstablishedEv received for call:71612173 [4503309/4], address 8302913, calling party 8302913, and called party 8302227
231820860: Jun 14 17:16:48.339 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] RIMgrAddressCallObserver: Called Address returned from JTAPI :8302227
231820861: Jun 14 17:16:48.339 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] RIMgrAddressCallObserver: Current Called Address returned from JTAPI :8302227
231820862: Jun 14 17:16:48.339 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] RIMgrAddressCallObserver: RIMgrAddressCallObserver: CallCtlConnEstablishedEv received for call 71612173 [4503309/4] and agent null being ignored because orig isn't a logged in agent
231820863: Jun 14 17:16:48.339 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] RIMgrAddressCallObserver: Exiting callChangedEvent
231820864: Jun 14 17:16:48.339 CDT %MIVR-SS_RM-7-UNK:[ObserverThread(com.cisco.wf.subsystems.rmcm.jtapi.RIMgrAddressCallObserver@1066349)] RIMgrAddressCallObserver: RIMgrAddressCallObserver.callChangedEvent():
Unknown event. eventID:107:1530135/1
CallCtlConnDisconnectedEv1530135/1

CUCM: