busy when call to CUE

[Nguyen KeThuc]

Hi all,

I am configuring CUE 7.1 with CME. I can dial to voice mail number but busy tone responding. Pls review the log:

CUE1# show trace buffer tail

Press <CTRL-C> to exit...

5011 10/17 11:37:59.829 ACCN EXMG 0 STANDARD-worker-8:Finished work

3626 10/17 11:38:04.197 WFSP APP 0 WFSysdbNdApp::get enter

3626 10/17 11:38:04.198 WFSP APP 0 WFSysdbNdApp::get appName=autoattendant attribute=enabled

3626 10/17 11:38:04.198 WFSP APP 0 WFSysdbNdApp::get exit

17419 10/17 11:38:04.810 ACCN COMG 0 Contact-21:Enter clockDaemon.run()

17419 10/17 11:38:04.810 ACCN SSCM 0 ICDContactAdapter 20 : ContactRejected received for App FW contact 21, iefSourceContact is 21 [BC4546D6-361C11E3-8088F4BF-EDEDA4C2@10.15.242.59] (20)

17419 10/17 11:38:04.810 ACCN SSCM 0  ICDContactAdapter 20 .contactRejected () has ICDContactAdapter as inactive

17419 10/17 11:38:04.810 ACCN SSCM 0 ContactMgr.getResourceFromContact(BC4546D6-361C11E3-8088F4BF-EDEDA4C2@10.15.242.59) returns null

17419 10/17 11:38:04.810 ACCN SSCM 0 channelImplId is null in ICDContactAdapter 20 .contactRejected (21, 21 [BC4546D6-361C11E3-8088F4BF-EDEDA4C2@10.15.242.59])

17419 10/17 11:38:04.810 ACCN EXMG 0 Hiring :STANDARD-worker-8

17419 10/17 11:38:04.810 ACCN EXMG 0 STANDARD-worker-8:Assinged work

17419 10/17 11:38:04.810 ACCN COMG 0 Contact-21:Leave clockDaemon.run()

5011 10/17 11:38:04.810 ACCN EXMG 0 STANDARD-worker-8:Started work

5011 10/17 11:38:04.810 ACCN EXMG 0 start executing worklist

5011 10/17 11:38:04.810 ACCN EXMG 0 QueuedExecutor WorkOrder :: Mnemonic= POSTING_EVENT Task=com.cisco.app.subsystem.SubsystemApp$3@fd40fd4 starting task

