cancel
Showing results for 
Search instead for 
Did you mean: 
cancel
1903
Views
5
Helpful
19
Replies

CME Call Forward

Jaswild999
Level 1
Level 1

I had posted this before, but can't seem to find it under these new forums.  I have a CME that the CFWA feature was working when service was POTS.  They changed to PRI and it has stopped.  Call trace from the carrier shows that on CFWA they are receiving 3 digits.

A manual CFWD works, but not a CFWA programmed on an IP phone.

I am assuming its the dial-peers or telephony configuration.  Please let me know what other information is needed to help me understand why this is failing.


chrs-phx-cme#sh logg
Syslog logging: enabled (0 messages dropped, 10 messages rate-limited, 0 flushes, 0 overruns, xml disabled, filtering disabled)

No Active Message Discriminator.

 

No Inactive Message Discriminator.


    Console logging: disabled
    Monitor logging: disabled
    Buffer logging:  level debugging, 2157 messages logged, xml disabled,
                    filtering disabled
    Exception Logging: size (4096 bytes)
    Count and timestamp logging messages: disabled
    Persistent logging: disabled
    Trap logging: level informational, 1147 message lines logged

Log Buffer (300000 bytes):

Mar 21 02:41:48.689: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Calling Number=123, Called Number=, Voice-Interface=0x4B40DF0C,
   Timeout=TRUE, Peer Encap Type=ENCAP_VOICE, Peer Search Type=PEER_TYPE_VOICE,
   Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:41:48.689: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_ANSWER; Calling Number=123
Mar 21 02:41:48.689: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
Mar 21 02:41:48.689: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=123T
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:41:48.689: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Result=-1
Mar 21 02:41:48.689: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5985
Mar 21 02:41:48.689: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_ORIGINATE; Calling Number=123
Mar 21 02:41:48.689: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
Mar 21 02:41:48.689: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=123T
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:41:48.689: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Incoming Dial-peer=123 Is Matched
Mar 21 02:41:48.689: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Incoming Dial-peer=20011 Is Matched
Mar 21 02:41:48.689: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5985
Mar 21 02:41:48.689: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=20011
Mar 21 02:41:48.689: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
Mar 21 02:41:48.689: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerSPI:exit@6564
Mar 21 02:41:54.533: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=123, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:41:54.533: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=123
Mar 21 02:41:54.533: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=123, Expanded String=123, Calling Number=
   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:41:54.533: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=123 Is Matched
Mar 21 02:41:54.533: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=20011 Is Matched
Mar 21 02:41:54.533: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
Mar 21 02:41:54.533: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
Mar 21 02:41:54.533: //-1/xxxxxxxxxxxx/DPM/dpMatchPeers:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=20011
     2: Dial-peer Tag=123
Mar 21 02:41:54.533: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=123, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:41:54.533: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=123
Mar 21 02:41:54.533: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=123, Expanded String=123, Calling Number=
   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:41:54.533: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=123 Is Matched
Mar 21 02:41:54.533: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=20011 Is Matched
Mar 21 02:41:54.533: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
Mar 21 02:41:54.533: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=123, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
Mar 21 02:41:54.533: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=20011
     2: Dial-peer Tag=123
Mar 21 02:41:59.769: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Calling Number=117, Called Number=, Voice-Interface=0x4B630BEC,
   Timeout=TRUE, Peer Encap Type=ENCAP_VOICE, Peer Search Type=PEER_TYPE_VOICE,
   Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:41:59.769: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_ANSWER; Calling Number=117
Mar 21 02:41:59.769: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
Mar 21 02:41:59.769: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=117T
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:41:59.769: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Result=-1
Mar 21 02:41:59.769: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5985
Mar 21 02:41:59.769: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_ORIGINATE; Calling Number=117
Mar 21 02:41:59.769: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
Mar 21 02:41:59.769: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=117T
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:41:59.769: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Incoming Dial-peer=20053 Is Matched
Mar 21 02:41:59.769: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5985
Mar 21 02:41:59.769: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=20053
Mar 21 02:41:59.769: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
Mar 21 02:41:59.769: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerSPI:exit@6564
Mar 21 02:42:00.349: //-1/35DD1DF8A678/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=1, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:00.349: //-1/35DD1DF8A678/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=1
Mar 21 02:42:00.349: //-1/35DD1DF8A678/DPM/dpMatchCore:
   Dial String=1, Expanded String=1, Calling Number=
   Timeout=FALSE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:00.349: //-1/35DD1DF8A678/DPM/MatchNextPeer:
   Result=MORE_DIGITS_NEEDED(1); Outgoing Dial-peer=123
