cancel
Showing results for 
Search instead for 
Did you mean: 
cancel
1510
Views
0
Helpful
23
Replies

voicemail problem CUCM 8.6.2 and CUE Express 8.6.4

gevangelista
Level 1
Level 1

Good Morning,

I've a problem with voicemail activation.

I've a Cisco Callmanager 8.6.2  and CUe express 8.6.4.

I've configured the CUE with the following parameters:

ccn subsystem jtapi

ctiport 4101 4102

mwiport 1999

ccm-manager address 172.16.41.240

ccm-manager credentials hidden "A2cB9mqLjwehMeqs1KYZPaRdosjZqnxAjmyLyTHrsTtJ3xlk2B35j0nfGWTYHfmPSd8ZZNgd+Y9J3xlk2B35j0nfGWTYHfmPSd8ZZNgd+Y9J3xlk2B35j0nfGWTYHfmP"

end subsystem

ccn trigger jtapi phonenumber 570

application "voicemail"

enabled

maxsessions 1

end trigger

voicemail default language it_IT

voicemail broadcast recording time 300

voicemail default messagesize 240

voicemail notification restriction msg-notification

voicemail mailbox owner "gianca" size 7200

end mailbox

At following on the CUCM  I've configured two cti port   (4101 and 4102), one CTI route-pont (570) and one application used (jtapiadmin).

I've associated the cti ports and the cti toute point to this user.

And then I've created the voice mail pilot and the voice mail profile.

I've also associate the voicemail porfile to the user.

Either CTI ports and CTI route point are correctly registerd.

Now when I press the voicemail key on the phone, the numer are  always busy.

Can you halp me to solve this problem?

Thanks in advance regards

Giancarlo  

2 Accepted Solutions

Accepted Solutions

the CTI ports, CTI route points and phones should be assigned to the same device pool

also make sure that codec in the same region using g711ulaw

HTH

Anas

please don't forget to rate the helpful posts

View solution in original post

Hi Giancarlo.

Can you post the CUE config?

Thx

Carlo

Please rate all helpful posts "The more you help the more you learn"

View solution in original post

23 Replies 23

Good Morning Giancarlo.

Are your cti ports and CTI route point in the same Device Pool/Region of ip phones?

CUE uses G.711  only

HTH

Regards

Carlo

Please rate all helpful posts "The more you help the more you learn"

Good morning Carlo,

Yes the cti ports and cti rp are in the same  ipphones dp/region

Hi Giancarlo.

Try to trace what happens in the CUE when calling the pilot using show trace buffer tail.


Default logging shoul be enabled on CUE.

HTH

Regards

Carlo

Please rate all helpful posts "The more you help the more you learn"

Hi Carlo, at following the show trace buffer tail

cue-GFL-ch# show trace buffer tail
Press to exit...
2622 03/25 16:59:39.966 ACCN LMED 0 PrPl: appId=1, confId=12, parId=0, channelId=7, channelImplId=7, contactId=15 -> UmsPromptPlayer::closeStream
1800 03/25 17:01:05.844 ACCN TELS 0 Route Connection=[570:PAR_ESTERNE_LUGANO:1/(P1-jtapiadmin) GCID=(1,274260)->ACTIVE]->OFFERED, reason=1, Event= CallCtlConnOfferedEv, cause=100, metacode=128, isMaster=true
1800 03/25 17:01:05.844 ACCN COMG 0 ContactStubImpl is constructed, id=16,type=Cisco JTAPI Call,impl id=274260/1
1800 03/25 17:01:05.845 ACCN CHMG 0 ChMgrImpl::defineStub() - defining channel stub ...
1800 03/25 17:01:05.845 ACCN CHMG 0 ChStubImpl::ChannelStubImpl - Channel created successfully,id--1
1800 03/25 17:01:05.845 ACCN CHMG 0 ChannelStubImpl::setState() -id=-1 setting channel state to IDLE
1800 03/25 17:01:05.845 ACCN CHMG 0 CHANNEL_STATE_CHANGE:Channel has changed state: Channel=null,Channel.cisco.call.CallControlChannel,Channel Type=null,Channel id=-1,Channel implementation id=-1,Old state=unknown,New state=IDLE
1800 03/25 17:01:05.845 ACCN CHMG 0 ChannelStubImpl::setState() -id=-1 setting channel state to IDLE
1800 03/25 17:01:05.845 ACCN CHMG 0 ChStubImpl:handleIdleTransition - channel state did not change-ChannelStub[channelClass=com.cisco.call.CallControlChannel,type=null,id=-1,state=IDLE,pendingState=null,groupId=,locked=false]
1800 03/25 17:01:05.846 ACCN CHMG 0 ChMgrImpl::defineStub() - defining channel stub ...
1800 03/25 17:01:05.846 ACCN CHMG 0 ChStubImpl::ChannelStubImpl - Channel created successfully,id-100
1800 03/25 17:01:05.846 ACCN CHMG 0 ChannelStubImpl::setState() -id=100 setting channel state to IDLE
1800 03/25 17:01:05.846 ACCN CHMG 0 CHANNEL_STATE_CHANGE:Channel has changed state: Channel=null,Channel.cisco.wf.subsystems.rmcm.ICDChannel,Channel Type=Cisco ICD Channel,Channel id=100,Channel implementation id=100,Old state=unknown,New state=IDLE
1800 03/25 17:01:05.846 ACCN COMG 0 getApplicationPrivilege(): Privilege for contact: 16 is:1
1800 03/25 17:01:05.846 ACCN CHMG 0 CHANNEL_STATE_CHANGE:Channel has changed state: Channel=null,Channel.cisco.wf.subsystems.rmcm.ICDChannel,Channel Type=Cisco ICD Channel,Channel id=100,Channel implementation id=100,Old state=IDLE,New state=IN_USE
1800 03/25 17:01:05.846 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]
1800 03/25 17:01:05.847 ACCN TELS 0 Call.received() JTAPICallContact[id=16,implId=274260/1,state=STATE_RECEIVED_IDX,inbound=true,App name=voicemail,task=null,session=null,seq num=-1,cn=570,dn=570,cgn=572,ani=null,dnis=null,clid=null,atype=DIRECT,lrd=null,ocn=570,route=RP[num=570],TP=null
1800 03/25 17:01:05.847 ACCN TELS 0 lrdVoiceMailboxNumber=null ocnVoiceMailboxNumber= currentVoiceMailboxNumber=
1800 03/25 17:01:05.847 ACCN CHMG 0 ChMgrImpl::getIdleChannels - entering getIdleChannels()
1800 03/25 17:01:05.847 ACCN CHMG 0 ChMgrImpl:getIdleChannels - dealing with 2 different classes of groups
1800 03/25 17:01:05.848 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=10]
1800 03/25 17:01:05.848 ACCN CHMG 0 ChStubImpl:getChannelLock() -id=2 trying to get channel lock ...
1800 03/25 17:01:05.848 ACCN CHMG 0 ChStubImpl:getChannelLock() - channel locked successfully-ChannelStub[channelClass=com.cisco.dialog.DialogChannel,type=Cisco Media Channel,id=2,state=IDLE,pendingState=null,groupId=0,locked=true]
1800 03/25 17:01:05.848 ACCN CHMG 0 GroupStubImpl::findAndLockIdleChannel() - channel successfully lockedChannelStub[channelClass=com.cisco.dialog.DialogChannel,type=Cisco Media Channel,id=2,state=IDLE,pendingState=null,groupId=0,locked=true]
1800 03/25 17:01:05.848 ACCN CHMG 0 GroupStubImpl::findAndLockIdleChannel() - inside method-GroupStub[channelClass=com.cisco.crs.jtapi.JTAPICallControlChannel,type=Cisco CTI Port,id=1,name=1,state=IN_SERVICE,channel=2]
1800 03/25 17:01:05.848 ACCN CHMG 0 ChStubImpl:getChannelLock() -id=1 trying to get channel lock ...
1800 03/25 17:01:05.848 ACCN CHMG 0 ChStubImpl:getChannelLock() - channel locked successfully-ChannelStub[channelClass=com.cisco.call.CallControlChannel,type=Cisco CTI Port,id=1,state=IDLE,pendingState=null,groupId=1,locked=true]
1800 03/25 17:01:05.848 ACCN CHMG 0 GroupStubImpl::findAndLockIdleChannel() - channel successfully lockedChannelStub[channelClass=com.cisco.call.CallControlChannel,type=Cisco CTI Port,id=1,state=IDLE,pendingState=null,groupId=1,locked=true]
1800 03/25 17:01:05.848 ACCN CHMG 0 ChMgrImpl:getIdleChannels - attempt() successful resulting in 2 permits allocated
1800 03/25 17:01:05.848 ACCN CHMG 0 ChMgrImpl:getIdleChannels - permit allocated: 'Cisco CTI Port Group #1'.1(1)
1800 03/25 17:01:05.849 ACCN CHMG 0 ChMgrImpl:getIdleChannels - permit allocated: 'Cisco Media Channel Group #0'.2(1)
1800 03/25 17:01:05.849 ACCN COMG 0 getApplicationPrivilege(): Privilege for contact: 16 is:1
1800 03/25 17:01:05.849 ACCN COMG 0 ContactManager: Acquiring a port of type: AAWorkflowPrivilege
1800 03/25 17:01:05.849 LLMA LAPI 0 Llama: vmPortAllocate(): Allocating vm port for requestor "CRS"
1800 03/25 17:01:05.849 LLMA LSDB 0 Llama: vmPortAllocate(): LlamaSysdbUser(): setString(): Setting /sw/apps/limitsManager/vmPort/allocate/requestor to CRS
2705 03/25 17:01:05.849 LLMA LSDB 0 LlamaVmPortAllocate: check(): requestor, Value: CRS: returns true
2711 03/25 17:01:05.850 LLMA LVMP 0 LlamaVmPortAllocate: commit(): Attribute: requestor, Value: CRS
1800 03/25 17:01:05.851 LLMA LSDB 0 sysdb set fails
com.cisco.aesop.sysdb.xactSysdbException: Allocate Error
        at com.cisco.aesop.sysdb.sysdbNative.set(Native Method)
        at com.cisco.aesop.sysdb.sysdbAttr.set(sysdbAttr.java:81)
        at com.cisco.aesop.llama.LlamaSysdbUser.set(LlamaSysdbUser.java:163)
        at com.cisco.aesop.llama.LlamaSysdbUser.set(LlamaSysdbUser.java:141)
        at com.cisco.aesop.llama.Llama.vmPortAllocate(Llama.java:490)
        at com.cisco.contact.impl.ContactManagerImpl.acquirePort(ContactManagerImpl.java:1549)
        at com.cisco.contact.impl.ContactStubImpl.addChannels(ContactStubImpl.java:1739)
        at com.cisco.channel.impl.ChannelManagerImpl.getIdleChannels(ChannelManagerImpl.java:991)
        at com.cisco.channel.impl.ChannelManagerImpl.getIdleChannels(ChannelManagerImpl.java:704)
        at com.cisco.channel.Group.getIdleChannels(Group.java:391)
        at com.cisco.channel.Group.getIdleChannels(Group.java:345)
        at com.cisco.wf.subsystems.jtapi.TAPIPortGroup$RouteCallObserver$1.run(TAPIPortGroup.java:18741)
        at com.cisco.executor.impl.ExecutorStubImpl$RequestImpl.runCommand(ExecutorStubImpl.java:690)
        at com.cisco.executor.impl.ExecutorStubImpl$RequestImpl.run(ExecutorStubImpl.java:486)
        at com.cisco.executor.impl.ExecutorStubImpl$RequestImpl.run(ExecutorStubImpl.java:762)
        at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:768)
        at com.cisco.executor.impl.PooledExecutorStubImpl$1$WorkerImpl.run(PooledExecutorStubImpl.java:99)
        at com.cisco.util.ThreadPoolFactory$ThreadImpl.run(ThreadPoolFactory.java:853)

1800 03/25 17:01:05.851 LLMA LAPI 0 Llama: vmPortAllocate():
com.cisco.aesop.llama.LlamaException: Allocate Error
        at com.cisco.aesop.llama.LlamaSysdbUser.set(LlamaSysdbUser.java:177)
        at com.cisco.aesop.llama.LlamaSysdbUser.set(LlamaSysdbUser.java:141)
        at com.cisco.aesop.llama.Llama.vmPortAllocate(Llama.java:490)
        at com.cisco.contact.impl.ContactManagerImpl.acquirePort(ContactManagerImpl.java:1549)
        at com.cisco.contact.impl.ContactStubImpl.addChannels(ContactStubImpl.java:1739)
        at com.cisco.channel.impl.ChannelManagerImpl.getIdleChannels(ChannelManagerImpl.java:991)
        at com.cisco.channel.impl.ChannelManagerImpl.getIdleChannels(ChannelManagerImpl.java:704)
        at com.cisco.channel.Group.getIdleChannels(Group.java:391)
        at com.cisco.channel.Group.getIdleChannels(Group.java:345)
        at com.cisco.wf.subsystems.jtapi.TAPIPortGroup$RouteCallObserver$1.run(TAPIPortGroup.java:18741)
        at com.cisco.executor.impl.ExecutorStubImpl$RequestImpl.runCommand(ExecutorStubImpl.java:690)
        at com.cisco.executor.impl.ExecutorStubImpl$RequestImpl.run(ExecutorStubImpl.java:486)
        at com.cisco.executor.impl.ExecutorStubImpl$RequestImpl.run(ExecutorStubImpl.java:762)
        at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:768)
        at com.cisco.executor.impl.PooledExecutorStubImpl$1$WorkerImpl.run(PooledExecutorStubImpl.java:99)
        at com.cisco.util.ThreadPoolFactory$ThreadImpl.run(ThreadPoolFactory.java:853)

1800 03/25 17:01:05.851 LLMA LAPI 0 Llama: vmPortAllocate(): Allocate vm port for requestor "CRS" returns false
1800 03/25 17:01:05.852 ACCN COMG 0 ContactManager: Unable to acquire Port
1800 03/25 17:01:05.852 ACCN COMG 0 CONTACT_ADD_CHANNEL_ERROR:Could not add a channel to a contact: Contact=JTAPICallContact[id=16,implId=274260/1,state=STATE_RECEIVED_IDX,inbound=true,App name=voicemail,task=null,session=31000000015,seq num=0,cn=570,dn=570,cgn=572,ani=null,dnis=null,clid=null,atype=DIRECT,lrd=null,ocn=570,route=RP[num=570],TP=null,Contact.cisco.call.CallContact,Contact Type=Cisco JTAPI Call,Contact id=16,Contact implementation id=274260/1,Exception=com.cisco.channel.ChannelLicenseViolationException: license limit exceeded for application type: AAWorkflowPrivilege
1800 03/25 17:01:05.852 ACCN COMG 0 EXCEPTION:com.cisco.channel.ChannelLicenseViolationException: license limit exceeded for application type: AAWorkflowPrivilege
1800 03/25 17:01:05.853 ACCN COMG 0 EXCEPTION:  at com.cisco.contact.impl.ContactStubImpl.addChannels(ContactStubImpl.java:1740)
1800 03/25 17:01:05.853 ACCN COMG 0 EXCEPTION:  at com.cisco.channel.impl.ChannelManagerImpl.getIdleChannels(ChannelManagerImpl.java:991)
1800 03/25 17:01:05.854 ACCN COMG 0 EXCEPTION:  at com.cisco.channel.impl.ChannelManagerImpl.getIdleChannels(ChannelManagerImpl.java:704)
1800 03/25 17:01:05.854 ACCN COMG 0 EXCEPTION:  at com.cisco.channel.Group.getIdleChannels(Group.java:391)
1800 03/25 17:01:05.854 ACCN COMG 0 EXCEPTION:  at com.cisco.channel.Group.getIdleChannels(Group.java:345)
1800 03/25 17:01:05.854 ACCN COMG 0 EXCEPTION:  at com.cisco.wf.subsystems.jtapi.TAPIPortGroup$RouteCallObserver$1.run(TAPIPortGroup.java:18741)
1800 03/25 17:01:05.855 ACCN COMG 0 EXCEPTION:  at com.cisco.executor.impl.ExecutorStubImpl$RequestImpl.runCommand(ExecutorStubImpl.java:690)
1800 03/25 17:01:05.855 ACCN COMG 0 EXCEPTION:  at com.cisco.executor.impl.ExecutorStubImpl$RequestImpl.run(ExecutorStubImpl.java:486)
1800 03/25 17:01:05.855 ACCN COMG 0 EXCEPTION:  at com.cisco.executor.impl.ExecutorStubImpl$RequestImpl.run(ExecutorStubImpl.java:762)
1800 03/25 17:01:05.855 ACCN COMG 0 EXCEPTION:  at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:768)
1800 03/25 17:01:05.856 ACCN COMG 0 EXCEPTION:  at com.cisco.executor.impl.PooledExecutorStubImpl$1$WorkerImpl.run(PooledExecutorStubImpl.java:99)
1800 03/25 17:01:05.858 ACCN COMG 0 EXCEPTION:  at com.cisco.util.ThreadPoolFactory$ThreadImpl.run(ThreadPoolFactory.java:853)
1800 03/25 17:01:05.860 ACCN CHMG 0 ChMgrImpl:getFromSingleGroup() - encountered ChannelLicenseViolationException releasing channel: Exception=com.cisco.channel.ChannelLicenseViolationException: license limit exceeded for application type: AAWorkflowPrivilege
1800 03/25 17:01:05.861 ACCN CHMG 0 EXCEPTION:com.cisco.channel.ChannelLicenseViolationException: license limit exceeded for application type: AAWorkflowPrivilege
1800 03/25 17:01:05.861 ACCN CHMG 0 EXCEPTION:  at com.cisco.contact.impl.ContactStubImpl.addChannels(ContactStubImpl.java:1740)
1800 03/25 17:01:05.861 ACCN CHMG 0 EXCEPTION:  at com.cisco.channel.impl.ChannelManagerImpl.getIdleChannels(ChannelManagerImpl.java:991)
1800 03/25 17:01:05.861 ACCN CHMG 0 EXCEPTION:  at com.cisco.channel.impl.ChannelManagerImpl.getIdleChannels(ChannelManagerImpl.java:704)
1800 03/25 17:01:05.861 ACCN CHMG 0 EXCEPTION:  at com.cisco.channel.Group.getIdleChannels(Group.java:391)
1800 03/25 17:01:05.861 ACCN CHMG 0 EXCEPTION:  at com.cisco.channel.Group.getIdleChannels(Group.java:345)
1800 03/25 17:01:05.861 ACCN CHMG 0 EXCEPTION:  at com.cisco.wf.subsystems.jtapi.TAPIPortGroup$RouteCallObserver$1.run(TAPIPortGroup.java:18741)
1800 03/25 17:01:05.861 ACCN CHMG 0 EXCEPTION:  at com.cisco.executor.impl.ExecutorStubImpl$RequestImpl.runCommand(ExecutorStubImpl.java:690)
1800 03/25 17:01:05.862 ACCN CHMG 0 EXCEPTION:  at com.cisco.executor.impl.ExecutorStubImpl$RequestImpl.run(ExecutorStubImpl.java:486)
1800 03/25 17:01:05.862 ACCN CHMG 0 EXCEPTION:  at com.cisco.executor.impl.ExecutorStubImpl$RequestImpl.run(ExecutorStubImpl.java:762)
1800 03/25 17:01:05.862 ACCN CHMG 0 EXCEPTION:  at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:768)
1800 03/25 17:01:05.862 ACCN CHMG 0 EXCEPTION:  at com.cisco.executor.impl.PooledExecutorStubImpl$1$WorkerImpl.run(PooledExecutorStubImpl.java:99)
1800 03/25 17:01:05.862 ACCN CHMG 0 EXCEPTION:  at com.cisco.util.ThreadPoolFactory$ThreadImpl.run(ThreadPoolFactory.java:853)
1800 03/25 17:01:05.862 ACCN CHMG 0 ChStubImpl:releaseChannelLock() - channel lock releasedChannelStub[channelClass=com.cisco.call.CallControlChannel,type=Cisco CTI Port,id=1,state=IDLE,pendingState=null,groupId=1,locked=false]
1800 03/25 17:01:05.862 ACCN CHMG 0 ChStubImpl:releaseChannelLock() - channel lock releasedChannelStub[channelClass=com.cisco.dialog.DialogChannel,type=Cisco Media Channel,id=2,state=IDLE,pendingState=null,groupId=0,locked=false]
1800 03/25 17:01:05.863 ACCN TELS 0 CTIPG_ROUTE_EVENT:CTI Port Group route event: Route Session=[570:PAR_ESTERNE_LUGANO:1/(P1-jtapiadmin) GCID=(1,274260)->ACTIVE]->OFFERED,Route Address=570,Failure reason=Terminate Route Connection,Exception=com.cisco.channel.ChannelLicenseViolationException: license limit exceeded for application type: AAWorkflowPrivilege
1800 03/25 17:01:05.865 ACCN TELS 0 EXCEPTION:com.cisco.channel.ChannelLicenseViolationException: license limit exceeded for application type: AAWorkflowPrivilege
1800 03/25 17:01:05.868 ACCN TELS 0 EXCEPTION:  at com.cisco.contact.impl.ContactStubImpl.addChannels(ContactStubImpl.java:1740)
1800 03/25 17:01:05.870 ACCN TELS 0 EXCEPTION:  at com.cisco.channel.impl.ChannelManagerImpl.getIdleChannels(ChannelManagerImpl.java:991)
1800 03/25 17:01:05.872 ACCN TELS 0 EXCEPTION:  at com.cisco.channel.impl.ChannelManagerImpl.getIdleChannels(ChannelManagerImpl.java:704)
1800 03/25 17:01:05.875 ACCN TELS 0 EXCEPTION:  at com.cisco.channel.Group.getIdleChannels(Group.java:391)
1800 03/25 17:01:05.877 ACCN TELS 0 EXCEPTION:  at com.cisco.channel.Group.getIdleChannels(Group.java:345)
1800 03/25 17:01:05.880 ACCN TELS 0 EXCEPTION:  at com.cisco.wf.subsystems.jtapi.TAPIPortGroup$RouteCallObserver$1.run(TAPIPortGroup.java:18741)
1800 03/25 17:01:05.882 ACCN TELS 0 EXCEPTION:  at com.cisco.executor.impl.ExecutorStubImpl$RequestImpl.runCommand(ExecutorStubImpl.java:690)
1800 03/25 17:01:05.884 ACCN TELS 0 EXCEPTION:  at com.cisco.executor.impl.ExecutorStubImpl$RequestImpl.run(ExecutorStubImpl.java:486)
1800 03/25 17:01:05.887 ACCN TELS 0 EXCEPTION:  at com.cisco.executor.impl.ExecutorStubImpl$RequestImpl.run(ExecutorStubImpl.java:762)
1800 03/25 17:01:05.889 ACCN TELS 0 EXCEPTION:  at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:768)
1800 03/25 17:01:05.892 ACCN TELS 0 EXCEPTION:  at com.cisco.executor.impl.PooledExecutorStubImpl$1$WorkerImpl.run(PooledExecutorStubImpl.java:99)
1800 03/25 17:01:05.892 ACCN TELS 0 EXCEPTION:  at com.cisco.util.ThreadPoolFactory$ThreadImpl.run(ThreadPoolFactory.java:853)
1606 03/25 17:01:05.897 ACCN TELS 0 RP[num=570], conn=[570:PAR_ESTERNE_LUGANO:1/(P1-jtapiadmin) GCID=(1,274260)->INVALID]->DISCONNECTED, event=CallCtlConnDisconnectedEv, cause=CAUSE_NORMAL[100], meta=META_CALL_ENDING[132]
1800 03/25 17:01:05.897 ACCN TELS 0 CallID:16 MediaId:274260/1 is removed from call session mapping in Session[id=001-0x737be760f,parent=null,active=true,state=SESSION_IN_USE,time=1364227265847], result:true
1800 03/25 17:01:05.897 ACCN CHMG 0 ChStubImpl:notifyIdle(true) - id=100, dynamic
1800 03/25 17:01:05.898 ACCN CHMG 0 ChStubImpl:notifyIdle(false) - id=1
1800 03/25 17:01:05.898 ACCN CHMG 0 ChStubImpl:handleIdleTransition - channel state did not change-ChannelStub[channelClass=com.cisco.call.CallControlChannel,type=Cisco CTI Port,id=1,state=IDLE,pendingState=null,groupId=1,locked=false]
1800 03/25 17:01:05.898 ACCN CHMG 0 GrpStubImpl:notifyChannelStateChange - state change recvd from: ChannelStub[channelClass=com.cisco.call.CallControlChannel,type=Cisco CTI Port,id=1,state=IDLE,pendingState=null,groupId=1,locked=false]
1800 03/25 17:01:05.898 ACCN CHMG 0 GrpStubImpl:notifyChannelStateChange - channel changed from: IDLE ---> IDLE for-GroupStub[channelClass=com.cisco.crs.jtapi.JTAPICallControlChannel,type=Cisco CTI Port,id=1,name=1,state=IN_SERVICE,channel=2]
1800 03/25 17:01:05.898 ACCN CHMG 0 GrpStubImpl:notifyChannelIdle - No Group state change - channel notified Idle Idle --> Idle
1800 03/25 17:01:05.898 ACCN CHMG 0 GrpStubImpl:notifyChannelIdle - calling async.release() to make channel available
1607 03/25 17:01:05.898 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]
1607 03/25 17:01:05.898 ACCN CHMG 0 CHANNEL_STATE_CHANGE:Channel has changed state: Channel=null,Channel.cisco.wf.subsystems.rmcm.ICDChannel,Channel Type=Cisco ICD Channel,Channel id=100,Channel implementation id=100,Old state=IN_USE,New state=IDLE
1607 03/25 17:01:05.899 ACCN CHMG 0 CHANNEL_STATE_CHANGE:Channel has changed state: Channel=null,Channel.cisco.wf.subsystems.rmcm.ICDChannel,Channel Type=Cisco ICD Channel,Channel id=100,Channel implementation id=100,Old state=IDLE,New state=DISPOSED
1607 03/25 17:01:05.899 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]
1800 03/25 17:01:05.899 ACCN TELS 0 Call.rejected(NO_CHANNEL_LICENSE) JTAPICallContact[id=16,implId=274260/1,state=STATE_REJECTED_IDX,inbound=true,App name=voicemail,task=null,session=31000000015,seq num=0,cn=570,dn=570,cgn=572,ani=null,dnis=null,clid=null,atype=DIRECT,lrd=null,ocn=570,route=RP[num=570],TP=null
3765 03/25 17:01:10.899 ACCN COMG 0 CONTACT_INVALID_STATE_ERROR:Some action is performed when contact is in invalid state: Contact=JTAPICallContact[id=16,implId=274260/1,state=STATE_REJECTED_IDX,inbound=true,App name=voicemail,task=null,session=31000000015,seq num=0,cn=570,dn=570,cgn=572,ani=null,dnis=null,clid=null,atype=DIRECT,lrd=null,ocn=570,route=RP[num=570],TP=null,Contact.cisco.call.CallContact,Contact Type=Cisco JTAPI Call,Contact id=16,Contact implementation id=274260/1,Exception=Contact is inactive when geting channel

Anas Abueideh
Level 9
Level 9

Hi,

kindly increase the maximum sessions under the voicemail trigger more than 1 session.

HTH

Anas

please rate if it is helpful

Hi,

I've configured the cue but if I put  voice mail maximum session  2  The cue display the following errore message:

"session value exceeds license limit"

I think that I've the licence for 2 session but I don't know How enable it.

I've the following licenses ( sh license all  command)

cue-GFL-ch# sh license all

License Store: Primary License Storage

StoreIndex:  0  Feature: VMIVR-PORT                        Version: 1.0

        License Type: Permanent

        License State: Inactive

        License Count: 2 / 0

        License Priority: Medium

License Store: Primary License Storage

StoreIndex:  1  Feature: VMIVR-VM-MBX                      Version: 1.0

        License Type: Permanent

        License State: Inactive

        License Count: 5 / 0

        License Priority: Medium

License Store: Primary License Storage

StoreIndex:  2  Feature: VMIVR-VM-MBX                      Version: 1.0

        License Type: Permanent

        License State: Inactive

        License Count: 10 / 0

        License Priority: Medium

License Store: Primary License Storage

StoreIndex:  3  Feature: VMIVR-VM-MBX                      Version: 1.0

        License Type: Permanent

        License State: Inactive

        License Count: 15 / 0

        License Priority: Medium

License Store: Primary License Storage

StoreIndex:  4  Feature: VMIVR-VM-MBX                      Version: 1.0

        License Type: Permanent

        License State: Inactive

        License Count: 20 / 0

        License Priority: Medium

License Store: Primary License Storage

StoreIndex:  5  Feature: VMIVR-VM-MBX                      Version: 1.0

        License Type: Permanent

        License State: Inactive

        License Count: 25 / 0

        License Priority: Medium

License Store: Primary License Storage

StoreIndex:  6  Feature: VMIVR-VM-MBX                      Version: 1.0

        License Type: Permanent

        License State: Active, In Use

        License Count: 30 /30

        License Priority: Medium

License Store: Primary License Storage

StoreIndex:  7  Feature: VMIVR-PORT                        Version: 1.0

        License Type: Permanent

        License State: Active, In Use

        License Count: 4 /2

        License Priority: Medium

License Store: Primary License Storage

StoreIndex:  8  Feature: VMIVR-IVR-SESS                    Version: 1.0

        License Type: Permanent

        License State: Active, In Use

        License Count: 2 /2

        License Priority: Medium

License Store: Evaluation License Storage

StoreIndex:  0  Feature: VMIVR-VM-MBX                      Version: 1.0

        License Type: Evaluation

        License State: Inactive

            Evaluation total period:  8 weeks  4 days

            Evaluation period left:  8 weeks  4 days

        License Count: 600 / 0

        License Priority: None

License Store: Evaluation License Storage

StoreIndex:  1  Feature: VMIVR-PORT                        Version: 1.0

        License Type: Evaluation

        License State: Inactive

            Evaluation total period:  8 weeks  4 days

            Evaluation period left:  8 weeks  4 days

        License Count: 60 / 0

        License Priority: None

License Store: Evaluation License Storage

StoreIndex:  2  Feature: VMIVR-IVR-SESS                    Version: 1.0

        License Type: Evaluation

        License State: Inactive

            Evaluation total period:  8 weeks  4 days

            Evaluation period left:  8 weeks  4 days

        License Count: 60 / 0

        License Priority: None cue-GFL-ch# sh license all
License Store: Primary License Storage
StoreIndex:  0  Feature: VMIVR-PORT                        Version: 1.0
        License Type: Permanent
        License State: Inactive
        License Count: 2 / 0
        License Priority: Medium
License Store: Primary License Storage
StoreIndex:  1  Feature: VMIVR-VM-MBX                      Version: 1.0
        License Type: Permanent
        License State: Inactive
        License Count: 5 / 0
        License Priority: Medium
License Store: Primary License Storage
StoreIndex:  2  Feature: VMIVR-VM-MBX                      Version: 1.0
        License Type: Permanent
        License State: Inactive
        License Count: 10 / 0
        License Priority: Medium
License Store: Primary License Storage
StoreIndex:  3  Feature: VMIVR-VM-MBX                      Version: 1.0
        License Type: Permanent
        License State: Inactive
        License Count: 15 / 0
        License Priority: Medium
License Store: Primary License Storage
StoreIndex:  4  Feature: VMIVR-VM-MBX                      Version: 1.0
        License Type: Permanent
        License State: Inactive
        License Count: 20 / 0
        License Priority: Medium
License Store: Primary License Storage
StoreIndex:  5  Feature: VMIVR-VM-MBX                      Version: 1.0
        License Type: Permanent
        License State: Inactive
        License Count: 25 / 0
        License Priority: Medium
License Store: Primary License Storage
StoreIndex:  6  Feature: VMIVR-VM-MBX                      Version: 1.0
        License Type: Permanent
        License State: Active, In Use
        License Count: 30 /30
        License Priority: Medium
License Store: Primary License Storage
StoreIndex:  7  Feature: VMIVR-PORT                        Version: 1.0
        License Type: Permanent
        License State: Active, In Use
        License Count: 4 /2
        License Priority: Medium
License Store: Primary License Storage
StoreIndex:  8  Feature: VMIVR-IVR-SESS                    Version: 1.0
        License Type: Permanent
        License State: Active, In Use
        License Count: 2 /2
        License Priority: Medium
License Store: Evaluation License Storage
StoreIndex:  0  Feature: VMIVR-VM-MBX                      Version: 1.0
        License Type: Evaluation
        License State: Inactive
            Evaluation total period:  8 weeks  4 days
            Evaluation period left:  8 weeks  4 days
        License Count: 600 / 0
        License Priority: None
License Store: Evaluation License Storage
StoreIndex:  1  Feature: VMIVR-PORT                        Version: 1.0
        License Type: Evaluation
        License State: Inactive
            Evaluation total period:  8 weeks  4 days
            Evaluation period left:  8 weeks  4 days
        License Count: 60 / 0
        License Priority: None
License Store: Evaluation License Storage
StoreIndex:  2  Feature: VMIVR-IVR-SESS                    Version: 1.0
        License Type: Evaluation
        License State: Inactive
            Evaluation total period:  8 weeks  4 days
            Evaluation period left:  8 weeks  4 days
        License Count: 60 / 0
        License Priority: None

Hi,

your license in not activated. you need to activate them. kindly do the following to activate the permenant licenses

CUE# license activate ?

       ivr               Activate IVR feature license

       ports             Activate CUE/IVR ports license

       voicemail         Activate CUE voicemail feature license

HTH

Anas

please rate if it is helpful

Hi Anas

When I use the command license activate ivr or  ports or voicemail the cue answer me:

"Current license already active, count saved for use at next reload"

Hi Giancarlo.

Did you try to reload the router after activate command?

Regards

Carlo

Please rate all helpful posts "The more you help the more you learn"

Hi Carlo, I've reloaded the router and the cue but the situatuin is the same.

When from my phone I call the voicemail pilot number it's always busy.

Anas Abueideh
Level 9
Level 9

did you reload the CUE? what is the license status after reload ?

Anas


Sent from Cisco Technical Support Android App

Hi anas,

thanks for help, I've reloaded the cue and now all are enabled

voicemail enabled: 4 ports, 2 sessions, 30 mailboxes
ivr enabled: 2 sessions

Hi Giancarlo.

Did you remember to set maxsession value to 2?

Please send now the output of a show trace buffer tail during a call to CUE.

Regards

Carlo

Please rate all helpful posts "The more you help the more you learn"

Hi CArlo.

Here the  info required

cue-GFL-ch# show trace buffer tail
Press to exit...
3622 03/26 14:46:13.288 ACCN ENGN 0 Notifying Debug Task Aborted
13259 03/26 14:51:48.748 ACCN TELS 0 Route Connection=[570:PAR_ESTERNE_LUGANO:1/(P1-jtapiadmin) GCID=(1,274536)->ACTIVE]->OFFERED, reason=1, Event= CallCtlConnOfferedEv, cause=100, metacode=128, isMaster=true
13259 03/26 14:51:48.748 ACCN COMG 0 ContactStubImpl is constructed, id=11,type=Cisco JTAPI Call,impl id=274536/1
13259 03/26 14:51:48.749 ACCN CHMG 0 ChMgrImpl::defineStub() - defining channel stub ...
13259 03/26 14:51:48.749 ACCN CHMG 0 ChStubImpl::ChannelStubImpl - Channel created successfully,id--1
13259 03/26 14:51:48.749 ACCN CHMG 0 ChannelStubImpl::setState() -id=-1 setting channel state to IDLE
13259 03/26 14:51:48.749 ACCN CHMG 0 CHANNEL_STATE_CHANGE:Channel has changed state: Channel=null,Channel.cisco.call.CallControlChannel,Channel Type=null,Channel id=-1,Channel implementation id=-1,Old state=unknown,New state=IDLE
13259 03/26 14:51:48.749 ACCN CHMG 0 ChannelStubImpl::setState() -id=-1 setting channel state to IDLE
13259 03/26 14:51:48.749 ACCN CHMG 0 ChStubImpl:handleIdleTransition - channel state did not change-ChannelStub[channelClass=com.cisco.call.CallControlChannel,type=null,id=-1,state=IDLE,pendingState=null,groupId=,locked=false]
13259 03/26 14:51:48.749 ACCN CHMG 0 ChMgrImpl::defineStub() - defining channel stub ...
13259 03/26 14:51:48.749 ACCN CHMG 0 ChStubImpl::ChannelStubImpl - Channel created successfully,id-100
13259 03/26 14:51:48.749 ACCN CHMG 0 ChannelStubImpl::setState() -id=100 setting channel state to IDLE
13259 03/26 14:51:48.750 ACCN CHMG 0 CHANNEL_STATE_CHANGE:Channel has changed state: Channel=null,Channel.cisco.wf.subsystems.rmcm.ICDChannel,Channel Type=Cisco ICD Channel,Channel id=100,Channel implementation id=100,Old state=unknown,New state=IDLE
13259 03/26 14:51:48.750 ACCN COMG 0 getApplicationPrivilege(): Privilege for contact: 11 is:1
13259 03/26 14:51:48.750 ACCN CHMG 0 CHANNEL_STATE_CHANGE:Channel has changed state: Channel=null,Channel.cisco.wf.subsystems.rmcm.ICDChannel,Channel Type=Cisco ICD Channel,Channel id=100,Channel implementation id=100,Old state=IDLE,New state=IN_USE
13259 03/26 14:51:48.750 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]
13259 03/26 14:51:48.750 ACCN TELS 0 Call.received() JTAPICallContact[id=11,implId=274536/1,state=STATE_RECEIVED_IDX,inbound=true,App name=voicemail,task=null,session=null,seq num=-1,cn=570,dn=570,cgn=572,ani=null,dnis=null,clid=null,atype=DIRECT,lrd=null,ocn=570,route=RP[num=570],TP=null
13259 03/26 14:51:48.750 ACCN TELS 0 lrdVoiceMailboxNumber=null ocnVoiceMailboxNumber= currentVoiceMailboxNumber=
13259 03/26 14:51:48.751 ACCN CHMG 0 ChMgrImpl::getIdleChannels - entering getIdleChannels()
13259 03/26 14:51:48.751 ACCN CHMG 0 ChMgrImpl:getIdleChannels - dealing with 2 different classes of groups
13259 03/26 14:51:48.751 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=10]
13259 03/26 14:51:48.751 ACCN CHMG 0 ChStubImpl:getChannelLock() -id=0 trying to get channel lock ...
13259 03/26 14:51:48.751 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]
13259 03/26 14:51:48.751 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]
13259 03/26 14:51:48.751 ACCN CHMG 0 GroupStubImpl::findAndLockIdleChannel() - inside method-GroupStub[channelClass=com.cisco.crs.jtapi.JTAPICallControlChannel,type=Cisco CTI Port,id=1,name=1,state=IN_SERVICE,channel=4]
13259 03/26 14:51:48.751 ACCN CHMG 0 ChStubImpl:getChannelLock() -id=1 trying to get channel lock ...
13259 03/26 14:51:48.751 ACCN CHMG 0 ChStubImpl:getChannelLock() - channel locked successfully-ChannelStub[channelClass=com.cisco.call.CallControlChannel,type=Cisco CTI Port,id=1,state=IDLE,pendingState=null,groupId=1,locked=true]
13259 03/26 14:51:48.751 ACCN CHMG 0 GroupStubImpl::findAndLockIdleChannel() - channel successfully lockedChannelStub[channelClass=com.cisco.call.CallControlChannel,type=Cisco CTI Port,id=1,state=IDLE,pendingState=null,groupId=1,locked=true]
13259 03/26 14:51:48.751 ACCN CHMG 0 ChMgrImpl:getIdleChannels - attempt() successful resulting in 2 permits allocated
13259 03/26 14:51:48.751 ACCN CHMG 0 ChMgrImpl:getIdleChannels - permit allocated: 'Cisco CTI Port Group #1'.1(1)
13259 03/26 14:51:48.751 ACCN CHMG 0 ChMgrImpl:getIdleChannels - permit allocated: 'Cisco Media Channel Group #0'.0(1)
13259 03/26 14:51:48.752 ACCN COMG 0 getApplicationPrivilege(): Privilege for contact: 11 is:1
13259 03/26 14:51:48.752 ACCN COMG 0 ContactManager: Acquiring a port of type: AAWorkflowPrivilege
13259 03/26 14:51:48.752 LLMA LAPI 0 Llama: vmPortAllocate(): Allocating vm port for requestor "CRS"
13259 03/26 14:51:48.752 LLMA LSDB 0 Llama: vmPortAllocate(): LlamaSysdbUser(): setString(): Setting /sw/apps/limitsManager/vmPort/allocate/requestor to CRS
2739 03/26 14:51:48.752 LLMA LSDB 0 LlamaVmPortAllocate: check(): requestor, Value: CRS: returns true
2732 03/26 14:51:48.752 LLMA LVMP 0 LlamaVmPortAllocate: commit(): Attribute: requestor, Value: CRS
13259 03/26 14:51:48.753 LLMA LAPI 0 Llama: vmPortAllocate(): Allocate vm port for requestor "CRS" returns true
13259 03/26 14:51:48.753 ACCN COMG 0 ContactManager: Port of type AAWorkflowPrivilege acquired successfully
13259 03/26 14:51:48.753 ACCN COMG 0 Port of type AAWorkflowPrivilege acquired for contact #11
13259 03/26 14:51:48.754 ACCN CHMG 0 CHANNEL_STATE_CHANGE:Channel has changed state: Channel=null,Channel.cisco.dialog.DialogChannel,Channel Type=Cisco Media Channel,Channel id=0,Channel implementation id=0,Old state=IDLE,New state=IN_USE
13259 03/26 14:51:48.754 ACCN CMTS 0 MediaDialogChannel id=0,state=IN_USE MDC::init
13259 03/26 14:51:48.754 ACCN CMTS 0 MediaDialogChannel id=0,state=IN_USE ::init: create newMediaManagerImpl
13259 03/26 14:51:48.754 ACCN LMED 0 appId returned by RTPStreammanager = 1
13259 03/26 14:51:48.754 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]
13259 03/26 14:51:48.754 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=10]
13259 03/26 14:51:48.754 ACCN CHMG 0 GrpStubImpl:notifyChannelInUse - No Group state change - channel state changed from Idle --> InUse
13259 03/26 14:51:48.754 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]
13259 03/26 14:51:48.754 ACCN CHMG 0 CHANNEL_STATE_CHANGE:Channel has changed state: Channel=null,Channel.cisco.call.CallControlChannel,Channel Type=Cisco CTI Port,Channel id=1,Channel implementation id=4102,Old state=IDLE,New state=IN_USE
13259 03/26 14:51:48.755 ACCN CHMG 0 GrpStubImpl:notifyChannelStateChange - state change recvd from: ChannelStub[channelClass=com.cisco.call.CallControlChannel,type=Cisco CTI Port,id=1,state=IN_USE,pendingState=null,groupId=1,locked=false]
13259 03/26 14:51:48.755 ACCN CHMG 0 GrpStubImpl:notifyChannelStateChange - channel changed from: IDLE ---> IN_USE for-GroupStub[channelClass=com.cisco.crs.jtapi.JTAPICallControlChannel,type=Cisco CTI Port,id=1,name=1,state=IN_SERVICE,channel=4]
13259 03/26 14:51:48.755 ACCN CHMG 0 GrpStubImpl:notifyChannelInUse - No Group state change - channel state changed from Idle --> InUse
13259 03/26 14:51:48.755 ACCN CHMG 0 ChStubImpl:handleInUseTransition - notifying all waiting threads that channel is no longer idle-ChannelStub[channelClass=com.cisco.call.CallControlChannel,type=Cisco CTI Port,id=1,state=IN_USE,pendingState=null,groupId=1,locked=false]
13259 03/26 14:51:48.755 ACCN TELS 0 Route Connection: [570:PAR_ESTERNE_LUGANO:1/(P1-jtapiadmin) GCID=(1,274536)->ACTIVE]->OFFERED, CTI Port selected: TP[id=1,implId=4102,state=IN_USE]
13259 03/26 14:51:48.755 ACCN TELS 0 RouteCallObserver.callChangedEvent: redirecting to 4102, css=default
13261 03/26 14:51:48.766 ACCN TELS 0  Got CallActiveEv, ConnCreatedEv, ConnInProgressEv, CallCtlConnOfferedEv, ConnCreatedEv, ConnConnectedEv, CallCtlConnEstablishedEv, ConnCreatedEv, ConnInProgressEv, CallCtlConnOfferedEv,  events on the AddressCallObserver.
13262 03/26 14:51:48.766 ACCN TELS 0  Got ConnDisconnectedEv, CallCtlConnDisconnectedEv,  events on the AddressCallObserver.
13263 03/26 14:51:48.767 ACCN TELS 0 RP[num=570], conn=[570:PAR_ESTERNE_LUGANO:1/(P1-jtapiadmin) GCID=(1,274536)->ACTIVE]->DISCONNECTED, event=CallCtlConnDisconnectedEv, cause=CAUSE_NORMAL[100], meta=META_CALL_REMOVING_PARTY[131]
13259 03/26 14:51:48.768 ACCN TELS 0 Call.associated() JTAPICallContact[id=11,implId=274536/1,state=STATE_RECEIVED_IDX,inbound=true,App name=voicemail,task=null,session=34000000010,seq num=0,cn=570,dn=570,cgn=572,ani=null,dnis=null,clid=null,atype=DIRECT,lrd=null,ocn=570,route=RP[num=570],TP=4102]
13259 03/26 14:51:48.768 ACCN TELS 0 Route Connection: [570:PAR_ESTERNE_LUGANO:1/(P1-jtapiadmin) GCID=(1,274536)->ACTIVE]->DISCONNECTED has 1 current sessions active.
13260 03/26 14:51:48.768 ACCN TELS 0 CallID: 11, MediaID: 274536/1 CallCtlConnOfferedEv received for CTI Port: 4102, lastRedirectedAddress: 570
13265 03/26 14:51:48.769 ACCN TELS 0 CallID:11 MediaId:274536/1 Got CallObservationEndedEv,  events on the AddressCallObserver.
13260 03/26 14:51:48.772 ACCN TELS 0 CallID: 11, MediaID: 274536/1 Accepting call for CTI Route Point: 570 on CTI Port: 4102, ciscoCause=31
13266 03/26 14:51:48.773 ACCN TELS 0 Call.accepted() JTAPICallContact[id=11,implId=274536/1,state=STATE_ACCEPTED_IDX,inbound=true,App name=voicemail,task=null,session=34000000010,seq num=0,cn=570,dn=570,cgn=572,ani=null,dnis=null,clid=null,atype=DIRECT,lrd=null,ocn=570,route=RP[num=570],TP=4102]
13266 03/26 14:51:48.773 ACCN TELS 0 CallID:11 MediaId:274536/1, TerminalConnection to Terminal: CTIPORT02 is RINGING, [4102:PAR_ESTERNE_LUGANO:1/(P1-jtapiadmin) GCID=(1,274536)->ACTIVE]->ALERTING
13266 03/26 14:51:48.773 ACCN TELS 0 CallID:11 MediaId:274536/1 com.cisco.jtapi.CiscoTermInServiceEvImpl received
2736 03/26 14:51:48.778 LLMA LIVR 0 LlamaIvrPortQuery: get(): isEnabled
2736 03/26 14:51:48.778 LLMA LFEA 0 LlamaFeatureManager: getIvrEnablement(): Getting IVR enablement variable, value is true
13266 03/26 14:51:48.778 LLMA LSDB 0 Llama: isIvrEnabled(): LlamaSysdbUser(): getInt(): Getting int /sw/apps/limitsManager/ivrPort/query/isEnabled returns 1
2735 03/26 14:51:48.778 WFSP SCRP 0 WFSysdbNdScript::get enter
2735 03/26 14:51:48.778 WFSP SCRP 0 WFSysdbNdScript::get attribute=privilege
2735 03/26 14:51:48.778 WFSP SCRP 0 WFSysdbNdScript::get exit
13266 03/26 14:51:48.779 ACCN APMG 0 TASK_CREATED:Application task created: Application=App[name=voicemail,type=Cisco Script Application,id=2,desc=voicemail,enabled=true,max=10,valid=true,cfg=[ApplicationConfig[,name=voicemail,id=2,type=Cisco Script Application.description=voicemail,enabled=true,sessions=10,script=SCRIPT[voicebrowser.aef],access=0,privilege=1,script=SCRIPT[voicebrowser.aef]]]],Application Trigger=ContactApplicationTrigger[time=1364305908777,locale=system.default,cfg=JTAPITriggerConfig[,name=570,type=Cisco JTAPI Trigger,appName=voicemail,enabled=true,sessions=2,idleTimeout=10000,locale=system.default,callControlGroup=1,priDialogGroup=0.secDialogGroup=0,controlClass=class com.cisco.call.CallControlChannel,controlGroupId=1,contactGroups=[GroupInfo[class=com.cisco.call.CallControlChannel,id=0], GroupInfo[class=com.cisco.call.CallControlChannel,id=0]]],contact=JTAPICallContact[id=11,implId=274536/1,state=STATE_ACCEPTED_IDX,inbound=true,App name=voicemail,task=null,session=34000000010,seq num=0,cn=570,dn=570,cgn=572,ani=null,dnis=null,clid=null,atype=DIRECT,lrd=null,ocn=570,route=RP[num=570],TP=4102]],Task id=22000000012,Task.cisco.wfframework.engine.core.WFEngineWorkflowDebugTask,New Task.cisco.app.impl.WFWorkflowAppDebugTaskWrapper
13266 03/26 14:51:48.780 ACCN APMG 0 APP_SESSION_ACTIVE:Active application session: Application=App[name=voicemail,type=Cisco Script Application,id=2,desc=voicemail,enabled=true,max=10,valid=true,cfg=[ApplicationConfig[,name=voicemail,id=2,type=Cisco Script Application.description=voicemail,enabled=true,sessions=10,script=SCRIPT[voicebrowser.aef],access=0,privilege=1,script=SCRIPT[voicebrowser.aef]]]],Application Trigger=ContactApplicationTrigger[time=1364305908777,locale=system.default,cfg=JTAPITriggerConfig[,name=570,type=Cisco JTAPI Trigger,appName=voicemail,enabled=true,sessions=2,idleTimeout=10000,locale=system.default,callControlGroup=1,priDialogGroup=0.secDialogGroup=0,controlClass=class com.cisco.call.CallControlChannel,controlGroupId=1,contactGroups=[GroupInfo[class=com.cisco.call.CallControlChannel,id=0], GroupInfo[class=com.cisco.call.CallControlChannel,id=0]]],contact=JTAPICallContact[id=11,implId=274536/1,state=STATE_ACCEPTED_IDX,inbound=true,App name=voicemail,task=null,session=34000000010,seq num=0,cn=570,dn=570,cgn=572,ani=null,dnis=null,clid=null,atype=DIRECT,lrd=null,ocn=570,route=RP[num=570],TP=4102]],Task id=22000000012,Active Sessions=1,Max Sessions=10
13266 03/26 14:51:48.780 ACCN TELS 0 Call.attributed() JTAPICallContact[id=11,implId=274536/1,state=STATE_ATTRIBUTED_IDX,inbound=true,App name=voicemail,task=null,session=34000000010,seq num=0,cn=570,dn=570,cgn=572,ani=null,dnis=null,clid=null,atype=DIRECT,lrd=null,ocn=570,route=RP[num=570],TP=4102]
13266 03/26 14:51:48.780 ACCN TELS 0 CallID:11 MediaId:274536/1 Task:22000000012 associated with Task ID: 22000000012
3628 03/26 14:51:48.781 ACCN APMG 0 TASK_INIT:Application task initializing: Application=App[name=voicemail,type=Cisco Script Application,id=2,desc=voicemail,enabled=true,max=10,valid=true,cfg=[ApplicationConfig[,name=voicemail,id=2,type=Cisco Script Application.description=voicemail,enabled=true,sessions=10,script=SCRIPT[voicebrowser.aef],access=0,privilege=1,script=SCRIPT[voicebrowser.aef]]]],Application Trigger=ContactApplicationTrigger[time=1364305908777,locale=system.default,cfg=JTAPITriggerConfig[,name=570,type=Cisco JTAPI Trigger,appName=voicemail,enabled=true,sessions=2,idleTimeout=10000,locale=system.default,callControlGroup=1,priDialogGroup=0.secDialogGroup=0,controlClass=class com.cisco.call.CallControlChannel,controlGroupId=1,contactGroups=[GroupInfo[class=com.cisco.call.CallControlChannel,id=0], GroupInfo[class=com.cisco.call.CallControlChannel,id=0]]],contact=JTAPICallContact[id=11,implId=274536/1,state=STATE_ATTRIBUTED_IDX,inbound=true,App name=voicemail,task=null,session=34000000010,seq num=0,cn=570,dn=570,cgn=572,ani=null,dnis=null,clid=null,atype=DIRECT,lrd=null,ocn=570,route=RP[num=570],TP=4102]],Task id=22000000012,Task com.cisco.app.impl.WFWorkflowAppDebugTaskWrapper
3628 03/26 14:51:48.782 ACCN APMG 0 TASK_STARTING:Application task starting: Application=App[name=voicemail,type=Cisco Script Application,id=2,desc=voicemail,enabled=true,max=10,valid=true,cfg=[ApplicationConfig[,name=voicemail,id=2,type=Cisco Script Application.description=voicemail,enabled=true,sessions=10,script=SCRIPT[voicebrowser.aef],access=0,privilege=1,script=SCRIPT[voicebrowser.aef]]]],Application Trigger=ContactApplicationTrigger[time=1364305908777,locale=system.default,cfg=JTAPITriggerConfig[,name=570,type=Cisco JTAPI Trigger,appName=voicemail,enabled=true,sessions=2,idleTimeout=10000,locale=system.default,callControlGroup=1,priDialogGroup=0.secDialogGroup=0,controlClass=class com.cisco.call.CallControlChannel,controlGroupId=1,contactGroups=[GroupInfo[class=com.cisco.call.CallControlChannel,id=0], GroupInfo[class=com.cisco.call.CallControlChannel,id=0]]],contact=JTAPICallContact[id=11,implId=274536/1,state=STATE_ATTRIBUTED_IDX,inbound=true,App name=voicemail,task=22000000012,session=34000000010,seq num=0,cn=570,dn=570,cgn=572,ani=null,dnis=null,clid=null,atype=DIRECT,lrd=null,ocn=570,route=RP[num=570],TP=4102]],Task id=22000000012,Task com.cisco.app.impl.WFWorkflowAppDebugTaskWrapper
3628 03/26 14:51:48.783 ACCN ENGN 0 Execute Task 22000000012
>>>>> 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=10,valid=true,cfg=[ApplicationConfig[,name=voicemail,id=2,type=Cisco Script Application.description=voicemail,enabled=true,sessions=10,script=SCRIPT[voicebrowser.aef],access=0,privilege=1,script=SCRIPT[voicebrowser.aef]]]]
Trigger: ContactApplicationTrigger[time=1364305908777,locale=system.default,cfg=JTAPITriggerConfig[,name=570,type=Cisco JTAPI Trigger,appName=voicemail,enabled=true,sessions=2,idleTimeout=10000,locale=system.default,callControlGroup=1,priDialogGroup=0.secDialogGroup=0,controlClass=class com.cisco.call.CallControlChannel,controlGroupId=1,contactGroups=[GroupInfo[class=com.cisco.call.CallControlChannel,id=0], GroupInfo[class=com.cisco.call.CallControlChannel,id=0]]],contact=JTAPICallContact[id=11,implId=274536/1,state=STATE_ATTRIBUTED_IDX,inbound=true,App name=voicemail,task=22000000012,session=34000000010,seq num=0,cn=570,dn=570,cgn=572,ani=null,dnis=null,clid=null,atype=DIRECT,lrd=null,ocn=570,route=RP[num=570],TP=4102]]
   An object of com.cisco.wfframework.engine.core.WFEngineWorkflowDebugTask
   ID: 22000000012
   Start time (ms): 1364305908778
   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
<<<<<

3628 03/26 14:51:48.784 ACCN APMG 0 EXECUTING_STEP:Executing a step: Application=App[name=voicemail,type=Cisco Script Application,id=2,desc=voicemail,enabled=true,max=10,valid=true,cfg=[ApplicationConfig[,name=voicemail,id=2,type=Cisco Script Application.description=voicemail,enabled=true,sessions=10,script=SCRIPT[voicebrowser.aef],access=0,privilege=1,script=SCRIPT[voicebrowser.aef]]]],Task id=22000000012,Step id=0,Step.cisco.wfframework.steps.core.StepStart,Step Description=Start
3628 03/26 14:51:48.784 ACCN ENGN 0 Execute step of Task 22000000012 : Start
3628 03/26 14:51:48.784 ACCN APMG 0 EXECUTING_STEP:Executing a step: Application=App[name=voicemail,type=Cisco Script Application,id=2,desc=voicemail,enabled=true,max=10,valid=true,cfg=[ApplicationConfig[,name=voicemail,id=2,type=Cisco Script Application.description=voicemail,enabled=true,sessions=10,script=SCRIPT[voicebrowser.aef],access=0,privilege=1,script=SCRIPT[voicebrowser.aef]]]],Task id=22000000012,Step id=115,Step.cisco.wf.steps.ivr.AcceptStep,Step Description=Accept (--Triggering Contact--)
3628 03/26 14:51:48.784 ACCN ENGN 0 Execute step of Task 22000000012 : Accept (--Triggering Contact--)
3781 03/26 14:51:48.789 ACCN TELS 0 CallID:11 MediaId:274536/1 Task:22000000012, CallCtlConnFailed, Inbound call, callctl cause:107, [4102:PAR_ESTERNE_LUGANO:1/(P1-jtapiadmin) GCID=(1,274536)->ACTIVE]->FAILED
13266 03/26 14:51:48.791 ACCN TELS 0 CallID:11 MediaId:274536/1 Task:22000000012 gets TermConnDroppedEv, meta code:132, cause code:100
13266 03/26 14:51:48.791 ACCN TELS 0 CallID:11 MediaId:274536/1 Task:22000000012, TerminalConnection to Terminal: CTIPORT02 is DROPPED, 274536/1
13266 03/26 14:51:48.791 ACCN TELS 0 CallID:11 MediaId:274536/1 is removed from call session mapping in Session[id=001-0x7ea8ed40a,parent=null,active=true,state=SESSION_IN_USE,time=1364305908750], result:true
13266 03/26 14:51:48.791 ACCN CHMG 0 ChStubImpl:notifyIdle(true) - id=100, dynamic
13266 03/26 14:51:48.791 ACCN CHMG 0 ChStubImpl:notifyIdle(true) - id=0, non-dynamic
13266 03/26 14:51:48.791 ACCN CHMG 0 ChStubImpl:notifyIdle(false) - id=1
13266 03/26 14:51:48.791 ACCN CHMG 0 ChStubImpl:handleIdleTransition - channel currently InUse - removing channel from contact-ChannelStub[channelClass=com.cisco.call.CallControlChannel,type=Cisco CTI Port,id=1,state=IN_USE,pendingState=null,groupId=1,locked=false]
13266 03/26 14:51:48.792 ACCN CHMG 0 CHANNEL_STATE_CHANGE:Channel has changed state: Channel=null,Channel.cisco.call.CallControlChannel,Channel Type=Cisco CTI Port,Channel id=1,Channel implementation id=4102,Old state=IN_USE,New state=IDLE
13266 03/26 14:51:48.792 ACCN CHMG 0 GrpStubImpl:notifyChannelStateChange - state change recvd from: ChannelStub[channelClass=com.cisco.call.CallControlChannel,type=Cisco CTI Port,id=1,state=IDLE,pendingState=null,groupId=1,locked=true]
13266 03/26 14:51:48.792 ACCN CHMG 0 GrpStubImpl:notifyChannelStateChange - channel changed from: IN_USE ---> IDLE for-GroupStub[channelClass=com.cisco.crs.jtapi.JTAPICallControlChannel,type=Cisco CTI Port,id=1,name=1,state=IN_SERVICE,channel=4]
13266 03/26 14:51:48.792 ACCN CHMG 0 GrpStubImpl:notifyChannelIdle - No Group state change - channel state changed from InUse --> Idle
13266 03/26 14:51:48.792 ACCN CHMG 0 GrpStubImpl:notifyChannelIdle - calling async.release() to make channel available
13266 03/26 14:51:48.792 ACCN CHMG 0 ChStubImpl:releaseChannelLock() - channel lock releasedChannelStub[channelClass=com.cisco.call.CallControlChannel,type=Cisco CTI Port,id=1,state=IDLE,pendingState=null,groupId=1,locked=false]
13266 03/26 14:51:48.792 ACCN COMG 0 getApplicationPrivilege(): Privilege for contact: 11 is:1
13266 03/26 14:51:48.792 ACCN COMG 0 ContactManager: Releasing a port of type: AAWorkflowPrivilege
13266 03/26 14:51:48.792 LLMA LAPI 0 Llama: vmPortDeallocate(): Deallocating vm port for requestor "CRS"
13266 03/26 14:51:48.792 LLMA LSDB 0 Llama: vmPortDeallocate(): LlamaSysdbUser(): setString(): Setting /sw/apps/limitsManager/vmPort/deallocate/requestor to CRS
2735 03/26 14:51:48.793 LLMA LSDB 0 LlamaVmPortDeallocate: check(): requestor, Value: CRS: returns true
2736 03/26 14:51:48.793 LLMA LVMP 0 LlamaVmPortDeallocate: commit(): Attribute: requestor, Value: CRS
13266 03/26 14:51:48.793 LLMA LAPI 0 Llama: vmPortDeallocate(): Deallocate vm port for requestor "CRS" returns true
13266 03/26 14:51:48.793 ACCN COMG 0 ContactManager: Port of type AAWorkflowPrivilege released successfully
13266 03/26 14:51:48.793 ACCN COMG 0 IVR port for privilege: AAWorkflowPrivilege released by contact #11
13267 03/26 14:51:48.794 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]
13267 03/26 14:51:48.794 ACCN CHMG 0 CHANNEL_STATE_CHANGE:Channel has changed state: Channel=null,Channel.cisco.dialog.DialogChannel,Channel Type=Cisco Media Channel,Channel id=0,Channel implementation id=0,Old state=IN_USE,New state=IDLE

Getting Started

Find answers to your questions by entering keywords or phrases in the Search bar above. New here? Use these resources to familiarize yourself with the community: