Buy or Renew
Buy or Renew
Cisco + Splunk: It’s a new day for your data. Learn more.
 
cancel
Turn on suggestions
Auto-suggest helps you quickly narrow down your search results by suggesting possible matches as you type.
Showing results for 
Search instead for 
Did you mean: 
cancel
Start a conversation
1140
Views
10
Helpful
9
Replies

Delay on external calls while in SRST

datacenter
Level 1
Level 1

‎05-30-2014 10:51 AM - edited ‎03-19-2019 08:14 AM

Hello Guys,

We have a router/VG working in MGCP (CUCM 8.6.2) with SRST configured.

Everything works fine till it fallback to SRST mode.

All the phones get registered and receives calls normally... but when you try to do an external call, it stucks for 1:15~1:30 minutes, when it finally gets external tone and starts to call.

I did some config check and ran some debugs but didn't find anything that may explain it.

Here some configs:

ROM: System Bootstrap, Version 12.4(13r)T11, RELEASE SOFTWARE (fc1)

RTPTMAI022 uptime is 8 weeks, 4 days, 10 hours, 32 minutes
System returned to ROM by power-on
System restarted at 07:14:54 GMT Mon Mar 31 2014
System image file is "flash:c3845-adventerprisek9-mz.151-4.M5.bin"
Last reload type: Normal Reload

Cisco 3845 (revision 1.0) with 483328K/40960K bytes of memory.
Processor board ID xxxx
1 FastEthernet interface
2 Gigabit Ethernet interfaces
2 Serial(sync/async) interfaces
4 ISDN Basic Rate interfaces
1 Virtual Private Network (VPN) Module
DRAM configuration is 64 bits wide with parity enabled.
479K bytes of NVRAM.
250880K bytes of ATA System CompactFlash (Read/Write)

 

##

 

voice service voip
 address-hiding
 allow-connections h323 to h323
 allow-connections h323 to sip
 allow-connections sip to h323
 allow-connections sip to sip
 supplementary-service h450.12
 redirect ip2ip
 signaling forward unconditional
 fax protocol t38 version 0 ls-redundancy 0 hs-redundancy 0 fallback cisco
 h323
 sip
  bind control source-interface FastEthernet0/3/0.5
  bind media source-interface FastEthernet0/3/0.5
  referto-passing
  registrar server expires max 3600 min 3600
  asserted-id pai
  privacy pstn
  no update-callerid
  early-offer forced
  midcall-signaling passthru
  privacy-policy send-always
  g729 annexb-all
!

 

call-manager-fallback
 secondary-dialtone 0
 max-conferences 8 gain -6
 transfer-system full-consult
 ip source-address 10.211.3.40 port 2000
 max-ephones 150
 max-dn 150
 system message primary Em Modo Local
 dialplan-pattern 1 229477... extension-length 3
 dialplan-pattern 2 220449... extension-length 3
 transfer-pattern ...
 call-forward pattern ...
 moh cme_music_on_hold.au
 multicast moh 239.1.1.6 port 16386 route 10.211.3.40 1.1.1.1
 time-format 24
 date-format dd-mm-yy
!

 

sip-ua
 keepalive target ipv4:62.48.131.34:5060
 no remote-party-id
 retry invite 2
 retry register 10
 timers keepalive active 100
 sip-server ipv4:62.48.131.34:5060
 connection-reuse
 host-registrar
 g729-annexb override
!

 

 

 

 

 

Thanks in advance

Labels:
0 Helpful
9 Replies 9

Rolando Valenzuela
Level 4
Level 4

‎05-30-2014 11:58 AM

How many phones do you have? have you check the CPU/Memory usage when SRST is active?

Have you tried using timeout interdigit??

 

Regards.

Rolando Valenzuela

datacenter
Level 1
Level 1
In response to Rolando Valenzuela

‎05-30-2014 12:34 PM

Thanks for you quick response.

I didn't try timeout interdigit yet.

About the CPU utilization, it is running low, we have at about 80 phones there, but even forcing only one to work in SRST (denying traffic to CUCM via ACL in the switchport) the problem still happening.

 

I saw the topic you sent me, it says that it is realted to CME, so i'm not sure if i can apply that here.

But in my logs i see the call been sent digit by digit as mentioned by that guy.

 

How can i test and enable the timeout interdigit?