Mar 21 02:42:00.349: //-1/35DD1DF8A678/DPM/dpMatchCore:
   Result=1
Mar 21 02:42:00.353: //-1/35DD1DF8A678/DPM/dpMatchPeersCore:
   Result=Partial Matches(1) after DP_MATCH_DEST
Mar 21 02:42:00.353: //-1/35DD1DF8A678/DPM/dpMatchSafModulePlugin:
   dialstring=1, saf_enabled=1, saf_dndb_lookup=0, dp_result=1
Mar 21 02:42:00.353: //-1/35DD1DF8A678/DPM/dpMatchPeersMoreArg:
   Result=MORE_DIGITS_NEEDED(1)
Mar 21 02:42:00.621: //-1/35DD1DF8A678/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=12, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:00.621: //-1/35DD1DF8A678/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=12
Mar 21 02:42:00.621: //-1/35DD1DF8A678/DPM/dpMatchCore:
   Dial String=12, Expanded String=12, Calling Number=
   Timeout=FALSE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:00.621: //-1/35DD1DF8A678/DPM/MatchNextPeer:
   Result=MORE_DIGITS_NEEDED(1); Outgoing Dial-peer=123
Mar 21 02:42:00.621: //-1/35DD1DF8A678/DPM/dpMatchCore:
   Result=1
Mar 21 02:42:00.621: //-1/35DD1DF8A678/DPM/dpMatchPeersCore:
   Result=Partial Matches(1) after DP_MATCH_DEST
Mar 21 02:42:00.621: //-1/35DD1DF8A678/DPM/dpMatchSafModulePlugin:
   dialstring=12, saf_enabled=1, saf_dndb_lookup=0, dp_result=1
Mar 21 02:42:00.621: //-1/35DD1DF8A678/DPM/dpMatchPeersMoreArg:
   Result=MORE_DIGITS_NEEDED(1)
Mar 21 02:42:00.861: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=123, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:00.861: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=123
Mar 21 02:42:00.861: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=123, Expanded String=123, Calling Number=
   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:00.861: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=123 Is Matched
Mar 21 02:42:00.861: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=20011 Is Matched
Mar 21 02:42:00.861: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
Mar 21 02:42:00.861: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=123, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
Mar 21 02:42:00.861: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=20011
     2: Dial-peer Tag=123
Mar 21 02:42:00.865: //-1/35DD1DF8A678/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=123, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:00.865: //-1/35DD1DF8A678/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=123
Mar 21 02:42:00.865: //-1/35DD1DF8A678/DPM/dpMatchCore:
   Dial String=123, Expanded String=123, Calling Number=
   Timeout=FALSE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:00.865: //-1/35DD1DF8A678/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=123 Is Matched
Mar 21 02:42:00.865: //-1/35DD1DF8A678/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=20011 Is Matched
Mar 21 02:42:00.865: //-1/35DD1DF8A678/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
Mar 21 02:42:00.865: //-1/35DD1DF8A678/DPM/dpMatchSafModulePlugin:
   dialstring=123, saf_enabled=1, saf_dndb_lookup=0, dp_result=0
Mar 21 02:42:00.865: //-1/35DD1DF8A678/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=20011
     2: Dial-peer Tag=123
Mar 21 02:42:00.865: //-1/35DD1DF8A678/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=123, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:00.869: //-1/35DD1DF8A678/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=123
Mar 21 02:42:00.869: //-1/35DD1DF8A678/DPM/dpMatchCore:
   Dial String=123, Expanded String=123, Calling Number=
   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:00.869: //-1/35DD1DF8A678/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=123 Is Matched
Mar 21 02:42:00.869: //-1/35DD1DF8A678/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=20011 Is Matched
Mar 21 02:42:00.869: //-1/35DD1DF8A678/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
Mar 21 02:42:00.869: //-1/35DD1DF8A678/DPM/dpMatchSafModulePlugin:
   dialstring=123, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
Mar 21 02:42:00.869: //-1/35DD1DF8A678/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=20011
     2: Dial-peer Tag=123
