Introduction
This document describres the different methods available to enable and disable the Message Waiting Indicator (MWI) on an Internet Protocol (IP) Phone, along with how to troubleshoot problems that arise when Cisco Unity Express (CUE) is integrated with Cisco Unified Communications Manager Express (CUCME).
Prerequisites
Requirements
Cisco recommends that you have knowledge of these topics:
- Call Manager Express (CME) or CUCME
- Cisco Unity Express
- Skinny Call Control Protocol (SCCP)
- Session Initiation Protocol (SIP)
Components Used
The information in this document is based on these software and hardware versions:
- CUE 7.x and 8.x. Sample configurations and screen captures are taken from CUE 7.0.6 and 8.6.2, installed on a NME-CUE module
- CUCME 7.1 and 8.5
- Cisco IP Phone 7965 registered with CUCME, with SCCP
The information in this document was created from the devices in a specific lab environment. All of the devices used in this document started with a cleared (default) configuration. If your network is live, ensure that you understand the potential impact of any command.
Related Products
This document can also be used with these hardware and software versions:
- Any CUE and CME version can be used
- Any CUE module or CME Router can be used
Background Information
MWI is used to indicate a new message is left in the voice mailbox. To indicate a new message, the red lamp on the IP Phones is turned on along with the envelop icon next to the line display.
Note: This document is based on a CUCME server integrated with a CUE module.
There are three MWI Mechanisms available when CUE integrates with CUCME:
- Outcall
- SIP Subscribe-Notify
- SIP Unsolicited
There is one MWI mechanism available when CUE integrates with CUCM:
- Java Telephony API (JTAPI)/ Computer Telephony Integration (CTI)
Note: TheCUE JTAPI Issues and Case Studies document provides information on how to enable JTAPI Traces in CUE and how to troubleshoot MWI via JTAPI.
Configure
SIP Outcall Method
Outcall is the default method used in CUE in order to provide backward compatibility for available systems. Although it is recommended to use Subscribe-Notify or Unsolicited for the MWI notification, the Outcall method is used in several network environments to configure and enable MWI for SCCP IP Phones registered to CME.
Note: The Outcall mechanism does not work in Cisco Survivable Remote Site Telephony (SRST) deployments. SIP endpoints are not supported.
In this mechanism, CUE sends an INVITE to CUCME when a user has a new voicemail.
Configure two ephone-dns on CUCME. The two DNs represent the extensions CUE must dial to enable or disable the MWI for a given extension.
ephone-dn 3 mwi on number 3999.... ! ephone-dn 4 mwi off number 3998....
Note: The number of dots at the end of the DN must match the extension length used by the phones registered to CUCME.
Ensure CUCME configuration is completed and then proceed to CUE configuration. In the intial configuration of CUE, the MWI DN’s are automatically populated in the Call Handling section of the Initialization Wizard.
Note: To access the Initialization Wizard, CME must be integrated with CUE for Graphical User Interface (GUI) access. On a production system, the DN information is synchronized with CUE. Navigate to Voice Mail > Message Waiting Indicators > Settings to view the DNs.
Example MWI settings page after the DN’s are configured and synchronized with CUE:
Note: Here Subscribe-Notify is also enabled. This is not required but it is supported to have Subscribe-Notify and Outcalling configured at the same time. CUE sends two notifications, one for each method, in order to turn on or off the MWI.
Note: Configuration of Outcall and Unsolicited Notify is not supported at the same time.
Sample CUE configuration:
ccn application ciscomwiapplication aa description "ciscomwiapplication" enabled maxsessions 6 script "setmwi.aef" parameter "strMWI_OFF_DN" "3999" parameter "strMWI_ON_DN" "3998" end application ccn subsystem sip mwi sip outcall
Use the show ccn subsystem sip command in order to determine the current MWI configuration.
CUE# sh ccn subsystem sip SIP Gateway: 10.10.202.1 SIP Port Number: 5060 DTMF Relay: sip-notify,sub-notify MWI Notification: outcall MWI Envelope Info: disabled Transfer Mode: bye-also SIP RFC Compliance: Pre-RFC3261
Note: In the sample configuration, the MWI extensions are defined without dots. Dots are defined only in CME to indicate the phone DN extension length. The show ccn subsystem sip command output can vary based on CUE version.
Changes to dial-peer, used for CUE, are necessary in order to ensure the correct inbound dial-peer is matched for the Outcall SIP INVITE. A new dial-peer can also be created to act as the inbound dial-peer:
dial-peer voice 3600 voip destination-pattern 3600 session protocol sipv2 session target ipv4:10.10.202.50 incoming called-number 399[89].... dtmf-relay sip-notify codec g711ulaw no vad !
Or
dial-peer voice 3999 voip session protocol sipv2 incoming called-number 399[89].... dtmf-relay sip-notify codec g711ulaw no vad !
SIP Subscribe Notify
In the Subscribe-Notify mechanism, the DNs initially Subscribe with the CUE. After subscription, the NOTIFY message from CUE is accepted for MWI notification.
Note:This method is recommended for SRST and CUCME deployments.
Enable Subscriber Notify inorder to use this notification method:
Alternatively, the Subscriber Notify method can be enabled on the Command Line Interface (CLI):
ccn subsystem sip gateway address "10.10.202.1" mwi envelope-info mwi sip sub-notify end subsystem
Configure the CME with the MWI server (CUE) IP address in the sip-ua section. You can confirm the IP address of the CUE from the Interface configuration of the Service Module on which the CUE is hosted with the show run interface command.
interface Integrated-Service-Engine1/0 ip unnumbered Vlan400 service-module ip address 10.10.202.50 255.255.255.0 sip-ua mwi-server ipv4:10.10.202.50 expires 3600 port 5060 transport udp
The command mwi-server ipv4:10.10.202.50 under sip-ua is sufficient to support Subscribe-Notify events for MWI. The Expires, Port and Transport are automatically included in the configuration with the default settings.
Configure the DNs to Subscribe with the CUE in order to receive MWI notification event. This method can be used for SCCP and SIP IP Phones registered to the CME Router.
voice register dn 1 number 3005 mwi ! ephone-dn 1 number 3001 mwi sip
Once the command is entered, the phone sends a SUBSCRIBE message to CUE in order to request a MWI Update and the CUE responds with a 202 Accepted SIP message:
Sent: SUBSCRIBE sip:[email protected]:5060 SIP/2.0 Via: SIP/2.0/UDP 10.10.202.1:5060;branch=z9hG4bK4812E5 From: <sip:[email protected]>;tag=CC5F60-3EC To: <sip:[email protected]> Call-ID: [email protected] CSeq: 101 SUBSCRIBE Max-Forwards: 70 Date: Thu, 04 Jul 2013 16:36:15 GMT User-Agent: Cisco-SIPGateway/IOS-12.x Event: message-summary Expires: 3600 Contact: <sip:[email protected]:5060> Accept: application/simple-message-summary Content-Length: 0 Received: SIP/2.0 202 Accepted Via: SIP/2.0/UDP 10.10.202.1:5060;branch=z9hG4bK4812E5 To: <sip:[email protected]>;tag=217fce13-1101 From: <sip:[email protected]>;tag=CC5F60-3EC Call-ID: [email protected] CSeq: 101 SUBSCRIBE Content-Length: 0 Expires: 3600 Contact: sip:[email protected] Allow-Events: refer Allow-Events: telephone-event Allow-Events: message-summary
Once the subscription is accepted, CUE sends a NOTIFY message with the current status of the MWI for that specific DN. In this example, the MWI Notification is set to Yes:
Received: NOTIFY sip:[email protected]:5060 SIP/2.0 Via: SIP/2.0/UDP 10.10.202.50:5060;branch=z9hG4bKkuJtPQPUKbreuy0GkQBlQw~~30 Max-Forwards: 70 To: <sip:[email protected]>;tag=CC5F60-3EC From: <sip:[email protected]>;tag=217fce13-1101 Call-ID: [email protected] CSeq: 1 NOTIFY Content-Length: 113 Contact: sip:[email protected] Event: message-summary Allow-Events: refer Allow-Events: telephone-event Allow-Events: message-summary Subscription-State: active Content-Type: application/simple-message-summary Messages-Waiting: yes Message-Account: sip:[email protected] Voice-Message: 1/0 (0/0) Fax-Message: 0/0 (0/0) Sent: SIP/2.0 200 OK Via: SIP/2.0/UDP 10.10.202.50:5060;branch=z9hG4bKkuJtPQPUKbreuy0GkQBlQw~~30 From: <sip:[email protected]>;tag=217fce13-1101 To: <sip:[email protected]>;tag=CC5F60-3EC Date: Thu, 04 Jul 2013 16:36:15 GMT Call-ID: [email protected] CSeq: 1 NOTIFY Content-Length: 0
On the CUE GUI, notice the Currently active subscriptions increments by 1 for each Subscribe message it receives:
Use show ccn sip subscription mwi command to view the subscription status.
DN Subscription Time Expires ------------------------------------------------------------------------------- 3001 Mon Sep 22 13:40:02 EDT 2008 3600
MWI Subscribe-Notifiy in SRST:
Configure mwi relay either under call-manager-fallback, for legacy SRST, or telephony-service for CME-SRST deployments.
call-manager-fallback mwi relay telephony-service mwi relay
SIP Unsolicited-Notify
The SIP Unsolicited-Notify method supports both CUCME and SRST. This method uses a SIP NOTIFY message in order to toggle MWI on or off. Unlike with Subscribe-Notify there is no subscription maintaned by CUE.
Configure mwi relay command under call-manager-fallback or CME-SRST in order to support SRST deployments, along with the sip-ua MWI configuration command.
Enable the Unsolicited Notify option:
Alternatively Unsolicited Notify can be enabled on the CUE CLI:
ccn subsystem sip mwi sip unsolicited
Enable unsolicited notify on the CME CLI:
sip-ua mwi-server ipv4:10.10.202.50 expires 3600 port 5060 transport udp unsolicited
Warning: Unless unsolicited is configured on the mwi-server command, the CME continues to use Subscribe-Notify and MWI does not work since the configuration on CME does not match with the configuration on CUE.
Note: You cannot use the Unsolicitied-Notify mechanism along with any other mechanism at the same time.
MWI with Cisco Unified Communications Manager (CUCM)
When CUE is integrated with CUCM the JTAPI protocol uses the setMessageWaiting message to toggle MWI on/off. The CTI Ports controlled by JTAPI are assigned with a Calling Search Space (CSS) that has the parition of the phone’s Directory Number. In later releases of CUE, you can configure a dedicated CTI Port in order to provide MWI Notifications. In the event the port is not available, the CUE uses any available port configured that is controlled by JTAPI.
Since JTAPI uses the setMessageWaiting message for MWI events, MWI extensions are not configured in CUCM. If the extensions are configured, they are ignored and cause no interoperability issues with JTAPI.
In CUE versions 7.x and higher, you can configure the CUCME Router and CUE to use the Unsolicited-Notify method in order to maintain full MWI functionality while in SRST.
Troubleshoot
This section provides information you can use in order to troubleshoot your configuration.
Debugs and Traces
CUCME
In order to troubleshoot MWI issues, use these debugs:
debug ccsip messages debug voice ccapi inout
In order to troubleshoot MWI issues with SCCP Phones registered to a CUCME Router with MWI outcall method, use these debugs:
debug ccsip messages debug voice ccapi inout debug ephone mwi mac <mac address> debug ephone detail mac <mac address>
The show ephone reg command is used to confirm the status of MWI regardless of the mechanism used. This is a useful command when the phone is located in a remote site.
#show ephone reg ephone-1[0] Mac:0023.5E18.23EC TCP socket:[1] activeLine:0 whisperLine:0 REGISTERED in SCCP ver 17/12 max_streams=5 mediaActive:0 whisper_mediaActive:0 startMedia:0 offhook:0 ringing:0 reset:0 reset_sent:0 paging 0 debug:1 caps:9 privacy:1 IP:10.10.202.2 22856 7965 keepalive 186 max_line 6 available_line 6 button 1: dn 1 number 3001 CH1 IDLE CH2 IDLE CH3 IDLE CH4 IDLE CH5 IDLE CH6 IDLE CH7 IDLE CH8 IDLE mwi Preferred Codec: g711ulaw Username: MWI1 Password: cisco
CUE
In CUE you can use these show commands in order to verify your configuration:
show ccn application show ccn subsystem sip
CUE also provides traces in order to troubleshoot any issues related to MWI. You can either use the default traces already enabled in CUE or use a specific trace which is easier to collect and read.
The specific trace you can enable in CUE is:
trace ccn stacksip dbug
This trace provides SIP signaling information useful in order to determine if the SIP Outcall or Notify message is sent correctly for MWI.
You can also enable the trace voicemail all in combination with the stacksip trace in order to get more information about the call and MWI events, or at a minimum enable the trace voicemail vxml all and trace voicemail mwi all.
Note: For more information about MWI Problems refer to the Troubleshooting Unity Express Message Waiting Indication (MWI) Problems.
CUE Licensing
In CUE, ensure licenses are installed in order to support the correct call agent (CUCME or CUCM) and voicemail ports.
In CUE version 7.0.x the command is show software licenses.
CUE# show software licenses Installed license files: - voicemail_lic.sig : 25 MAILBOX LICENSE - ivr_lic.sig : 4 PORT IVR BASE LICENSE - port_lic.sig : 24 PORT BASE LICENSE Core: - Application mode: CCME - Total usable system ports: 24 Voicemail/Auto Attendant: - Max system mailbox capacity time: 18000 - Default # of general delivery mailboxes: 10 - Default # of personal mailboxes: 25 - Max # of configurable mailboxes: 35 Interactive Voice Response: - Max # of IVR sessions: 4 Languages: - Max installed languages: 5 - Max enabled languages: 5
In CUE 7.1.x and later the commands are show license status application and show call-agent
CUE# show license status application voicemail enabled: 10 ports, 10 sessions, 30 mailboxes ivr disabled, ivr session activation count has been set to zero CUE# show call-agent Call-agent: CUCME
You can also use the show license all which provides detailed information about the licenses. This show command is useful in order to determine if the CUE has evaluation licenses and how much time is left before it expires or if the licenses installed are permanent:
CUE# show license all
License Store: Primary License Storage
StoreIndex: 0 Feature: VMIVR-VM-MBX Version: 1.0
License Type: Permanent
License State: Active, In Use
License Count: 65 /30
License Priority: Medium
License Store: Primary License Storage
StoreIndex: 1 Feature: VMIVR-IVR-SESS Version: 1.0
License Type: Permanent
License State: Active, Not in Use
License Count: 10 / 0
License Priority: Medium
License Store: Primary License Storage
StoreIndex: 2 Feature: TCV-USER Version: 1.0
License Type: Permanent
License State: Active, Not in Use
License Count: 60 / 0
License Priority: Medium
License Store: Primary License Storage
StoreIndex: 3 Feature: VMIVR-PORT Version: 1.0
License Type: Permanent
License State: Active, In Use
License Count: 20 /10
License Priority: Medium
License Store: Evaluation License Storage
Troubleshoot SIP Outcall
The SIP Outcall method generates a SIP call event to CUCME in order to toggle on or off the MWI for one specific extension. CUE waits for the 180 Ringing message. Once it is received, then it can disconnect the call.
In CUE:
CUE# no trace all #trace ccn StackSip dbug # clear trace #mwi refresh telephonenumber 3001 #show trace buff tail Press <CTRL-C> to exit... 4524 07/04 09:35:16.484 ACCN STGN 0 Task: 263000000018GetListMember: output string:outcall 4524 07/04 09:35:16.489 ACCN SIPL 0 sip-ltp17: 3602, State=CONTACTINGenter connect 4524 07/04 09:35:16.489 ACCN SIPL 0 sip-ltp17: 3602, State=CONTACTINGenter createInvitation 4524 07/04 09:35:16.489 ACCN SIPL 0 SDPBody : v=0 o=CiscoSystemsSIP-Workflow-App-UserAgent 3337 3337 IN IP4 10.10.202.50 s=SIP Call c=IN IP4 10.10.202.50 t=0 0 m=audio 16910 RTP/AVP 0 a=rtpmap:0 pcmu/8000 a=ptime:20 4524 07/04 09:35:16.489 ACCN SIPL 0 sip-ltp17: 3602, State=CONTACTINGbefore invitationmanager.createInvitation. body : v=0 o=CiscoSystemsSIP-Workflow-App-UserAgent 3337 3337 IN IP4 10.10.202.50 s=SIP Call c=IN IP4 10.10.202.50 t=0 0 m=audio 16910 RTP/AVP 0 a=rtpmap:0 pcmu/8000 a=ptime:20 body type : application/sdp toNA : <sip:[email protected]:5060;user=phone> fromNA : <sip:[email protected]:5060> from tag : cue5aa7689b 4524 07/04 09:35:16.489 ACCN SIPL 0 sip-ltp17: 3602, State=CONTACTINGafter invitationmanager.createInvitation 4524 07/04 09:35:16.489 ACCN SIPL 0 sip-ltp17: 3602, State=CONTACTING added ciscogcid 4524 07/04 09:35:16.489 ACCN SIPL 0 sip-ltp17: 3602, State=CONTACTING getDTMFHeader: Enter 4524 07/04 09:35:16.489 ACCN SIPL 0 sip-ltp17: 3602, State=CONTACTING getDTMFHeader: getting the headers 4524 07/04 09:35:16.490 ACCN SIPL 0 sip-ltp17: 3602, State=CONTACTING getDTMFHeader: before adding headers to message 4524 07/04 09:35:16.490 ACCN SIPL 0 sip-ltp17: 3602, State=CONTACTING getDTMFHeader: after adding headers to message : INVITE sip:[email protected]:5060;user=phone SIP/2.0 Via: SIP/2.0/UDP 10.10.202.50:5060;branch=z9hG4bKkuJtPQPUKbreuy0GkQBlQw~~10 Max-Forwards: 70 To: <sip:[email protected]:5060;user=phone> From: <sip:[email protected]:5060>;tag=cue5aa7689b Call-ID: [email protected] CSeq: 1 INVITE Content-Length: 178 Contact: <sip:[email protected]:5060> Content-Type: application/sdp Cisco-Gcid: AA52BD08-013F-1000-4000-001125CUCE68 Call-Info: <sip:10.10.202.50:5060>;method="NOTIFY;Event=telephone-event;Duration=2000" Allow-Events: telephone-event v=0 o=CiscoSystemsSIP-Workflow-App-UserAgent 3337 3337 IN IP4 10.10.202.50 s=SIP Call c=IN IP4 10.10.202.50 t=0 0 m=audio 16910 RTP/AVP 0 a=rtpmap:0 pcmu/8000 a=ptime:20 4524 07/04 09:35:16.490 ACCN SIPL 0 sip-ltp17: 3602, State=CONTACTINGinvite message : INVITE sip:[email protected]:5060;user=phone SIP/2.0 Via: SIP/2.0/UDP 10.10.202.50:5060;branch=z9hG4bKkuJtPQPUKbreuy0GkQBlQw~~10 Max-Forwards: 70 To: <sip:[email protected]:5060;user=phone> From: <sip:[email protected]:5060>;tag=cue5aa7689b Call-ID: [email protected] CSeq: 1 INVITE Content-Length: 178 Contact: <sip:[email protected]:5060> Content-Type: application/sdp Cisco-Gcid: AA52BD08-013F-1000-4000-001125CUCE68 Call-Info: <sip:10.10.202.50:5060>;method="NOTIFY;Event=telephone-event;Duration=2000" Allow-Events: telephone-event v=0 o=CiscoSystemsSIP-Workflow-App-UserAgent 3337 3337 IN IP4 10.10.202.50 s=SIP Call c=IN IP4 10.10.202.50 t=0 0 m=audio 16910 RTP/AVP 0 a=rtpmap:0 pcmu/8000 a=ptime:20 4524 07/04 09:35:16.490 ACCN SIPL 0 sip-ltp17: 3602, State=CONTACTINGreturning invitation 4524 07/04 09:35:16.490 ACCN SIPL 0 sip-ltp17: 3602, State=CONTACTINGgot Invitation 4524 07/04 09:35:16.490 ACCN SIPL 0 sip-ltp17: 3602, State=CONTACTINGbefore Invitation start 4524 07/04 09:35:16.491 ACCN SIPL 0 sip-ltp17: 3602, State=CONTACTINGafter Invitation start 4846 07/04 09:35:16.509 ACCN SIPL 0 sip-ltp17: 3602, State=CONTACTING InvitationCallback.proceeding 4846 07/04 09:35:16.509 ACCN SIPL 0 sip-ltp17: 3602, State=CONTACTING SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.10.202.50:5060;branch=z9hG4bKkuJtPQPUKbreuy0GkQBlQw~~10 To: <sip:[email protected]:5060;user=phone> From: <sip:[email protected]:5060>;tag=cue5aa7689b Call-ID: [email protected] CSeq: 1 INVITE Content-Length: 0 Date: Thu, 04 Jul 2013 15:50:11 GMT Allow-Events: telephone-event Server: Cisco-SIPGateway/IOS-12.x 4846 07/04 09:35:16.515 ACCN SIPL 0 sip-ltp17: 3602, State=CONTACTING--- InvitationCallback.proceeding (dialog) 4846 07/04 09:35:16.515 ACCN SIPL 0 sip-ltp17: 3602, State=CONTACTINGSIP/2.0 180 Ringing Via: SIP/2.0/UDP 10.10.202.50:5060;branch=z9hG4bKkuJtPQPUKbreuy0GkQBlQw~~10 To: <sip:[email protected]:5060;user=phone>;tag=A233D8-2382 From: <sip:[email protected]:5060>;tag=cue5aa7689b Call-ID: [email protected] CSeq: 1 INVITE Content-Length: 0 Date: Thu, 04 Jul 2013 15:50:11 GMT Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER Allow-Events: telephone-event Remote-Party-ID: <sip:[email protected]>;party=called;screen=no;privacy=off Contact: <sip:[email protected]:5060> Server: Cisco-SIPGateway/IOS-12.x 4524 07/04 09:35:21.489 ACCN SIPL 0 sip-ltp17: 3602, State=CONTACTING terminating dialog in contacting state 20 4524 07/04 09:35:21.491 ACCN SIPL 0 sip-ltp17: 3602, State=TERMINATEDcontacting state hangup 4524 07/04 09:35:21.492 ACCN SIPL 0 sip-ltp17: 3602, State=TERMINATEDAfter contacting state hangup 4846 07/04 09:35:21.507 ACCN SIPL 0 sip-ltp17: 3602, State=TERMINATED InvitationDialogCallback.rejected 4846 07/04 09:35:21.508 ACCN SIPL 0 sip-ltp17: 3602, State=TERMINATED SIP/2.0 487 Request Cancelled Via: SIP/2.0/UDP 10.10.202.50:5060;branch=z9hG4bKkuJtPQPUKbreuy0GkQBlQw~~10 To: <sip:[email protected]:5060;user=phone>;tag=A233D8-2382 From: <sip:[email protected]:5060>;tag=cue5aa7689b Call-ID: [email protected] CSeq: 1 INVITE Content-Length: 0 Date: Thu, 04 Jul 2013 15:50:16 GMT Allow-Events: telephone-event Server: Cisco-SIPGateway/IOS-12.x Reason: Q.850;cause=16
In CUCME:
Received: INVITE sip:[email protected]:5060;user=phone SIP/2.0 Via: SIP/2.0/UDP 10.10.202.50:5060;branch=z9hG4bKkuJtPQPUKbreuy0GkQBlQw~~3 Max-Forwards: 70 To: <sip:[email protected]:5060;user=phone> From: <sip:[email protected]:5060>;tag=cue9c19e76c Call-ID: [email protected] CSeq: 1 INVITE Content-Length: 178 Contact: <sip:[email protected]:5060> Content-Type: application/sdp Cisco-Gcid: AA313BF9-013F-1000-4000-001125CUCE68 Call-Info: <sip:10.10.202.50:5060>;method="NOTIFY;Event=telephone-event;Duration=2000" Allow-Events: telephone-event v=0 o=CiscoSystemsSIP-Workflow-App-UserAgent 3068 3068 IN IP4 10.10.202.50 s=SIP Call c=IN IP4 10.10.202.50 t=0 0 m=audio 16928 RTP/AVP 0 a=rtpmap:0 pcmu/8000 a=ptime:20 //-1/21F6E0878040/CCAPI/cc_api_display_ie_subfields: cc_api_call_setup_ind_common: cisco-username=3602 ----- ccCallInfo IE subfields ----- cisco-ani=3602 cisco-anitype=0 cisco-aniplan=0 cisco-anipi=0 cisco-anisi=0 dest=39983001 //-1/21F6E0878040/CCAPI/cc_api_call_setup_ind_common: Interface=0x49432FE0, Call Info( Calling Number=3602,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed), Called Number=39983001(TON=Unknown, NPI=Unknown), Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Incoming Dial-peer=3600, Progress Indication=NULL(0), Calling IE Present=TRUE, Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=22 … //22/21F6E0878040/CCAPI/cc_api_display_ie_subfields: ccCallSetupRequest: cisco-username=3602 ----- ccCallInfo IE subfields ----- cisco-ani=3602 cisco-anitype=0 cisco-aniplan=0 cisco-anipi=0 cisco-anisi=0 dest=39983001 //22/21F6E0878040/CCAPI/ccIFCallSetupRequestPrivate: Interface=0x4A492188, Interface Type=6, Destination=, Mode=0x0, Call Params(Calling Number=3602,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed), Called Number=39983001(TON=Unknown, NPI=Unknown), Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing Dial-peer=20004, Call Count On=FALSE, Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=) Sent: SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.10.202.50:5060;branch=z9hG4bKkuJtPQPUKbreuy0GkQBlQw~~3 From: <sip:[email protected]:5060>;tag=cue9c19e76c To: <sip:[email protected]:5060;user=phone> Date: Thu, 04 Jul 2013 15:13:36 GMT Call-ID: [email protected] CSeq: 1 INVITE Allow-Events: telephone-event Server: Cisco-SIPGateway/IOS-12.x Content-Length: 0 Sent: SIP/2.0 180 Ringing Via: SIP/2.0/UDP 10.10.202.50:5060;branch=z9hG4bKkuJtPQPUKbreuy0GkQBlQw~~3 From: <sip:[email protected]:5060>;tag=cue9c19e76c To: <sip:[email protected]:5060;user=phone>;tag=80B2C0-1CF Date: Thu, 04 Jul 2013 15:13:36 GMT Call-ID: [email protected] CSeq: 1 INVITE Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER Allow-Events: telephone-event Remote-Party-ID: <sip:[email protected]>;party=called;screen=no;privacy=off Contact: <sip:[email protected]:5060> Server: Cisco-SIPGateway/IOS-12.x Content-Length: 0 Received: CANCEL sip:[email protected]:5060;user=phone SIP/2.0 Via: SIP/2.0/UDP 10.10.202.50:5060;branch=z9hG4bKkuJtPQPUKbreuy0GkQBlQw~~3 Max-Forwards: 70 To: <sip:[email protected]:5060;user=phone> From: <sip:[email protected]:5060>;tag=cue9c19e76c Call-ID: [email protected] CSeq: 1 CANCEL Content-Length: 0 //22/21F6E0878040/CCAPI/cc_api_call_disconnected: Cause Value=16, Interface=0x49432FE0, Call Id=22 Sent: SIP/2.0 200 OK Via: SIP/2.0/UDP 10.10.202.50:5060;branch=z9hG4bKkuJtPQPUKbreuy0GkQBlQw~~3 From: <sip:[email protected]:5060>;tag=cue9c19e76c To: <sip:[email protected]:5060;user=phone> Date: Thu, 04 Jul 2013 15:13:41 GMT Call-ID: [email protected] CSeq: 1 CANCEL Content-Length: 0 Sent: SIP/2.0 487 Request Cancelled Via: SIP/2.0/UDP 10.10.202.50:5060;branch=z9hG4bKkuJtPQPUKbreuy0GkQBlQw~~3 From: <sip:[email protected]:5060>;tag=cue9c19e76c To: <sip:[email protected]:5060;user=phone>;tag=80B2C0-1CF Date: Thu, 04 Jul 2013 15:13:41 GMT Call-ID: [email protected] CSeq: 1 INVITE Allow-Events: telephone-event Server: Cisco-SIPGateway/IOS-12.x Reason: Q.850;cause=16 Content-Length: 0 Received: ACK sip:[email protected]:5060;user=phone SIP/2.0 Via: SIP/2.0/UDP 10.10.202.50:5060;branch=z9hG4bKkuJtPQPUKbreuy0GkQBlQw~~3 Max-Forwards: 70 To: <sip:[email protected]:5060;user=phone>;tag=80B2C0-1CF From: <sip:[email protected]:5060>;tag=cue9c19e76c Call-ID: [email protected] CSeq: 1 ACK Content-Length: 0 Debug ephone mwi: 000922: Jul 4 10:23:22.654: SetCallInfo MODE 1 calling dn -1 chan 1 dn 3 chan 1 000923: Jul 4 10:23:22.654: alling [3602] called [39993001] 000924: Jul 4 10:23:22.654: SkinnyTryCall to 3001 instance 1 start at 0SkinnyTryCall to 3001 instance 1 match DN 1 000925: Jul 4 10:23:22.654: ephone-1[1]:Set MWI line 1 to ON count 0 000926: Jul 4 10:23:22.654: ephone-1[1]:Set MWI line 0 to ON count 0 Debug ephone detailed: 001231: Jul 4 10:25:37.899: Phone 0 DN 1 MWI on 0 messages 001232: Jul 4 10:25:37.899: ephone-1[1]:Set MWI line 1 to ON count 0 001233: Jul 4 10:25:37.899: ephone-1[1]:Set MWI line 0 to ON count 0
Troubleshoot SIP Subscribe-Notify
After the initial subscription of the DNs, CUE sends a Notify Message towards CUCME in order to inform which extension needs to have the MWI toggle on or off.
In CUE:
CUE#no trace all CUE#trace ccn stacksip dbug CUE#trace voicemail all CUE# clear trace CUE#mwi refresh telephonenumber 3001 CUE#show trace buff tail Press <CTRL-C> to exit... 4430 07/04 10:43:39.263 VMSS dbug 1 com.cisco.aesop.voicemail.LdapAgent : getAttributeValue: /sw/local/users/MWI1/TelephoneNumbers/primaryExtension 4430 07/04 10:43:39.264 VMSS vmwi 0x00000000000f1206 2 3001,true 4430 07/04 10:43:39.264 VMSS dbug 1 com.cisco.aesop.voicemail.Mailbox : setMessageWaiting: 3001,true 4430 07/04 10:43:39.264 VMSS vmdb 0 Request connection: inUse: 1, active: 2 4430 07/04 10:43:39.264 VMSS dbug 1 com.cisco.aesop.voicemail.VMDatabase : Request connection: inUse: 1, active: 2 4430 07/04 10:43:39.264 VMSS vmdb 0 Got connection: 1, inUse: 2, active: 2 4430 07/04 10:43:39.264 VMSS dbug 1 com.cisco.aesop.voicemail.VMDatabase : Got connection: 1, inUse: 2, active: 2 4430 07/04 10:43:39.264 VMSS vmdb 7 select uid from vm_message where vm_message.messageid='FTX1242A3S6-NME-FOC12394L3Y-1372949852538' ; 4430 07/04 10:43:39.264 VMSS dbug 1 com.cisco.aesop.voicemail.VMDatabase : select uid from vm_message where vm_message.messageid='FTX1242A3S6-NME-FOC12394L3Y-1372949852538' ; 4430 07/04 10:43:39.273 VMSS vmdb 3 PERSONAL_00000000000000000000000 4430 07/04 10:43:39.273 VMSS dbug 1 com.cisco.aesop.voicemail.VMDatabase : PERSONAL_00000000000000000000000 4430 07/04 10:43:39.273 VMSS dbug 1 com.cisco.aesop.voicemail.VMUser : getMailboxInfo: personalMailboxId=PERSONAL_00000000000000000000000 4430 07/04 10:43:39.273 VMSS vmdb 0 Freed connection: 1, inUse: 1, active: 2 4430 07/04 10:43:39.273 VMSS dbug 1 com.cisco.aesop.voicemail.VMDatabase : Freed connection: 1, inUse: 1, active: 2 4430 07/04 10:43:39.273 VMSS vmsg 8 populateSenderDetails: sender entity: id=MW2,type=1,ext=3002,cn=MW2,desc= 4430 07/04 10:43:39.273 VMSS vmsg 8 populateSenderDetails: localPart=MW2 4430 07/04 10:43:39.273 VMSS vmsg 8 populateSenderDetails: imapSender="MW2 \(MW2\)" <MW2@localdomain>, mwiFrom="MW2" <sip:[email protected]>, subjectLine=3002 4430 07/04 10:43:39.273 VMSS dbug 1 com.cisco.aesop.voicemail.Message : getLengthMillisec(): msgid: FTX1242A3S6-NME-FOC12394L3Y-1372949852538 totalMsgLength: 14287 4430 07/04 10:43:39.273 VMSS dbug 1 com.cisco.aesop.voicemail.Message : getLengthMillisec(): msgid: FTX1242A3S6-NME-FOC12394L3Y-1372949852538 totalMsgLength: 14287 4430 07/04 10:43:39.274 VMSS vmwi 4 MessageWaitingThread.addJob: Messages-Waiting: yes Message-Account: sip:[email protected] Voice-Message: 1/0 (0/0) Fax-Message: 0/0 (0/0) X-Cisco-Message-State: new X-Cisco-Message-Type: normal From: "MW2" <sip:[email protected]> To: <sip:[email protected]> Date: Thu, 4 Jul 2013 16:43:39 GMT Message-ID: FTX1242A3S6-NME-FOC12394L3Y-1372949852538 Message-Context: voice-message Content-Duration: 14 4430 07/04 10:43:39.274 VMSS vmwi 4 MessageWaitingThread.addJob: numJobs=1 4430 07/04 10:43:39.274 VMSS dbug 1 MessageWaitingThread : adding job 4430 07/04 10:43:39.274 VMSS vmdb 0x00000000000f1206 7 update vm_message set mwion=true where messageid='FTX1242A3S6-NME-FOC12394L3Y-1372949852538'; 4430 07/04 10:43:39.274 VMSS dbug 1 com.cisco.aesop.voicemail.VMDatabase : taskId: 987654(0xf1206): update vm_message set mwion=true where messageid='FTX1242A3S6-NME-FOC12394L3Y-1372949852538'; 4430 07/04 10:43:39.274 VMSS dbug 1 com.cisco.aesop.voicemail.VMDatabase : connection 0: execute: update vm_message set mwion=true where messageid='FTX1242A3S6-NME-FOC12394L3Y-1372949852538'; 3450 07/04 10:43:39.274 VMSS vmwi 4 MessageWaitingThread.run: extn=3001, numJobs=0 3450 07/04 10:43:39.274 VMSS vmwi 4 http://localhost:8080/mwiapp?extn=3001&state=1 3450 07/04 10:43:39.274 VMSS dbug 1 com.cisco.aesop.voicemail.MessageWaitingThread : http://localhost:8080/mwiapp?extn=3001&state=1 4522 07/04 10:43:39.289 ACCN STGN 0 Task: 263000000053GetListMember: output string:sub-notify
In CUCME:
Received: NOTIFY sip:[email protected]:5060 SIP/2.0 Via: SIP/2.0/UDP 10.10.202.50:5060;branch=z9hG4bKkuJtPQPUKbreuy0GkQBlQw~~26 Max-Forwards: 70 To: <sip:[email protected]>;tag=C253E4-7B4 From: <sip:[email protected]>;tag=a4c2d6ba-1099 Call-ID: [email protected] CSeq: 5 NOTIFY Content-Length: 113 Contact: sip:[email protected]0 Event: message-summary Allow-Events: refer Allow-Events: telephone-event Allow-Events: message-summary Subscription-State: active Content-Type: application/simple-message-summary Messages-Waiting: yes Message-Account: sip:[email protected] Voice-Message: 1/0 (0/0) Fax-Message: 0/0 (0/0) Sent: SIP/2.0 200 OK Via: SIP/2.0/UDP 10.10.202.50:5060;branch=z9hG4bKkuJtPQPUKbreuy0GkQBlQw~~26 From: <sip:[email protected]>;tag=a4c2d6ba-1099 To: <sip:[email protected]>;tag=C253E4-7B4 Date: Thu, 04 Jul 2013 16:33:26 GMT Call-ID: [email protected] CSeq: 5 NOTIFY Content-Length: 0
Troubleshoot SIP Unsolicited
CUE sends a NOTIFY message to CUCME. No prior subscription is required.
In CUE:
2922 07/04 11:07:59.028 VMSS vmwi 0x00000000000f1206 2 3001,true 2922 07/04 11:07:59.028 VMSS dbug 1 com.cisco.aesop.voicemail.Mailbox : setMessageWaiting: 3001,true 2922 07/04 11:07:59.029 VMSS vmwi 4 MessageWaitingThread.addJob: Messages-Waiting: yes Message-Account: sip:[email protected] Voice-Message: 2/0 (0/0) Fax-Message: 0/0 (0/0) 2922 07/04 11:07:59.029 VMSS vmwi 4 MessageWaitingThread.addJob: numJobs=1 2922 07/04 11:07:59.029 VMSS dbug 1 MessageWaitingThread : adding job 3450 07/04 11:07:59.029 VMSS vmwi 4 MessageWaitingThread.run: extn=3001, numJobs=0 3450 07/04 11:07:59.029 VMSS vmwi 4 http://localhost:8080/mwiapp?extn=3001&state=1 3450 07/04 11:07:59.029 VMSS dbug 1 com.cisco.aesop.voicemail.MessageWaitingThread : http://localhost:8080/mwiapp?extn=3001&state=1 2924 07/04 11:07:59.037 VMSS sydb 1 MailboxNode: PERSONAL_00000000000000000000000,ownerDn 2924 07/04 11:07:59.037 VMSS dbug 1 com.cisco.aesop.voicemail.VMSysdbMailboxNode : Get attribute: ownerDn Mailbox: PERSONAL_00000000000000000000000 2921 07/04 11:07:59.039 VMSS sydb 1 MailboxNode: PERSONAL_00000000000000000000000,mailboxDesc 2921 07/04 11:07:59.039 VMSS dbug 1 com.cisco.aesop.voicemail.VMSysdbMailboxNode : Get attribute: mailboxDesc Mailbox: PERSONAL_00000000000000000000000 4524 07/04 11:07:59.041 ACCN STGN 0 Task: 263000000060GetListMember: output string:unsolicited 4524 07/04 11:07:59.041 ACCN STGN 0 Task: 263000000060GetListMember: Position variable is beyond the string list: number of tokens in the list:1 4524 07/04 11:07:59.041 ACCN SIPL 0 SubscriptionLineImpl: Unsolicited Notify Message being sent:NOTIFY sip:[email protected]:5060;transport=udp SIP/2.0 Max-Forwards: 70 To: <sip:[email protected]:5060> From: <sip:[email protected]:5060>;tag=ds9b9149a8 Call-ID: a5244b0b-1105@sip:[email protected]:5060 CSeq: 1 NOTIFY Content-Length: 113 Contact: <sip:[email protected]:5060> Content-Type: application/simple-message-summary Event: message-summary Messages-Waiting: yes Message-Account: sip:[email protected] Voice-Message: 2/0 (0/0) Fax-Message: 0/0 (0/0) 4524 07/04 11:07:59.052 ACCN SIPL 0 SubscriptionLineImpl: Unsolicited Notify Message sent, result:true 4524 07/04 11:08:09.053 ACCN SIPL 0 SubscriptionLineImpl: Unsolicited Notify Message sent, result:true
In CUCME:
Received: NOTIFY sip:[email protected]:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP 10.10.202.50:5060;branch=z9hG4bKkuJtPQPUKbreuy0GkQBlQw~~44 Max-Forwards: 70 To: <sip:[email protected]:5060> From: <sip:[email protected]:5060>;tag=ds3f77b499 Call-ID: 7364fb7c-1104@sip:[email protected]:5060 CSeq: 1 NOTIFY Content-Length: 113 Contact: <sip:[email protected]:5060> Content-Type: application/simple-message-summary Event: message-summary Messages-Waiting: yes Message-Account: sip:[email protected] Voice-Message: 1/0 (0/0) Fax-Message: 0/0 (0/0) Sent: SIP/2.0 200 OK Via: SIP/2.0/UDP 10.10.202.50:5060;branch=z9hG4bKkuJtPQPUKbreuy0GkQBlQw~~44 From: <sip:[email protected]:5060>;tag=ds3f77b499 To: <sip:[email protected]:5060>;tag=F07F98-117C Date: Thu, 04 Jul 2013 17:15:43 GMT Call-ID: 7364fb7c-1104@sip:[email protected]:5060 CSeq: 1 NOTIFY Content-Length: 0
Common Issues
Issue 1. MWI does not work after SIP bind Commands
SIP Bind commands are configured under voice service voip to an interface that is not the one used for CUE. This is a very common issue and tricky to detect with the troubleshoot tools inside CME. From a CME point of view there are no SIP messages shown.
In CME if you run debug ip udp you can notice packets from CUE but no messages shown in debug ccsip messages or debug ccsip all:
000186: *Jul 8 17:30:48.843: UDP: rcvd src=10.10.202.50(32777), dst=10.10.202.1(5060), length=748 000187: *Jul 8 17:30:49.343: UDP: rcvd src=10.10.202.50(32777), dst=10.10.202.1(5060), length=748 000188: *Jul 8 17:30:50.347: UDP: rcvd src=10.10.202.50(32777), dst=10.10.202.1(5060), length=748 000189: *Jul 8 17:30:52.351: UDP: rcvd src=10.10.202.50(32777), dst=10.10.202.1(5060), length=748 000190: *Jul 8 17:30:56.351: UDP: rcvd src=10.10.202.50(32777), dst=10.10.202.1(5060), length=748 000191: *Jul 8 17:31:04.355: UDP: rcvd src=10.10.202.50(32777), dst=10.10.202.1(5060), length=748
If a packet capture is collected directly from the CUE interface with ip traffic export you can notice the INVITE is indeed received by CUCME:
In CUE the INVITE is shown as sent, although since there is no response from CUCME, it continues to send INVITEs until the retry count is reached, then the connection is terminated due to no response:
4480 07/08 10:30:59.377 ACCN HTTS 0 -> AInvoker.doGet() (/mwiapp) EXIT 4901 07/08 10:31:01.858 DSSP LWRE 0 Sending UDP packet on 10.10.202.50:32775, destination 10.10.202.1:5060 INVITE sip:[email protected]:5060;user=phone SIP/2.0 Via: SIP/2.0/UDP 10.10.202.50:5060;branch=z9hG4bK51VhKqo+pUDrDt5LgLS2yA~~5 Max-Forwards: 70 To: <sip:[email protected]:5060;user=phone> From: <sip:[email protected]:5060>;tag=cuefb95dbea Call-ID: [email protected] CSeq: 1 INVITE Content-Length: 178 Contact: <sip:[email protected]:5060> Content-Type: application/sdp Cisco-Gcid: BF1F1B8C-013F-1000-4000-001125CUCE68 Call-Info: <sip:10.10.202.50:5060>;method="NOTIFY;Event=telephone-event;Duration=2000" Allow-Events: telephone-event v=0 o=CiscoSystemsSIP-Workflow-App-UserAgent 2956 2956 IN IP4 10.10.202.50 s=SIP Call c=IN IP4 10.10.202.50 t=0 0 m=audio 16926 RTP/AVP 0 a=rtpmap:0 pcmu/8000 a=ptime:20 4488 07/08 10:31:04.355 ACCN ENGN 0 Record 544481396 enqueued. Queue size=0 total number of writes=10 4863 07/08 10:31:04.359 ACCN ENGN 0 Insert Record 544481396 took 3ms finish at 1373301064359 4903 07/08 10:31:09.860 DSSP LWRE 0 Sending UDP packet on 10.10.202.50:32775, destination 10.10.202.1:5060 INVITE sip:[email protected]:5060;user=phone SIP/2.0 Via: SIP/2.0/UDP 10.10.202.50:5060;branch=z9hG4bK51VhKqo+pUDrDt5LgLS2yA~~5 Max-Forwards: 70 To: <sip:[email protected]:5060;user=phone> From: <sip:[email protected]:5060>;tag=cuefb95dbea Call-ID: [email protected] CSeq: 1 INVITE Content-Length: 178 Contact: <sip:[email protected]:5060> Content-Type: application/sdp Cisco-Gcid: BF1F1B8C-013F-1000-4000-001125CUCE68 Call-Info: <sip:10.10.202.50:5060>;method="NOTIFY;Event=telephone-event;Duration=2000" Allow-Events: telephone-event v=0 o=CiscoSystemsSIP-Workflow-App-UserAgent 2956 2956 IN IP4 10.10.202.50 s=SIP Call c=IN IP4 10.10.202.50 t=0 0 m=audio 16926 RTP/AVP 0 a=rtpmap:0 pcmu/8000 a=ptime:20 2882 07/08 10:36:30.909 VMSS dbug 1 com.cisco.aesop.voicemail.Mailbox : refreshMWI: 3001 2882 07/08 10:36:30.909 VMSS dbug 1 com.cisco.aesop.voicemail.LdapAgent : getUserByPhoneNo: 3001 2882 07/08 10:36:30.918 VMSS dbug 1 com.cisco.aesop.voicemail.LdapAgent : getUserByPhoneNo: id MWIOne 2882 07/08 10:36:30.918 VMSS dbug 1 com.cisco.aesop.voicemail.VMDatabase : connection 0: query: select mailboxid from vm_mbxusers where owner=true and userdn='/sw/local/users/MWIOne'; 2882 07/08 10:36:30.919 VMSS dbug 1 com.cisco.aesop.voicemail.VMUser : getMailboxInfo: personalMailboxId=PERSONAL_00000000000000000000000 2882 07/08 10:36:30.920 VMSS dbug 1 com.cisco.aesop.voicemail.VMDatabase : connection 1: query: select messageid from vm_message where messagetype=50 and starttime<=1373301390920 and endtime>=1373301390920 and private='false' except select vm_bcst_heard.messageid from vm_message, vm_bcst_heard where vm_message.messageid=vm_bcst_heard.messageid and vm_bcst_heard.mailboxid='PERSONAL_00000000000000000000000'; 2882 07/08 10:36:30.922 VMSS dbug 1 com.cisco.aesop.voicemail.LdapAgent : getAttributeValue: /sw/local/users/MWIOne/TelephoneNumbers/primaryExtension 2882 07/08 10:36:30.923 VMSS dbug 1 com.cisco.aesop.voicemail.Mailbox : setMessageWaiting: 3001,true 2882 07/08 10:36:30.923 VMSS dbug 1 MessageWaitingThread : adding job 3400 07/08 10:36:30.923 VMSS dbug 1 com.cisco.aesop.voicemail.MessageWaitingThread : http://localhost:8080/mwiapp?extn=3001&state=1 4481 07/08 10:36:30.935 ACCN STGN 0 Task: 265000000011GetListMember: output string:outcall 4481 07/08 10:36:30.937 ACCN SIPL 0 sip-ltp10: 3, State=CONTACTINGenter connect 4481 07/08 10:36:30.937 ACCN SIPL 0 sip-ltp10: 3, State=CONTACTINGenter createInvitation 4481 07/08 10:36:30.937 ACCN SIPL 0 SDPBody : v=0 o=CiscoSystemsSIP-Workflow-App-UserAgent 3673 3673 IN IP4 10.10.202.50 s=SIP Call c=IN IP4 10.10.202.50 t=0 0 m=audio 16924 RTP/AVP 0 a=rtpmap:0 pcmu/8000 a=ptime:20 4481 07/08 10:36:30.937 ACCN SIPL 0 sip-ltp10: 3, State=CONTACTINGbefore invitationmanager.createInvitation. body : v=0 o=CiscoSystemsSIP-Workflow-App-UserAgent 3673 3673 IN IP4 10.10.202.50 s=SIP Call c=IN IP4 10.10.202.50 t=0 0 m=audio 16924 RTP/AVP 0 a=rtpmap:0 pcmu/8000 a=ptime:20 body type : application/sdp toNA : <sip:[email protected]:5060;user=phone> fromNA : <sip:[email protected]:5060> from tag : cue9d5cfebc 4481 07/08 10:36:30.937 ACCN SIPL 0 sip-ltp10: 3, State=CONTACTINGafter invitationmanager.createInvitation 4481 07/08 10:36:30.937 ACCN SIPL 0 sip-ltp10: 3, State=CONTACTING added ciscogcid 4481 07/08 10:36:30.937 ACCN SIPL 0 sip-ltp10: 3, State=CONTACTING getDTMFHeader: Enter 4481 07/08 10:36:30.937 ACCN SIPL 0 sip-ltp10: 3, State=CONTACTING getDTMFHeader: getting the headers 4481 07/08 10:36:30.937 ACCN SIPL 0 sip-ltp10: 3, State=CONTACTING getDTMFHeader: before adding headers to message 4481 07/08 10:36:30.938 ACCN SIPL 0 sip-ltp10: 3, State=CONTACTING getDTMFHeader: after adding headers to message : INVITE sip:[email protected]:5060;user=phone SIP/2.0 Via: SIP/2.0/UDP 10.10.202.50:5060;branch=z9hG4bK51VhKqo+pUDrDt5LgLS2yA~~6 Max-Forwards: 70 To: <sip:[email protected]:5060;user=phone> From: <sip:[email protected]:5060>;tag=cue9d5cfebc Call-ID: [email protected] CSeq: 1 INVITE Content-Length: 178 Contact: <sip:[email protected]:5060> Content-Type: application/sdp Cisco-Gcid: BF243E58-013F-1000-4000-001125CUCE68 Call-Info: <sip:10.10.202.50:5060>;method="NOTIFY;Event=telephone-event;Duration=2000" Allow-Events: telephone-event v=0 o=CiscoSystemsSIP-Workflow-App-UserAgent 3673 3673 IN IP4 10.10.202.50 s=SIP Call c=IN IP4 10.10.202.50 t=0 0 m=audio 16924 RTP/AVP 0 a=rtpmap:0 pcmu/8000 a=ptime:20 4481 07/08 10:36:30.938 ACCN SIPL 0 sip-ltp10: 3, State=CONTACTINGinvite message : INVITE sip:[email protected]:5060;user=phone SIP/2.0 Via: SIP/2.0/UDP 10.10.202.50:5060;branch=z9hG4bK51VhKqo+pUDrDt5LgLS2yA~~6 Max-Forwards: 70 To: <sip:[email protected]:5060;user=phone> From: <sip:[email protected]:5060>;tag=cue9d5cfebc Call-ID: [email protected] CSeq: 1 INVITE Content-Length: 178 Contact: <sip:[email protected]:5060> Content-Type: application/sdp Cisco-Gcid: BF243E58-013F-1000-4000-001125CUCE68 Call-Info: <sip:10.10.202.50:5060>;method="NOTIFY;Event=telephone-event;Duration=2000" Allow-Events: telephone-event v=0 o=CiscoSystemsSIP-Workflow-App-UserAgent 3673 3673 IN IP4 10.10.202.50 s=SIP Call c=IN IP4 10.10.202.50 t=0 0 m=audio 16924 RTP/AVP 0 a=rtpmap:0 pcmu/8000 a=ptime:20 4481 07/08 10:36:30.938 ACCN SIPL 0 sip-ltp10: 3, State=CONTACTINGreturning invitation 4481 07/08 10:36:30.938 ACCN SIPL 0 sip-ltp10: 3, State=CONTACTINGgot Invitation 4481 07/08 10:36:30.938 ACCN SIPL 0 sip-ltp10: 3, State=CONTACTINGbefore Invitation start 4481 07/08 10:36:30.939 ACCN SIPL 0 sip-ltp10: 3, State=CONTACTINGafter Invitation start 4481 07/08 10:36:35.938 ACCN SIPL 0 sip-ltp10: 3, State=CONTACTING terminating dialog in contacting state 20 4481 07/08 10:36:35.939 ACCN SIPL 0 sip-ltp10: 3, State=TERMINATEDcontacting state hangup 4481 07/08 10:36:35.939 ACCN SIPL 0 sip-ltp10: 3, State=TERMINATEDAfter contacting state hangup
Solution:
The bind interface command allows you to configure the source IP address of signaling and media packets to a specific interface’s IP address. Thus, the address that goes out on the packet is bound to the IP address of the interface specified with the bind command. Packets that are not destined to the bound address are discarded.
- Check the interface used to configure CUE.
- It is recommended that the interface used in CUCME inside the ip source-address is the same used for CUE.
- Make proper adjustments in order to accept SIP Traffic sourced from CUE interface:
3.1 You can remove the bind commands from voice service voip. This allows the Gateway to accept SIP Traffic from any interface.
voice service voip sip no bind control source-interface [interface] no bind media source-interface [interface]
3.2 You can configure SIP bind commands on a dial-peer basis. This is commonly used when you have a SIP Trunk to your Carrier or Firewalls that requires specific IP Addresses to allow:
dial-peer voice tag voip
session protocol sipv2
voice-class sip bind {control | media} source interface interface-id[ipv6-address ipv6-address]
exit
Issue 2. Proper Extension length not defined in ephone-dn
MWI DN configuration in CUCME for the Outcall Method is not properly provisioned with the correct extension length used in the CUCME Dial Plan for MWI.
Possible Cause #1
If ephoned-dn is configured with only the MWI extension and no dots (‘.’) CUE synchronization fails:
Navigate to Administration > Synchronize Information
Possible Cause #2
Extension Length does not match the right amount of digits for the users extensions.
In CUCME you can use the debug ccsip messages and the messages are present, but the MWI does not work:
Received: INVITE sip:[email protected]:5060;user=phone SIP/2.0 Via: SIP/2.0/UDP 10.10.202.50:5060;branch=z9hG4bK51VhKqo+pUDrDt5LgLS2yA~~20 Max-Forwards: 70 To: <sip:[email protected]:5060;user=phone> From: <sip:[email protected]:5060>;tag=cue5d4ca12d Call-ID: [email protected] CSeq: 1 INVITE Content-Length: 176 Contact: <sip:[email protected]:5060> Content-Type: application/sdp Cisco-Gcid: BF56E097-013F-1000-4000-001125CUCE68 Call-Info: <sip:10.10.202.50:5060>;method="NOTIFY;Event=telephone-event;Duration=2000" Allow-Events: telephone-event v=0 o=CiscoSystemsSIP-Workflow-App-UserAgent 151 151 IN IP4 10.10.202.50 s=SIP Call c=IN IP4 10.10.202.50 t=0 0 m=audio 16932 RTP/AVP 0 a=rtpmap:0 pcmu/8000 a=ptime:20 000815: *Jul 8 18:26:07.215: SetCallInfo MODE 1 calling dn -1 chan 1 dn 3 chan 1 000816: *Jul 8 18:26:07.215: alling [3] called [39993001] 000817: *Jul 8 18:26:07.215: SkinnyTryCall to 1 instance 1 start at 0 000818: *Jul 8 18:26:07.215: MWI-on non-local target 1 000819: *Jul 8 18:26:07.215: MWI-on has no non-local target 1 CME#show ephone reg ephone-1[0] Mac:0023.5E18.23EC TCP socket:[2] activeLine:0 whisperLine:0 REGISTERED in SCCP ver 17/12 max_streams=5 mediaActive:0 whisper_mediaActive:0 startMedia:0 offhook:0 ringing:0 reset:0 reset_sent:0 paging 0 debug:1 caps:9 IP:10.10.202.2 31984 7965 keepalive 4 max_line 6 available_line 6 button 1: dn 1 number 3001 CH1 IDLE CH2 IDLE Preferred Codec: g711ulaw Username: MWIOne Password: cisco
Solution:
Ensure the proper amount of dots (‘.’) are configured after the MWI extension number in order to match the length of the extensions used in CUCME for the users:
ephone-dn 3 mwi on number 3999…. ! ephone-dn 4 mwi off number 3998….
Issue 3. Subscribe-Notify with no Subscription
When SIP Subscribe-Notify method is used and no prior Subscription of the Directory Numbers has taken place, MWI does not work and no SIP Notify is sent for MWI events.
In CUCME after leaving or retrieving voicemails, no SIP Notify is sent from CUE in order to turn on/off MWI:
Sent: BYE sip:[email protected]:5060 SIP/2.0 Via: SIP/2.0/UDP 10.10.202.1:5060;branch=z9hG4bK601067 From: <sip:[email protected]>;tag=716F18-152D To: <sip:[email protected]>;tag=cue861dc350 Date: Mon, 08 Jul 2013 18:35:00 GMT Call-ID: [email protected] User-Agent: Cisco-SIPGateway/IOS-12.x Max-Forwards: 70 Timestamp: 1373308519 CSeq: 102 BYE Reason: Q.850;cause=16 Content-Length: 0 Received: SIP/2.0 200 Ok Via: SIP/2.0/UDP 10.10.202.1:5060;branch=z9hG4bK601067 To: <sip:[email protected]>;tag=cue861dc350 From: <sip:[email protected]>;tag=716F18-152D Call-ID: [email protected] CSeq: 102 BYE Content-Length: 0
Solution:
Configure the proper MWI Subscription commands in order to get the DNs Subscribe with CUE for MWI events:
voice register dn 1 number 3005 mwi ! ephone-dn 1 number 3001 mwi sip Sent: SUBSCRIBE sip:[email protected]:5060 SIP/2.0 Via: SIP/2.0/UDP 10.10.202.1:5060;branch=z9hG4bK61210 From: <sip:[email protected]>;tag=728524-1B54 To: <sip:[email protected]> Call-ID: [email protected] CSeq: 101 SUBSCRIBE Max-Forwards: 70 Date: Mon, 08 Jul 2013 18:36:11 GMT User-Agent: Cisco-SIPGateway/IOS-12.x Event: message-summary Expires: 3600 Contact: <sip:[email protected]:5060> Accept: application/simple-message-summary Content-Length: 0 Received: SIP/2.0 202 Accepted Via: SIP/2.0/UDP 10.10.202.1:5060;branch=z9hG4bK61210 To: <sip:[email protected]>;tag=591a1296-1099 From: <sip:[email protected]>;tag=728524-1B54 Call-ID: [email protected] CSeq: 101 SUBSCRIBE Content-Length: 0 Expires: 3600 Contact: sip:[email protected] Allow-Events: refer Allow-Events: telephone-event Allow-Events: message-summary Received: NOTIFY sip:[email protected]:5060 SIP/2.0 Via: SIP/2.0/UDP 10.10.202.50:5060;branch=z9hG4bK51VhKqo+pUDrDt5LgLS2yA~~27 Max-Forwards: 70 To: <sip:[email protected]>;tag=728524-1B54 From: <sip:[email protected]>;tag=591a1296-1099 Call-ID: [email protected] CSeq: 1 NOTIFY Content-Length: 113 Contact: sip:[email protected] Event: message-summary Allow-Events: refer Allow-Events: telephone-event Allow-Events: message-summary Subscription-State: active Content-Type: application/simple-message-summary Messages-Waiting: yes Message-Account: sip:[email protected] Voice-Message: 1/0 (0/0) Fax-Message: 0/0 (0/0) Sent: SIP/2.0 200 OK Via: SIP/2.0/UDP 10.10.202.50:5060;branch=z9hG4bK51VhKqo+pUDrDt5LgLS2yA~~27 From: <sip:[email protected]>;tag=591a1296-1099 To: <sip:[email protected]>;tag=728524-1B54 Date: Mon, 08 Jul 2013 18:36:11 GMT Call-ID: [email protected] CSeq: 1 NOTIFY Content-Length: 0 000963: *Jul 8 18:36:12.255: %SYS-5-CONFIG_I: Configured from console by jovalver on vty0 (10.10.100.6) 000964: *Jul 8 18:36:12.599: ephone-1[2]:Set MWI line 1 to ON count 1 000965: *Jul 8 18:36:12.599: ephone-1[2]:Set MWI line 0 to ON count 1
Issue 4. 488 Not Acceptable Media
CUCME sends 488 Not Acceptable Media when CUE sends an Outcall INVITE for MWI.
Sent: SIP/2.0 488 Not Acceptable Media Via: SIP/2.0/UDP 172.18.106.88:5060 From: "Cisco SIP Channel1" <sip:[email protected]>;tag=75b5194d-133 To: <sip:[email protected];user=phone>;tag=23F1578C-252 Date: Fri, 11 Mar 2005 15:09:13 GMT Call-ID: [email protected]:5060 Server: Cisco-SIPGateway/IOS-12.x CSeq: 51 INVITE Allow-Events: telephone-event Content-Length: 0
Solution:
CUE only supports G711ulaw
Ensure the proper incoming dial-peer is match that supports codec G711ulaw. You can either create a new dial-peer or use the pre-existing dial-peer for voicemail access:
dial-peer voice 3600 voip destination-pattern 3600 session protocol sipv2 session target ipv4:10.10.202.2 incoming called-number 399[89].... dtmf-relay sip-notify codec g711ulaw no vad !
Related Information
- Cisco Unity Express VoiceMail and Auto Attendant CLI Administrator Guide for 3.0 and Later Versions
- Troubleshooting Unity Express Message Waiting Indication (MWI) Problems
- Integrating Voice Mail with Cisco Unified SRST
- SIP Gateway Support for the Bind Command
- Cisco CallManager Express/Cisco Unity Express Configuration Example