0 Helpful

Rolando Valenzuela
Level 4
Level 4
In response to datacenter

‎05-30-2014 01:05 PM

Check this: http://www.cisco.com/c/en/us/td/docs/voice_ip_comm/cucme/command/reference/cme_cr/cme_t1ht.html#pgfId-1025536

 

Also, is this for all the external calls? what about internal calls?

Can you share your dial peer configuration (just one or two)

Regards.

 

Rolando Valenzuela.

 

datacenter
Level 1
Level 1
In response to Rolando Valenzuela

‎06-02-2014 04:40 AM

Thanks Rolando.

I'm keen to think that there might be some dial-peer problem...

When i try an external calls, this is the dial-peer that matches (10):

dial-peer voice 10 voip
 description Outboud calls to PSTN
 destination-pattern 0T
 progress_ind setup enable 3
 session protocol sipv2
 session target ipv4:62.48.131.34
 voice-class codec 100
 voice-class sip dtmf-relay force rtp-nte
 voice-class sip early-offer forced
 voice-class sip options-keepalive
 dtmf-relay rtp-nte
 fax-relay ecm disable
 fax rate 9600
!
dial-peer voice 11 voip
 description Outbound calls to PSTN - Emeregncy Services
 preference 1
 destination-pattern 11[278]
 progress_ind setup enable 3
 session protocol sipv2
 session target ipv4:62.48.131.34
 voice-class codec 10
 voice-class h323 1
 voice-class sip dtmf-relay force rtp-nte
 voice-class sip options-keepalive
 dtmf-relay rtp-nte
!

About internal calls... they work fine.

 

About the timeout interdigits... as it is not a CME (it works in a CUCM located on the HQ, using MGCP) i have no telephony-service configured on it where i could put the "timeout itnerdigit" comand.

 

Daniel

0 Helpful

Rolando Valenzuela
Level 4
Level 4
In response to datacenter

‎06-02-2014 08:23 AM

So, the router in questions doesn't is not directly connected with the PSTN?

This is an example of the Dial-peer I have:

 

dial-peer voice 11 pots
 description LONG-DISTANCE CALLS
 translation-profile outgoing 11
 destination-pattern 91[2-9]..[2-9]......$
 port 0/1/0:23
 forward-digits 11

 

I'm sending the traffic port 0/1/0:23, but you are using a SIP trunk to 62.48.131.34, what is that node, another gateway?
Where CUCM sends the calls?
Why the patterns are so general?

 

Thanks

Rolando Valenzuela.

0 Helpful

datacenter
Level 1
Level 1
In response to Rolando Valenzuela

‎06-02-2014 08:35 AM

This IP address is a PSTN gateway within our branch.

Every local (0+...) should go to that GW... in fallback or normal operation.

when it comes to SRST every external call matches on that dialpeer, once they need to pull "0" to start.

Here is a debug msg... from where it starts to where it freezes:

 

RTPTMAI022#term mon
RTPTMAI022#
RTPTMAI022#
RTPTMAI022#
RTPTMAI022#
RTPTMAI022#
RTPTMAI022#
152975: .Jun  2 14:12:42.504: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Calling Number=229477320, Called Number=, Voice-Interface=0x71DED96C,
   Timeout=TRUE, Peer Encap Type=ENCAP_VOICE, Peer Search Type=PEER_TYPE_VOICE,
   Peer Info Type=DIALPEER_INFO_SPEECH
152976: .Jun  2 14:12:42.504: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=22663
152977: .Jun  2 14:12:42.504: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
152978: .Jun  2 14:12:42.788: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Calling Number=ping, Called Number=, Voice-Interface=0x0,
   Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
   Peer Info Type=DIALPEER_INFO_SPEECH
152979: .Jun  2 14:12:42.788: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Result=NO_MATCH(-1) After All Match Rules Attempt
152980: .Jun  2 14:12:42.788: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
152981: .Jun  2 14:12:42.788: //-1/CBFBCB2EA1BE/DPM/dpAssociateIncomingPeerCore:
   Calling Number=ping, Called Number=, Voice-Interface=0x0,
   Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
   Peer Info Type=DIALPEER_INFO_SPEECH
152982: .Jun  2 14:12:42.788: //-1/CBFBCB2EA1BE/DPM/dpAssociateIncomingPeerCore:
   Result=NO_MATCH(-1) After All Match Rules Attempt
