cancel
Showing results for 
Search instead for 
Did you mean: 
cancel
1073
Views
5
Helpful
8
Replies

Inter-digit timout for all outgoing calls.

kely
Level 1
Level 1

I have a call delay with all calls that go out to the PSTN on one GW. I think the inter-digit timeout is catching all calls, but I'm not sure why.

I have setup a new partition and CSS that has one route pattern for 9.222040900#. As soon as I dial it, the route pattern translates the dialed number to: 9.222040900. I see on the phone it is removing the # right away so I believe the issue lies on the GW.

I have tested this with a SIP and SCCP phone.

 

These are my dial patterns:

 

dial-peer voice 2000 voip
 description **Incoming Calls to Gateway from CUCM**
 incoming called-number 9T
 voice-class codec 1 
 voice-class h323 1
 dtmf-relay h245-signal h245-alphanumeric
 no vad
!
dial-peer voice 10 pots
 description **Incoming from PSTN**
 translation-profile incoming did
 incoming called-number .
 direct-inward-dial
!
dial-peer voice 20 pots
 description **Chile Emergency Services Calling to E1**
 destination-pattern 913.$
 port 0/0/0:15
 prefix 13
!
dial-peer voice 22 pots
 description **Chile Emergency Services Calling to E1**
 destination-pattern 914.$
 port 0/0/0:15
 prefix 14
!
dial-peer voice 24 pots
 description **cotia cell phone operator call**
 destination-pattern 910315$
 prefix 10315
!
dial-peer voice 30 pots
 description **Chile Cell phone Calling to E1**
 destination-pattern 99[4-9].......$
 port 0/0/0:15
 prefix 9
!
dial-peer voice 40 pots
 description **Chile LandLine area code + 7 dig Calling to E1-Santiago**
 shutdown
 destination-pattern 922.......$
 port 0/0/0:15
 prefix 22
!
dial-peer voice 42 pots
 description **Chile LandLine area code + 7 dig Calling to E1-Chile**
 destination-pattern 9[^0]........$
 port 0/0/0:15
!
dial-peer voice 60 pots
 description **Chile International Calling to E1**
 destination-pattern 900T
 port 0/0/0:15
 prefix 00
!
dial-peer voice 1000 voip
 description **Incoming from PSTN to CUCM Subscriber**
 destination-pattern 85....
 session target ipv4:CUCMSubIP
 voice-class codec 1 
 voice-class h323 1
 dtmf-relay h245-signal h245-alphanumeric
 no vad
!        
dial-peer voice 1001 voip
 description **Incoming from Voice Gateway to CuCM Publisher**
 huntstop
 preference 1
 destination-pattern 85....
 session target ipv4:CUCMPubIP
 voice-class codec 1 
 voice-class h323 1
 dtmf-relay h245-signal h245-alphanumeric
 no vad
!

 

1 Accepted Solution

Accepted Solutions

R0g22
Cisco Employee
Cisco Employee
It's not an issue with the GW. Your Telco is delaying the call progress. Look at the timestamp below -

*Feb 22 09:22:27.579 clt: H225.0 INCOMING PDU ::=

