VoiceViewExpress Authentication Error

Unanswered Question
Oct 9th, 2009

I'm trying to get VoiceViewExpress working on our UC520W with a 7962G, IPCommunicator, and SPA504G attached.  It's running software pack UC520-7.1.3-ea.  Configuration done entirely through CCA and CUE.  Nothing done with the CLI.


Voicemail is configured and working fine when calling in, but VVE gives:


"Authentication error.  Report this error to your system administrator."


But only when trying to listen to messages in VVE, and only on the IPC and 7962.  The 504G works fine.  I can log in to VVE from any phone, see the list of messages, and select any message with no problems. 


Any help would be greatly appreciated.  Thanks!


Mike


A <trace voiceview all> when trying to listen to a message from VVE on the IPC gives:


3609 10/09 18:25:35.639 vovw cont 0 Submit Type 'LISTEN'

3609 10/09 18:25:35.639 vovw sydb 0 /sw/apps/monitor/ctrl/offline

3609 10/09 18:25:35.640 vovw sydb 0 0

3609 10/09 18:25:35.640 vovw cont 0 Center Controller Requested URI: /voiceview/voicemail/playvm.do

3609 10/09 18:25:35.641 vovw vcmt 0 Adding OID: 26046

3609 10/09 18:25:35.641 vovw vcmt 0 Total play time 4

3609 10/09 18:25:35.642 vovw vcmt 0 SoundTransmitter started

3938 10/09 18:25:35.644 ACCN COMG 0 ContactStubImpl is constructed, id=15,type=Cisco VoiceView Call,impl id=null

3609 10/09 18:25:35.646 vovw vcmt 0 New CRA callId: 15

3938 10/09 18:25:35.652 ACCN CHMG 0 ChMgrImpl:getGroup - got following group GroupStub[channelClass=com.cisco.wf.subsystems.cmt.CMTDialogChannelImpl,type=Cisco Media Channel,id=0,name=0,state=IN_SERVICE,channel=6]

3938 10/09 18:25:35.653 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=6]

3938 10/09 18:25:35.653 ACCN CHMG 0 ChStubImpl:getChannelLock() -id=2 trying to get channel lock ...

3938 10/09 18:25:35.653 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]

3938 10/09 18:25:35.653 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]

3938 10/09 18:25:35.653 ACCN COMG 0 getApplicationPrivilege(): application is null for contact: 15

3938 10/09 18:25:35.653 ACCN COMG 0 ContactManager: Acquiring a port of type: AAWorkflowPrivilege

3938 10/09 18:25:35.653 LLMA LAPI 0 Llama: vmPortAllocate(): Allocating vm port for requestor "CRS"

3938 10/09 18:25:35.654 LLMA LSDB 0 Llama: vmPortAllocate(): LlamaSysdbUser(): setString(): Setting /sw/apps/limitsManager/vmPort/allocate/requestor to CRS

2234 10/09 18:25:35.659 LLMA LSDB 0 LlamaVmPortAllocate: check(): requestor, Value: CRS: returns true

2223 10/09 18:25:35.660 LLMA LVMP 0 LlamaVmPortAllocate: commit(): Attribute: requestor, Value: CRS

2223 10/09 18:25:35.660 LLMA LLIC 0 LlamaLicense incrementCount VM Port Feature - countsUsed = 1

3938 10/09 18:25:35.660 LLMA LAPI 0 Llama: vmPortAllocate(): Allocate vm port for requestor "CRS" returns true

3938 10/09 18:25:35.660 ACCN COMG 0 ContactManager: Port of type AAWorkflowPrivilege acquired successfully

3938 10/09 18:25:35.660 ACCN COMG 0 Port of type AAWorkflowPrivilege acquired for contact #15

3938 10/09 18:25:35.661 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=2,Channel implementation id=2,Old state=IDLE,New state=IN_USE

3938 10/09 18:25:35.661 ACCN CMTS 0 MediaDialogChannel id=2,state=IN_USE MDC::init

3938 10/09 18:25:35.661 ACCN CMTS 0 MediaDialogChannel id=2,state=IN_USE ::init: create newMediaManagerImpl