152983: .Jun  2 14:12:42.788: //-1/CBFBCB2EA1BE/DPM/dpMatchSafModulePlugin:
   dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
152984: .Jun  2 14:12:42.913: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=0, Peer Info Type=DIALPEER_INFO_SPEECH
152985: .Jun  2 14:12:42.913: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=0
152986: .Jun  2 14:12:42.913: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Result=Partial Matches(1) after DP_MATCH_DEST
152987: .Jun  2 14:12:42.913: //-1/CBD07623A1BA/DPM/dpMatchSafModulePlugin:
   dialstring=0, saf_enabled=1, saf_dndb_lookup=0, dp_result=1
152988: .Jun  2 14:12:42.913: //-1/CBD07623A1BA/DPM/dpMatchPeersMoreArg:
   Result=MORE_DIGITS_NEEDED(1)
152989: .Jun  2 14:12:43.113: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=09, Peer Info Type=DIALPEER_INFO_SPEECH
152990: .Jun  2 14:12:43.113: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=09
152991: .Jun  2 14:12:43.113: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Result=Partial Matches(1) after DP_MATCH_DEST
152992: .Jun  2 14:12:43.113: //-1/CBD07623A1BA/DPM/dpMatchSafModulePlugin:
   dialstring=09, saf_enabled=1, saf_dndb_lookup=0, dp_result=1
152993: .Jun  2 14:12:43.113: //-1/CBD07623A1BA/DPM/dpMatchPeersMoreArg:
   Result=MORE_DIGITS_NEEDED(1)
152994: .Jun  2 14:12:43.313: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=091, Peer Info Type=DIALPEER_INFO_SPEECH
152995: .Jun  2 14:12:43.313: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=091
RTPTMAI022#
152996: .Jun  2 14:12:43.313: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Result=Partial Matches(1) after DP_MATCH_DEST
152997: .Jun  2 14:12:43.313: //-1/CBD07623A1BA/DPM/dpMatchSafModulePlugin:
   dialstring=091, saf_enabled=1, saf_dndb_lookup=0, dp_result=1
152998: .Jun  2 14:12:43.313: //-1/CBD07623A1BA/DPM/dpMatchPeersMoreArg:
   Result=MORE_DIGITS_NEEDED(1)
152999: .Jun  2 14:12:43.513: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=0919, Peer Info Type=DIALPEER_INFO_SPEECH
153000: .Jun  2 14:12:43.513: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=0919
153001: .Jun  2 14:12:43.513: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Result=Partial Matches(1) after DP_MATCH_DEST
153002: .Jun  2 14:12:43.513: //-1/CBD07623A1BA/DPM/dpMatchSafModulePlugin:
   dialstring=0919, saf_enabled=1, saf_dndb_lookup=0, dp_result=1
153003: .Jun  2 14:12:43.513: //-1/CBD07623A1BA/DPM/dpMatchPeersMoreArg:
   Result=MORE_DIGITS_NEEDED(1)
153004: .Jun  2 14:12:43.713: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=09198, Peer Info Type=DIALPEER_INFO_SPEECH
153005: .Jun  2 14:12:43.713: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=09198
153006: .Jun  2 14:12:43.713: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Result=Partial Matches(1) after DP_MATCH_DEST
153007: .Jun  2 14:12:43.713: //-1/CBD07623A1BA/DPM/dpMatchSafModulePlugin:
   dialstring=09198, saf_enabled=1, saf_dndb_lookup=0, dp_result=1
153008: .Jun  2 14:12:43.713: //-1/CBD07623A1BA/DPM/dpMatchPeersMoreArg:
   Result=MORE_DIGITS_NEEDED(1)
153009: .Jun  2 14:12:43.913: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=091981, Peer Info Type=DIALPEER_INFO_SPEECH
153010: .Jun  2 14:12:43.913: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=091981
153011: .Jun  2 14:12:43.913: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Result=Partial Matches(1) after DP_MATCH_DEST
153012: .Jun  2 14:12:43.913: //-1/CBD07623A1BA/DPM/dpMatchSafModulePlugin:
   dialstring=091981, saf_enabled=1, saf_dndb_lookup=0, dp_result=1
