Calls routed to CUE module result in a busy signal

Kynnzak · ‎03-30-2010

I may be a bit too deep in the weeds on this one and simply need another set of eyes to help me spot something, but here goes:

I'm configuring a CME system with AIM-CUE for VM and auto attendant. Whenever I dial any number that needs routed to the AIM-CUE, the result is a busy signal. NOT a fast busy, but a regular busy. This includes pressing the VM button on the 7942 phone(s).

Is this simply a matter of RMA'ing the module and getting a new one? If so, it'd be ironic as this was a replacement for a DoA AIM-CUE originally. (I'm fully open to suggestions that this system just isn't supposed to have a CUE module in it. . . ) :-)

Anyone encountered this before and have a fix?

I've attached the CME and CUE config files, again, it's quite possible that I'm not stepping back from this far enough to see where a config problem lies.

Brandon Buffin · ‎03-30-2010

Can you ping the CUE module? Can you get to the CLI/GUI of the CUE module? If you run "debug voip dialpeer" and dial the VM DN, is the call hitting the proper dial peer?

Brandon

Kynnzak · ‎03-30-2010

Ping - Yes.

CLI/GUI Access - Yes

Debug - doesn't provide anything.

Brandon Buffin · ‎03-30-2010

Did you enable "term mon" before running the debug?

Brandon

Kynnzak · ‎03-30-2010

Well, poo! I thought I had logging console on!

Here's the debug:

NEA-Meriden-IPVPN#
001510: Mar 30 11:16:40.252 EDT: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Calling Number=6009, Called Number=, Voice-Interface=0x4B5D2168,
   Timeout=TRUE, Peer Encap Type=ENCAP_VOICE, Peer Search Type=PEER_TYPE_VOICE,
   Peer Info Type=DIALPEER_INFO_SPEECH
001511: Mar 30 11:16:40.256 EDT: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=20009
001512: Mar 30 11:16:40.256 EDT: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
001513: Mar 30 11:16:40.820 EDT: //-1/135627F68072/DPM/dpMatchPeersCore:

NEA-Meriden-IPVPN#Calling Number=, Called Number=6, Peer Info Type=DIALPEER_INFO_SPEECH
001514: Mar 30 11:16:40.820 EDT: //-1/135627F68072/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=6
001515: Mar 30 11:16:40.824 EDT: //-1/135627F68072/DPM/dpMatchPeersCore:
   Result=Partial Matches(1) after DP_MATCH_DEST
001516: Mar 30 11:16:40.824 EDT: //-1/135627F68072/DPM/dpMatchSafModulePlugin:
   dialstring=6, saf_enabled=1, saf_dndb_lookup=0, dp_result=1
001517: Mar 30 11:16:40.824 EDT: //-1/135627F68072/DPM/dpMatchPeersMoreArg:
   Result=MORE_DIGITS_NEEDED(1)
001518: Mar 30 11:16:41.172 EDT: //-1/135627F68072/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=60, Peer Info Type=DIALPEER_INFO_SPEECH
001519: Mar 30 11:16:41.172 EDT: //-1/135627F68072/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=60
001520: Mar 30 11:16:41.176 EDT: //-1/135627F68072/DPM/dpMatchPeersCore:
   Result=Partial Matches(1) after DP_MATCH_DEST
001521: Mar 30 11:16:41.176 EDT: //-
NEA-Meriden-IP1/135627F68072/DPM/dpMatchSafModulePlugin:
   dialstring=60, saf_enabled=1, saf_dndb_lookup=0, dp_result=1
001522: Mar 30 11:16:41.176 EDT: //-1/135627F68072/DPM/dpMatchPeersMoreArg:
   Result=MORE_DIGITS_NEEDED(1)
001523: Mar 30 11:16:41.376 EDT: //-1/135627F68072/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=600, Peer Info Type=DIALPEER_INFO_SPEECH
001524: Mar 30 11:16:41.376 EDT: //-1/135627F68072/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=600
001525: Mar 30 11:16:41.376 EDT: //-1/135627F68072/DPM/dpMatchPeersCore:
   Result=Partial Matches(1) after DP_MATCH_DEST
001526: Mar 30 11:16:41.376 EDT: //-1/135627F68072/DPM/dpMatchSafModulePlugin:
   dialstring=600, saf_enabled=1, saf_dndb_lookup=0, dp_result=1
001527: Mar 30 11:16:41.376 EDT: //-1/135627F68072/DPM/dpMatchPeersMoreArg:
   Result=MORE_DIGITS_NEEDED(1)
001528: Mar 30 11:16:41.552 EDT: //-1/135627F68072/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=6000, Peer Info Type=DIALPEER_INFO_SPEECH
001529: Mar 30 11:16:41.552 EDT: //-1/135627F68072/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=6000
001530: Mar 30 11:16:41.552 EDT: //-1/135627F68072/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
001531: Mar 30 11:16:41.552 EDT: //-1/135627F68072/DPM/dpMatchSafModulePlugin:
   dialstring=6000, saf_enabled=1, saf_dndb_lookup=0, dp_result=0
001532: Mar 30 11:16:41.552 EDT: //-1/135627F68072/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=6000
001533: Mar 30 11:16:41.556 EDT: //-1/135627F68072/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=6000, Peer Info Type=DIALPEER_INFO_SPEECH
001534: Mar 30 11:16:41.556 EDT: //-1/135627F68072/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=6000
001535: Mar 30 11:16:41.556 EDT: //-1/135627F68072/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
001536: Mar 30 11:16:41.556 EDT: //-1/135627F68072/DPM/dpMatchSafModulePlugin:
   dialstring=6000, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
001537: Mar 30 11:16:41.556 EDT: //-1/135627F68072/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=6000
001538: Mar 30 11:16:41.556 EDT: //-1/135627F68072/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=6000, Peer Info Type=DIALPEER_INFO_SPEECH
001539: Mar 30 11:16:41.556 EDT: //-1/135627F68072/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=6000
001540: Mar 30 11:16:41.556 EDT: //-1/135627F68072/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
001541: Mar 30 11:16:41.556 EDT: //-1/135627F68072/DPM/dpMatchSafModulePlugin:
   dialstring=6000, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
001542: Mar 30 11:16:41.556 EDT: //-1/135627F68072/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=6000
001543: Mar 30 11:16:41.556 EDT: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Calling Number=6000, Called Number=6000, Peer Info Type=DIALPEER_INFO_SPEECH
001544: Mar 30 11:16:41.560 EDT: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=6000
001545: Mar 30 11:16:41.560 EDT: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
001546: Mar 30 11:16:41.560 EDT: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=6000, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
001547: Mar 30 11:16:41.560 EDT: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=6000
001548: Mar 30 11:16:41.560 EDT: //-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
001549: Mar 30 11:16:41.560 EDT: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Result=NO_MATCH(-1) After All Match Rules Attempt
001550: Mar 30 11:16:41.560 EDT: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
001551: Mar 30 11:16:41.560 EDT: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Calling Number=6000, Called Number=, Voice-Interface=0x0,
   Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
   Peer Info Type=DIALPEER_INFO_SPEECH
001552: Mar 30 11:16:41.560 EDT: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=6000
001553: Mar 30 11:16:41.560 EDT: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
001554: Mar 30 11:16:41.560 EDT: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=6000, Peer Info Type=DIALPEER_INFO_SPEECH
001555: Mar 30 11:16:41.560 EDT: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=6000
001556: Mar 30 11:16:41.564 EDT: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
001557: Mar 30 11:16:41.564 EDT: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=6000, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
001558: Mar 30 11:16:41.564 EDT: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=6000
001559: Mar 30 11:16:41.564 EDT: //-1/135627F68072/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=6000, Peer Info Type=DIALPEER_INFO_SPEECH
001560: Mar 30 11:16:41.564 EDT: //-1/135627F68072/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=6000
001561: Mar 30 11:16:41.564 EDT: //-1/135627F68072/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
001562: Mar 30 11:16:41.564 EDT: //-1/135627F68072/DPM/dpMatchSafModulePlugin:
   dialstring=6000, saf_enabled=1, saf_dndb_lookup=1, dp_result=0
001563: Mar 30 11:16:41.564 EDT: //-1/135627F68072/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=6000
001564: Mar 30 11:16:41.776 EDT: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Calling Number=60.., Called Number=, Voice-Interface=0x0,
   Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
   Peer Info Type=DIALPEER_INFO_SPEECH
001565: Mar 30 11:16:41.776 EDT: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Result=NO_MATCH(-1) After All Match Rules Attempt
001566: Mar 30 11:16:41.776 EDT: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
001567: Mar 30 11:16:48.664 EDT: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Calling Number=6009$, Called Number=, Voice-Interface=0x0,
   Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
   Peer Info Type=DIALPEER_INFO_SPEECH
001568: Mar 30 11:16:48.664 EDT: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=6000
001569: Mar 30 11:16:48.664 EDT: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0VPN#

Kynnzak · ‎03-30-2010

Ok, it's resolved.

Apparently I needed some schooling on the licensing conflicts between IVR and regular ports. Once the IVR was taken out of the picture, everything works fine.

Thanks for your help Brandon!

Kevin