5011 10/17 11:38:04.811 ACCN LEVT 0 POSTING_EVENT:: Event=com.cisco.call.CallEvent[CALL_REJECTED,state=CALL_REJECTED,reason=1,exception=null,reason=CHANNELS_BUSY,contactImplId=BC4546D6-361C11E3-8088F4BF-EDEDA4C2@10.15.242.59,lastContactImplId=BC4546D6-361C11E3-8088F4BF-EDEDA4C2@10.15.242.59,session=Session[id=0x15,parent=null,active=true,state=SESSION_IDLE,time=1381984679718],lastSession=Session[id=0x15,parent=null,active=true,state=SESSION_IDLE,time=1381984679718],contactSeqNum=0,lastContactSeqNum=0] on SIPCallContact[id=21,type=Cisco SIP Call,implId=BC4546D6-361C11E3-8088F4BF-EDEDA4C2@10.15.242.59,active=false,state=CALL_REJECTED,inbound=true,handled=false,locale=en_US,aborting=false,app=App[name=voicemail,type=Cisco Script Application,id=2,desc=voicemail,enabled=true,max=10,valid=true,optional=[cfgVars=[Lcom.cisco.wfapi.util.WFNameValuePair;@4f564f56,privilegeType=1,last.modified=1381836999105,script=voicebrowser.aef]],task=null,session=Session[id=0x15,parent=null,active=true,state=SESSION_IDLE,time=1381984679718],seqNum=0,time=1381984679716,cn=1000,dn=null,cgn=2000,ani=null,dnis=null,clid=sip:2000@10.15.242.59,atype=DIRECT,lrd=null,ocn=1000,odn=null,uui=null,aniii=null,ced=null,lrvmn=null,ocvmn=1000,route=TR[num=1000],port=null,aborting=false,transferring=false,disconnecting=false] at Thu Oct 17 11:37:59 GMT+07:00 2013

5011 10/17 11:38:04.811 ACCN EXMG 0 QueuedExecutor WorkOrder :: Mnemonic= POSTING_EVENT Task=com.cisco.app.subsystem.SubsystemApp$3@fd40fd4 finished task

5011 10/17 11:38:04.811 ACCN EXMG 0 QueuedExecutor WorkOrder :: Mnemonic= POSTED_EVENT Task=com.cisco.app.subsystem.SubsystemApp$3@11381138 starting task

5011 10/17 11:38:04.812 ACCN LEVT 0 POSTED_EVENT:: Event=com.cisco.call.CallEvent[CALL_REJECTED,state=CALL_REJECTED,reason=1,exception=null,reason=CHANNELS_BUSY,contactImplId=BC4546D6-361C11E3-8088F4BF-EDEDA4C2@10.15.242.59,lastContactImplId=BC4546D6-361C11E3-8088F4BF-EDEDA4C2@10.15.242.59,session=Session[id=0x15,parent=null,active=true,state=SESSION_IDLE,time=1381984679718],lastSession=Session[id=0x15,parent=null,active=true,state=SESSION_IDLE,time=1381984679718],contactSeqNum=0,lastContactSeqNum=0] on SIPCallContact[id=21,type=Cisco SIP Call,implId=BC4546D6-361C11E3-8088F4BF-EDEDA4C2@10.15.242.59,active=false,state=CALL_REJECTED,inbound=true,handled=false,locale=en_US,aborting=false,app=App[name=voicemail,type=Cisco Script Application,id=2,desc=voicemail,enabled=true,max=10,valid=true,optional=[cfgVars=[Lcom.cisco.wfapi.util.WFNameValuePair;@4f564f56,privilegeType=1,last.modified=1381836999105,script=voicebrowser.aef]],task=null,session=Session[id=0x15,parent=null,active=true,state=SESSION_IDLE,time=1381984679718],seqNum=0,time=1381984679716,cn=1000,dn=null,cgn=2000,ani=null,dnis=null,clid=sip:2000@10.15.242.59,atype=DIRECT,lrd=null,ocn=1000,odn=null,uui=null,aniii=null,ced=null,lrvmn=null,ocvmn=1000,route=TR[num=1000],port=null,aborting=false,transferring=false,disconnecting=false] at Thu Oct 17 11:37:59 GMT+07:00 2013

5011 10/17 11:38:04.812 ACCN EXMG 0 QueuedExecutor WorkOrder :: Mnemonic= POSTED_EVENT Task=com.cisco.app.subsystem.SubsystemApp$3@11381138 finished task

5011 10/17 11:38:04.812 ACCN EXMG 0 QueuedExecutorWorkList : WorkerName =null Current WorkOrder =null finished executing worklist

5011 10/17 11:38:04.812 ACCN EXMG 0 STANDARD-worker-8:Finished work

4985 10/17 11:38:04.812 ACCN EXMG 0 Hiring :STANDARD-worker-8

4985 10/17 11:38:04.812 ACCN EXMG 0 STANDARD-worker-8:Assinged work

4985 10/17 11:38:04.812 ACCN RTRS 0 CoreReportDataManager.contactRejected() called.

4985 10/17 11:38:04.812 ACCN EXMG 0 Hiring :STANDARD-worker-7

4985 10/17 11:38:04.812 ACCN EXMG 0 STANDARD-worker-7:Assinged work

5010 10/17 11:38:04.812 ACCN EXMG 0 STANDARD-worker-7:Started work

5010 10/17 11:38:04.812 ACCN EXMG 0 start executing worklist

5010 10/17 11:38:04.812 ACCN EXMG 0 QueuedExecutor WorkOrder :: Mnemonic= CONTACT_REJECTED Task=com.cisco.wf.subsystems.corereporting.CoreReportDataManager$11@3e283e28 starting task

5010 10/17 11:38:04.812 ACCN RTRS 0 CoreReportDataManager.contactRejected().  typeKey is CallContact className is com.cisco.call.CallContact

5011 10/17 11:38:04.812 ACCN EXMG 0 STANDARD-worker-8:Started work

5011 10/17 11:38:04.812 ACCN EXMG 0 start executing worklist

5011 10/17 11:38:04.812 ACCN EXMG 0 QueuedExecutor WorkOrder :: Mnemonic= DISPATCHING_EVENT Task=com.cisco.app.subsystem.SubsystemApp$3@3c923c92 starting task

5011 10/17 11:38:04.814 ACCN LEVT 0 DISPATCHING_EVENT:: Event=com.cisco.call.CallEvent[CALL_REJECTED,state=CALL_REJECTED,reason=1,exception=null,reason=CHANNELS_BUSY,contactImplId=BC4546D6-361C11E3-8088F4BF-EDEDA4C2@10.15.242.59,lastContactImplId=BC4546D6-361C11E3-8088F4BF-EDEDA4C2@10.15.242.59,session=Session[id=0x15,parent=null,active=true,state=SESSION_IDLE,time=1381984679718],lastSession=Session[id=0x15,parent=null,active=true,state=SESSION_IDLE,time=1381984679718],contactSeqNum=0,lastContactSeqNum=0] on SIPCallContact[id=21,type=Cisco SIP Call,implId=BC4546D6-361C11E3-8088F4BF-EDEDA4C2@10.15.242.59,active=false,state=CALL_REJECTED,inbound=true,handled=false,locale=en_US,aborting=false,app=App[name=voicemail,type=Cisco Script Application,id=2,desc=voicemail,enabled=true,max=10,valid=true,optional=[cfgVars=[Lcom.cisco.wfapi.util.WFNameValuePair;@4f564f56,privilegeType=1,last.modified=1381836999105,script=voicebrowser.aef]],task=null,session=Session[id=0x15,parent=null,active=true,state=SESSION_IDLE,time=1381984679718],seqNum=0,time=1381984679716,cn=1000,dn=null,cgn=2000,ani=null,dnis=null,clid=sip:2000@10.15.242.59,atype=DIRECT,lrd=null,ocn=1000,odn=null,uui=null,aniii=null,ced=null,lrvmn=null,ocvmn=1000,route=TR[num=1000],port=null,aborting=false,transferring=false,disconnecting=false] at Thu Oct 17 11:37:59 GMT+07:00 2013

5011 10/17 11:38:04.814 ACCN EXMG 0 QueuedExecutor WorkOrder :: Mnemonic= DISPATCHING_EVENT Task=com.cisco.app.subsystem.SubsystemApp$3@3c923c92 finished task

5011 10/17 11:38:04.814 ACCN EXMG 0 QueuedExecutor WorkOrder :: Mnemonic= DISPATCHED_EVENT Task=com.cisco.app.subsystem.SubsystemApp$3@3f8c3f8c starting task

5011 10/17 11:38:04.815 ACCN LEVT 0 DISPATCHED_EVENT:: Event=com.cisco.call.CallEvent[CALL_REJECTED,state=CALL_REJECTED,reason=1,exception=null,reason=CHANNELS_BUSY,contactImplId=BC4546D6-361C11E3-8088F4BF-EDEDA4C2@10.15.242.59,lastContactImplId=BC4546D6-361C11E3-8088F4BF-EDEDA4C2@10.15.242.59,session=Session[id=0x15,parent=null,active=true,state=SESSION_IDLE,time=1381984679718],lastSession=Session[id=0x15,parent=null,active=true,state=SESSION_IDLE,time=1381984679718],contactSeqNum=0,lastContactSeqNum=0] on SIPCallContact[id=21,type=Cisco SIP Call,implId=BC4546D6-361C11E3-8088F4BF-EDEDA4C2@10.15.242.59,active=false,state=CALL_REJECTED,inbound=true,handled=false,locale=en_US,aborting=false,app=App[name=voicemail,type=Cisco Script Application,id=2,desc=voicemail,enabled=true,max=10,valid=true,optional=[cfgVars=[Lcom.cisco.wfapi.util.WFNameValuePair;@4f564f56,privilegeType=1,last.modified=1381836999105,script=voicebrowser.aef]],task=null,session=Session[id=0x15,parent=null,active=true,state=SESSION_IDLE,time=1381984679718],seqNum=0,time=1381984679716,cn=1000,dn=null,cgn=2000,ani=null,dnis=null,clid=sip:2000@10.15.242.59,atype=DIRECT,lrd=null,ocn=1000,odn=null,uui=null,aniii=null,ced=null,lrvmn=null,ocvmn=1000,route=TR[num=1000],port=null,aborting=false,transferring=false,disconnecting=false] at Thu Oct 17 11:37:59 GMT+07:00 2013

5011 10/17 11:38:04.815 ACCN EXMG 0 QueuedExecutor WorkOrder :: Mnemonic= DISPATCHED_EVENT Task=com.cisco.app.subsystem.SubsystemApp$3@3f8c3f8c finished task

5011 10/17 11:38:04.815 ACCN EXMG 0 QueuedExecutorWorkList : WorkerName =null Current WorkOrder =null finished executing worklist

5011 10/17 11:38:04.815 ACCN EXMG 0 STANDARD-worker-8:Finished work

5205 10/17 11:38:04.816 ACCN SSCM 0 Processing msg: class com.cisco.wf.subsystems.rmcm.msgs.CMFireContactRejectedEventReqMsg Contact21

5205 10/17 11:38:04.816 ACCN SSCM 0 ContactMgrMsgProcessor.processRejectedEvReqMsg(21 [BC4546D6-361C11E3-8088F4BF-EDEDA4C2@10.15.242.59], IAQ, Thu Oct 17 11:37:59 GMT+07:00 2013)

5205 10/17 11:38:04.816 ACCN SSCM 0  IEF Contact ID: [21] BC4546D6-361C11E3-8088F4BF-EDEDA4C2@10.15.242.59 ContactEventsGenerator: Posting the ContactRejectedEvent contact type IAQ session -1 session seq num 0

5205 10/17 11:38:04.816 ACCN EXMG 0 Hiring :STANDARD-worker-8

5011 10/17 11:38:04.817 ACCN EXMG 0 STANDARD-worker-8:Started work

5011 10/17 11:38:04.817 ACCN EXMG 0 start executing worklist

5011 10/17 11:38:04.817 ACCN EXMG 0 QueuedExecutor WorkOrder :: Mnemonic= POSTING_EVENT Task=com.cisco.app.subsystem.SubsystemApp$3@64f864f8 starting task

5011 10/17 11:38:04.817 ACCN LEVT 0 POSTING_EVENT:: Event=com.cisco.wf.subsystems.events.ContactRejectedEvent[] on com.cisco.wf.subsystems.rmcm.CallContact@71a071a0 at Thu Oct 17 11:37:59 GMT+07:00 2013

5011 10/17 11:38:04.817 ACCN EXMG 0 QueuedExecutor WorkOrder :: Mnemonic= POSTING_EVENT Task=com.cisco.app.subsystem.SubsystemApp$3@64f864f8 finished task

5205 10/17 11:38:04.817 ACCN EXMG 0 STANDARD-worker-8:Assinged work

4986 10/17 11:38:04.817 ACCN HRDM 0 contactRejected - contactID: 21, sessionSeqNr: 0, sessionID: 21

4986 10/17 11:38:04.818 ACCN HRDM 0 setCalledNumber: setting called number : 1000

4986 10/17 11:38:04.818 ACCN HRDM 0 setApplicationInfo

4986 10/17 11:38:04.818 ACCN HRDM 0 setApplicationInfo - appID= 2

4986 10/17 11:38:04.818 ACCN HRDM 0 setApplicationInfo - throws exception java.lang.NullPointerException

4986 10/17 11:38:04.818 ACCN HRDM 0 writeCCDRToDatabase

5204 10/17 11:38:04.818 ACCN ENGN 0 Record 436345346 enqueued. Queue size=0 total number of writes=21

3624 10/17 11:38:04.826 WFSP APP 0 WFSysdbNdApp::get enter

3624 10/17 11:38:04.826 WFSP APP 0 WFSysdbNdApp::get appName=voicemail attribute=privilege

3624 10/17 11:38:04.826 WFSP APP 0 WFSysdbNdApp::get exit

5010 10/17 11:38:04.827 ACCN RTRS 0 VMAA Rej Call counter for bin 11 incremented to 8

5010 10/17 11:38:04.827 ACCN RTRS 0 CoreReportDataManager.contactRejected(). contactDataCounter size is 1

5010 10/17 11:38:04.827 ACCN EXMG 0 QueuedExecutor WorkOrder :: Mnemonic= CONTACT_REJECTED Task=com.cisco.wf.subsystems.corereporting.CoreReportDataManager$11@3e283e28 finished task

5010 10/17 11:38:04.827 ACCN EXMG 0 QueuedExecutorWorkList : WorkerName =null Current WorkOrder =null finished executing worklist

5010 10/17 11:38:04.827 ACCN EXMG 0 STANDARD-worker-7:Finished work

I see the Call is rejected because of channel busy. So, could you explain to me ?

Thanks,

Thuc

[Selvarathnam Nadar]

Hi,

Can you provide with the configuration output of CUCM and CME