153013: .Jun  2 14:12:43.913: //-1/CBD07623A1BA/DPM/dpMatchPeersMoreArg:
   Result=MORE_DIGITS_NEEDED(1)
153014: .Jun  2 14:12:44.113: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=0919813, Peer Info Type=DIALPEER_INFO_SPEECH
153015: .Jun  2 14:12:44.113: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=0919813
153016: .Jun  2 14:12:44.113: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Result=Partial Matches(1) after DP_MATCH_DEST
153017: .Jun  2 14:12:44.113: //-1/CBD07623A1BA/DPM/dpMatchSafModulePlugin:
   dialstring=0919813, saf_enabled=1, saf_dndb_lookup=0, dp_result=1
153018: .Jun  2 14:12:44.113: //-1/CBD07623A1BA/DPM/dpMatchPeersMoreArg:
   Result=MORE_DIGITS_NEEDED(1)
153019: .Jun  2 14:12:44.313: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=09198138, Peer Info Type=DIALPEER_INFO_SPEECH
153020: .Jun  2 14:12:44.313: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=09198138
RTPTMAI022#
153021: .Jun  2 14:12:44.313: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Result=Partial Matches(1) after DP_MATCH_DEST
153022: .Jun  2 14:12:44.313: //-1/CBD07623A1BA/DPM/dpMatchSafModulePlugin:
   dialstring=09198138, saf_enabled=1, saf_dndb_lookup=0, dp_result=1
153023: .Jun  2 14:12:44.313: //-1/CBD07623A1BA/DPM/dpMatchPeersMoreArg:
   Result=MORE_DIGITS_NEEDED(1)
153024: .Jun  2 14:12:44.513: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=091981385, Peer Info Type=DIALPEER_INFO_SPEECH
153025: .Jun  2 14:12:44.513: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=091981385
153026: .Jun  2 14:12:44.513: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Result=Partial Matches(1) after DP_MATCH_DEST
153027: .Jun  2 14:12:44.513: //-1/CBD07623A1BA/DPM/dpMatchSafModulePlugin:
   dialstring=091981385, saf_enabled=1, saf_dndb_lookup=0, dp_result=1
153028: .Jun  2 14:12:44.513: //-1/CBD07623A1BA/DPM/dpMatchPeersMoreArg:
   Result=MORE_DIGITS_NEEDED(1)
153029: .Jun  2 14:12:44.713: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=0919813859, Peer Info Type=DIALPEER_INFO_SPEECH
153030: .Jun  2 14:12:44.713: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=0919813859
153031: .Jun  2 14:12:44.713: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
153032: .Jun  2 14:12:44.713: //-1/CBD07623A1BA/DPM/dpMatchSafModulePlugin:
   dialstring=0919813859, saf_enabled=1, saf_dndb_lookup=0, dp_result=0
153033: .Jun  2 14:12:44.713: //-1/CBD07623A1BA/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=13
153034: .Jun  2 14:12:44.713: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=0919813859, Peer Info Type=DIALPEER_INFO_SPEECH
153035: .Jun  2 14:12:44.713: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=0919813859
153036: .Jun  2 14:12:44.713: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
153037: .Jun  2 14:12:44.713: //-1/CBD07623A1BA/DPM/dpMatchSafModulePlugin:
   dialstring=0919813859, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
153038: .Jun  2 14:12:44.713: //-1/CBD07623A1BA/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=13
     2: Dial-peer Tag=10
     3: Dial-peer Tag=35
     4: Dial-peer Tag=36
153039: .Jun  2 14:12:44.713: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=0919813859, Peer Info Type=DIALPEER_INFO_SPEECH
153040: .Jun  2 14:12:44.713: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=0919813859
153041: .Jun  2 14:12:44.713: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
153042: .Jun  2 14:12:44.713: //-1/CBD07623A1BA/DPM/dpMatchSafModulePlugin:
   dialstring=0919813859, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
153043: .Jun  2 14:12:44.713: //-1/CBD07623A1BA/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=13
     2: Dial-peer Tag=10
     3: Dial-peer Tag=35
     4: Dial-peer Tag=36
153044: .Jun  2 14:12:44.713: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Calling Number=0919813859, Called Number=0919813859, Peer Info Type=DIALPEER_INFO_SPEECH
153045: .Jun  2 14:12:44.713: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=0919813859
153046: .Jun  2 14:12:44.713: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
153047: .Jun  2 14:12:44.713: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=0919813859, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
153048: .Jun  2 14:12:44.713: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=13
     2: Dial-peer Tag=10
     3: Dial-peer Tag=35
     4: Dial-peer Tag=36
153049: .Jun  2 14:12:44.713: //-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
153050: .Jun  2 14:12:44.713: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Result=NO_MATCH(-1) After All Match Rules Attempt
153051: .Jun  2 14:12:44.713: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
153052: .Jun  2 14:12:44.713: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Calling Number=0919813859, Called Number=, Voice-Interface=0x0,
   Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
   Peer Info Type=DIALPEER_INFO_SPEECH
153053: .Jun  2 14:12:44.713: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=13
153054: .Jun  2 14:12:44.713: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
153055: .Jun  2 14:12:44.717: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=0919813859, Peer Info Type=DIALPEER_INFO_SPEECH
153056: .Jun  2 14:12:44.717: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=0919813859
153057: .Jun  2 14:12:44.717: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
153058: .Jun  2 14:12:44.717: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=0919813859, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
153059: .Jun  2 14:12:44.717: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=13
     2: Dial-peer Tag=10
     3: Dial-peer Tag=35
     4: Dial-peer Tag=36
153060: .Jun  2 14:12:44.717: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=0919813859, Peer Info Type=DIALPEER_INFO_SPEECH
153061: .Jun  2 14:12:44.717: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=0919813859
153062: .Jun  2 14:12:44.717: //-1/CBD07623A1BA/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
153063: .Jun  2 14:12:44.717: //-1/CBD07623A1BA/DPM/dpMatchSafModulePlugin:
   dialstring=0919813859, saf_enabled=1, saf_dndb_lookup=1, dp_result=0
153064: .Jun  2 14:12:44.717: //-1/CBD07623A1BA/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=13
     2: Dial-peer Tag=10
     3: Dial-peer Tag=35
     4: Dial-peer Tag=36
153065: .Jun  2 14:12:44.721: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=0919813859, Peer Info Type=DIALPEER_INFO_SPEECH
153066: .Jun  2 14:12:44.721: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=0919813859
153067: .Jun  2 14:12:44.721: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
153068: .Jun  2 14:12:44.721: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=0919813859, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
153069: .Jun  2 14:12:44.721: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=13
     2: Dial-peer Tag=10
     3: Dial-peer Tag=35
     4: Dial-peer Tag=36
153070: .Jun  2 14:12:44.721: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=0919813859, Peer Info Type=DIALPEER_INFO_SPEECH
153071: .Jun  2 14:12:44.721: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=0919813859
153072: .Jun  2 14:12:44.721: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
RTPTMAI022#
153073: .Jun  2 14:12:44.721: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=0919813859, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
153074: .Jun  2 14:12:44.721: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=13
     2: Dial-peer Tag=10
     3: Dial-peer Tag=35
     4: Dial-peer Tag=36

 

I've created dialpeer 13 equal to 10, but to a specific number, only to test.

After the log above, it freezes for 1:30 (approx) then it continues:

 

RTPTMAI022#
153214: .Jun  2 14:14:53.434: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Calling Number=0919813859, Called Number=0919813859, Peer Info Type=DIALPEER_INFO_SPEECH
153215: .Jun  2 14:14:53.434: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=0919813859
153216: .Jun  2 14:14:53.434: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
153217: .Jun  2 14:14:53.434: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=0919813859, saf_enabled=1, saf_dndb_lookup=1, dp_result=0
153218: .Jun  2 14:14:53.434: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=13
     2: Dial-peer Tag=10
     3: Dial-peer Tag=36
     4: Dial-peer Tag=35
153219: .Jun  2 14:14:53.434: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Calling Number=229477320, Called Number=, Voice-Interface=0x0,
   Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
   Peer Info Type=DIALPEER_INFO_SPEECH
153220: .Jun  2 14:14:53.434: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=35
153221: .Jun  2 14:14:53.434: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
153222: .Jun  2 14:14:53.438: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Calling Number=229477320, Called Number=, Voice-Interface=0x0,
   Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
   Peer Info Type=DIALPEER_INFO_SPEECH