value H323_UserInformation ::=
{
h323-uu-pdu
{
h323-message-body setup :
{
protocolIdentifier { 0 0 8 2250 0 5 }
sourceAddress
{
h323-ID : {"test..."}

It takes less than 20 ms to send an ISDN SETUP to Telco -

*Feb 22 09:22:27.595 clt: ISDN Se0/0/0:15 Q931: TX -> SETUP pd = 8 callref = 0x00CB
Bearer Capability i = 0x8090A3
Standard = CCITT
Transfer Capability = Speech
Transfer Mode = Circuit
Transfer Rate = 64 kbit/s
Channel ID i = 0xA9838F
Exclusive, Channel 15
Calling Party Number i = 0x0081, '859349'
Plan:Unknown, Type:Unknown
Called Party Number i = 0x80, '222040900'
Plan:Unknown, Type:Unknown

Look at the time difference below from ACK to Proceeding -

*Feb 22 09:22:27.779 clt: ISDN Se0/0/0:15 Q931: RX <- SETUP_ACK pd = 8 callref = 0x80CB
Channel ID i = 0xA9838F
Exclusive, Channel 15
fe85vg#
*Feb 22 09:22:37.823 clt: ISDN Se0/0/0:15 Q931: RX <- CALL_PROC pd = 8 callref = 0x80CB
Channel ID i = 0xA9838F
Exclusive, Channel 15

A couple of second delay in receiving Progress -

*Feb 22 09:22:39.023 clt: ISDN Se0/0/0:15 Q931: RX <- PROGRESS pd = 8 callref = 0x80CB
Progress Ind i = 0x8284 - Call has returned to the ISDN

Finally ALERTING with PI=8 and GW does a backward cut through to allow media/ring back in this case to flow -

*Feb 22 09:22:39.027 clt: ISDN Se0/0/0:15 Q931: RX <- ALERTING pd = 8 callref = 0x80CB
Progress Ind i = 0x8488 - In-band info or appropriate now available
Progress Ind i = 0x8482 - Destination address is non-ISDN

View solution in original post

8 Replies 8

R0g22
Cisco Employee
Cisco Employee
The delay is in connecting the call or for audio to flow through ?

The delay is in the dialing. I enter the number on the phone and press call.

The screen immediately updates the called number.

Then, there is a 15 second pause.

Then I start to hear ringing.

R0g22
Cisco Employee
Cisco Employee
That's the call manager introducing the delay due to overlapping dial-plan. Looking at your dial-peers I am assuming this is a H.323 GW. Enable "debug h225 asn1" and terminal monitor. Make a test call and see when does the call hit the GW.

The call hits the GW almost immediately. Processes to this point: *Feb 21 14:16:51.872 clt: H225 NONSTD OUTGOING PDU ::=

Pauses ~15 seconds. Then continues with the call.
*Feb 21 14:16:26.720 clt: H225 NONSTD OUTGOING PDU ::=

value H323_UU_NonStdInfo ::=
{
version 1
protoParam qsigNonStdInfo :
{
iei 30
rawMesg '1E0282841C269E8100036774640000001B435047...'H
}
}



*Feb 21 14:16:26.724 clt: H225 NONSTD OUTGOING ENCODE BUFFER::= 60010100011E2C1E0282841C269E8100036774640000001B4350472C0D0A50524E2C6973646E2A2C2C4E4554352A2C0D0A0D0A
*Feb 21 14:16:26.724 clt:
*Feb 21 14:16:26.724 clt: H225.0 OUTGOING PDU ::=

value H323_UserInformation ::=
{
h323-uu-pdu
{
h323-message-body progress :
{
protocolIdentifier { 0 0 8 2250 0 4 }
destinationInfo
{
vendor
{
vendor
{
t35CountryCode 181
t35Extension 0
manufacturerCode 18
}
}
gateway
{
protocol
{
voice :
{
supportedPrefixes
{
}
}
}
}
mc FALSE
undefinedNode FALSE
}
h245Address ipAddress :
{
ip '0A55F00A'H
port 32496
}
callIdentifier
{
guid '003AF3102DCBD1A86F0073020A01F066'H
}
multipleCalls FALSE
maintainConnection FALSE
}
h245Tunneling FALSE
nonStandardControl
{

{
nonStandardIdentifier h221NonStandard :
{
t35CountryCode 181
t35Extension 0
manufacturerCode 18
}
data '60010100011E2C1E0282841C269E810003677464...'H
}
}
tunnelledSignallingMessage
{
tunnelledProtocolID
{
id tunnelledProtocolAlternateID :
{
protocolType "gtd"
}
}
messageContent
{
'4350472C0D0A50524E2C6973646E2A2C2C4E4554...'H
}
tunnellingRequired NULL
}
}
}



*Feb 21 14:16:26.728 clt: H225.0 OUTGOING ENCODE BUFFER::= 280032C0060008914A00042800B500001240013C050100000A55F00A7EF000003AF3102DCBD1A86F0073020A01F06605800100010010A801003A0140B50000123360010100011E2C1E0282841C269E8100036774640000001B4350472C0D0A50524E2C6973646E2A2C2C4E4554352A2C0D0A0D0A224204677464011B4350472C0D0A50524E2C6973646E2A2C2C4E4554352A2C0D0A0D0A
*Feb 21 14:16:26.728 clt:
*Feb 21 14:16:26.728 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=9222040900, Peer Info Type=DIALPEER_INFO_SPEECH
*Feb 21 14:16:26.728 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=9222040900
*Feb 21 14:16:26.728 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=9222040900, Expanded String=9222040900, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
*Feb 21 14:16:26.728 clt: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=42 Is Matched
*Feb 21 14:16:26.728 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
*Feb 21 14:16:26.728 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
*Feb 21 14:16:26.728 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeers:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=42
*Feb 21 14:16:26.728 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=859349, Peer Info Type=DIALPEER_INFO_SPEECH
*Feb 21 14:16:26.728 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=859349
*Feb 21 14:16:26.728 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=859349, Expanded String=859349, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
*Feb 21 14:16:26.728 clt: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=1000 Is Matched
*Feb 21 14:16:26.728 clt: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=1001 Is Matched
*Feb 21 14:16:26.728 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
*Feb 21 14:16:26.728 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
*Feb 21 14:16:26.728 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeers:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=1000
2: Dial-peer Tag=1001
*Feb 21 14:16:26.728 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=222040900, Peer Info Type=DIALPEER_INFO_SPEECH
*Feb 21 14:16:26.728 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=222040900
*Feb 21 14:16:26.728 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=222040900, Expanded String=222040900, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
*Feb 21 14:16:26.728 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Result=-1
*Feb 21 14:16:26.728 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
No Outgoing Dial-peer Is Matched; Result=NO_MATCH(-1)
*Feb 21 14:16:26.728 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
*Feb 21 14:16:26.728 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeers:
Result=NO_MATCH(-1)
*Feb 21 14:16:26.732 clt: H225 NONSTD OUTGOING PDU ::=

value H323_UU_NonStdInfo ::=
{
version 1
protoParam qsigNonStdInfo :
{
iei 30
rawMesg '1E0284881E0284821C269E810003677464000000...'H
}
}



*Feb 21 14:16:26.732 clt: H225 NONSTD OUTGOING ENCODE BUFFER::= 60010100011E301E0284881E0284821C269E8100036774640000001B41434D2C0D0A50524E2C6973646E2A2C2C4E4554352A2C0D0A0D0A
*Feb 21 14:16:26.732 clt:
*Feb 21 14:16:26.732 clt: H225.0 OUTGOING PDU ::=

value H323_UserInformation ::=
{
h323-uu-pdu
{
h323-message-body alerting :
{
protocolIdentifier { 0 0 8 2250 0 4 }
destinationInfo
{
vendor
{
vendor
{
t35CountryCode 181
t35Extension 0
manufacturerCode 18
}
}
gateway
{
protocol
{
voice :
{
supportedPrefixes
{
}
}
}
}
mc FALSE
undefinedNode FALSE
}
h245Address ipAddress :
{
ip '0A55F00A'H
port 32496
}
callIdentifier
{
guid '003AF3102DCBD1A86F0073020A01F066'H
}
multipleCalls FALSE
maintainConnection FALSE
}
h245Tunneling FALSE
nonStandardControl
{

{
nonStandardIdentifier h221NonStandard :
{
t35CountryCode 181
t35Extension 0
manufacturerCode 18
}
data '60010100011E301E0284881E0284821C269E8100...'H
}
}
tunnelledSignallingMessage
{
tunnelledProtocolID
{
id tunnelledProtocolAlternateID :
{
protocolType "gtd"
}
}
messageContent
{
'41434D2C0D0A50524E2C6973646E2A2C2C4E4554...'H
}
tunnellingRequired NULL
}
}
}



*Feb 21 14:16:26.736 clt: H225.0 OUTGOING ENCODE BUFFER::= 23C0060008914A00042800B500001240013C050100000A55F00A7EF01B0C001100003AF3102DCBD1A86F0073020A01F0660100010010A801003E0140B50000123760010100011E301E0284881E0284821C269E8100036774640000001B41434D2C0D0A50524E2C6973646E2A2C2C4E4554352A2C0D0A0D0A224204677464011B41434D2C0D0A50524E2C6973646E2A2C2C4E4554352A2C0D0A0D0A
*Feb 21 14:16:26.736 clt:
*Feb 21 14:16:26.736 clt: H225.0 OUTGOING PDU ::=

fe85vg#
value H323_UserInformation ::=
{
h323-uu-pdu
{
h323-message-body notify :
{
protocolIdentifier { 0 0 8 2250 0 4 }
callIdentifier
{
guid '003AF3102DCBD1A86F0073020A01F066'H
}
}
h245Tunneling FALSE
}
}



*Feb 21 14:16:26.736 clt: H225.0 OUTGOING ENCODE BUFFER::= 28501900060008914A000400003AF3102DCBD1A86F0073020A01F06610800100
*Feb 21 14:16:26.736 clt:
fe85vg#
*Feb 21 14:16:28.316 clt: H225.0 INCOMING ENCODE BUFFER::= 2580060008914A000511001100003AF3102DCBD1A86F0073020A01F06610800100
*Feb 21 14:16:28.316 clt:
*Feb 21 14:16:28.316 clt: H225.0 INCOMING PDU ::=

value H323_UserInformation ::=
{
h323-uu-pdu
{
h323-message-body releaseComplete :
{
protocolIdentifier { 0 0 8 2250 0 5 }
callIdentifier
{
guid '003AF3102DCBD1A86F0073020A01F066'H
}
}
h245Tunneling FALSE
}
}



*Feb 21 14:16:28.328 clt: H225.0 OUTGOING PDU ::=

fe85vg#
value H323_UserInformation ::=
{
h323-uu-pdu
{
h323-message-body releaseComplete :
{
protocolIdentifier { 0 0 8 2250 0 4 }
callIdentifier
{
guid '003AF3102DCBD1A86F0073020A01F066'H
}
}
h245Tunneling FALSE
}
}



*Feb 21 14:16:28.328 clt: H225.0 OUTGOING ENCODE BUFFER::= 2580060008914A000411001100003AF3102DCBD1A86F0073020A01F06610800100
*Feb 21 14:16:28.328 clt:
fe85vg#
*Feb 21 14:16:40.320 clt: H225.0 INCOMING ENCODE BUFFER::= 20B0060008914A000501401F0074006500730074000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000022C0B50000120F436973636F43616C6C4D616E61676572003100010480C555373C330080ECD91F46CBD1A8700074020A01F06600D50D80000700AC13570B06B8110080ECD91F46CBD1A8700074020A01F066010001000100010010A00100140140B50000120D82040020040001030003000101
*Feb 21 14:16:40.320 clt:
*Feb 21 14:16:40.320 clt: H225.0 INCOMING PDU ::=

value H323_UserInformation ::=
{
h323-uu-pdu
{
h323-message-body setup :
{
protocolIdentifier { 0 0 8 2250 0 5 }
sourceAddress
{
h323-ID : {"test..."}
}
sourceInfo
{
vendor
{
vendor
{
t35CountryCode 181
t35Extension 0
manufacturerCode 18
}
productId '436973636F43616C6C4D616E61676572'H
versionId '31'H
}
terminal
{
}
mc FALSE
undefinedNode FALSE
}
destinationAddress
{
dialedDigits : "9222040900"
}
activeMC FALSE
conferenceID '80ECD91F46CBD1A8700074020A01F066'H
conferenceGoal create : NULL
callType pointToPoint : NULL
sourceCallSignalAddress ipAddress :
{
ip 'AC13570B'H
port 1720
}
callIdentifier
{
guid '80ECD91F46CBD1A8700074020A01F066'H
}
mediaWaitForConnect FALSE
canOverlapSend FALSE
multipleCalls FALSE
maintainConnection FALSE
}
h245Tunneling FALSE
nonStandardControl
{

{
nonStandardIdentifier h221NonStandard :
{
t35CountryCode 181
t35Extension 0
manufacturerCode 18
}
data '82040020040001030003000101'H
}
}
}
}



*Feb 21 14:16:40.324 clt: H225 NONSTD INCOMING ENCODE BUFFER::= 82040020040001030003000101
*Feb 21 14:16:40.324 clt:
*Feb 21 14:16:40.324 clt: H225 NONSTD INCOMING PDU ::=

value H323_UU_NonStdInfo ::=
{
callMgrParam
{
interclusterVersion 3
enterpriseID {}
}
}



*Feb 21 14:16:40.324 clt: //-1/80ECD91F7000/DPM/dpAssociateIncomingPeerCore:
Calling Number=859349, Called Number=9222040900, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
*Feb 21 14:16:40.324 clt: //-1/80ECD91F7000/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_INCOMING_DNIS; Called Number=9222040900
*Feb 21 14:16:40.324 clt: //-1/80ECD91F7000/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
*Feb 21 14:16:40.324 clt: //-1/80ECD91F7000/DPM/dpMatchCore:
Dial String=9222040900, Expanded String=9222040900, Calling Number=
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Feb 21 14:16:40.324 clt: //-1/80ECD91F7000/DPM/MatchNextPeer:
Result=Success(0); Incoming Dial-peer=2000 Is Matched
*Feb 21 14:16:40.324 clt: //-1/80ECD91F7000/DPM/MatchNextPeer:
Result=Success(0); Incoming Dial-peer=10 Is Matched
*Feb 21 14:16:40.324 clt: //-1/80ECD91F7000/DPM/dpMatchPeertype:exit@7260
*Feb 21 14:16:40.324 clt: //-1/80ECD91F7000/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=2000
*Feb 21 14:16:40.324 clt: //-1/80ECD91F7000/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
*Feb 21 14:16:40.324 clt: //-1/80ECD91F7000/DPM/dpAssociateIncomingPeerSPI:exit@7842
*Feb 21 14:16:40.324 clt: //-1/80ECD91F7000/DPM/dpAssociateIncomingPeerCore:
Calling Number=859349, Called Number=9222040900, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
*Feb 21 14:16:40.324 clt: //-1/80ECD91F7000/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_INCOMING_DNIS; Called Number=9222040900
*Feb 21 14:16:40.324 clt: //-1/80ECD91F7000/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
*Feb 21 14:16:40.324 clt: //-1/80ECD91F7000/DPM/dpMatchCore:
Dial String=9222040900, Expanded String=9222040900, Calling Number=
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Feb 21 14:16:40.324 clt: //-1/80ECD91F7000/DPM/MatchNextPeer:
Result=Success(0); Incoming Dial-peer=2000 Is Matched
*Feb 21 14:16:40.324 clt: //-1/80ECD91F7000/DPM/MatchNextPeer:
Result=Success(0); Incoming Dial-peer=10 Is Matched
*Feb 21 14:16:40.324 clt: //-1/80ECD91F7000/DPM/dpMatchPeertype:exit@7260
*Feb 21 14:16:40.324 clt: //-1/80ECD91F7000/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=2000
*Feb 21 14:16:40.324 clt: //-1/80ECD91F7000/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
*Feb 21 14:16:40.324 clt: //-1/80ECD91F7000/DPM/dpAssociateIncomingPeerSPI:exit@7842
*Feb 21 14:16:40.324 clt: //-1/80ECD91F7000/DPM/dpMatchPeersCore:
Calling Number=, Called Number=9222040900, Peer Info Type=DIALPEER_INFO_SPEECH
*Feb 21 14:16:40.324 clt: //-1/80ECD91F7000/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=9222040900
*Feb 21 14:16:40.324 clt: //-1/80ECD91F7000/DPM/dpMatchCore:
Dial String=9222040900, Expanded String=9222040900, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
*Feb 21 14:16:40.324 clt: //-1/80ECD91F7000/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=42 Is Matched
*Feb 21 14:16:40.324 clt: //-1/80ECD91F7000/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
*Feb 21 14:16:40.324 clt: //-1/80ECD91F7000/DPM/dpMatchSafModulePlugin:
dialstring=9222040900, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
*Feb 21 14:16:40.324 clt: //-1/80ECD91F7000/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=42
*Feb 21 14:16:40.324 clt: //-1/80ECD91F7000/DPM/dpMatchPeersCore:
Calling Number=, Called Number=9222040900, Peer Info Type=DIALPEER_INFO_SPEECH
*Feb 21 14:16:40.324 clt: //-1/80ECD91F7000/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=9222040900
*Feb 21 14:16:40.324 clt: //-1/80ECD91F7000/DPM/dpMatchCore:
Dial String=9222040900, Expanded String=9222040900, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
*Feb 21 14:16:40.324 clt: //-1/80ECD91F7000/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=42 Is Matched
*Feb 21 14:16:40.324 clt: //-1/80ECD91F7000/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
*Feb 21 14:16:40.324 clt: //-1/80ECD91F7000/DPM/dpMatchSafModulePlugin:
dialstring=9222040900, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
*Feb 21 14:16:40.324 clt: //-1/80ECD91F7000/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=42
*Feb 21 14:16:40.324 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=9222040900, Called Number=9222040900, Peer Info Type=DIALPEER_INFO_SPEECH
*Feb 21 14:16:40.324 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=9222040900
*Feb 21 14:16:40.324 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=9222040900, Expanded String=9222040900, Calling Number=9222040900T
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
*Feb 21 14:16:40.324 clt: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=42 Is Matched
*Feb 21 14:16:40.324 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
*Feb 21 14:16:40.324 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=9222040900, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
*Feb 21 14:16:40.328 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=42
*Feb 21 14:16:40.328 clt: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=9222040900, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
*Feb 21 14:16:40.328 clt: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ANSWER; Calling Number=9222040900
*Feb 21 14:16:40.328 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
*Feb 21 14:16:40.328 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=9222040900T
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Feb 21 14:16:40.328 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Result=-1
*Feb 21 14:16:40.328 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@7260
*Feb 21 14:16:40.328 clt: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ORIGINATE; Calling Number=9222040900
*Feb 21 14:16:40.328 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
*Feb 21 14:16:40.328 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=9222040900T
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Feb 21 14:16:40.328 clt: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Incoming Dial-peer=42 Is Matched
*Feb 21 14:16:40.328 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@7260
*Feb 21 14:16:40.328 clt: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
*Feb 21 14:16:40.328 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
*Feb 21 14:16:40.328 clt: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:exit@7891
*Feb 21 14:16:40.328 clt: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=9222040900, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
*Feb 21 14:16:40.328 clt: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ANSWER; Calling Number=9222040900
*Feb 21 14:16:40.328 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
*Feb 21 14:16:40.328 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=9222040900T
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Feb 21 14:16:40.328 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Result=-1
*Feb 21 14:16:40.328 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@7260
*Feb 21 14:16:40.328 clt: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ORIGINATE; Calling Number=9222040900
*Feb 21 14:16:40.328 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
*Feb 21 14:16:40.328 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=9222040900T
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
*Feb 21 14:16:40.328 clt: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Incoming Dial-peer=42 Is Matched
*Feb 21 14:16:40.328 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@7260
*Feb 21 14:16:40.328 clt: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
*Feb 21 14:16:40.328 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
*Feb 21 14:16:40.328 clt: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:exit@7891
*Feb 21 14:16:40.328 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=9222040900, Peer Info Type=DIALPEER_INFO_SPEECH
*Feb 21 14:16:40.328 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=9222040900
*Feb 21 14:16:40.328 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=9222040900, Expanded String=9222040900, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
*Feb 21 14:16:40.328 clt: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=42 Is Matched
*Feb 21 14:16:40.328 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
*Feb 21 14:16:40.328 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=9222040900, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
*Feb 21 14:16:40.328 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=42
*Feb 21 14:16:40.328 clt: //-1/80ECD91F7000/DPM/dpMatchPeersCore:
Calling Number=, Called Number=9222040900, Peer Info Type=DIALPEER_INFO_SPEECH
*Feb 21 14:16:40.328 clt: //-1/80ECD91F7000/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=9222040900
*Feb 21 14:16:40.328 clt: //-1/80ECD91F7000/DPM/dpMatchCore:
Dial String=9222040900, Expanded String=9222040900, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
*Feb 21 14:16:40.328 clt: //-1/80ECD91F7000/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=42 Is Matched
*Feb 21 14:16:40.328 clt: //-1/80ECD91F7000/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
*Feb 21 14:16:40.328 clt: //-1/80ECD91F7000/DPM/dpMatchSafModulePlugin:
dialstring=9222040900, saf_enabled=1, saf_dndb_lookup=1, dp_result=0
*Feb 21 14:16:40.328 clt: //-1/80ECD91F7000/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=42
*Feb 21 14:16:40.336 clt: H225.0 OUTGOING PDU ::=

value H323_UserInformation ::=
{
h323-uu-pdu
{
h323-message-body callProceeding :
{
protocolIdentifier { 0 0 8 2250 0 4 }
destinationInfo
{
vendor
{
vendor
{
t35CountryCode 181
t35Extension 0
manufacturerCode 18
}
}
gateway
{
protocol
{
voice :
{
supportedPrefixes
{
}
}
}
}
mc FALSE
undefinedNode FALSE
}
h245Address ipAddress :
{
ip '0A55F00A'H
port 41859
}
callIdentifier
{
guid '80ECD91F46CBD1A8700074020A01F066'H
}
multipleCalls FALSE
maintainConnection FALSE
}
h245Tunneling FALSE
}
}



*Feb 21 14:16:40.336 clt: H225.0 OUTGOING ENCODE BUFFER::=
fe85vg#21C0060008914A00042800B500001240013C050100000A55F00AA383110C110080ECD91F46CBD1A8700074020A01F0660100010010800100
*Feb 21 14:16:40.336 clt:
fe85vg#
*Feb 21 14:16:51.872 clt: H225 NONSTD OUTGOING PDU ::=


::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
PAUSE HAPPENS HERE
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::

value H323_UU_NonStdInfo ::=
{
version 1
protoParam qsigNonStdInfo :
{
iei 30
rawMesg '1E0282841C269E8100036774640000001B435047...'H
}
}



*Feb 21 14:16:51.872 clt: H225 NONSTD OUTGOING ENCODE BUFFER::= 60010100011E2C1E0282841C269E8100036774640000001B4350472C0D0A50524E2C6973646E2A2C2C4E4554352A2C0D0A0D0A
*Feb 21 14:16:51.872 clt:
*Feb 21 14:16:51.872 clt: H225.0 OUTGOING PDU ::=

value H323_UserInformation ::=
{
h323-uu-pdu
{
h323-message-body progress :
{
protocolIdentifier { 0 0 8 2250 0 4 }
destinationInfo
{
vendor
{
vendor
{
t35CountryCode 181
t35Extension 0
manufacturerCode 18
}
}
gateway
{
protocol
{
voice :
{
supportedPrefixes
{
}
}
}
}
mc FALSE
undefinedNode FALSE
}
h245Address ipAddress :
{
ip '0A55F00A'H
port 41859
}
callIdentifier
{
guid '80ECD91F46CBD1A8700074020A01F066'H
}
multipleCalls FALSE
maintainConnection FALSE
}
h245Tunneling FALSE
nonStandardControl
{

{
nonStandardIdentifier h221NonStandard :
{
t35CountryCode 181
t35Extension 0
manufacturerCode 18
}
data '60010100011E2C1E0282841C269E810003677464...'H
}
}
tunnelledSignallingMessage
{
tunnelledProtocolID
{
id tunnelledProtocolAlternateID :
{
protocolType "gtd"
}
}
messageContent
{
'4350472C0D0A50524E2C6973646E2A2C2C4E4554...'H
}
tunnellingRequired NULL
}
}
}



*Feb 21 14:16:51.876 clt: H225.0 OUTGOING ENCODE BUFFER::= 280032C0060008914A00042800B500001240013C050100000A55F00AA3830080ECD91F46CBD1A8700074020A01F06605800100010010A801003A0140B50000123360010100011E2C1E0282841C269E8100036774640000001B4350472C0D0A50524E2C6973646E2A2C2C4E4554352A2C0D0A0D0A224204677464011B4350472C0D0A50524E2C6973646E2A2C2C4E4554352A2C0D0A0D0A
*Feb 21 14:16:51.876 clt:
*Feb 21 14:16:51.880 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=9222040900, Peer Info Type=DIALPEER_INFO_SPEECH
*Feb 21 14:16:51.880 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=9222040900
*Feb 21 14:16:51.880 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=9222040900, Expanded String=9222040900, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
*Feb 21 14:16:51.880 clt: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=42 Is Matched
*Feb 21 14:16:51.880 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
*Feb 21 14:16:51.880 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
*Feb 21 14:16:51.880 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeers:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=42
*Feb 21 14:16:51.880 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=859349, Peer Info Type=DIALPEER_INFO_SPEECH
*Feb 21 14:16:51.880 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=859349
*Feb 21 14:16:51.880 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=859349, Expanded String=859349, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
*Feb 21 14:16:51.880 clt: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=1000 Is Matched
*Feb 21 14:16:51.880 clt: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=1001 Is Matched
*Feb 21 14:16:51.880 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
*Feb 21 14:16:51.880 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
*Feb 21 14:16:51.880 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeers:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=1000
2: Dial-peer Tag=1001
*Feb 21 14:16:51.880 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=222040900, Peer Info Type=DIALPEER_INFO_SPEECH
*Feb 21 14:16:51.880 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=222040900
*Feb 21 14:16:51.880 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=222040900, Expanded String=222040900, Calling Number=
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
*Feb 21 14:16:51.880 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Result=-1
*Feb 21 14:16:51.880 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
No Outgoing Dial-peer Is Matched; Result=NO_MATCH(-1)
*Feb 21 14:16:51.880 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
*Feb 21 14:16:51.880 clt: //-1/xxxxxxxxxxxx/DPM/dpMatchPeers:
Result=NO_MATCH(-1)
*Feb 21 14:16:51.880 clt: H225 NONSTD OUTGOING PDU ::=

value H323_UU_NonStdInfo ::=
{
version 1
protoParam qsigNonStdInfo :
{
iei 30
rawMesg '1E0284881E0284821C269E810003677464000000...'H
}
}



*Feb 21 14:16:51.880 clt: H225 NONSTD OUTGOING ENCODE BUFFER::= 60010100011E301E0284881E0284821C269E8100036774640000001B41434D2C0D0A50524E2C6973646E2A2C2C4E4554352A2C0D0A0D0A
*Feb 21 14:16:51.880 clt:
*Feb 21 14:16:51.880 clt: H225.0 OUTGOING PDU ::=

value H323_UserInformation ::=
{
h323-uu-pdu
{
h323-message-body alerting :
{
protocolIdentifier { 0 0 8 2250 0 4 }
destinationInfo
{
vendor
{
vendor
{
t35CountryCode 181
t35Extension 0
manufacturerCode 18
}
}
gateway
{
protocol
{
voice :
{
supportedPrefixes
{
}
}
}
}
mc FALSE
undefinedNode FALSE
}
h245Address ipAddress :
{
ip '0A55F00A'H
port 41859
}
callIdentifier
{
guid '80ECD91F46CBD1A8700074020A01F066'H
}
multipleCalls FALSE
maintainConnection FALSE
}
h245Tunneling FALSE
nonStandardControl
{

{
nonStandardIdentifier h221NonStandard :
{
t35CountryCode 181
t35Extension 0
manufacturerCode 18
}
data '60010100011E301E0284881E0284821C269E8100...'H
}
}
tunnelledSignallingMessage
{
tunnelledProtocolID
{
id tunnelledProtocolAlternateID :
{
protocolType "gtd"
}
}
messageContent
{
'41434D2C0D0A50524E2C6973646E2A2C2C4E4554...'H
}
tunnellingRequired NULL
}
}
}



*Feb 21 14:16:51.884 clt: H225.0 OUTGOING ENCODE BUFFER::= 23C0060008914A00042800B500001240013C050100000A55F00AA3831B0C00110080ECD91F46CBD1A8700074020A01F0660100010010A801003E0140B50000123760010100011E301E0284881E0284821C269E8100036774640000001B41434D2C0D0A50524E2C6973646E2A2C2C4E4554352A2C0D0A0D0A224204677464011B41434D2C0D0A50524E2C6973646E2A2C2C4E4554352A2C0D0A0D0A
*Feb 21 14:16:51.884 clt:
*Feb 21 14:16:51.884 clt: H225.0 OUTGOING PDU ::=

fe85vg#
value H323_UserInformation ::=
{
h323-uu-pdu
{
h323-message-body notify :
{
protocolIdentifier { 0 0 8 2250 0 4 }
callIdentifier
{
guid '80ECD91F46CBD1A8700074020A01F066'H
}
}
h245Tunneling FALSE
}
}



*Feb 21 14:16:51.888 clt: H225.0 OUTGOING ENCODE BUFFER::= 28501900060008914A00040080ECD91F46CBD1A8700074020A01F06610800100
*Feb 21 14:16:51.888 clt:
fe85vg#
*Feb 21 14:16:54.624 clt: H225.0 INCOMING ENCODE BUFFER::= 2580060008914A00051100110080ECD91F46CBD1A8700074020A01F06610800100
*Feb 21 14:16:54.624 clt:
*Feb 21 14:16:54.624 clt: H225.0 INCOMING PDU ::=

value H323_UserInformation ::=
{
h323-uu-pdu
{
h323-message-body releaseComplete :
{
protocolIdentifier { 0 0 8 2250 0 5 }
callIdentifier
{
guid '80ECD91F46CBD1A8700074020A01F066'H
}
}
h245Tunneling FALSE
}
}



*Feb 21 14:16:54.636 clt: H225.0 OUTGOING PDU ::=

fe85vg#
value H323_UserInformation ::=
{
h323-uu-pdu
{
h323-message-body releaseComplete :
{
protocolIdentifier { 0 0 8 2250 0 4 }
callIdentifier
{
guid '80ECD91F46CBD1A8700074020A01F066'H
}
}
h245Tunneling FALSE
}
}



*Feb 21 14:16:54.636 clt: H225.0 OUTGOING ENCODE BUFFER::= 2580060008914A00041100110080ECD91F46CBD1A8700074020A01F06610800100
*Feb 21 14:16:54.636 clt:


R0g22
Cisco Employee
Cisco Employee
Interesting. Can you enable the following and make another test call ?

debug isdn q931
debug voice ccapi inout
debug h225 asn1
debug h245 asn1
debug voip dialpeer

Copy the logs to a text file.

See attached.

R0g22
Cisco Employee
Cisco Employee
It's not an issue with the GW. Your Telco is delaying the call progress. Look at the timestamp below -

*Feb 22 09:22:27.579 clt: H225.0 INCOMING PDU ::=

value H323_UserInformation ::=
{
h323-uu-pdu
{
h323-message-body setup :
{
protocolIdentifier { 0 0 8 2250 0 5 }
sourceAddress
{
h323-ID : {"test..."}

It takes less than 20 ms to send an ISDN SETUP to Telco -

*Feb 22 09:22:27.595 clt: ISDN Se0/0/0:15 Q931: TX -> SETUP pd = 8 callref = 0x00CB
Bearer Capability i = 0x8090A3
Standard = CCITT
Transfer Capability = Speech
Transfer Mode = Circuit
Transfer Rate = 64 kbit/s
Channel ID i = 0xA9838F
Exclusive, Channel 15
Calling Party Number i = 0x0081, '859349'
Plan:Unknown, Type:Unknown
Called Party Number i = 0x80, '222040900'
Plan:Unknown, Type:Unknown

Look at the time difference below from ACK to Proceeding -

*Feb 22 09:22:27.779 clt: ISDN Se0/0/0:15 Q931: RX <- SETUP_ACK pd = 8 callref = 0x80CB
Channel ID i = 0xA9838F
Exclusive, Channel 15
fe85vg#
*Feb 22 09:22:37.823 clt: ISDN Se0/0/0:15 Q931: RX <- CALL_PROC pd = 8 callref = 0x80CB
Channel ID i = 0xA9838F
Exclusive, Channel 15

A couple of second delay in receiving Progress -

*Feb 22 09:22:39.023 clt: ISDN Se0/0/0:15 Q931: RX <- PROGRESS pd = 8 callref = 0x80CB
Progress Ind i = 0x8284 - Call has returned to the ISDN

Finally ALERTING with PI=8 and GW does a backward cut through to allow media/ring back in this case to flow -

*Feb 22 09:22:39.027 clt: ISDN Se0/0/0:15 Q931: RX <- ALERTING pd = 8 callref = 0x80CB
Progress Ind i = 0x8488 - In-band info or appropriate now available
Progress Ind i = 0x8482 - Destination address is non-ISDN

Nipun,

 

Thanks for reviewing the logs. I will reach out to the Telco.

 

Ken