Scenario#13 – Voicemail not working CME-CUE

The Voicemail for the users was not working. If an external call reaches to their voiceail, they will get a message saying ‘ there is no voicemail box attached to this extension’

I checked the CME configuration for dialpeers etc which looks fine and the Caller getting that message actually means they can get to CUE but then CUE doesn’t recognize that Subscriber or there is no mailbox for that Subscriber.

I checked the configuration for few users at CUE and found all users with proper Voicemail box and everything configured.

To check how the call is flowing myself, I Call-forward all one of the extension and opened the debug iusdn q931 and debug voice dialpeer to check how the call is flowing. Call came in as follows:

Jul  7 16:26:25.294: ISDN BR0/2/1 Q931: RX <- SETUP pd = 8  callref = 0x01

Sending Complete

Bearer Capability i = 0x8090A3
Standard = CCITT
Transfer Capability = Speech
Transfer Mode = Circuit
Transfer Rate = 64 kbit/s
Channel ID i = 0x89
Exclusive, B1
Called Party Number i = 0x81, ‘788552’
Plan:ISDN, Type:Unknown
Jul  7 16:26:25.314: ISDN BR0/2/1 Q931: TX -> CALL_PROC pd = 8  callref = 0x81
Channel ID i = 0x89
Exclusive, B1
Jul  7 16:26:25.354: ISDN BR0/2/1 Q931: TX -> ALERTING pd = 8  callref = 0x81
Progress Ind i = 0x8188 – In-band info or appropriate now available
Jul  7 16:26:37.370: ISDN BR0/2/1 Q931: RX <- DISCONNECT pd = 8  callref = 0x01
Cause i = 0x8090 – Normal call clearing

ephone-dn  1  dual-line
ring internal primary
number 1551 secondary 788552
pickup-group 500
label Joe Bloggs (1552)
description XXXXX788552
name Joe Bloggs
call-forward busy 4000
call-forward noan 4000 timeout 15
corlist incoming User-unrestricted
huntstop channel

From the debugs I found out that the call is coming in to the extension ‘788552’ and then goes to the Voicemail with the same extension.

Here are the debugs – 4000 is the Voicemail Pilot:

Jul 19 12:53:50.525: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
Jul 19 12:53:50.525: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=788552T
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 19 12:53:50.525: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Incoming Dial-peer=20008 Is Matched
Jul 19 12:53:50.525: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5616
Jul 19 12:53:50.525: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
Jul 19 12:53:50.525: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:exit@6216
Jul 19 12:53:50.529: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=788552, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 19 12:53:50.529: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=788552
Jul 19 12:53:50.529: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=788552, Expanded String=788552, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 19 12:53:50.529: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=20008 Is Matched
Jul 19 12:53:50.529: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
Jul 19 12:53:50.529: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=20008
Jul 19 12:53:50.529: //-1/8336FB9580EA/DPM/dpMatchPeersCore:
Calling Number=, Called Number=788552, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 19 12:53:50.529: //-1/8336FB9580EA/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=788552
Jul 19 12:53:50.529: //-1/8336FB9580EA/DPM/dpMatchCore:
Dial String=788552, Expanded String=788552, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 19 12:53:50.529: //-1/8336FB9580EA/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=20008 Is Matched
Jul 19 12:53:50.529: //-1/8336FB9580EA/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
Jul 19 12:53:50.529: //-1/8336FB9580EA/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=20008
Jul 19 12:53:50.529: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=4000, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 19 12:53:50.529: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=4000
Jul 19 12:53:50.529: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=4000, Expanded String=4000, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 19 12:53:50.529: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=1000 Is Matched
Jul 19 12:53:50.533: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
Jul 19 12:53:50.533: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=1000
Jul 19 12:53:50.533: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=4000, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 19 12:53:50.533: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=4000
Jul 19 12:53:50.533: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=4000, Expanded String=4000, Calling Number=
Timeout=FALSE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 19 12:53:50.533: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=1000 Is Matched
Jul 19 12:53:50.533: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
Jul 19 12:53:50.533: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=1000
Jul 19 12:53:50.533: ISDN BR0/2/1 Q931: TX -> CALL_PROC pd = 8  callref = 0x81
Channel ID i = 0x89
Exclusive, B1
Jul 19 12:53:50.537: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=788552, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 19 12:53:50.537: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=788552
Jul 19 12:53:50.537: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=788552, Expanded String=788552, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 19 12:53:50.537: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=20008 Is Matched
Jul 19 12:53:50.537: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
Jul 19 12:53:50.537: //-1/xxxxxxxxxxxx/DPM/dpMatchPeers:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=20008
Jul 19 12:53:50.541: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=4000, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
Jul 19 12:53:50.541: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ANSWER; Calling Number=4000
Jul 19 12:53:50.541: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
Jul 19 12:53:50.541: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=4000T
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 19 12:53:50.541: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Result=-1
Jul 19 12:53:50.541: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5616
Jul 19 12:53:50.541: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ORIGINATE; Calling Number=4000
Jul 19 12:53:50.541: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
Jul 19 12:53:50.541: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=4000T
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 19 12:53:50.541: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Incoming Dial-peer=1000 Is Matched
Jul 19 12:53:50.541: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5616
Jul 19 12:53:50.541: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=1000
Jul 19 12:53:50.541: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:exit@6216
Jul 19 12:53:50.541: //-1/833DB27E8C13/DPM/dpMatchPeersCore:
Calling Number=, Called Number=4000, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 19 12:53:50.541: //-1/833DB27E8C13/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=4000
Jul 19 12:53:50.541: //-1/833DB27E8C13/DPM/dpMatchCore:
Dial String=4000, Expanded String=4000, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 19 12:53:50.545: //-1/833DB27E8C13/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=1000 Is Matched
Jul 19 12:53:50.545: //-1/833DB27E8C13/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
Jul 19 12:53:50.545: //-1/833DB27E8C13/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=1000
Jul 19 12:53:50.589: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 19 12:53:50.589: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
No Outgoing Dial-peer Is Matched; Result=NO_MATCH(-1)
Jul 19 12:53:50.589: //-1/xxxxxxxxxxxx/DPM/dpMatchPeers:
Result=NO_MATCH(-1)
Jul 19 12:53:50.589: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=4000, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 19 12:53:50.589: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=4000
Jul 19 12:53:50.593: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=4000, Expanded String=4000, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 19 12:53:50.593: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=1000 Is Matched
Jul 19 12:53:50.593: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
Jul 19 12:53:50.593: //-1/xxxxxxxxxxxx/DPM/dpMatchPeers:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=1000
Jul 19 12:53:50.597: ISDN BR0/2/1 Q931: TX -> ALERTING pd = 8  callref = 0x81
Jul 19 12:53:50.625: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=, Peer Info Type=DIALPEER_INFO_SPEECH

Jul 19 12:53:50.625: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
No Outgoing Dial-peer Is Matched; Result=NO_MATCH(-1)
Jul 19 12:53:50.625: //-1/xxxxxxxxxxxx/DPM/dpMatchPeers:
Result=NO_MATCH(-1)
Jul 19 12:53:50.629: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=4000, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 19 12:53:50.629: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=4000
Jul 19 12:53:50.629: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=4000, Expanded String=4000, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
Jul 19 12:53:50.629: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=1000 Is Matched
Jul 19 12:53:50.629: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
Jul 19 12:53:50.629: //-1/xxxxxxxxxxxx/DPM/dpMatchPeers:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=1000
Jul 19 12:53:50.637: ISDN BR0/2/1 Q931: TX -> CONNECT pd = 8  callref = 0x81
Channel ID i = 0x89
Exclusive, B1
Jul 19 12:53:50.713: ISDN BR0/2/1 Q931: RX <- CONNECT_ACK pd = 8  callref = 0x01
Jul 19 12:53:50.713: %ISDN-6-CONNECT: Interface BRI0/2/1:1 is now connected to N/A N/A

Jul 19 12:53:58.649: ISDN BR0/2/1 Q931: RX <- DISCONNECT pd = 8  callref = 0x01
Cause i = 0x8090 – Normal call clearing

So the call was reaching CUE with 788552 but what I found under user settings at CUE that the Primary E.164 number was configured as full DDI instead of ‘788552’.  I  changed that to the 6-digit number and all started working fine.

 

Advertisements

Leave a Reply

Fill in your details below or click an icon to log in:

WordPress.com Logo

You are commenting using your WordPress.com account. Log Out / Change )

Twitter picture

You are commenting using your Twitter account. Log Out / Change )

Facebook photo

You are commenting using your Facebook account. Log Out / Change )

Google+ photo

You are commenting using your Google+ account. Log Out / Change )

Connecting to %s