Mar 21 02:42:00.869: //-1/35DD1DF8A678/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=123, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:00.869: //-1/35DD1DF8A678/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=123
Mar 21 02:42:00.869: //-1/35DD1DF8A678/DPM/dpMatchCore:
   Dial String=123, Expanded String=123, Calling Number=
   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:00.869: //-1/35DD1DF8A678/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=123 Is Matched
Mar 21 02:42:00.869: //-1/35DD1DF8A678/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=20011 Is Matched
Mar 21 02:42:00.869: //-1/35DD1DF8A678/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
Mar 21 02:42:00.869: //-1/35DD1DF8A678/DPM/dpMatchSafModulePlugin:
   dialstring=123, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
Mar 21 02:42:00.869: //-1/35DD1DF8A678/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=20011
     2: Dial-peer Tag=123
Mar 21 02:42:00.869: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Calling Number=123, Called Number=123, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:00.869: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=123
Mar 21 02:42:00.869: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=123, Expanded String=123, Calling Number=123T
   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:00.869: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=123 Is Matched
Mar 21 02:42:00.869: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=20011 Is Matched
Mar 21 02:42:00.869: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
Mar 21 02:42:00.869: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=123, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
Mar 21 02:42:00.869: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=20011
     2: Dial-peer Tag=123
Mar 21 02:42:00.869: //-1/35DD1DF8A678/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=9602xxx6403, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:00.869: //-1/35DD1DF8A678/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=96025056403
Mar 21 02:42:00.869: //-1/35DD1DF8A678/DPM/dpMatchCore:
   Dial String=96025056403, Expanded String=96025056403, Calling Number=
   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:00.869: //-1/35DD1DF8A678/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=8 Is Matched
Mar 21 02:42:00.869: //-1/35DD1DF8A678/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
Mar 21 02:42:00.869: //-1/35DD1DF8A678/DPM/dpMatchSafModulePlugin:
   dialstring=96025056403, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
Mar 21 02:42:00.869: //-1/35DD1DF8A678/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=8
Mar 21 02:42:00.873: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Calling Number=, Called Number=, Voice-Interface=0x0,
   Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
   Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:00.873: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Result=NO_MATCH(-1) After All Match Rules Attempt
Mar 21 02:42:00.873: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
Mar 21 02:42:00.873: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:exit@6613
Mar 21 02:42:00.873: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Calling Number=123, Called Number=, Voice-Interface=0x0,
   Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
   Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:00.873: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_ANSWER; Calling Number=123
Mar 21 02:42:00.873: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
Mar 21 02:42:00.873: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=123T
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:00.873: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Result=-1
Mar 21 02:42:00.873: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5985
Mar 21 02:42:00.873: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_ORIGINATE; Calling Number=123
Mar 21 02:42:00.873: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
Mar 21 02:42:00.873: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=123T
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:00.873: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Incoming Dial-peer=123 Is Matched
Mar 21 02:42:00.873: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Incoming Dial-peer=20011 Is Matched
Mar 21 02:42:00.873: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5985
Mar 21 02:42:00.873: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=123
Mar 21 02:42:00.873: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
Mar 21 02:42:00.873: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:exit@6613
Mar 21 02:42:00.873: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=123, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:00.873: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=123
Mar 21 02:42:00.873: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=123, Expanded String=123, Calling Number=
   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:00.873: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=123 Is Matched
Mar 21 02:42:00.873: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=20011 Is Matched
Mar 21 02:42:00.873: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
Mar 21 02:42:00.873: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=123, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
Mar 21 02:42:00.873: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=20011
     2: Dial-peer Tag=123
Mar 21 02:42:00.873: //-1/35DD1DF8A678/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=123, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:00.877: //-1/35DD1DF8A678/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=123
Mar 21 02:42:00.877: //-1/35DD1DF8A678/DPM/dpMatchCore:
   Dial String=123, Expanded String=123, Calling Number=
   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:00.877: //-1/35DD1DF8A678/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=123 Is Matched
Mar 21 02:42:00.877: //-1/35DD1DF8A678/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=20011 Is Matched
Mar 21 02:42:00.877: //-1/35DD1DF8A678/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
Mar 21 02:42:00.877: //-1/35DD1DF8A678/DPM/dpMatchSafModulePlugin:
   dialstring=123, saf_enabled=1, saf_dndb_lookup=1, dp_result=0