3938 10/09 18:25:35.661 ACCN LMED 0 After allocating sync object

3938 10/09 18:25:35.662 ACCN LMED 0 Enter RTPRecorder constructor

3938 10/09 18:25:35.662 ACCN LMED 0 Enter: DoubleBufferDatagramInputStream:Constructor

3938 10/09 18:25:35.662 ACCN CHMG 0 GrpStubImpl:notifyChannelStateChange - state change recvd from: ChannelStub[channelClass=com.cisco.dialog.DialogChannel,type=Cisco Media Channel,id=2,state=IN_USE,pendingState=null,groupId=0,locked=false]

3938 10/09 18:25:35.662 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=6]

3938 10/09 18:25:35.662 ACCN CHMG 0 GrpStubImpl:notifyChannelInUse - No Group state change - channel state changed from Idle --> InUse

3938 10/09 18:25:35.662 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=2,state=IN_USE,pendingState=null,groupId=0,locked=false]

3611 10/09 18:25:35.852 vovw cont 0 Enter Controller Requested URI: /voiceview/authentication/authenticate.do

3611 10/09 18:25:35.859 vovw sydb 0 /sw/limits/global/applicationMode

3611 10/09 18:25:35.859 vovw sydb 0 ITS

3611 10/09 18:25:35.861 vovw auth 0 Request to authenticate ciscocisco at 192.168.10.10

3611 10/09 18:25:35.861 vovw sydb 0 /sw/apps/vui/vvconfig/authenticateAll

3611 10/09 18:25:35.863 vovw sydb 0 0

3611 10/09 18:25:35.863 vovw auth 0 Falling back to the fallback URL, if any

3611 10/09 18:25:35.863 vovw sydb 0 /sw/apps/vui/vvconfig/fallbackURL

3611 10/09 18:25:35.864 vovw sydb 0

3611 10/09 18:25:35.864 vovw auth 0 No fallback URL. UNAUTHORIZED

3611 10/09 18:25:35.864 vovw cont 0 Exit Controller Requested URI: /voiceview/authentication/authenticate.do

3938 10/09 18:25:35.906 ACCN CHMG 0 ChStubImpl:handleIdleTransition - channel currently InUse - removing channel from contact-ChannelStub[channelClass=com.cisco.dialog.DialogChannel,type=Cisco Media Channel,id=2,state=IN_USE,pendingState=null,groupId=0,locked=false]

3938 10/09 18:25:35.907 ACCN CMTS 0 MediaDialogChannel id=2,state=IN_USE MDC::abort: return without waiting

3938 10/09 18:25:35.907 ACCN CMTS 0 MediaDialogChannel id=2,state=IN_USE MediaManager resetted.

3938 10/09 18:25:35.907 ACCN LMED 0 PromptPlayer::closeStream

3938 10/09 18:25:35.907 ACCN LMED 0 PromptPlayer::resetSources

3938 10/09 18:25:35.907 ACCN LMED 0 PromptPlayer::rtpData close

3938 10/09 18:25:35.907 ACCN LMED 0 PromptPlayer::closeStream done


From the 504G I see:

3609 10/09 18:38:48.970 vovw cont 0 Submit Type 'LISTEN'
3609 10/09 18:38:48.970 vovw sydb 0 /sw/apps/monitor/ctrl/offline
3609 10/09 18:38:48.971 vovw sydb 0 0
3609 10/09 18:38:48.971 vovw cont 0 Center Controller Requested URI: /voiceview/voicemail/playvm.do
3609 10/09 18:38:48.972 vovw vcmt 0 Adding OID: 26046
3609 10/09 18:38:48.973 vovw vcmt 0 Total play time 4
3609 10/09 18:38:48.973 vovw vcmt 0 SoundTransmitter started
4053 10/09 18:38:48.981 ACCN COMG 0 ContactStubImpl is constructed, id=16,type=Cisco VoiceView Call,impl id=null
3609 10/09 18:38:49.022 vovw vcmt 0 New CRA callId: 16
4053 10/09 18:38:49.065 ACCN CHMG 0 ChMgrImpl:getGroup - got following group GroupStub[channelClass=com.cisco.wf.subsystems.cmt.CMTDialogChannelImpl,type=Cisco Media Channel,id=0,name=0,state=IN_SERVICE,channel=6]
4053 10/09 18:38:49.065 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=6]
4053 10/09 18:38:49.065 ACCN CHMG 0 ChStubImpl:getChannelLock() -id=1 trying to get channel lock ...
4053 10/09 18:38:49.065 ACCN CHMG 0 ChStubImpl:getChannelLock() - channel locked successfully-ChannelStub[channelClass=com.cisco.dialog.DialogChannel,type=Cisco Media Channel,id=1,state=IDLE,pendingState=null,groupId=0,locked=true]
4053 10/09 18:38:49.065 ACCN CHMG 0 GroupStubImpl::findAndLockIdleChannel() - channel successfully lockedChannelStub[channelClass=com.cisco.dialog.DialogChannel,type=Cisco Media Channel,id=1,state=IDLE,pendingState=null,groupId=0,locked=true]
4053 10/09 18:38:49.065 ACCN COMG 0 getApplicationPrivilege(): application is null for contact: 16
4053 10/09 18:38:49.065 ACCN COMG 0 ContactManager: Acquiring a port of type: AAWorkflowPrivilege
4053 10/09 18:38:49.065 LLMA LAPI 0 Llama: vmPortAllocate(): Allocating vm port for requestor "CRS"
4053 10/09 18:38:49.067 LLMA LSDB 0 Llama: vmPortAllocate(): LlamaSysdbUser(): setString(): Setting /sw/apps/limitsManager/vmPort/allocate/requestor to CRS
2223 10/09 18:38:49.068 LLMA LSDB 0 LlamaVmPortAllocate: check(): requestor, Value: CRS: returns true
2242 10/09 18:38:49.069 LLMA LVMP 0 LlamaVmPortAllocate: commit(): Attribute: requestor, Value: CRS
2242 10/09 18:38:49.069 LLMA LLIC 0 LlamaLicense incrementCount VM Port Feature - countsUsed = 1
4053 10/09 18:38:49.069 LLMA LAPI 0 Llama: vmPortAllocate(): Allocate vm port for requestor "CRS" returns true
4053 10/09 18:38:49.069 ACCN COMG 0 ContactManager: Port of type AAWorkflowPrivilege acquired successfully
4053 10/09 18:38:49.069 ACCN COMG 0 Port of type AAWorkflowPrivilege acquired for contact #16
4053 10/09 18:38:49.070 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=1,Channel implementation id=1,Old state=IDLE,New state=IN_USE
4053 10/09 18:38:49.071 ACCN CMTS 0 MediaDialogChannel id=1,state=IN_USE MDC::init
4053 10/09 18:38:49.071 ACCN CMTS 0 MediaDialogChannel id=1,state=IN_USE ::init: create newMediaManagerImpl
4053 10/09 18:38:49.071 ACCN LMED 0 After allocating sync object
4053 10/09 18:38:49.071 ACCN LMED 0 Enter RTPRecorder constructor
4053 10/09 18:38:49.071 ACCN LMED 0 Enter: DoubleBufferDatagramInputStream:Constructor
4053 10/09 18:38:49.071 ACCN CHMG 0 GrpStubImpl:notifyChannelStateChange - state change recvd from: ChannelStub[channelClass=com.cisco.dialog.DialogChannel,type=Cisco Media Channel,id=1,state=IN_USE,pendingState=null,groupId=0,locked=false]
4053 10/09 18:38:49.072 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=6]
4053 10/09 18:38:49.072 ACCN CHMG 0 GrpStubImpl:notifyChannelInUse - No Group state change - channel state changed from Idle --> InUse
4053 10/09 18:38:49.072 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=1,state=IN_USE,pendingState=null,groupId=0,locked=false]
3609 10/09 18:38:49.263 vovw vcmt 0 IP Phone authentication done. Remote Port: 16488. Starting RTPSender
4053 10/09 18:38:49.292 ACCN LMED 0 RTP_PROPERTIES_REASSIGNED:RTP Properties Reassigned: Method Name=setTxDestination(),HOST NAME=10.1.1.12,PORT NUMBER=16,488,PACKET SIZE=20
4053 10/09 18:38:49.292 ACCN LMED 0 setTxDestination(): allocating outputSink(RTPSenderN)
3609 10/09 18:38:49.294 vovw vcmt 0 /voicemail/playvm.do?submitType=END
3609 10/09 18:38:49.300 vovw cont 0 Exit Controller Requested URI: /voiceview/WEB-INF/screens/phoneobjects/CiscoIPPhoneText.jsp
4054 10/09 18:38:49.301 vovw cont 0 sp.setMaxInactiveInterval 304
4053 10/09 18:38:49.304 ACCN CMTS 0 MediaDialogChannel id=1,state=IN_USE MDC::set: [email protected] enter OK
4053 10/09 18:38:49.304 ACCN CMTS 0 init:: flushDTMF=true DTMFBuffer.size=0
4053 10/09 18:38:49.304 ACCN CMTS 0 CMTPlayPrompt: Queuing & Playing offset: 0
4053 10/09 18:38:49.304 ACCN LMED 0 In PromptPlayer.playPromptQueue
4053 10/09 18:38:49.304 ACCN LMED 0 PromptPlayer.initializeDataSource()
4053 10/09 18:38:49.304 ACCN LMED 0 In play !!!!!
4053 10/09 18:38:49.304 ACCN LMED 0 play() promptQ.size=1
4053 10/09 18:38:49.327 ACCN LMED 0 In play: before dataSource.start
4053 10/09 18:38:49.327 ACCN LMED 0 In play: after dataSource.start
4053 10/09 18:38:49.327 ACCN LMED 0 startOutput()
4053 10/09 18:38:49.327 ACCN LMED 0 In play: after startOutput
4053 10/09 18:38:49.327 ACCN LMED 0 play(). StartOutput() called.
4053 10/09 18:38:49.327 ACCN LMED 0 In play(): Now going to wait() for prompt to finish
3557 10/09 18:38:52.415 ACCN LMED 0 fileSendDone(), finished=false
3557 10/09 18:38:52.415 ACCN LMED 0 PromptPlayer.destroy() called.
4053 10/09 18:38:52.420 ACCN LMED 0 In play(): After the wait()
4053 10/09 18:38:52.420 ACCN CMTS 0 CMTPlayPrompt: Playing done. Returning
4053 10/09 18:38:52.420 ACCN CMTS 0 MediaDialogChannel id=1,state=IN_USE MDC::clear [email protected] abortWaiting=false
4053 10/09 18:38:52.423 ACCN CHMG 0 ChStubImpl:handleIdleTransition - channel currently InUse - removing channel from contact-ChannelStub[channelClass=com.cisco.dialog.DialogChannel,type=Cisco Media Channel,id=1,state=IN_USE,pendingState=null,groupId=0,locked=false]
4053 10/09 18:38:52.423 ACCN LMED 0 muteDestination()
4053 10/09 18:38:52.423 ACCN LMED 0 PromptPlayer.muteDestination().  !started. Return...
4053 10/09 18:38:52.423 ACCN LMED 0 Enter RTPRecorder:stopInput
4053 10/09 18:38:52.423 ACCN LMED 0 Enter: DoubleBufferDatagramInputStream:stopReceivingRTP
4053 10/09 18:38:52.423 ACCN LMED 0 Done RTPRecorder:stopInput
4053 10/09 18:38:52.424 ACCN CMTS 0 MediaDialogChannel id=1,state=IN_USE MDC::abort: return without waiting
4053 10/09 18:38:52.424 ACCN CMTS 0 MediaDialogChannel id=1,state=IN_USE MediaManager resetted.
4053 10/09 18:38:52.424 ACCN LMED 0 PromptPlayer::closeStream
4053 10/09 18:38:52.424 ACCN LMED 0 PromptPlayer::closing outputSink
4053 10/09 18:38:52.426 ACCN LMED 0 PromptPlayer::resetSources
4053 10/09 18:38:52.426 ACCN LMED 0 PromptPlayer::rtpData close
4053 10/09 18:38:52.426 ACCN LMED 0 PromptPlayer::closeStream done
4053 10/09 18:38:52.426 ACCN LMED 0 Enter RTPRecorder:stopRecord
4053 10/09 18:38:52.426 ACCN LMED 0 closeStream(), port=0
4053 10/09 18:38:52.426 ACCN LMED 0 Stopping doublebuffer input stream
4053 10/09 18:38:52.426 ACCN LMED 0 Enter: DoubleBufferDatagramInputStream:stop
4053 10/09 18:38:52.427 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=1,Channel implementation id=1,Old state=IN_USE,New state=IDLE
4053 10/09 18:38:52.474 ACCN CHMG 0 GrpStubImpl:notifyChannelStateChange - state change recvd from: ChannelStub[channelClass=com.cisco.dialog.DialogChannel,type=Cisco Media Channel,id=1,state=IDLE,pendingState=null,groupId=0,locked=true]
4053 10/09 18:38:52.474 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=6]
4053 10/09 18:38:52.474 ACCN CHMG 0 GrpStubImpl:notifyChannelIdle - No Group state change - channel state changed from InUse --> Idle
4053 10/09 18:38:52.474 ACCN CHMG 0 GrpStubImpl:notifyChannelIdle - calling async.release() to make channel available
4053 10/09 18:38:52.474 ACCN CHMG 0 ChStubImpl:releaseChannelLock() - channel lock releasedChannelStub[channelClass=com.cisco.dialog.DialogChannel,type=Cisco Media Channel,id=1,state=IDLE,pendingState=null,groupId=0,locked=false]
4053 10/09 18:38:52.474 ACCN COMG 0 getApplicationPrivilege(): application is null for contact: 16
4053 10/09 18:38:52.474 ACCN COMG 0 ContactManager: Releasing a port of type: AAWorkflowPrivilege
4053 10/09 18:38:52.474 LLMA LAPI 0 Llama: vmPortDeallocate(): Deallocating vm port for requestor "CRS"
4053 10/09 18:38:52.476 LLMA LSDB 0 Llama: vmPortDeallocate(): LlamaSysdbUser(): setString(): Setting /sw/apps/limitsManager/vmPort/deallocate/requestor to CRS
2234 10/09 18:38:52.477 LLMA LSDB 0 LlamaVmPortDeallocate: check(): requestor, Value: CRS: returns true
2238 10/09 18:38:52.480 LLMA LVMP 0 LlamaVmPortDeallocate: commit(): Attribute: requestor, Value: CRS
2238 10/09 18:38:52.480 LLMA LLIC 0 LlamaLicense decrementCount VM Port Feature - countsUsed = 0
4053 10/09 18:38:52.480 LLMA LAPI 0 Llama: vmPortDeallocate(): Deallocate vm port for requestor "CRS" returns true
4053 10/09 18:38:52.481 ACCN COMG 0 ContactManager: Port of type AAWorkflowPrivilege released successfully
4053 10/09 18:38:52.481 ACCN COMG 0 IVR port for privilege: AAWorkflowPrivilege released by contact #16
4054 10/09 18:38:52.482 vovw vcmt 0 Sound Transmitter done. Initiating shutdown sequence.
4054 10/09 18:38:52.482 vovw vcmt 0 Stopping RTP Sender
4054 10/09 18:38:52.491 vovw vcmt 0 Closing RTP Channel. Shutdown URL http://10.1.10.1/voiceview/voicemail/playvm.do;jsessionid=iaxalawu61?submitType=AUTOEND
4054 10/09 18:38:52.873 vovw vcmt 0 RTP Channel closed
4054 10/09 18:38:52.873 vovw cont 0 sp.setMaxInactiveInterval 300
3513 10/09 18:38:52.878 vovw cont 0 Enter Controller Requested URI: /voiceview/voicemail/playvm.do
3513 10/09 18:38:52.878 vovw sydb 0 /sw/apps/vui/vvconfig/enabled
3513 10/09 18:38:52.881 vovw sydb 0 1
3513 10/09 18:38:52.881 vovw cont 0 Submit Type 'AUTOEND'
3513 10/09 18:38:52.881 vovw sydb 0 /sw/apps/monitor/ctrl/offline
3513 10/09 18:38:52.883 vovw sydb 0 0
3513 10/09 18:38:52.883 vovw cont 0 Center Controller Requested URI: /voiceview/voicemail/playvm.do
3513 10/09 18:38:52.884 vovw vcmt 0 END request false
3513 10/09 18:38:52.898 VMSS vmdb 0 Request connection: inUse: 0, active: 2
3513 10/09 18:38:52.898 VMSS vmdb 0 Got connection: 0, inUse: 1, active: 2
3513 10/09 18:38:52.899 VMSS vmdb 7 select mailboxid from vm_mbxusers where owner=true and userdn='/sw/local/users/awyatt';
3513 10/09 18:38:52.902 VMSS vmdb 0 Freed connection: 0, inUse: 0, active: 2
3513 10/09 18:38:52.921 vovw cont 0 Exit Controller Requested URI: /voiceview/WEB-INF/screens/phoneobjects/CiscoIPPhoneText.jsp


some more stuff:

se-10-1-10-1# show voiceview configuration
Phone service URL:       http://<CUE-hostname>/voiceview/common/login.do
Enabled:                 Yes
Idle Timeout (minutes):  5
se-10-1-10-1# show phone-authentication configuration
Authentication service URL: http://<CUE-hostname>/voiceview/authentication/authenticate.do
Authentication Fallback Server URL:
se-10-1-10-1#

UC_520#show running-config | inc url
url services 1 http://10.1.10.1/voiceview/common/login.do VoiceviewExpress
url services 1 http://10.1.10.1/voiceview/common/login.do VoiceviewExpress
UC_520#

se-10-1-10-1# show software licenses
Installed license files:
- voicemail_lic.sig : 50 MAILBOX LICENSE

Core:
- Application mode: CCME
- Total usable system ports: 6

Voicemail/Auto Attendant:
- Max system mailbox capacity time: 840
- Default # of general delivery mailboxes: 15
- Default # of personal mailboxes: 50

- Max # of configurable mailboxes: 65

Interactive Voice Response:
- Max # of IVR sessions: Not Available

Languages:
- Max installed languages: 2
- Max enabled languages: 1
se-10-1-10-1#


Attachment: 
  • 1
  • 2
  • 3
  • 4
  • 5
Overall Rating: 0 (0 ratings)
Loading.
michaelsobik Sun, 10/11/2009 - 07:32

Thanks for looking at this Marcos.  I updated the URL as follows and rebooted the phones.


UC_520#show configuration | inc url

url services http://10.1.10.1/voiceview/common/login.do

url authentication http://10.1.1.1/CCMCIP/authenticate.asp

url services 1 http://10.1.10.1/voiceview/common/login.do VoiceviewExpress

url services 1 http://10.1.10.1/voiceview/common/login.do VoiceviewExpress

UC_520#


The problem remains.

I'm a little confused as to why the 504G works fine and the other two don't.  The 504 doesn't have a user associated with the extension, so I have to enter a login and a pin from the GUI, but that's the only difference.

Let me know if I can provide anything else.  Thanks.

Mike
michaelsobik Sun, 10/11/2009 - 14:04

CCA won't allow spaces in the authentication URL.  I updated the URL from the CLI using:


url authentication http://10.1.10.1/CCMCIP/authenticate.asp

authentication credential cisco cisco


reset the Phone Authentication User Name/Password in CUE to cisco/cisco


rebooted the phones


trace from the working 504G:

10827 10/11 16:12:15.143 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=6]

10827 10/11 16:12:15.143 ACCN CHMG 0 GrpStubImpl:notifyChannelInUse - No Group state change - channel state changed from Idle --> InUse

10827 10/11 16:12:15.143 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]

9891 10/11 16:12:15.497 vovw vcmt 0 IP Phone authentication done. Remote Port: 16408. Starting RTPSender


trace from the broken IPC:
10837 10/11 16:15:33.082 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=6]
10837 10/11 16:15:33.082 ACCN CHMG 0 GrpStubImpl:notifyChannelInUse - No Group state change - channel state changed from Idle --> InUse
10837 10/11 16:15:33.082 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=5,state=IN_USE,pendingState=null,groupId=0,locked=false]
10837 10/11 16:15:33.342 ACCN CHMG 0 ChStubImpl:handleIdleTransition - channel currently InUse - removing channel from contact-ChannelStub[channelClass=com.cisco.dialog.DialogChannel,type=Cisco Media Channel,id=5,state=IN_USE,pendingState=null,groupId=0,locked=false]
10837 10/11 16:15:33.342 ACCN CMTS 0 MediaDialogChannel id=5,state=IN_USE MDC::abort: return without waiting
10837 10/11 16:15:33.342 ACCN CMTS 0 MediaDialogChannel id=5,state=IN_USE MediaManager resetted.
10837 10/11 16:15:33.343 ACCN LMED 0 PromptPlayer::closeStream
10837 10/11 16:15:33.343 ACCN LMED 0 PromptPlayer::resetSources
10837 10/11 16:15:33.343 ACCN LMED 0 PromptPlayer::rtpData close
10837 10/11 16:15:33.343 ACCN LMED 0 PromptPlayer::closeStream done
10837 10/11 16:15:33.343 ACCN LMED 0 Enter RTPRecorder:stopRecord
10837 10/11 16:15:33.343 ACCN LMED 0 closeStream(), port=0
10837 10/11 16:15:33.343 ACCN LMED 0 Stopping doublebuffer input stream

IPC now displays:


Unknown error code {0}. Report this error to your system administrator.





Marcos Hernandez Mon, 10/12/2009 - 05:55

Hi Michael,,


May I ask you to please go ahead and open a TAC case? We need to track this through our formal support process.


Thanks a lot,


Marcos

michaelsobik Mon, 10/19/2009 - 06:25

Sorry for the delay, Marcos.  The contract had expired on this box, and to be honest Cisco doesn't make it easy to buy support contracts.  Anyway, I opened the TAC request this morning.  Tracking number 612738719.  Thanks!


Mike

Christian Isla Fri, 05/21/2010 - 08:46

Was there a resolution to this?  I'm running the ver 8 now on a 560, with the exact problem. I get as far as seeing the message envelope, but as soon as I try to play it I get " Playback Failed"  .. "Authentication error.  Report this error to your system administrator"..


ci

Steven DiStefano Fri, 05/21/2010 - 09:05
I took a look at the case and found this:

TRY THIS:
-telephony-service
-url authentication http:///CCMCIP/authenticate.asp

Reboot phones and retry.


Before CME/CUE 7.0, the correct way was - "
http://192.0.2.0/voiceview/authentication/authenticate.do"
<http://192.0.2.0/voiceview/authentication/authenticate.do> ;

The problem with this was only one IP Phone Service App could be
supported.


michaelsobik Fri, 05/21/2010 - 09:08

Steve, you guys are speedy.  I just looked back through my email and this was indeed the solution.  VVE has been working great since I made the change.  Thanks.

Christian Isla Fri, 05/21/2010 - 09:12

Well,  I just tried a few things here after getting some ideas from this post and finally ended up fixing it.


last week we changed out just the IOS from Version 15.0(1)XA1, to Version 15.0(1)XA2,


My original config in telephony-services  had this:

url authentication http://10.1.10.1/voiceview/authentication/authenticate.do


and for what ever reason my current telephony-service with broken voiceview had this:

url authentication http://10.1.10.2/CCMCIP/authenticate.asp


so I switched it back and did a restart on my ephone..


worked.!!!


ci

Christian Isla Fri, 05/21/2010 - 09:28

wow, that was fast.


Hmm, that's odd then. if mine works as pre 7.0,

yet my IOS is 15.X2 and my CUE is

CCN-UC560#ser int0/0 status
Service Module is Cisco Integrated-Service-Engine0/0
Service Module supports session via TTY line 2
Service Module is in Steady state
Service Module heartbeat-reset is enabled
Getting status from the Service Module, please wait..


Cisco Unity Express 7.1.3
CUE is on UC500UC500

  Does that mean I'm only able to run this one app?


ci

Actions

This Discussion