Press to exit... 2766 04/02 12:48:16.597 WFSP HTRG 0 WFSysdbNdHttpTrg::get exit 5021 04/02 12:49:03.082 DSSP LWRE 0 Received UDP packet on 172.16.40.253:5060 ,source 172.16.40.254:55912 INVITE sip:801@172.16.40.253:5060 SIP/2.0 Via: SIP/2.0/UDP 172.16.40.254:5060;branch=z9hG4bKF1511DA9 Remote-Party-ID: ;party=calling;screen=no;privacy=off From: ;tag=13CE7F64-259D To: Date: Mon, 02 Apr 2018 23:38:47 GMT Call-ID: D2A033A2-360511E8-80F6D9CC-31AADFB2@172.16.40.254 Supported: timer,resource-priority,replaces,sdp-anat Min-SE: 1800 Cisco-Guid: 3533674362-0906301928-2163399116-0833281970 User-Agent: Cisco-SIPGateway/IOS-12.x Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER CSeq: 101 INVITE Timestamp: 1522712327 Contact: Call-Info: ;method="NOTIFY;Event=telephone-event;Duration=2000" Diversion: ;privacy=off;reason=unconditional;counter=1;screen=no Expires: 180 Allow-Events: telephone-event Max-Forwards: 18 Content-Type: multipart/mixed;boundary=uniqueBoundary Mime-Version: 1.0 Content-Length: 461 --uniqueBoundary Content-Type: application/sdp Content-Disposition: session;handling=required v=0 o=CiscoSystemsSIP-GW-UserAgent 2984 2021 IN IP4 172.16.40.254 s=SIP Call c=IN IP4 172.16.40.254 t=0 0 m=audio 18418 RTP/AVP 0 c=IN IP4 172.16.40.254 a=rtpmap:0 PCMU/8000 a=ptime:20 --uniqueBoundary Content-Type: application/gtd Content-Disposition: signal;handling=optional IAM, GCI,d29f977a360511e880f2d9cc31aadfb2 --uniqueBoundary-- --- end of packet --- 5185 04/02 12:49:03.085 DSSP LWRE 0 Sending UDP packet on 172.16.40.253:32844, destination 172.16.40.254:5060 SIP/2.0 100 Trying Via: SIP/2.0/UDP 172.16.40.254:5060;branch=z9hG4bKF1511DA9 To: ;tag=cued4de6936 From: ;tag=13CE7F64-259D Call-ID: D2A033A2-360511E8-80F6D9CC-31AADFB2@172.16.40.254 CSeq: 101 INVITE Content-Length: 0 Timestamp: 1522712327 Contact: 5185 04/02 12:49:03.085 ACCN SIPS 0 LocalLine-801 InboundCall GCID=D2A033A2-360511E8-80F6D9CC-31AADFB2@172.16.40.254 from=2369908465 to=801 5185 04/02 12:49:03.085 ACCN COMG 0 ContactStubImpl is constructed, id=41,type=Cisco SIP Call,impl id=D2A033A2-360511E8-80F6D9CC-31AADFB2@172.16.40.254 5185 04/02 12:49:03.086 ACCN CHMG 0 ChMgrImpl::defineStub() - defining channel stub ... 5185 04/02 12:49:03.086 ACCN CHMG 0 ChStubImpl::ChannelStubImpl - Channel created successfully,id-100 5185 04/02 12:49:03.086 ACCN CHMG 0 ChannelStubImpl::setState() -id=100 setting channel state to IDLE 5185 04/02 12:49:03.086 ACCN CHMG 0 CHANNEL_STATE_CHANGE:Channel has changed state: Channel=null,Channel Class=com.cisco.wf.subsystems.rmcm.ICDChannel,Channel Type=Cisco ICD Channel,Channel id=100,Channel implementation id=100,Old state=unknown,New state=IDLE 5185 04/02 12:49:03.086 ACCN COMG 0 getApplicationPrivilege(): Privilege for contact: 41 is:1 5185 04/02 12:49:03.087 ACCN CHMG 0 CHANNEL_STATE_CHANGE:Channel has changed state: Channel=null,Channel Class=com.cisco.wf.subsystems.rmcm.ICDChannel,Channel Type=Cisco ICD Channel,Channel id=100,Channel implementation id=100,Old state=IDLE,New state=IN_USE 5185 04/02 12:49:03.087 ACCN CHMG 0 ChStubImpl:handleInUseTransition - notifying all waiting threads that channel is no longer idle-ChannelStub[channelClass=com.cisco.wf.subsystems.rmcm.ICDChannel,type=Cisco ICD Channel,id=100,state=IN_USE,pendingState=null,groupId=,locked=false] 5185 04/02 12:49:03.087 ACCN SIPS 0 Call.received() SIPCallContact[id=41,type=Cisco SIP Call,implId=D2A033A2-360511E8-80F6D9CC-31AADFB2@172.16.40.254,active=true,state=CALL_RECEIVED,inbound=true,handled=false,locale=en_US,aborting=false,app=App[name=voicemail,type=Cisco Script Application,id=2,desc=voicemail,enabled=true,max=8,valid=true,optional=[cfgVars=[Lcom.cisco.wfapi.util.WFNameValuePair;@32943294,privilegeType=1,last.modified=1521428366446,script=voicebrowser.aef]],task=null,session=null,seqNum=-1,time=1522698543085,cn=801,dn=null,cgn=2369908465,ani=null,dnis=null,clid=sip:2369908465@10.185.93.51,atype=FORWARD_ALL,lrd=6045608088,ocn=6045608088,odn=null,uui=null,aniii=null,ced=null,lrvmn=6045608088,ocvmn=6045608088,route=null,port=null,aborting=false,transferring=false,disconnecting=false] 5185 04/02 12:49:03.088 ACCN CHMG 0 ChMgrImpl::getIdleChannels - entering getIdleChannels() 5185 04/02 12:49:03.088 ACCN CHMG 0 ChMgrImpl:getIdleChannels - dealing with groups of more then 1 class - USING MultiSync 5185 04/02 12:49:03.088 ACCN CHMG 0 ChMgrImpl:getIdleChannels - The class class com.cisco.dialog.DialogChannel has more than 1 group async 5185 04/02 12:49:03.088 ACCN CHMG 0 ChMgrImpl:getIdleChannels - created asyncs for the group. Now wrapping it in Exclusive async .. 5185 04/02 12:49:03.088 ACCN CHMG 0 ChMgrImpl:getIdleChannels - The class class com.cisco.call.CallControlChannel has only 1 group async 5185 04/02 12:49:03.088 ACCN CHMG 0 GroupStubImpl::findAndLockIdleChannel() - inside method-GroupStub[channelClass=com.cisco.wf.subsystems.cmt.CMTDialogChannelImpl,type=Cisco Media Channel,id=0,name=0,state=IN_SERVICE,channel=8] 5185 04/02 12:49:03.088 ACCN CHMG 0 ChStubImpl:getChannelLock() -id=0 trying to get channel lock ... 5185 04/02 12:49:03.088 ACCN CHMG 0 ChStubImpl:getChannelLock() - channel locked successfully-ChannelStub[channelClass=com.cisco.dialog.DialogChannel,type=Cisco Media Channel,id=0,state=IDLE,pendingState=null,groupId=0,locked=true] 5185 04/02 12:49:03.088 ACCN CHMG 0 GroupStubImpl::findAndLockIdleChannel() - channel successfully lockedChannelStub[channelClass=com.cisco.dialog.DialogChannel,type=Cisco Media Channel,id=0,state=IDLE,pendingState=null,groupId=0,locked=true] 5185 04/02 12:49:03.088 ACCN CHMG 0 GroupStubImpl::findAndLockIdleChannel() - inside method-GroupStub[channelClass=com.cisco.wf.subsystems.callcontrol.sip.SIPCallControlChannel,type=Cisco SIP Channel,id=0,name=0,state=IN_SERVICE,channel=9] 5185 04/02 12:49:03.088 ACCN CHMG 0 ChStubImpl:getChannelLock() -id=4 trying to get channel lock ... 5185 04/02 12:49:03.088 ACCN CHMG 0 ChStubImpl:getChannelLock() - channel locked successfully-ChannelStub[channelClass=com.cisco.call.CallControlChannel,type=Cisco SIP Channel,id=4,state=IDLE,pendingState=null,groupId=0,locked=true] 5185 04/02 12:49:03.088 ACCN CHMG 0 GroupStubImpl::findAndLockIdleChannel() - channel successfully lockedChannelStub[channelClass=com.cisco.call.CallControlChannel,type=Cisco SIP Channel,id=4,state=IDLE,pendingState=null,groupId=0,locked=true] 5185 04/02 12:49:03.088 ACCN COMG 0 getApplicationPrivilege(): Privilege for contact: 41 is:1 5185 04/02 12:49:03.088 ACCN COMG 0 ContactManager: Acquiring a port of type: AAWorkflowPrivilege 5185 04/02 12:49:03.088 LLMA LAPI 0 Llama: vmPortAllocate(): Allocating vm port for requestor "CRS" 5185 04/02 12:49:03.089 LLMA LSDB 0 Llama: vmPortAllocate(): LlamaSysdbUser(): setString(): Setting /sw/apps/limitsManager/vmPort/allocate/requestor to CRS 2701 04/02 12:49:03.091 LLMA LSDB 0 LlamaVmPortAllocate: check(): requestor, Value: CRS: returns true 2766 04/02 12:49:03.092 LLMA LVMP 0 LlamaVmPortAllocate: commit(): Attribute: requestor, Value: CRS 2766 04/02 12:49:03.092 LLMA LLIC 0 LlamaLicense incrementCount VM Port Feature - countsUsed = 1 5185 04/02 12:49:03.092 LLMA LAPI 0 Llama: vmPortAllocate(): Allocate vm port for requestor "CRS" returns true 5185 04/02 12:49:03.092 ACCN COMG 0 ContactManager: Port of type AAWorkflowPrivilege acquired successfully 5185 04/02 12:49:03.092 ACCN COMG 0 Port of type AAWorkflowPrivilege acquired for contact #41 5185 04/02 12:49:03.093 ACCN CHMG 0 CHANNEL_STATE_CHANGE:Channel has changed state: Channel=null,Channel Class=com.cisco.dialog.DialogChannel,Channel Type=Cisco Media Channel,Channel id=0,Channel implementation id=0,Old state=IDLE,New state=IN_USE 5185 04/02 12:49:03.093 ACCN CMTS 0 MediaDialogChannel id=0,state=IN_USE MDC::init 5185 04/02 12:49:03.093 ACCN CMTS 0 MediaDialogChannel id=0,state=IN_USE ::init: create newMediaManagerImpl 5185 04/02 12:49:03.093 ACCN LMED 0 After allocating sync object 5185 04/02 12:49:03.093 ACCN LMED 0 Enter RTPRecorder constructor 5185 04/02 12:49:03.094 ACCN LMED 0 Enter: DoubleBufferDatagramInputStream:Constructor 5185 04/02 12:49:03.094 ACCN CHMG 0 GrpStubImpl:notifyChannelStateChange - state change recvd from: ChannelStub[channelClass=com.cisco.dialog.DialogChannel,type=Cisco Media Channel,id=0,state=IN_USE,pendingState=null,groupId=0,locked=false] 5185 04/02 12:49:03.094 ACCN CHMG 0 GrpStubImpl:notifyChannelStateChange - channel changed from: IDLE ---> IN_USE for-GroupStub[channelClass=com.cisco.wf.subsystems.cmt.CMTDialogChannelImpl,type=Cisco Media Channel,id=0,name=0,state=IN_SERVICE,channel=8] 5185 04/02 12:49:03.094 ACCN CHMG 0 GrpStubImpl:notifyChannelInUse - No Group state change - channel state changed from Idle --> InUse 5185 04/02 12:49:03.094 ACCN CHMG 0 ChStubImpl:handleInUseTransition - notifying all waiting threads that channel is no longer idle-ChannelStub[channelClass=com.cisco.dialog.DialogChannel,type=Cisco Media Channel,id=0,state=IN_USE,pendingState=null,groupId=0,locked=false] 5185 04/02 12:49:03.094 ACCN CHMG 0 CHANNEL_STATE_CHANGE:Channel has changed state: Channel=null,Channel Class=com.cisco.call.CallControlChannel,Channel Type=Cisco SIP Channel,Channel id=4,Channel implementation id=16904,Old state=IDLE,New state=IN_USE 5185 04/02 12:49:03.094 ACCN CHMG 0 GrpStubImpl:notifyChannelStateChange - state change recvd from: ChannelStub[channelClass=com.cisco.call.CallControlChannel,type=Cisco SIP Channel,id=4,state=IN_USE,pendingState=null,groupId=0,locked=false] 5185 04/02 12:49:03.094 ACCN CHMG 0 GrpStubImpl:notifyChannelStateChange - channel changed from: IDLE ---> IN_USE for-GroupStub[channelClass=com.cisco.wf.subsystems.callcontrol.sip.SIPCallControlChannel,type=Cisco SIP Channel,id=0,name=0,state=IN_SERVICE,channel=9] 5185 04/02 12:49:03.094 ACCN CHMG 0 GrpStubImpl:notifyChannelInUse - No Group state change - channel state changed from Idle --> InUse 5185 04/02 12:49:03.095 ACCN CHMG 0 ChStubImpl:handleInUseTransition - notifying all waiting threads that channel is no longer idle-ChannelStub[channelClass=com.cisco.call.CallControlChannel,type=Cisco SIP Channel,id=4,state=IN_USE,pendingState=null,groupId=0,locked=false] 5185 04/02 12:49:03.095 ACCN SIPS 0 GCID=D2A033A2-360511E8-80F6D9CC-31AADFB2@172.16.40.254, CallControlChannel selected: Port[type=Cisco SIP Channel,id=4,implId=16904,active=true,state=IN_USE] 5185 04/02 12:49:03.096 ACCN SIPS 0 Call.associated() SIPCallContact[id=41,type=Cisco SIP Call,implId=D2A033A2-360511E8-80F6D9CC-31AADFB2@172.16.40.254,active=true,state=CALL_RECEIVED,inbound=true,handled=false,locale=en_US,aborting=false,app=App[name=voicemail,type=Cisco Script Application,id=2,desc=voicemail,enabled=true,max=8,valid=true,optional=[cfgVars=[Lcom.cisco.wfapi.util.WFNameValuePair;@32943294,privilegeType=1,last.modified=1521428366446,script=voicebrowser.aef]],task=null,session=Session[id=0x1a13b8629,parent=null,active=true,state=SESSION_IN_USE,time=1522698543087],seqNum=0,time=1522698543085,cn=801,dn=null,cgn=2369908465,ani=null,dnis=null,clid=sip:2369908465@10.185.93.51,atype=FORWARD_ALL,lrd=6045608088,ocn=6045608088,odn=null,uui=null,aniii=null,ced=null,lrvmn=6045608088,ocvmn=6045608088,route=TR[num=801],port=Port[type=Cisco SIP Channel,id=4,implId=16904,active=true,state=IN_USE],aborting=false,transferring=false,disconnecting=false] 5185 04/02 12:49:03.096 ACCN SIPS 0 Call.accepted() SIPCallContact[id=41,type=Cisco SIP Call,implId=D2A033A2-360511E8-80F6D9CC-31AADFB2@172.16.40.254,active=true,state=CALL_RECEIVED,inbound=true,handled=false,locale=en_US,aborting=false,app=App[name=voicemail,type=Cisco Script Application,id=2,desc=voicemail,enabled=true,max=8,valid=true,optional=[cfgVars=[Lcom.cisco.wfapi.util.WFNameValuePair;@32943294,privilegeType=1,last.modified=1521428366446,script=voicebrowser.aef]],task=null,session=Session[id=0x1a13b8629,parent=null,active=true,state=SESSION_IN_USE,time=1522698543087],seqNum=0,time=1522698543085,cn=801,dn=null,cgn=2369908465,ani=null,dnis=null,clid=sip:2369908465@10.185.93.51,atype=FORWARD_ALL,lrd=6045608088,ocn=6045608088,odn=null,uui=null,aniii=null,ced=null,lrvmn=6045608088,ocvmn=6045608088,route=TR[num=801],port=Port[type=Cisco SIP Channel,id=4,implId=16904,active=true,state=IN_USE],aborting=false,transferring=false,disconnecting=false] 5185 04/02 12:49:03.097 ACCN SIPL 0 sip-ltp40: 801, State=ALERTING sending ringing response: SIP/2.0 180 Ringing Via: SIP/2.0/UDP 172.16.40.254:5060;branch=z9hG4bKF1511DA9 To: ;tag=cued4de6936 From: ;tag=13CE7F64-259D Call-ID: D2A033A2-360511E8-80F6D9CC-31AADFB2@172.16.40.254 CSeq: 101 INVITE Content-Length: 0 Contact: 5185 04/02 12:49:03.097 DSSP LWRE 0 Sending UDP packet on 172.16.40.253:32844, destination 172.16.40.254:5060 SIP/2.0 180 Ringing Via: SIP/2.0/UDP 172.16.40.254:5060;branch=z9hG4bKF1511DA9 To: ;tag=cued4de6936 From: ;tag=13CE7F64-259D Call-ID: D2A033A2-360511E8-80F6D9CC-31AADFB2@172.16.40.254 CSeq: 101 INVITE Content-Length: 0 Contact: 5185 04/02 12:49:03.099 ACCN SIPS 0 OutLineListener:---------------- LocalLine-801 notified about an InboundCall GCID=D2A033A2-360511E8-80F6D9CC-31AADFB2@172.16.40.254 from=2369908465 to=801 5169 04/02 12:49:03.099 ACCN SIPS 0 TR[num=801] Get TriggerMap[] return:{secondaryDialogGroup=0, primaryDialogGroup=0} 5169 04/02 12:49:03.100 ACCN SIPS 0 TR[num=801] Get TriggerMap[] return:{secondaryDialogGroup=0, primaryDialogGroup=0} 5169 04/02 12:49:03.100 ACCN SIPS 0 trigger created: ContactApplicationTrigger[time=1522698543099,contact=SIPCallContact[id=41,type=Cisco SIP Call,implId=D2A033A2-360511E8-80F6D9CC-31AADFB2@172.16.40.254,active=true,state=CALL_RECEIVED,inbound=true,handled=false,locale=en_US,aborting=false,app=App[name=voicemail,type=Cisco Script Application,id=2,desc=voicemail,enabled=true,max=8,valid=true,optional=[cfgVars=[Lcom.cisco.wfapi.util.WFNameValuePair;@32943294,privilegeType=1,last.modified=1521428366446,script=voicebrowser.aef]],task=null,session=Session[id=0x1a13b8629,parent=null,active=true,state=SESSION_IN_USE,time=1522698543087],seqNum=0,time=1522698543085,cn=801,dn=null,cgn=2369908465,ani=null,dnis=null,clid=sip:2369908465@10.185.93.51,atype=FORWARD_ALL,lrd=6045608088,ocn=6045608088,odn=null,uui=null,aniii=null,ced=null,lrvmn=6045608088,ocvmn=6045608088,route=TR[num=801],port=Port[type=Cisco SIP Channel,id=4,implId=16904,active=true,state=IN_USE],aborting=false,transferring=false,disconnecting=false]] route: TR[num=801] triggermap: {secondaryDialogGroup=0, primaryDialogGroup=0} 5169 04/02 12:49:03.100 LLMA LAPI 0 Llama: getMaxIvrPortCount(): 5169 04/02 12:49:03.100 LLMA LSDB 0 Llama: getMaxIvrPortCount(): LlamaSysdbUser(): getInt(): Getting int /sw/apps/limitsManager/ivrPort/query/maxCount 2763 04/02 12:49:03.102 LLMA LIVR 0 LlamaIvrPortQuery: get(): maxCount 5169 04/02 12:49:03.102 LLMA LSDB 0 Llama: getMaxIvrPortCount(): LlamaSysdbUser(): getInt(): Getting int /sw/apps/limitsManager/ivrPort/query/maxCount returns 0 5169 04/02 12:49:03.103 LLMA LAPI 0 Llama: getMaxIvrPortCount(): count: 0 2702 04/02 12:49:03.104 WFSP SCRP 0 WFSysdbNdScript::get enter 2702 04/02 12:49:03.104 WFSP SCRP 0 WFSysdbNdScript::get attribute=privilege 2702 04/02 12:49:03.104 WFSP SCRP 0 WFSysdbNdScript::get exit 5169 04/02 12:49:03.107 ACCN APMG 0 TASK_CREATED:Application task created: Application=App[name=voicemail,type=Cisco Script Application,id=2,desc=voicemail,enabled=true,max=8,valid=true,optional=[cfgVars=[Lcom.cisco.wfapi.util.WFNameValuePair;@32943294,privilegeType=1,last.modified=1521428366446,script=voicebrowser.aef]],Application Trigger=ContactApplicationTrigger[time=1522698543099,contact=SIPCallContact[id=41,type=Cisco SIP Call,implId=D2A033A2-360511E8-80F6D9CC-31AADFB2@172.16.40.254,active=true,state=CALL_RECEIVED,inbound=true,handled=false,locale=en_US,aborting=false,app=App[name=voicemail,type=Cisco Script Application,id=2,desc=voicemail,enabled=true,max=8,valid=true,optional=[cfgVars=[Lcom.cisco.wfapi.util.WFNameValuePair;@32943294,privilegeType=1,last.modified=1521428366446,script=voicebrowser.aef]],task=null,session=Session[id=0x1a13b8629,parent=null,active=true,state=SESSION_IN_USE,time=1522698543087],seqNum=0,time=1522698543085,cn=801,dn=null,cgn=2369908465,ani=null,dnis=null,clid=sip:2369908465@10.185.93.51,atype=FORWARD_ALL,lrd=6045608088,ocn=6045608088,odn=null,uui=null,aniii=null,ced=null,lrvmn=6045608088,ocvmn=6045608088,route=TR[num=801],port=Port[type=Cisco SIP Channel,id=4,implId=16904,active=true,state=IN_USE],aborting=false,transferring=false,disconnecting=false]],Task id=22,000,000,041,Task Class=com.cisco.wfframework.engine.core.WFEngineWorkflowDebugTask,New Task Class=com.cisco.app.impl.WFWorkflowAppDebugTaskWrapper 5169 04/02 12:49:03.108 ACCN APMG 0 APP_SESSION_ACTIVE:Active application session: Application=App[name=voicemail,type=Cisco Script Application,id=2,desc=voicemail,enabled=true,max=8,valid=true,optional=[cfgVars=[Lcom.cisco.wfapi.util.WFNameValuePair;@32943294,privilegeType=1,last.modified=1521428366446,script=voicebrowser.aef]],Application Trigger=ContactApplicationTrigger[time=1522698543099,contact=SIPCallContact[id=41,type=Cisco SIP Call,implId=D2A033A2-360511E8-80F6D9CC-31AADFB2@172.16.40.254,active=true,state=CALL_RECEIVED,inbound=true,handled=false,locale=en_US,aborting=false,app=App[name=voicemail,type=Cisco Script Application,id=2,desc=voicemail,enabled=true,max=8,valid=true,optional=[cfgVars=[Lcom.cisco.wfapi.util.WFNameValuePair;@32943294,privilegeType=1,last.modified=1521428366446,script=voicebrowser.aef]],task=null,session=Session[id=0x1a13b8629,parent=null,active=true,state=SESSION_IN_USE,time=1522698543087],seqNum=0,time=1522698543085,cn=801,dn=null,cgn=2369908465,ani=null,dnis=null,clid=sip:2369908465@10.185.93.51,atype=FORWARD_ALL,lrd=6045608088,ocn=6045608088,odn=null,uui=null,aniii=null,ced=null,lrvmn=6045608088,ocvmn=6045608088,route=TR[num=801],port=Port[type=Cisco SIP Channel,id=4,implId=16904,active=true,state=IN_USE],aborting=false,transferring=false,disconnecting=false]],Task id=22,000,000,041,Active Sessions=1,Max Sessions=8 5169 04/02 12:49:03.109 ACCN SIPS 0 Call.attributed() SIPCallContact[id=41,type=Cisco SIP Call,implId=D2A033A2-360511E8-80F6D9CC-31AADFB2@172.16.40.254,active=true,state=CALL_RECEIVED,inbound=true,handled=false,locale=en_US,aborting=false,app=App[name=voicemail,type=Cisco Script Application,id=2,desc=voicemail,enabled=true,max=8,valid=true,optional=[cfgVars=[Lcom.cisco.wfapi.util.WFNameValuePair;@32943294,privilegeType=1,last.modified=1521428366446,script=voicebrowser.aef]],task=null,session=Session[id=0x1a13b8629,parent=null,active=true,state=SESSION_IN_USE,time=1522698543087],seqNum=0,time=1522698543085,cn=801,dn=null,cgn=2369908465,ani=null,dnis=null,clid=sip:2369908465@10.185.93.51,atype=FORWARD_ALL,lrd=6045608088,ocn=6045608088,odn=null,uui=null,aniii=null,ced=null,lrvmn=6045608088,ocvmn=6045608088,route=TR[num=801],port=Port[type=Cisco SIP Channel,id=4,implId=16904,active=true,state=IN_USE],aborting=false,transferring=false,disconnecting=false] 5169 04/02 12:49:03.109 ACCN SIPS 0 CallID:41 GCID:D2A033A2-360511E8-80F6D9CC-31AADFB2@172.16.40.254 associated with Task ID: 22000000041 4520 04/02 12:49:03.111 ACCN APMG 0 TASK_INIT:Application task initializing: Application=App[name=voicemail,type=Cisco Script Application,id=2,desc=voicemail,enabled=true,max=8,valid=true,optional=[cfgVars=[Lcom.cisco.wfapi.util.WFNameValuePair;@32943294,privilegeType=1,last.modified=1521428366446,script=voicebrowser.aef]],Application Trigger=ContactApplicationTrigger[time=1522698543099,contact=SIPCallContact[id=41,type=Cisco SIP Call,implId=D2A033A2-360511E8-80F6D9CC-31AADFB2@172.16.40.254,active=true,state=CALL_RECEIVED,inbound=true,handled=false,locale=en_US,aborting=false,app=App[name=voicemail,type=Cisco Script Application,id=2,desc=voicemail,enabled=true,max=8,valid=true,optional=[cfgVars=[Lcom.cisco.wfapi.util.WFNameValuePair;@32943294,privilegeType=1,last.modified=1521428366446,script=voicebrowser.aef]],task=null,session=Session[id=0x1a13b8629,parent=null,active=true,state=SESSION_IN_USE,time=1522698543087],seqNum=0,time=1522698543085,cn=801,dn=null,cgn=2369908465,ani=null,dnis=null,clid=sip:2369908465@10.185.93.51,atype=FORWARD_ALL,lrd=6045608088,ocn=6045608088,odn=null,uui=null,aniii=null,ced=null,lrvmn=6045608088,ocvmn=6045608088,route=TR[num=801],port=Port[type=Cisco SIP Channel,id=4,implId=16904,active=true,state=IN_USE],aborting=false,transferring=false,disconnecting=false]],Task id=22,000,000,041,Task Class=class com.cisco.app.impl.WFWorkflowAppDebugTaskWrapper 4520 04/02 12:49:03.112 ACCN APMG 0 TASK_STARTING:Application task starting: Application=App[name=voicemail,type=Cisco Script Application,id=2,desc=voicemail,enabled=true,max=8,valid=true,optional=[cfgVars=[Lcom.cisco.wfapi.util.WFNameValuePair;@32943294,privilegeType=1,last.modified=1521428366446,script=voicebrowser.aef]],Application Trigger=ContactApplicationTrigger[time=1522698543099,contact=SIPCallContact[id=41,type=Cisco SIP Call,implId=D2A033A2-360511E8-80F6D9CC-31AADFB2@172.16.40.254,active=true,state=CALL_RECEIVED,inbound=true,handled=false,locale=en_US,aborting=false,app=App[name=voicemail,type=Cisco Script Application,id=2,desc=voicemail,enabled=true,max=8,valid=true,optional=[cfgVars=[Lcom.cisco.wfapi.util.WFNameValuePair;@32943294,privilegeType=1,last.modified=1521428366446,script=voicebrowser.aef]],task=22000000041,session=Session[id=0x1a13b8629,parent=null,active=true,state=SESSION_IN_USE,time=1522698543087],seqNum=0,time=1522698543085,cn=801,dn=null,cgn=2369908465,ani=null,dnis=null,clid=sip:2369908465@10.185.93.51,atype=FORWARD_ALL,lrd=6045608088,ocn=6045608088,odn=null,uui=null,aniii=null,ced=null,lrvmn=6045608088,ocvmn=6045608088,route=TR[num=801],port=Port[type=Cisco SIP Channel,id=4,implId=16904,active=true,state=IN_USE],aborting=false,transferring=false,disconnecting=false]],Task id=22,000,000,041,Task Class=class com.cisco.app.impl.WFWorkflowAppDebugTaskWrapper 4520 04/02 12:49:03.113 ACCN ENGN 0 Execute Task 22000000041 >>>>> Before execution An object of com.cisco.app.impl.WFWorkflowAppDebugTaskWrapper State: STARTED Application: App[name=voicemail,type=Cisco Script Application,id=2,desc=voicemail,enabled=true,max=8,valid=true,optional=[cfgVars=[Lcom.cisco.wfapi.util.WFNameValuePair;@32943294,privilegeType=1,last.modified=1521428366446,script=voicebrowser.aef]] Trigger: ContactApplicationTrigger[time=1522698543099,contact=SIPCallContact[id=41,type=Cisco SIP Call,implId=D2A033A2-360511E8-80F6D9CC-31AADFB2@172.16.40.254,active=true,state=CALL_RECEIVED,inbound=true,handled=false,locale=en_US,aborting=false,app=App[name=voicemail,type=Cisco Script Application,id=2,desc=voicemail,enabled=true,max=8,valid=true,optional=[cfgVars=[Lcom.cisco.wfapi.util.WFNameValuePair;@32943294,privilegeType=1,last.modified=1521428366446,script=voicebrowser.aef]],task=22000000041,session=Session[id=0x1a13b8629,parent=null,active=true,state=SESSION_IN_USE,time=1522698543087],seqNum=0,time=1522698543085,cn=801,dn=null,cgn=2369908465,ani=null,dnis=null,clid=sip:2369908465@10.185.93.51,atype=FORWARD_ALL,lrd=6045608088,ocn=6045608088,odn=null,uui=null,aniii=null,ced=null,lrvmn=6045608088,ocvmn=6045608088,route=TR[num=801],port=Port[type=Cisco SIP Channel,id=4,implId=16904,active=true,state=IN_USE],aborting=false,transferring=false,disconnecting=false]] An object of com.cisco.wfframework.engine.core.WFEngineWorkflowDebugTask ID: 22000000041 Start time (ms): 1522698543106 Source ID: Destination ID: Media Instance ID: null Session handled: false Context: An object of com.cisco.wfframework.obj.WFWorkflowContext { {uri, java.lang.String, http://localhost/voicemail/vxmlscripts/login.vxml} {session, com.cisco.session.Session, null} {sessionId, java.lang.String, } {doc, com.cisco.doc.Document, null} {logoutUri, java.lang.String, http://localhost/voicemail/vxmlscripts/mbxLogout.jsp} {aesopJavaType, com.cisco.aesop.AesopJavaType, null} } Private Context: An object of com.cisco.wfframework.obj.WFWorkflowContext { } Number of executed steps: 0 Current step: Start <<<<< 4520 04/02 12:49:03.118 ACCN APMG 0 EXECUTING_STEP:Executing a step: Application=App[name=voicemail,type=Cisco Script Application,id=2,desc=voicemail,enabled=true,max=8,valid=true,optional=[cfgVars=[Lcom.cisco.wfapi.util.WFNameValuePair;@32943294,privilegeType=1,last.modified=1521428366446,script=voicebrowser.aef]],Task id=22,000,000,041,Step id=0,Step Class=com.cisco.wfframework.steps.core.StepStart,Step Description=Start 4520 04/02 12:49:03.118 ACCN ENGN 0 Execute step of Task 22000000041 : Start 4520 04/02 12:49:03.118 ACCN APMG 0 EXECUTING_STEP:Executing a step: Application=App[name=voicemail,type=Cisco Script Application,id=2,desc=voicemail,enabled=true,max=8,valid=true,optional=[cfgVars=[Lcom.cisco.wfapi.util.WFNameValuePair;@32943294,privilegeType=1,last.modified=1521428366446,script=voicebrowser.aef]],Task id=22,000,000,041,Step id=115,Step Class=com.cisco.wf.steps.ivr.AcceptStep,Step Description=Accept (contact: --Triggering Contact--) 4520 04/02 12:49:03.118 ACCN ENGN 0 Execute step of Task 22000000041 : Accept (contact: --Triggering Contact--) 4520 04/02 12:49:03.119 ACCN SIPL 0 sip-ltp40: 801, State=ALERTING answer : invite has sdp body : false 4520 04/02 12:49:03.119 ACCN SIPL 0 SDPBody : v=0 o=CiscoSystemsSIP-Workflow-App-UserAgent 15 15 IN IP4 172.16.40.253 s=SIP Call c=IN IP4 172.16.40.253 t=0 0 m=audio 16904 RTP/AVP 0 a=rtpmap:0 pcmu/8000 a=ptime:20 4520 04/02 12:49:03.119 ACCN SIPL 0 sip-ltp40: 801, State=ALERTING sendOK : invite has sdp body : false 4520 04/02 12:49:03.119 ACCN SIPL 0 sip-ltp40: 801, State=ALERTING getDTMFHeader: Enter 4520 04/02 12:49:03.119 ACCN SIPL 0 sip-ltp40: 801, State=ALERTING getDTMFHeader: getting the headers 4520 04/02 12:49:03.119 ACCN SIPL 0 sip-ltp40: 801, State=ALERTING getDTMFHeader: before adding headers to message 4520 04/02 12:49:03.119 ACCN SIPL 0 sip-ltp40: 801, State=ALERTING getDTMFHeader: after adding headers to message : SIP/2.0 200 Ok Via: SIP/2.0/UDP 172.16.40.254:5060;branch=z9hG4bKF1511DA9 To: ;tag=cued4de6936 From: ;tag=13CE7F64-259D Call-ID: D2A033A2-360511E8-80F6D9CC-31AADFB2@172.16.40.254 CSeq: 101 INVITE Content-Length: 176 Contact: Content-Type: application/sdp Call-Info: ;method="NOTIFY;Event=telephone-event;Duration=2000" Allow-Events: telephone-event v=0 o=CiscoSystemsSIP-Workflow-App-UserAgent 15 15 IN IP4 172.16.40.253 s=SIP Call c=IN IP4 172.16.40.253 t=0 0 m=audio 16904 RTP/AVP 0 a=rtpmap:0 pcmu/8000 a=ptime:20 4520 04/02 12:49:03.120 ACCN SIPL 0 sip-ltp40: 801, State=ALERTING sending ok response for dialog SIP/2.0 200 Ok Via: SIP/2.0/UDP 172.16.40.254:5060;branch=z9hG4bKF1511DA9 To: ;tag=cued4de6936 From: ;tag=13CE7F64-259D Call-ID: D2A033A2-360511E8-80F6D9CC-31AADFB2@172.16.40.254 CSeq: 101 INVITE Content-Length: 176 Contact: Content-Type: application/sdp Call-Info: ;method="NOTIFY;Event=telephone-event;Duration=2000" Allow-Events: telephone-event v=0 o=CiscoSystemsSIP-Workflow-App-UserAgent 15 15 IN IP4 172.16.40.253 s=SIP Call c=IN IP4 172.16.40.253 t=0 0 m=audio 16904 RTP/AVP 0 a=rtpmap:0 pcmu/8000 a=ptime:20 4520 04/02 12:49:03.120 DSSP LWRE 0 Sending UDP packet on 172.16.40.253:32844, destination 172.16.40.254:5060 SIP/2.0 200 Ok Via: SIP/2.0/UDP 172.16.40.254:5060;branch=z9hG4bKF1511DA9 To: ;tag=cued4de6936 From: ;tag=13CE7F64-259D Call-ID: D2A033A2-360511E8-80F6D9CC-31AADFB2@172.16.40.254 CSeq: 101 INVITE Content-Length: 176 Contact: Content-Type: application/sdp Call-Info: ;method="NOTIFY;Event=telephone-event;Duration=2000" Allow-Events: telephone-event v=0 o=CiscoSystemsSIP-Workflow-App-UserAgent 15 15 IN IP4 172.16.40.253 s=SIP Call c=IN IP4 172.16.40.253 t=0 0 m=audio 16904 RTP/AVP 0 a=rtpmap:0 pcmu/8000 a=ptime:20 4520 04/02 12:49:03.120 ACCN SIPL 0 sip-ltp40: 801, State=ALERTING after sending ok response for dialog 5021 04/02 12:49:03.128 DSSP LWRE 0 Received UDP packet on 172.16.40.253:5060 ,source 172.16.40.254:55912 ACK sip:801@172.16.40.253:5060 SIP/2.0 Via: SIP/2.0/UDP 172.16.40.254:5060;branch=z9hG4bKF152710 From: ;tag=13CE7F64-259D To: ;tag=cued4de6936 Date: Mon, 02 Apr 2018 23:38:47 GMT Call-ID: D2A033A2-360511E8-80F6D9CC-31AADFB2@172.16.40.254 Max-Forwards: 70 CSeq: 101 ACK Allow-Events: telephone-event Content-Length: 0 --- end of packet --- 5191 04/02 12:49:03.131 ACCN SIPL 0 LTP_NO_MEDIA_FOR_DIALOG:no media for the dialog: Stack Message=sip-ltp40: 801, State=ALERTING INVITE sip:801@172.16.40.253:5060 SIP/2.0 Via: SIP/2.0/UDP 172.16.40.254:5060;branch=z9hG4bKF1511DA9 Max-Forwards: 17 To: From: ;tag=13CE7F64-259D Call-ID: D2A033A2-360511E8-80F6D9CC-31AADFB2@172.16.40.254 CSeq: 101 INVITE Content-Length: 461 Remote-Party-ID: ;party=calling;screen=no;privacy=off Date: Mon, 02 Apr 2018 23:38:47 GMT Supported: timer,resource-priority,replaces,sdp-anat Min-SE: 1800 Cisco-Guid: 3533674362-0906301928-2163399116-0833281970 User-Agent: Cisco-SIPGateway/IOS-12.x Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER Timestamp: 1522712327 Contact: Call-Info: ;method="NOTIFY;Event=telephone-event;Duration=2000" Diversion: ;reason=unconditional;counter=1;screen=no;privacy=off Expires: 180 Allow-Events: telephone-event Content-Type: multipart/mixed;boundary=uniqueBoundary MIME-Version: 1.0 --uniqueBoundary Content-Type: application/sdp Content-Disposition: session;handling=required v=0 o=CiscoSystemsSIP-GW-UserAgent 2984 2021 IN IP4 172.16.40.254 s=SIP Call c=IN IP4 172.16.40.254 t=0 0 m=audio 18418 RTP/AVP 0 c=IN IP4 172.16.40.254 a=rtpmap:0 PCMU/8000 a=ptime:20 --uniqueBoundary Content-Type: application/gtd Content-Disposition: signal;handling=optional IAM, GCI,d29f977a360511e880f2d9cc31aadfb2 --uniqueBoundary-- ACK sip:801@172.16.40.253:5060 SIP/2.0 Via: SIP/2.0/UDP 172.16.40.254:5060;branch=z9hG4bKF152710 Max-Forwards: 69 To: ;tag=cued4de6936 From: ;tag=13CE7F64-259D Call-ID: D2A033A2-360511E8-80F6D9CC-31AADFB2@172.16.40.254 CSeq: 101 ACK Content-Length: 0 Date: Mon, 02 Apr 2018 23:38:47 GMT Allow-Events: telephone-event 5191 04/02 12:49:03.132 ACCN SIPL 0 sip-ltp40: 801, State=ALERTING no media terminating connection 5191 04/02 12:49:03.133 DSSP LWRE 0 Sending UDP packet on 172.16.40.253:32844, destination 172.16.40.254:5060 BYE sip:2369908465@172.16.40.254:5060 SIP/2.0 Via: SIP/2.0/UDP 172.16.40.253:5060;branch=z9hG4bKn41.BuznNHYPklhEe5sVFQ~~42 Max-Forwards: 70 To: ;tag=13CE7F64-259D From: ;tag=cued4de6936 Call-ID: D2A033A2-360511E8-80F6D9CC-31AADFB2@172.16.40.254 CSeq: 1 BYE Content-Length: 0 Contact: 5191 04/02 12:49:03.134 ACCN SIPS 0 CallID:41 GCID:D2A033A2-360511E8-80F6D9CC-31AADFB2@172.16.40.254, onTerminated - Terminal: sip-ltp40: 801, State=TERMINATED is DROPPED, cause=26 islocalorigin:false 5191 04/02 12:49:03.134 ACCN SIPS 0 CallID:41 GCID:D2A033A2-360511E8-80F6D9CC-31AADFB2@172.16.40.254 is removed from call session mapping in Session[id=0x1a13b8629,parent=null,active=true,state=SESSION_IN_USE,time=1522698543087], result:false 5191 04/02 12:49:03.134 ACCN CHMG 0 ChStubImpl:handleIdleTransition - channel currently InUse - removing channel from contact-ChannelStub[channelClass=com.cisco.dialog.DialogChannel,type=Cisco Media Channel,id=0,state=IN_USE,pendingState=null,groupId=0,locked=false] 5191 04/02 12:49:03.134 ACCN LMED 0 muteDestination() 5191 04/02 12:49:03.134 ACCN LMED 0 PromptPlayer.muteDestination(). !started. Return... 5191 04/02 12:49:03.134 ACCN LMED 0 Enter RTPRecorder:stopInput 5191 04/02 12:49:03.134 ACCN LMED 0 Enter: DoubleBufferDatagramInputStream:stopReceivingRTP 5191 04/02 12:49:03.134 ACCN LMED 0 Done RTPRecorder:stopInput 5191 04/02 12:49:03.134 ACCN CMTS 0 MediaDialogChannel id=0,state=IN_USE MDC::abort: return without waiting 5191 04/02 12:49:03.134 ACCN CMTS 0 MediaDialogChannel id=0,state=IN_USE MediaManager resetted. 5191 04/02 12:49:03.134 ACCN LMED 0 PromptPlayer::closeStream 5191 04/02 12:49:03.134 ACCN LMED 0 PromptPlayer::resetSources 5191 04/02 12:49:03.134 ACCN LMED 0 PromptPlayer::rtpData close 5191 04/02 12:49:03.134 ACCN LMED 0 PromptPlayer::closeStream done 5191 04/02 12:49:03.134 ACCN LMED 0 Enter RTPRecorder:stopRecord 5191 04/02 12:49:03.134 ACCN LMED 0 closeStream(), port=0 5191 04/02 12:49:03.134 ACCN LMED 0 Stopping doublebuffer input stream 5191 04/02 12:49:03.134 ACCN LMED 0 Enter: DoubleBufferDatagramInputStream:stop 5191 04/02 12:49:03.138 ACCN CHMG 0 CHANNEL_STATE_CHANGE:Channel has changed state: Channel=null,Channel Class=com.cisco.dialog.DialogChannel,Channel Type=Cisco Media Channel,Channel id=0,Channel implementation id=0,Old state=IN_USE,New state=IDLE 5191 04/02 12:49:03.138 ACCN CHMG 0 GrpStubImpl:notifyChannelStateChange - state change recvd from: ChannelStub[channelClass=com.cisco.dialog.DialogChannel,type=Cisco Media Channel,id=0,state=IDLE,pendingState=null,groupId=0,locked=true] 5191 04/02 12:49:03.138 ACCN CHMG 0 GrpStubImpl:notifyChannelStateChange - channel changed from: IN_USE ---> IDLE for-GroupStub[channelClass=com.cisco.wf.subsystems.cmt.CMTDialogChannelImpl,type=Cisco Media Channel,id=0,name=0,state=IN_SERVICE,channel=8] 5191 04/02 12:49:03.138 ACCN CHMG 0 GrpStubImpl:notifyChannelIdle - No Group state change - channel state changed from InUse --> Idle 5191 04/02 12:49:03.138 ACCN CHMG 0 GrpStubImpl:notifyChannelIdle - calling async.release() to make channel available 5191 04/02 12:49:03.138 ACCN CHMG 0 ChStubImpl:releaseChannelLock() - channel lock releasedChannelStub[channelClass=com.cisco.dialog.DialogChannel,type=Cisco Media Channel,id=0,state=IDLE,pendingState=null,groupId=0,locked=false] 5191 04/02 12:49:03.138 ACCN CHMG 0 ChStubImpl:handleIdleTransition - channel currently InUse - removing channel from contact-ChannelStub[channelClass=com.cisco.call.CallControlChannel,type=Cisco SIP Channel,id=4,state=IN_USE,pendingState=null,groupId=0,locked=false] 5021 04/02 12:49:03.139 DSSP LWRE 0 Received UDP packet on 172.16.40.253:5060 ,source 172.16.40.254:5060 SIP/2.0 200 OK Via: SIP/2.0/UDP 172.16.40.253:5060;branch=z9hG4bKn41.BuznNHYPklhEe5sVFQ~~42 From: ;tag=cued4de6936 To: ;tag=13CE7F64-259D Date: Mon, 02 Apr 2018 23:38:47 GMT Call-ID: D2A033A2-360511E8-80F6D9CC-31AADFB2@172.16.40.254 Server: Cisco-SIPGateway/IOS-12.x CSeq: 1 BYE Reason: Q.850;cause=16 Content-Length: 0 --- end of packet --- 5191 04/02 12:49:03.139 ACCN CHMG 0 CHANNEL_STATE_CHANGE:Channel has changed state: Channel=null,Channel Class=com.cisco.call.CallControlChannel,Channel Type=Cisco SIP Channel,Channel id=4,Channel implementation id=16904,Old state=IN_USE,New state=IDLE 5191 04/02 12:49:03.139 ACCN CHMG 0 GrpStubImpl:notifyChannelStateChange - state change recvd from: ChannelStub[channelClass=com.cisco.call.CallControlChannel,type=Cisco SIP Channel,id=4,state=IDLE,pendingState=null,groupId=0,locked=true] 5191 04/02 12:49:03.139 ACCN CHMG 0 GrpStubImpl:notifyChannelStateChange - channel changed from: IN_USE ---> IDLE for-GroupStub[channelClass=com.cisco.wf.subsystems.callcontrol.sip.SIPCallControlChannel,type=Cisco SIP Channel,id=0,name=0,state=IN_SERVICE,channel=9] 5191 04/02 12:49:03.139 ACCN CHMG 0 GrpStubImpl:notifyChannelIdle - No Group state change - channel state changed from InUse --> Idle 5191 04/02 12:49:03.139 ACCN CHMG 0 GrpStubImpl:notifyChannelIdle - calling async.release() to make channel available 5191 04/02 12:49:03.139 ACCN CHMG 0 ChStubImpl:releaseChannelLock() - channel lock releasedChannelStub[channelClass=com.cisco.call.CallControlChannel,type=Cisco SIP Channel,id=4,state=IDLE,pendingState=null,groupId=0,locked=false] 5191 04/02 12:49:03.140 ACCN CHMG 0 ChStubImpl:handleIdleTransition - channel currently InUse - removing channel from contact-ChannelStub[channelClass=com.cisco.wf.subsystems.rmcm.ICDChannel,type=Cisco ICD Channel,id=100,state=IN_USE,pendingState=null,groupId=,locked=false] 5191 04/02 12:49:03.140 ACCN CHMG 0 CHANNEL_STATE_CHANGE:Channel has changed state: Channel=null,Channel Class=com.cisco.wf.subsystems.rmcm.ICDChannel,Channel Type=Cisco ICD Channel,Channel id=100,Channel implementation id=100,Old state=IN_USE,New state=IDLE 5191 04/02 12:49:03.140 ACCN CHMG 0 CHANNEL_STATE_CHANGE:Channel has changed state: Channel=null,Channel Class=com.cisco.wf.subsystems.rmcm.ICDChannel,Channel Type=Cisco ICD Channel,Channel id=100,Channel implementation id=100,Old state=IDLE,New state=DISPOSED 5191 04/02 12:49:03.141 ACCN CHMG 0 ChStubImpl:handleDisposedTransition - notifying all waiting threads that channel is no longer idle-ChannelStub[channelClass=com.cisco.wf.subsystems.rmcm.ICDChannel,type=Cisco ICD Channel,id=100,state=DISPOSED,pendingState=null,groupId=,locked=false] 5191 04/02 12:49:03.141 ACCN COMG 0 getApplicationPrivilege(): Privilege for contact: 41 is:1 5191 04/02 12:49:03.141 ACCN COMG 0 ContactManager: Releasing a port of type: AAWorkflowPrivilege 5191 04/02 12:49:03.141 LLMA LAPI 0 Llama: vmPortDeallocate(): Deallocating vm port for requestor "CRS" 5191 04/02 12:49:03.141 LLMA LSDB 0 Llama: vmPortDeallocate(): LlamaSysdbUser(): setString(): Setting /sw/apps/limitsManager/vmPort/deallocate/requestor to CRS 2763 04/02 12:49:03.143 LLMA LSDB 0 LlamaVmPortDeallocate: check(): requestor, Value: CRS: returns true 2702 04/02 12:49:03.145 LLMA LVMP 0 LlamaVmPortDeallocate: commit(): Attribute: requestor, Value: CRS 2702 04/02 12:49:03.145 LLMA LLIC 0 LlamaLicense decrementCount VM Port Feature - countsUsed = 0 5191 04/02 12:49:03.146 LLMA LAPI 0 Llama: vmPortDeallocate(): Deallocate vm port for requestor "CRS" returns true 5191 04/02 12:49:03.146 ACCN COMG 0 ContactManager: Port of type AAWorkflowPrivilege released successfully 5191 04/02 12:49:03.146 ACCN COMG 0 IVR port for privilege: AAWorkflowPrivilege released by contact #41 5191 04/02 12:49:03.147 ACCN SIPS 0 Call.abandoned() SIPCallContact[id=41,type=Cisco SIP Call,implId=D2A033A2-360511E8-80F6D9CC-31AADFB2@172.16.40.254,active=false,state=CALL_DISCONNECTED,inbound=true,handled=false,locale=en_US,aborting=false,app=App[name=voicemail,type=Cisco Script Application,id=2,desc=voicemail,enabled=true,max=8,valid=true,optional=[cfgVars=[Lcom.cisco.wfapi.util.WFNameValuePair;@32943294,privilegeType=1,last.modified=1521428366446,script=voicebrowser.aef]],task=22000000041,session=Session[id=0x1a13b8629,parent=null,active=true,state=SESSION_IDLE,time=1522698543087],seqNum=0,time=1522698543085,cn=801,dn=null,cgn=2369908465,ani=null,dnis=null,clid=sip:2369908465@10.185.93.51,atype=FORWARD_ALL,lrd=6045608088,ocn=6045608088,odn=null,uui=null,aniii=null,ced=null,lrvmn=6045608088,ocvmn=6045608088,route=TR[num=801],port=Port[type=Cisco SIP Channel,id=4,implId=16904,active=false,state=IDLE],aborting=false,transferring=false,disconnecting=false] 5191 04/02 12:49:03.147 ACCN SIPS 0 CallID:41 GCID:D2A033A2-360511E8-80F6D9CC-31AADFB2@172.16.40.254, released Port[type=Cisco SIP Channel,id=4,implId=16904,active=false,state=IDLE] from 801 4520 04/02 12:49:03.148 ACCN SIPS 0 CallID:41 GCID:D2A033A2-360511E8-80F6D9CC-31AADFB2@172.16.40.254, GenericMsgHandler: exception while read/peek MsgQ.: Exception=com.cisco.wf.subsystems.obj.WFCallDroppedException: CallID:41 GCID:D2A033A2-360511E8-80F6D9CC-31AADFB2@172.16.40.254 Call dropped 4520 04/02 12:49:03.152 ACCN SIPS 0 EXCEPTION:com.cisco.wf.subsystems.obj.WFCallDroppedException: CallID:41 GCID:D2A033A2-360511E8-80F6D9CC-31AADFB2@172.16.40.254 Call dropped 4520 04/02 12:49:03.152 ACCN SIPS 0 EXCEPTION:com.cisco.wf.subsystems.callcontrol.Port$AppLocalTerminationPointListener.onTerminated (Port.java:2368) 4520 04/02 12:49:03.153 ACCN SIPS 0 EXCEPTION:com.cisco.jasmin.LocalTerminationPointEvent.dispatch (LocalTerminationPointEvent.java:183) 4520 04/02 12:49:03.153 ACCN SIPS 0 EXCEPTION:com.cisco.jasmin.impl.ds.ltp.LocalTerminationPointImpl.dispatchEvent (LocalTerminationPointImpl.java:153) 4520 04/02 12:49:03.153 ACCN SIPS 0 EXCEPTION:com.cisco.jasmin.impl.AbstractLocalTerminationPointImpl.notifyAll (AbstractLocalTerminationPointImpl.java:176) 4520 04/02 12:49:03.153 ACCN SIPS 0 EXCEPTION:com.cisco.jasmin.impl.ds.ltp.LocalTerminationPointImpl.notifyAll (LocalTerminationPointImpl.java:139) 4520 04/02 12:49:03.153 ACCN SIPS 0 EXCEPTION:com.cisco.jasmin.impl.ds.ltp.TerminatedState.activate (TerminatedState.java:64) 4520 04/02 12:49:03.153 ACCN SIPS 0 EXCEPTION:com.cisco.jasmin.impl.ds.ltp.AbstractState.setTerminated (AbstractState.java:160) 4520 04/02 12:49:03.153 ACCN SIPS 0 EXCEPTION:com.cisco.jasmin.impl.ds.ltp.AlertedState.processAck (AlertedState.java:273) 4520 04/02 12:49:03.154 ACCN SIPS 0 EXCEPTION:com.cisco.jasmin.impl.ds.ltp.LocalTerminationPointImpl.processAck (LocalTerminationPointImpl.java:456) 4520 04/02 12:49:03.154 ACCN SIPS 0 EXCEPTION:com.cisco.jasmin.impl.ds.InvitationDialogCallback.ack (InvitationDialogCallback.java:154) 4520 04/02 12:49:03.154 ACCN SIPS 0 EXCEPTION:com.dynamicsoft.DsLibs.DsSipDialog.DsSipInviteDialog$Callback.process (DsSipInviteDialog.java:1876) 4520 04/02 12:49:03.154 ACCN SIPS 0 EXCEPTION:com.dynamicsoft.DsLibs.DsUtil.DsQProcessor.run (DsQProcessor.java:49) 4520 04/02 12:49:03.155 ACCN APMG 0 STEP_FAILURE:Failure to execute a step: Application=App[name=voicemail,type=Cisco Script Application,id=2,desc=voicemail,enabled=true,max=8,valid=true,optional=[cfgVars=[Lcom.cisco.wfapi.util.WFNameValuePair;@32943294,privilegeType=1,last.modified=1521428366446,script=voicebrowser.aef]],Task id=22,000,000,041,Step id=115,Step Class=com.cisco.wf.steps.ivr.AcceptStep,Step Description=Accept (contact: --Triggering Contact--),Exception=com.cisco.contact.ContactInactiveException: Contact id: 41, Channel id: 4, Contact is in Terminated/Connected state 4520 04/02 12:49:03.167 ACCN APMG 0 EXCEPTION:com.cisco.contact.ContactInactiveException: Contact id: 41, Channel id: 4, Contact is in Terminated/Connected state 4520 04/02 12:49:03.167 ACCN APMG 0 EXCEPTION:com.cisco.channel.Channel.isAborted (Channel.java:420) 4520 04/02 12:49:03.168 ACCN APMG 0 EXCEPTION:com.cisco.wf.subsystems.callcontrol.Port.access$2300 (Port.java:138) 4520 04/02 12:49:03.168 ACCN APMG 0 EXCEPTION:com.cisco.wf.subsystems.callcontrol.Port$AppLocalTerminationPointListener.waitForCallAndMediaActive (Port.java:2580) 4520 04/02 12:49:03.168 ACCN APMG 0 EXCEPTION:com.cisco.wf.subsystems.callcontrol.Port$AppLocalTerminationPointListener.access$500 (Port.java:1722) 4520 04/02 12:49:03.168 ACCN APMG 0 EXCEPTION:com.cisco.wf.subsystems.callcontrol.Port.accept (Port.java:316) 4520 04/02 12:49:03.168 ACCN APMG 0 EXCEPTION:com.cisco.wf.steps.ivr.AcceptStep.execute (AcceptStep.java:158) 4520 04/02 12:49:03.168 ACCN APMG 0 EXCEPTION:com.cisco.wfframework.obj.WFBeanStep.executeImpl (WFBeanStep.java:123) 4520 04/02 12:49:03.168 ACCN APMG 0 EXCEPTION:com.cisco.wfframework.obj.WFStep.execute (WFStep.java:162) 4520 04/02 12:49:03.168 ACCN APMG 0 EXCEPTION:com.cisco.wfframework.obj.WFWorkflowTask.executeStep (WFWorkflowTask.java:466) 4520 04/02 12:49:03.169 ACCN APMG 0 EXCEPTION:com.cisco.wfframework.engine.core.WFEngineWorkflowTask.executeStep (WFEngineWorkflowTask.java:115) 4520 04/02 12:49:03.169 ACCN APMG 0 EXCEPTION:com.cisco.app.impl.WFWorkflowAppDebugTaskWrapper.executeStep (WFWorkflowAppDebugTaskWrapper.java:382) 4520 04/02 12:49:03.169 ACCN APMG 0 EXCEPTION:com.cisco.wfframework.obj.WFWorkflowTask.execute (WFWorkflowTask.java:332) 4520 04/02 12:49:03.169 ACCN APMG 0 EXCEPTION:com.cisco.wfframework.engine.core.WFEngineWorkflowTask.execute (WFEngineWorkflowTask.java:70) 4520 04/02 12:49:03.169 ACCN APMG 0 EXCEPTION:com.cisco.app.impl.WFWorkflowAppDebugTaskWrapper.execute (WFWorkflowAppDebugTaskWrapper.java:702) 4520 04/02 12:49:03.169 ACCN APMG 0 EXCEPTION:com.cisco.wfframework.engine.core.TaskManager.runTaskNormally (TaskManager.java:291) 4520 04/02 12:49:03.169 ACCN APMG 0 EXCEPTION:com.cisco.wfframework.engine.core.TaskManager.runTask (TaskManager.java:246) 4520 04/02 12:49:03.169 ACCN APMG 0 EXCEPTION:com.cisco.wfframework.engine.core.TaskManager$RunnableTask.run (TaskManager.java:432) 4520 04/02 12:49:03.170 ACCN APMG 0 EXCEPTION:com.cisco.executor.impl.ParallelExecutorImpl$WorkOrder.run (ParallelExecutorImpl.java:283) 4520 04/02 12:49:03.170 ACCN APMG 0 EXCEPTION:com.cisco.executor.impl.WorkerPool$Worker.run (WorkerPool.java:398) 4520 04/02 12:49:03.170 ACCN APMG 0 EXCEPTION:java.lang.Thread.run (Thread.java:777) 4520 04/02 12:49:03.170 ACCN APMG 0 EXCEPTION:Caused by: com.cisco.contact.ContactInactiveException: Contact id: 41, Channel id: 4, Contact is in Terminated/Connected state 4520 04/02 12:49:03.170 ACCN APMG 0 EXCEPTION:com.cisco.contact.impl.ContactStubImpl.setToFinalState (ContactStubImpl.java:785) 4520 04/02 12:49:03.170 ACCN APMG 0 EXCEPTION:com.cisco.contact.impl.ContactStubImpl.setState (ContactStubImpl.java:964) 4520 04/02 12:49:03.170 ACCN APMG 0 EXCEPTION:com.cisco.contact.Contact.setState (Contact.java:978) 4520 04/02 12:49:03.171 ACCN APMG 0 EXCEPTION:com.cisco.wf.subsystems.callcontrol.CallImpl.setState (CallImpl.java:1571) 4520 04/02 12:49:03.171 ACCN APMG 0 EXCEPTION:com.cisco.wf.subsystems.callcontrol.CallImpl.abandoned (CallImpl.java:1309) 4520 04/02 12:49:03.171 ACCN APMG 0 EXCEPTION:com.cisco.wf.subsystems.callcontrol.Port$AppLocalTerminationPointListener.onTerminated (Port.java:2362) 4520 04/02 12:49:03.171 ACCN APMG 0 EXCEPTION:com.cisco.jasmin.LocalTerminationPointEvent.dispatch (LocalTerminationPointEvent.java:183) 4520 04/02 12:49:03.171 ACCN APMG 0 EXCEPTION:com.cisco.jasmin.impl.ds.ltp.LocalTerminationPointImpl.dispatchEvent (LocalTerminationPointImpl.java:153) 4520 04/02 12:49:03.171 ACCN APMG 0 EXCEPTION:com.cisco.jasmin.impl.AbstractLocalTerminationPointImpl.notifyAll (AbstractLocalTerminationPointImpl.java:176) 4520 04/02 12:49:03.171 ACCN APMG 0 EXCEPTION:com.cisco.jasmin.impl.ds.ltp.LocalTerminationPointImpl.notifyAll (LocalTerminationPointImpl.java:139) 4520 04/02 12:49:03.172 ACCN APMG 0 EXCEPTION:com.cisco.jasmin.impl.ds.ltp.TerminatedState.activate (TerminatedState.java:64) 4520 04/02 12:49:03.172 ACCN APMG 0 EXCEPTION:com.cisco.jasmin.impl.ds.ltp.AbstractState.setTerminated (AbstractState.java:160) 4520 04/02 12:49:03.172 ACCN APMG 0 EXCEPTION:com.cisco.jasmin.impl.ds.ltp.AlertedState.processAck (AlertedState.java:273) 4520 04/02 12:49:03.172 ACCN APMG 0 EXCEPTION:com.cisco.jasmin.impl.ds.ltp.LocalTerminationPointImpl.processAck (LocalTerminationPointImpl.java:456) 4520 04/02 12:49:03.172 ACCN APMG 0 EXCEPTION:com.cisco.jasmin.impl.ds.InvitationDialogCallback.ack (InvitationDialogCallback.java:154) 4520 04/02 12:49:03.172 ACCN APMG 0 EXCEPTION:com.dynamicsoft.DsLibs.DsSipDialog.DsSipInviteDialog$Callback.process (DsSipInviteDialog.java:1876) 4520 04/02 12:49:03.172 ACCN APMG 0 EXCEPTION:com.dynamicsoft.DsLibs.DsUtil.DsQProcessor.run (DsQProcessor.java:49) 4520 04/02 12:49:03.174 ACCN APMG 0 TASK_ABORTED:Application task aborted: Application=App[name=voicemail,type=Cisco Script Application,id=2,desc=voicemail,enabled=true,max=8,valid=true,optional=[cfgVars=[Lcom.cisco.wfapi.util.WFNameValuePair;@32943294,privilegeType=1,last.modified=1521428366446,script=voicebrowser.aef]],Application Trigger=ContactApplicationTrigger[time=1522698543099,contact=SIPCallContact[id=41,type=Cisco SIP Call,implId=D2A033A2-360511E8-80F6D9CC-31AADFB2@172.16.40.254,active=false,state=CALL_DISCONNECTED,inbound=true,handled=false,locale=en_US,aborting=false,app=App[name=voicemail,type=Cisco Script Application,id=2,desc=voicemail,enabled=true,max=8,valid=true,optional=[cfgVars=[Lcom.cisco.wfapi.util.WFNameValuePair;@32943294,privilegeType=1,last.modified=1521428366446,script=voicebrowser.aef]],task=22000000041,session=Session[id=0x1a13b8629,parent=null,active=true,state=SESSION_IDLE,time=1522698543087],seqNum=0,time=1522698543085,cn=801,dn=null,cgn=2369908465,ani=null,dnis=null,clid=sip:2369908465@10.185.93.51,atype=FORWARD_ALL,lrd=6045608088,ocn=6045608088,odn=null,uui=null,aniii=null,ced=null,lrvmn=6045608088,ocvmn=6045608088,route=TR[num=801],port=Port[type=Cisco SIP Channel,id=4,implId=16904,active=false,state=IDLE],aborting=false,transferring=false,disconnecting=false]],Task id=22,000,000,041,Task Class=class com.cisco.app.impl.WFWorkflowAppDebugTaskWrapper,Exception=com.cisco.contact.ContactInactiveException: Contact id: 41, Channel id: 4, Contact is in Terminated/Connected state 4520 04/02 12:49:03.174 ACCN APMG 0 EXCEPTION:com.cisco.contact.ContactInactiveException: Contact id: 41, Channel id: 4, Contact is in Terminated/Connected state 4520 04/02 12:49:03.174 ACCN APMG 0 EXCEPTION:com.cisco.channel.Channel.isAborted (Channel.java:420) 4520 04/02 12:49:03.175 ACCN APMG 0 EXCEPTION:com.cisco.wf.subsystems.callcontrol.Port.access$2300 (Port.java:138) 4520 04/02 12:49:03.175 ACCN APMG 0 EXCEPTION:com.cisco.wf.subsystems.callcontrol.Port$AppLocalTerminationPointListener.waitForCallAndMediaActive (Port.java:2580) 4520 04/02 12:49:03.175 ACCN APMG 0 EXCEPTION:com.cisco.wf.subsystems.callcontrol.Port$AppLocalTerminationPointListener.access$500 (Port.java:1722) 4520 04/02 12:49:03.175 ACCN APMG 0 EXCEPTION:com.cisco.wf.subsystems.callcontrol.Port.accept (Port.java:316) 4520 04/02 12:49:03.175 ACCN APMG 0 EXCEPTION:com.cisco.wf.steps.ivr.AcceptStep.execute (AcceptStep.java:158) 4520 04/02 12:49:03.175 ACCN APMG 0 EXCEPTION:com.cisco.wfframework.obj.WFBeanStep.executeImpl (WFBeanStep.java:123) 4520 04/02 12:49:03.175 ACCN APMG 0 EXCEPTION:com.cisco.wfframework.obj.WFStep.execute (WFStep.java:162) 4520 04/02 12:49:03.176 ACCN APMG 0 EXCEPTION:com.cisco.wfframework.obj.WFWorkflowTask.executeStep (WFWorkflowTask.java:466) 4520 04/02 12:49:03.176 ACCN APMG 0 EXCEPTION:com.cisco.wfframework.engine.core.WFEngineWorkflowTask.executeStep (WFEngineWorkflowTask.java:115) 4520 04/02 12:49:03.177 ACCN APMG 0 EXCEPTION:com.cisco.app.impl.WFWorkflowAppDebugTaskWrapper.executeStep (WFWorkflowAppDebugTaskWrapper.java:382) 4520 04/02 12:49:03.177 ACCN APMG 0 EXCEPTION:com.cisco.wfframework.obj.WFWorkflowTask.execute (WFWorkflowTask.java:332) 4520 04/02 12:49:03.177 ACCN APMG 0 EXCEPTION:com.cisco.wfframework.engine.core.WFEngineWorkflowTask.execute (WFEngineWorkflowTask.java:70) 4520 04/02 12:49:03.177 ACCN APMG 0 EXCEPTION:com.cisco.app.impl.WFWorkflowAppDebugTaskWrapper.execute (WFWorkflowAppDebugTaskWrapper.java:702) 4520 04/02 12:49:03.177 ACCN APMG 0 EXCEPTION:com.cisco.wfframework.engine.core.TaskManager.runTaskNormally (TaskManager.java:291) 4520 04/02 12:49:03.177 ACCN APMG 0 EXCEPTION:com.cisco.wfframework.engine.core.TaskManager.runTask (TaskManager.java:246) 4520 04/02 12:49:03.177 ACCN APMG 0 EXCEPTION:com.cisco.wfframework.engine.core.TaskManager$RunnableTask.run (TaskManager.java:432) 4520 04/02 12:49:03.177 ACCN APMG 0 EXCEPTION:com.cisco.executor.impl.ParallelExecutorImpl$WorkOrder.run (ParallelExecutorImpl.java:283) 4520 04/02 12:49:03.178 ACCN APMG 0 EXCEPTION:com.cisco.executor.impl.WorkerPool$Worker.run (WorkerPool.java:398) 4520 04/02 12:49:03.178 ACCN APMG 0 EXCEPTION:java.lang.Thread.run (Thread.java:777) 4520 04/02 12:49:03.178 ACCN APMG 0 EXCEPTION:Caused by: com.cisco.contact.ContactInactiveException: Contact id: 41, Channel id: 4, Contact is in Terminated/Connected state 4520 04/02 12:49:03.178 ACCN APMG 0 EXCEPTION:com.cisco.contact.impl.ContactStubImpl.setToFinalState (ContactStubImpl.java:785) 4520 04/02 12:49:03.178 ACCN APMG 0 EXCEPTION:com.cisco.contact.impl.ContactStubImpl.setState (ContactStubImpl.java:964) 4520 04/02 12:49:03.178 ACCN APMG 0 EXCEPTION:com.cisco.contact.Contact.setState (Contact.java:978) 4520 04/02 12:49:03.178 ACCN APMG 0 EXCEPTION:com.cisco.wf.subsystems.callcontrol.CallImpl.setState (CallImpl.java:1571) 4520 04/02 12:49:03.178 ACCN APMG 0 EXCEPTION:com.cisco.wf.subsystems.callcontrol.CallImpl.abandoned (CallImpl.java:1309) 4520 04/02 12:49:03.178 ACCN APMG 0 EXCEPTION:com.cisco.wf.subsystems.callcontrol.Port$AppLocalTerminationPointListener.onTerminated (Port.java:2362) 4520 04/02 12:49:03.179 ACCN APMG 0 EXCEPTION:com.cisco.jasmin.LocalTerminationPointEvent.dispatch (LocalTerminationPointEvent.java:183) 4520 04/02 12:49:03.179 ACCN APMG 0 EXCEPTION:com.cisco.jasmin.impl.ds.ltp.LocalTerminationPointImpl.dispatchEvent (LocalTerminationPointImpl.java:153) 4520 04/02 12:49:03.179 ACCN APMG 0 EXCEPTION:com.cisco.jasmin.impl.AbstractLocalTerminationPointImpl.notifyAll (AbstractLocalTerminationPointImpl.java:176) 4520 04/02 12:49:03.179 ACCN APMG 0 EXCEPTION:com.cisco.jasmin.impl.ds.ltp.LocalTerminationPointImpl.notifyAll (LocalTerminationPointImpl.java:139) 4520 04/02 12:49:03.179 ACCN APMG 0 EXCEPTION:com.cisco.jasmin.impl.ds.ltp.TerminatedState.activate (TerminatedState.java:64) 4520 04/02 12:49:03.179 ACCN APMG 0 EXCEPTION:com.cisco.jasmin.impl.ds.ltp.AbstractState.setTerminated (AbstractState.java:160) 4520 04/02 12:49:03.179 ACCN APMG 0 EXCEPTION:com.cisco.jasmin.impl.ds.ltp.AlertedState.processAck (AlertedState.java:273) 4520 04/02 12:49:03.180 ACCN APMG 0 EXCEPTION:com.cisco.jasmin.impl.ds.ltp.LocalTerminationPointImpl.processAck (LocalTerminationPointImpl.java:456) 4520 04/02 12:49:03.180 ACCN APMG 0 EXCEPTION:com.cisco.jasmin.impl.ds.InvitationDialogCallback.ack (InvitationDialogCallback.java:154) 4520 04/02 12:49:03.180 ACCN APMG 0 EXCEPTION:com.dynamicsoft.DsLibs.DsSipDialog.DsSipInviteDialog$Callback.process (DsSipInviteDialog.java:1876) 4520 04/02 12:49:03.180 ACCN APMG 0 EXCEPTION:com.dynamicsoft.DsLibs.DsUtil.DsQProcessor.run (DsQProcessor.java:49) 4520 04/02 12:49:03.181 ACCN APMG 0 APP_SESSION_IDLE:Idle application session: Application=App[name=voicemail,type=Cisco Script Application,id=2,desc=voicemail,enabled=true,max=8,valid=true,optional=[cfgVars=[Lcom.cisco.wfapi.util.WFNameValuePair;@32943294,privilegeType=1,last.modified=1521428366446,script=voicebrowser.aef]],Application Trigger=ContactApplicationTrigger[time=1522698543099,contact=SIPCallContact[id=41,type=Cisco SIP Call,implId=D2A033A2-360511E8-80F6D9CC-31AADFB2@172.16.40.254,active=false,state=CALL_DISCONNECTED,inbound=true,handled=false,locale=en_US,aborting=false,app=App[name=voicemail,type=Cisco Script Application,id=2,desc=voicemail,enabled=true,max=8,valid=true,optional=[cfgVars=[Lcom.cisco.wfapi.util.WFNameValuePair;@32943294,privilegeType=1,last.modified=1521428366446,script=voicebrowser.aef]],task=22000000041,session=Session[id=0x1a13b8629,parent=null,active=true,state=SESSION_IDLE,time=1522698543087],seqNum=0,time=1522698543085,cn=801,dn=null,cgn=2369908465,ani=null,dnis=null,clid=sip:2369908465@10.185.93.51,atype=FORWARD_ALL,lrd=6045608088,ocn=6045608088,odn=null,uui=null,aniii=null,ced=null,lrvmn=6045608088,ocvmn=6045608088,route=TR[num=801],port=Port[type=Cisco SIP Channel,id=4,implId=16904,active=false,state=IDLE],aborting=false,transferring=false,disconnecting=false]],Task id=22,000,000,041,Active Sessions=0,Max Sessions=8 4520 04/02 12:49:03.182 ACCN ENGN 0 com.cisco.contact.ContactInactiveException: Contact id: 41, Channel id: 4, Contact is in Terminated/Connected state com.cisco.channel.Channel.isAborted (Channel.java:420) com.cisco.wf.subsystems.callcontrol.Port.access$2300 (Port.java:138) com.cisco.wf.subsystems.callcontrol.Port$AppLocalTerminationPointListener.waitForCallAndMediaActive (Port.java:2580) com.cisco.wf.subsystems.callcontrol.Port$AppLocalTerminationPointListener.access$500 (Port.java:1722) com.cisco.wf.subsystems.callcontrol.Port.accept (Port.java:316) com.cisco.wf.steps.ivr.AcceptStep.execute (AcceptStep.java:158) com.cisco.wfframework.obj.WFBeanStep.executeImpl (WFBeanStep.java:123) com.cisco.wfframework.obj.WFStep.execute (WFStep.java:162) com.cisco.wfframework.obj.WFWorkflowTask.executeStep (WFWorkflowTask.java:466) com.cisco.wfframework.engine.core.WFEngineWorkflowTask.executeStep (WFEngineWorkflowTask.java:115) com.cisco.app.impl.WFWorkflowAppDebugTaskWrapper.executeStep (WFWorkflowAppDebugTaskWrapper.java:382) com.cisco.wfframework.obj.WFWorkflowTask.execute (WFWorkflowTask.java:332) com.cisco.wfframework.engine.core.WFEngineWorkflowTask.execute (WFEngineWorkflowTask.java:70) com.cisco.app.impl.WFWorkflowAppDebugTaskWrapper.execute (WFWorkflowAppDebugTaskWrapper.java:702) com.cisco.wfframework.engine.core.TaskManager.runTaskNormally (TaskManager.java:291) com.cisco.wfframework.engine.core.TaskManager.runTask (TaskManager.java:246) com.cisco.wfframework.engine.core.TaskManager$RunnableTask.run (TaskManager.java:432) com.cisco.executor.impl.ParallelExecutorImpl$WorkOrder.run (ParallelExecutorImpl.java:283) com.cisco.executor.impl.WorkerPool$Worker.run (WorkerPool.java:398) java.lang.Thread.run (Thread.java:777) Caused by: com.cisco.contact.ContactInactiveException: Contact id: 41, Channel id: 4, Contact is in Terminated/Connected state com.cisco.contact.impl.ContactStubImpl.setToFinalState (ContactStubImpl.java:785) com.cisco.contact.impl.ContactStubImpl.setState (ContactStubImpl.java:964) com.cisco.contact.Contact.setState (Contact.java:978) com.cisco.wf.subsystems.callcontrol.CallImpl.setState (CallImpl.java:1571) com.cisco.wf.subsystems.callcontrol.CallImpl.abandoned (CallImpl.java:1309) com.cisco.wf.subsystems.callcontrol.Port$AppLocalTerminationPointListener.onTerminated (Port.java:2362) com.cisco.jasmin.LocalTerminationPointEvent.dispatch (LocalTerminationPointEvent.java:183) com.cisco.jasmin.impl.ds.ltp.LocalTerminationPointImpl.dispatchEvent (LocalTerminationPointImpl.java:153) com.cisco.jasmin.impl.AbstractLocalTerminationPointImpl.notifyAll (AbstractLocalTerminationPointImpl 4520 04/02 12:49:03.182 ACCN ENGN 0 Notifying Debug Task Aborted