153223: .Jun  2 14:14:53.438: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=35
153224: .Jun  2 14:14:53.438: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
153225: .Jun  2 14:14:53.438: //-1/4C539A000000/DPM/dpAssociateIncomingPeerCore:
   Calling Number=229477320, Called Number=0919813859, Voice-Interface=0x0,
   Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
   Peer Info Type=DIALPEER_INFO_SPEECH
153226: .Jun  2 14:14:53.438: //-1/4C539A000000/DPM/dpAssociateIncomingPeerCore:
   Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=35
153227: .Jun  2 14:14:53.438: //-1/4C539A000000/DPM/dpMatchSafModulePlugin:
   dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
153228: .Jun  2 14:14:53.438: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=0919813859, Peer Info Type=DIALPEER_INFO_SPEECH
153229: .Jun  2 14:14:53.438: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=0919813859
153230: .Jun  2 14:14:53.442: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
153231: .Jun  2 14:14:53.442: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=0919813859, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
153232: .Jun  2 14:14:53.442: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=13
     2: Dial-peer Tag=10
     3: Dial-peer Tag=35
     4: Dial-peer Tag=36
153233: .Jun  2 14:14:53.442: //-1/4C539A000000/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=0919813859, Peer Info Type=DIALPEER_INFO_SPEECH
153234: .Jun  2 14:14:53.442: //-1/4C539A000000/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=0919813859
153235: .Jun  2 14:14:53.442: //-1/4C539A000000/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
153236: .Jun  2 14:14:53.442: //-1/4C539A000000/DPM/dpMatchSafModulePlugin:
   dialstring=0919813859, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
153237: .Jun  2 14:14:53.442: //-1/4C539A000000/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=13
     2: Dial-peer Tag=10
     3: Dial-peer Tag=35
     4: Dial-peer Tag=36
153238: .Jun  2 14:14:53.442: //-1/4C539A000000/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=0919813859, Peer Info Type=DIALPEER_INFO_SPEECH
153239: .Jun  2 14:14:53.442: //-1/4C539A000000/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=0919813859
153240: .Jun  2 14:14:53.442: //-1/4C539A000000/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
153241: .Jun  2 14:14:53.442: //-1/4C539A000000/DPM/dpMatchSafModulePlugin:
   dialstring=0919813859, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
153242: .Jun  2 14:14:53.442: //-1/4C539A000000/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=13
     2: Dial-peer Tag=10
     3: Dial-peer Tag=35
     4: Dial-peer Tag=36
153243: .Jun  2 14:14:53.442: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Calling Number=0919813859, Called Number=0919813859, Peer Info Type=DIALPEER_INFO_SPEECH
153244: .Jun  2 14:14:53.442: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=0919813859
153245: .Jun  2 14:14:53.442: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
153246: .Jun  2 14:14:53.442: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=0919813859, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
153247: .Jun  2 14:14:53.442: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=13
     2: Dial-peer Tag=10
     3: Dial-peer Tag=35
     4: Dial-peer Tag=36
153248: .Jun  2 14:14:53.442: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Calling Number=0919813859, Called Number=, Voice-Interface=0x0,
   Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
   Peer Info Type=DIALPEER_INFO_SPEECH
153249: .Jun  2 14:14:53.442: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=13
153250: .Jun  2 14:14:53.442: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
153251: .Jun  2 14:14:53.442: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Calling Number=0919813859, Called Number=, Voice-Interface=0x0,
   Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
   Peer Info Type=DIALPEER_INFO_SPEECH
153252: .Jun  2 14:14:53.442: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=13
153253: .Jun  2 14:14:53.442: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
153254: .Jun  2 14:14:53.446: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=0919813859, Peer Info Type=DIALPEER_INFO_SPEECH
153255: .Jun  2 14:14:53.446: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=0919813859
153256: .Jun  2 14:14:53.446: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
153257: .Jun  2 14:14:53.446: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=0919813859, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
153258: .Jun  2 14:14:53.446: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=13
     2: Dial-peer Tag=10
     3: Dial-peer Tag=35
     4: Dial-peer Tag=36
153259: .Jun  2 14:14:53.446: //-1/4C539A000000/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=0919813859, Peer Info Type=DIALPEER_INFO_SPEECH
153260: .Jun  2 14:14:53.446: //-1/4C539A000000/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=0919813859
153261: .Jun  2 14:14:53.446: //-1/4C539A000000/DPM/dpMatchPeersCore:
   
RTPTMAI022#Result=Success(0) after DP_MATCH_DEST
153262: .Jun  2 14:14:53.446: //-1/4C539A000000/DPM/dpMatchSafModulePlugin:
   dialstring=0919813859, saf_enabled=1, saf_dndb_lookup=1, dp_result=0
153263: .Jun  2 14:14:53.446: //-1/4C539A000000/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=13
     2: Dial-peer Tag=10
     3: Dial-peer Tag=35
     4: Dial-peer Tag=36
RTPTMAI022#
153264: .Jun  2 14:14:54.622: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=229477320, Peer Info Type=DIALPEER_INFO_SPEECH
153265: .Jun  2 14:14:54.622: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=229477320
153266: .Jun  2 14:14:54.622: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
153267: .Jun  2 14:14:54.622: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
153268: .Jun  2 14:14:54.622: //-1/xxxxxxxxxxxx/DPM/dpMatchPeers:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=22663
     2: Dial-peer Tag=35
     3: Dial-peer Tag=36
153269: .Jun  2 14:14:54.622: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=0919813859, Peer Info Type=DIALPEER_INFO_SPEECH
153270: .Jun  2 14:14:54.622: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=0919813859
153271: .Jun  2 14:14:54.622: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
153272: .Jun  2 14:14:54.622: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
153273: .Jun  2 14:14:54.622: //-1/xxxxxxxxxxxx/DPM/dpMatchPeers:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=13
     2: Dial-peer Tag=10
     3: Dial-peer Tag=35
     4: Dial-peer Tag=36
153274: .Jun  2 14:14:54.622: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=0919813859, Peer Info Type=DIALPEER_INFO_SPEECH
153275: .Jun  2 14:14:54.622: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=0919813859
153276: .Jun  2 14:14:54.626: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
153277: .Jun  2 14:14:54.626: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
   dialstring=0919813859, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
153278: .Jun  2 14:14:54.626: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=13
     2: Dial-peer Tag=10
     3: Dial-peer Tag=35
     4: Dial-peer Tag=36

 

 


 

0 Helpful

Rolando Valenzuela
Level 4
Level 4
In response to datacenter

‎06-02-2014 09:10 AM

Weird...

The call manager send the calls to this gateway first or it send it to 62.48.131.34 directly?

Have you check the DSP resources?

Do you have the same output when the call manger sends the call?

Can you try with the command "show dialplan number 0919813859" to double check if there is a match.

0 Helpful

datacenter
Level 1
Level 1
In response to Rolando Valenzuela

‎06-02-2014 10:32 AM

When an user call a local number, it goes directly to that GW... it doesn't reach the CUCM.

Any other call (doesn't starting with '0') goes to the CUCM.

 

             AD                                    PRE PASS                OUT
TAG    TYPE  MIN  OPER PREFIX    DEST-PATTERN      FER THRU SESS-TARGET    STAT PORT    KEEPALIVE
35     voip  up   up             .T                 1  syst ipv4:10.1.9.35
36     voip  up   up             .T                 2  syst ipv4:10.1.9.36

10     voip  up   up             0T                 0  syst ipv4:62.48.131.34             active
12     voip  up   up             9[347]..           0  syst ipv4:62.48.131.34             active

13     voip  up   up             0919813859         0  syst ipv4:62.48.131.34

 

And yes, there's a pattern that matches...

This last one (13) i've created specially to test it.

What sounds really weird is that the router uses the same dialpeer... in or out of fallback mode... so why only when in srst it adds this delay of almost 2 minutes?

i'm getting crazy, i've changed some fallback configs and did configure a new dialpeer... but it stills the same.

If the telephone goes back to normal mode... it completes the call correctly.

0 Helpful

Rolando Valenzuela
Level 4
Level 4
In response to datacenter

‎06-02-2014 11:24 AM

What I dont complete understand is this:

How the phone bypass CUCM is not using SRST??
If the phone is registered with CUCM, the pattern information should be handled by the call manager.

 

If CUCM sends the calls directly to the GW, that means the router (RTPTMAI022) never received traffic, right? so we cannot ensure everything works while you are not in SRST.

 

Did you check your DSP resources? maybe the router is not capable of handle it.

 

I'm running out ideas, have you tried with TAC?

0 Helpful
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:

Customers Also Viewed These Support Documents