Mar 21 02:42:00.877: //-1/35DD1DF8A678/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=20011
     2: Dial-peer Tag=123
Mar 21 02:42:00.877: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=9602xxxx403, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:00.877: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=9602xxxx403
Mar 21 02:42:00.877: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=96025056403, Expanded String=9602xxxx403, Calling Number=
   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:00.877: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=8 Is Matched
Mar 21 02:42:00.877: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
Mar 21 02:42:00.877: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=96025056403, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
Mar 21 02:42:00.877: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=8
Mar 21 02:42:00.877: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=9602xxxx403, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:00.877: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=9602xxxx403
Mar 21 02:42:00.877: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=96025056403, Expanded String=9602xxxx403, Calling Number=
   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:00.877: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=8 Is Matched
Mar 21 02:42:00.877: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
Mar 21 02:42:00.877: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=96025056403, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
Mar 21 02:42:00.877: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=8
Mar 21 02:42:00.877: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=9602xxxx403, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:00.877: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=9602xxxx403
Mar 21 02:42:00.877: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=96025056403, Expanded String=9602xxxx403, Calling Number=
   Timeout=FALSE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:00.877: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=MORE_DIGITS_NEEDED(1); Outgoing Dial-peer=8
Mar 21 02:42:00.877: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Result=1
Mar 21 02:42:00.877: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Result=Partial Matches(1) after DP_MATCH_DEST
Mar 21 02:42:00.877: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=96025056403, saf_enabled=0, saf_dndb_lookup=1, dp_result=1
Mar 21 02:42:00.877: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
   Result=MORE_DIGITS_NEEDED(1)
Mar 21 02:42:00.881: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Calling Number=96025056403, Called Number=, Voice-Interface=0x0,
   Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
   Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:00.881: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_ANSWER; Calling Number=9602xxxx403
Mar 21 02:42:00.881: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
Mar 21 02:42:00.881: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=9602xxx403T
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:00.881: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Result=-1
Mar 21 02:42:00.881: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5985
Mar 21 02:42:00.881: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_ORIGINATE; Calling Number=9602xxxx403
Mar 21 02:42:00.881: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
Mar 21 02:42:00.881: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=9602xxxx403T
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:00.881: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Incoming Dial-peer=8 Is Matched
Mar 21 02:42:00.881: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5985
Mar 21 02:42:00.881: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Result=NO_MATCH(-1) After All Match Rules Attempt
Mar 21 02:42:00.885: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
Mar 21 02:42:00.885: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:exit@6613
Mar 21 02:42:00.885: //-1/36876761A67C/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=9602xxxx403, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:00.885: //-1/36876761A67C/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=9602xxxx403
Mar 21 02:42:00.885: //-1/36876761A67C/DPM/dpMatchCore:
   Dial String=96025056403, Expanded String=9602xxxx403, Calling Number=
   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:00.885: //-1/36876761A67C/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=8 Is Matched
Mar 21 02:42:00.885: //-1/36876761A67C/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
Mar 21 02:42:00.885: //-1/36876761A67C/DPM/dpMatchSafModulePlugin:
   dialstring=96025056403, saf_enabled=1, saf_dndb_lookup=1, dp_result=0
Mar 21 02:42:00.885: //-1/36876761A67C/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=8
Mar 21 02:42:00.889: ISDN Se0/3/0:23 Q931: Applying typeplan for sw-type 0xD is 0x0 0x0, Calling num 117
Mar 21 02:42:00.893: ISDN Se0/3/0:23 Q931: Sending SETUP  callref = 0x01A3 callID = 0x8124 switch = primary-ni interface = User
Mar 21 02:42:00.893: ISDN Se0/3/0:23 Q931: TX -> SETUP pd = 8  callref = 0x01A3
        Bearer Capability i = 0x8090A2
                Standard = CCITT
                Transfer Capability = Speech
                Transfer Mode = Circuit
                Transfer Rate = 64 kbit/s
        Channel ID i = 0xA9838A
                Exclusive, Channel 10
        Progress Ind i = 0x8183 - Origination address is non-ISDN
        Calling Party Number i = 0x0080, '117'
                Plan:Unknown, Type:Unknown
        Called Party Number i = 0xA1, '602xxxx403'
                Plan:ISDN, Type:National
        Redirecting Number i = 0x00008F, '123'
                Plan:Unknown, Type:Unknown
Mar 21 02:42:00.917: ISDN Se0/3/0:23 Q931: RX <- CALL_PROC pd = 8  callref = 0x81A3
        Channel ID i = 0xA9838A
                Exclusive, Channel 10
Mar 21 02:42:01.017: ISDN Se0/3/0:23 Q931: RX <- PROGRESS pd = 8  callref = 0x81A3
        Progress Ind i = 0x8288 - In-band info or appropriate now available
Mar 21 02:42:01.021: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=117, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:01.021: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=117
Mar 21 02:42:01.021: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=117, Expanded String=117, Calling Number=
   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:01.021: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=20053 Is Matched
Mar 21 02:42:01.021: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
Mar 21 02:42:01.021: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
Mar 21 02:42:01.021: //-1/xxxxxxxxxxxx/DPM/dpMatchPeers:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=20053
Mar 21 02:42:01.021: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=602xxxx403, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:01.021: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=602xxxx403
Mar 21 02:42:01.021: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=6025056403, Expanded String=602xxxx403, Calling Number=
   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:01.021: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Result=-1
Mar 21 02:42:01.021: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   No Outgoing Dial-peer Is Matched; Result=NO_MATCH(-1)
Mar 21 02:42:01.025: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
Mar 21 02:42:01.025: //-1/xxxxxxxxxxxx/DPM/dpMatchPeers:
   Result=NO_MATCH(-1)
Mar 21 02:42:01.025: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=602xxxx403, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:01.025: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=602xxxx403
Mar 21 02:42:01.025: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=6025056403, Expanded String=602xxxx403, Calling Number=
   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:01.025: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Result=-1
Mar 21 02:42:01.025: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   No Outgoing Dial-peer Is Matched; Result=NO_MATCH(-1)
Mar 21 02:42:01.025: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=6025056403, saf_enabled=0, saf_dndb_lookup=1, dp_result=-1
Mar 21 02:42:01.025: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
   Result=NO_MATCH(-1)
Mar 21 02:42:01.401: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=9602xxxx403, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:01.401: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=9602xxxx403
Mar 21 02:42:01.401: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=96025056403, Expanded String=9602xxxx403, Calling Number=
   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
Mar 21 02:42:01.401: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=8 Is Matched
Mar 21 02:42:01.401: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
Mar 21 02:42:01.401: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=96025056403, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
Mar 21 02:42:01.401: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=8
Mar 21 02:42:05.893: ISDN Se0/3/0:23 Q931: TX -> DISCONNECT pd = 8  callref = 0x01A3
        Cause i = 0x8090 - Normal call clearing
Mar 21 02:42:05.917: ISDN Se0/3/0:23 Q931: RX <- RELEASE pd = 8  callref = 0x81A3
Mar 21 02:42:05.917: ISDN Se0/3/0:23 Q931: TX -> RELEASE_COMP pd = 8  callref = 0x01A3
chrs-phx-cme#

 

Here is the telephony:


telephony-service
 sdspfarm units 2
 sdspfarm tag 2 DSPprofile2
 conference hardware
 authentication credential admin cisco
 max-ephones 58
 max-dn 192
 ip source-address 192.168.0.8 port 2000
 auto assign 100 to 150
 timeouts interdigit 5
 system message Making A Difference!
 url services http://192.168.0.9/voiceview/common/login.do
 url authentication http://192.168.0.8/CCMCIP/authenticate.asp  
 cnf-file location flash:
 load 7912 CP7912080004SCCP080108A.sbin
 load 7960-7940 P00308010200.loads
 load 7941 SCCP41.9-1-1SR1S.loads
 load 7970 SCCP70.9-1-1SR1S.loads
 time-zone 7
 voicemail 777
 mwi relay
 max-conferences 4 gain -6
 web admin system name admin secret 5 $1$Y5fU$YqKVbGIBzQ/YDVFr9QglP0
 transfer-system full-consult
 transfer-pattern .T
 secondary-dialtone 9
 directory last-name-first
!

 

Here are the dial-peers:


dial-peer voice 10 voip
 destination-pattern 778
 session protocol sipv2
 session target ipv4:192.168.0.9
 codec g711ulaw
 no vad
!
dial-peer voice 11 voip
 destination-pattern 779
 session protocol sipv2
 session target ipv4:192.168.0.9
 codec g711ulaw
 no vad
!
dial-peer voice 777 voip
 description Cisco Unity Express voicemail pilot number
 destination-pattern 777
 session protocol sipv2
 session target ipv4:192.168.0.9
 dtmf-relay sip-notify
 codec g711ulaw
 no vad
!
dial-peer voice 776 voip
 description Cisco Unity AA
 destination-pattern 776
 session protocol sipv2
 session target ipv4:192.168.0.9
 dtmf-relay sip-notify
 codec g711ulaw
 no vad
!
dial-peer voice 775 voip
 description Cisco Unity AA-admin
 destination-pattern 775
 session protocol sipv2
 session target ipv4:192.168.0.9
 dtmf-relay sip-notify
 codec g711ulaw
 no vad
!
dial-peer voice 443 pots
 destination-pattern 443
 port 0/1/3
 forward-digits 0
!
dial-peer voice 444 pots
 description Bogen Paging Adapter
 service stcapp
 port 0/2/0
!
dial-peer voice 5 pots
 description inbound PRI DP
 translation-profile incoming from_pri
 incoming called-number .
 port 0/3/0:23
 no sip-register
!
dial-peer voice 8 pots
 description PRI
 preference 1
 destination-pattern 9T
 incoming called-number .
 port 0/3/0:23
 no sip-register
!

 

19 Replies 19

Sreekanth Narayanan
Cisco Employee
Cisco Employee

Hi Jaswild,

Need more clarity here. What's the call flow? Is this a call coming in from the PRI and then being forwarded back to the PRI?

can you take the following and upload that as a file to this post?

debug voip ccapi inout

debug isdn q931

 

Thanks

Will have these shortly.

Call is from IP Phone to IP Phone on CFWD.

In the case of this example:  Ext. 123 > Ext. 107 (CFWA to 602xxxx403).  It appears to exit the correct dial peer.

info attached.

 

It looks like the disconnect is originating on the ephone side.

Mar 21 06:54:35.908: //3545/7A6C9B62A816/CCAPI/cc_api_call_disconnected:
   Cause Value=16, Interface=0x4B46BBCC, Call Id=3545

The cause value is 16 so I'm guessing the phone went on hook.

This happens about 6 seconds after the provider gives us progress information and we cut through the audio to the phone.

Mar 21 06:54:29.492: ISDN Se0/3/0:23 Q931: RX <- PROGRESS pd = 8  callref = 0x81BB
        Progress Ind i = 0x8288 - In-band info or appropriate now available
Mar 21 06:54:29.496: //3546/7A6C9B62A816/CCAPI/cc_api_call_cut_progress:
   Interface=0x4A04C394, Progress Indication=INBAND(8), Signal Indication=INTERCEPT(2),
   Cause Value=0

 

What do you hear on the IP phone when this happens?

We may need to take debug ephone messages, debug ephone detail to see what's happening on the phone side.

 

Thanks

 

I'll pull the information.  When the call is attempted, we hear, "Your call cannot be completed as dialed".

That's a Cisco prompt. Where is this PRI going? Is it going to a CUCM/CME over the ISDN?

PRI is connected to wic card in CME router.  All other call traffic works, it is only on CFWA that this issue occurs.

Where's the other end of the PRI connected?

Let's take a look at the debugs:

debug voip ccapi inout

debug ephone detail

debug ephone message

debug isdn q931

Please see attached.

Call from 888 (CIPC) > 107 (CFWA to 602XXXX403).

 

I can see the phone messages before this all look good, and the digit analysis.

>> outgoing setup. Why is the number '602XXXX403'? Is this allowed/legal?

What is the dialed number when CFA is not invoked??

 

Mar 21 23:06:55.420: ISDN Se0/3/0:23 Q931: TX -> SETUP pd = 8  callref = 0x01FF
    Bearer Capability i = 0x8090A2
        Standard = CCITT
        Transfer Capability = Speech  
        Transfer Mode = Circuit
        Transfer Rate = 64 kbit/s
    Channel ID i = 0xA9838A
        Exclusive, Channel 10
    Progress Ind i = 0x8183 - Origination address is non-ISDN  
    Calling Party Number i = 0x0080, '888'
        Plan:Unknown, Type:Unknown
    Called Party Number i = 0xA1, '602XXXX403'
        Plan:ISDN, Type:National
    Redirecting Number i = 0x00008F, '107'
        Plan:Unknown, Type:Unknown

>> We receive proceeding and progress indicating that the progress information will be provided by the telco.
Mar 21 23:06:55.452: ISDN Se0/3/0:23 Q931: RX <- CALL_PROC pd = 8  callref = 0x81FF
    Channel ID i = 0xA9838A
        Exclusive, Channel 10
Mar 21 23:06:55.456: //4242/544C95E8AFB0/CCAPI/cc_api_call_proceeding:
   Interface=0x4A04C394, Progress Indication=NULL(0)
Mar 21 23:06:55.552: ISDN Se0/3/0:23 Q931: RX <- PROGRESS pd = 8  callref = 0x81FF
    Progress Ind i = 0x8288 - In-band info or appropriate now available

>> The stats are updated on the phone,
Mar 21 23:06:55.560: SkinnyGetCallState for DN 54 chan 1 ALERTING
Mar 21 23:06:55.564: dn_callerid_update DN 54 number= 602XXXX403 name=  in state ALERTING
Mar 21 23:06:55.564: callingName= Wilder, calledName= , origCalledName= Ext 107, redirectedTo = 9602XXXX403

Mar 21 23:06:55.568: SkinnyUpdateDnState by EFXS_OPEN_VOICE_PATH
  for DN 54 chan 1 to state CALL_START
 
>> Phone opens its media receive channel, and also is asked by CME to start transmitting to CME IP address
Mar 21 23:06:55.972: ephone-30[34]:OpenReceiveChannelAck:IP 192.168.0.235, port=24580, dn_index=54, dn=54, chan=1
Mar 21 23:06:55.972: ephone-30[29/34]:StartMedia media_dn=54 media_ch=1 activeLine=1 whisperLine=0
Mar 21 23:06:55.972: ephone-30[34]:StartMedia 192.168.0.8 port=2000

*** At this point, anything that you hear on the phone is coming over the PRI. This is NOT locally generated by the phone or the CME! ***
So if you hear the "Call cannot be completed as dialed", it's coming from the other side of the ISDN PRI line, and not our CME.

>> A release comes from the telco 10 seconds later saying user is not responding. The call then disconnects.
Mar 21 23:07:05.992: ISDN Se0/3/0:23 Q931: RX <- DISCONNECT pd = 8  callref = 0x81FF
    Cause i = 0x8292 - No user responding
Mar 21 23:07:05.996: ISDN Se0/3/0:23 Q931: TX -> RELEASE pd = 8  callref = 0x01FF

 

The call is IP to IP (107 > 123), but when CFWA is invoked it goes to 602XXXX403 (XXXX are just missing digits, I was asked to not show the full number).

Attached are results from a call trace with the telco.  It appears that the telco is receiving 3 digits (i'm assuming the IP extension).  So apparently the CME is sending the 3 digit redirect rather than the numbers of the CFWA?

It's clear in the attached file that the PBX is getting the information.

 

12:23:31 ISDN.L2_FMT PRI 1 IE - 70 CALLED PARTY # Len=11
12:23:31 ISDN.L2_FMT PRI 1 A1 Numb. Type:NATIONAL
12:23:31 ISDN.L2_FMT PRI 1 Numb. Plan:E.164
12:23:31 ISDN.L2_FMT PRI 1 Ph.# 6025056403
12:23:31 ISDN.L2_FMT PRI 1 IE - 74 REDIRECTING # Len=6
12:23:31 ISDN.L2_FMT PRI 1 00 Numb. Type:UNKNOWN
12:23:31 ISDN.L2_FMT PRI 1 Numb. Plan:UNKNOWN
12:23:31 ISDN.L2_FMT PRI 1 00 Presentation:ALLOWED
12:23:31 ISDN.L2_FMT PRI 1 8F Redir reason:CALL FWD UNCOND'L
12:23:31 ISDN.L2_FMT PRI 1 Ph.# 123

 

It's getting both called number and redirect number. Perhaps the PBX is only taking the redirect number instead of the called number. We'll need an answer from the PBX team on that.

Does this mean a correction on what I'm sending to the Telco or they need to correct what they are receiving?


This is a CME 2800 Series router with a PRI connected.  There is not a separate cucm.

Where was the latest log taken from? Is this from the PBX?

The last lost was from the carrier side.

 

Jason

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: