SIP Cause error Reason: Q.850;cause=38

[Wonxie]

Hi

i am trying to establish a trunk with an ITSP using cisco router. however call is not going through and giving below error code.

Reason: Q.850;cause=38

please see a sample sip capture form the router where it recived call from cucm and after matching proper inbound and outbound sip dial peer it sent to itsp using proper bind interface which itsp has whitelisted. 

[Robert Profit]

Hello,

Cause Code 38=Network Out of Order. Based off of your sip ladder it looks like your CUBE is sending the Cause Code/BYE and the ITSP responds with the 200OK

It may be beneficial to also enable 'debug ccapi voice inout' debug to see why your CUBE is yelling.

V/R,

Rob Profit

[b.winter]

Hey,

Cause code 38 means "network out of order". Maybe there is a misconfig on your CUBE.

please attach the config (without sensitive data like username, passwords, ...) and a full debug output of a call.
debug voice ccapi ind 1
debug voice ccapi ind 2
debug voice ccapi ind 74
debug ccsip messages

[Wonxie]

I am attaching the conigs.

here is the network diagram. I already have sip trunk from ITSP1 ad its working fine.

I want to establish new trunk with DT as per below diagram and want extensions  on both sides to be able to call each other. i hope i havent messed too much with the config file as i used notepad to replace our ips with random ips. in the attached configs i have added just two dial peers for extensions at the otther side (DT) and assigned the ip on g0/0.10 and added in trusted list and few acls. reamining configs were from conection used for ITSP1.  please guide me if i have missed something.

[b.winter]

Are you using SIP Trunk of Deutsche Telekom in Germany? If yes, which product? I'm asking, because there are config templates for it.

Do both SIP trunks need to register with the provider? If yes, you cannot work with SIP-UA configuration. Because you can only config 1 authentication for 1 SIP Trunk. You need to use tenant configuration (check the Cisco docs and also the huge amount of forum threads about it, this has been discussed already a lot of times).

[Wonxie]

i am not using Deutch telecom.its just random name.

at the moment the second ITSP doesnt requires any authentication but once i get base testing done i will do that as well. only itsp 1 requies authencticatoin and all config under sip-ua are for it.

[b.winter]

Ok, just assumed, because you wrote "DT".

Honest opinion:
Upgrade the router to the latest IOS and try to use tenant config.

About your problem:
You already have the logs of a call with the suggested debugs enabled?

[Wonxie]

here are the logs  . its not allowing me to upload in txt file eve though i saved the file via windows notepad. 

The attachment's sip debugs logs.txt content type (text/plain) does not match its file extension and has been removed.

VoiceRouter1#sh debugging

DIALPEER:
debug voip dialpeer error call is ON (filter is OFF)
debug voip dialpeer error software is ON
debug voip dialpeer inout is ON (filter is OFF)
CCAPI:
debug voip ccapi inout is ON (filter is OFF)
CCSIP SPI: SIP Call Statistics tracing is enabled (filter is OFF)
CCSIP SPI: SIP Call Message tracing is enabled (filter is OFF)

VoiceRouter1#

262130: *Mar 2 05:44:14.410: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
OPTIONS sip:22.22.22.22:5060 SIP/2.0
Via: SIP/2.0/UDP 12.12.12.12:5060;rport;branch=z9hG4bKPjdfdb155c-7caa-4a79-b064-e10d7656b973
From: <sip:CUCM01@22.22.22.22>;tag=69ff7039-b75f-418f-b5c0-49ff4ea43f7a
To: <sip:22.22.22.22>
Contact: <sip:CUCM01@12.12.12.12:5060>
Call-ID: 33d0147d-8670-4ef5-81f0-2c196447d77a
CSeq: 57620 OPTIONS
Max-Forwards: 70
User-Agent: FPBX-15.0.23(16.28.0)
Content-Length: 0

262131: *Mar 2 05:44:14.410: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=CUCM01, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
262132: *Mar 2 05:44:14.410: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
262133: *Mar 2 05:44:14.410: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
262134: *Mar 2 05:44:14.410: //-1/C5C0D3E9BBA9/DPM/dpAssociateIncomingPeerCore:
Calling Number=CUCM01, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
262135: *Mar 2 05:44:14.410: //-1/C5C0D3E9BBA9/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
262136: *Mar 2 05:44:14.410: //-1/C5C0D3E9BBA9/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
262137: *Mar 2 05:44:14.410: //13956/C5C0D3E9BBA9/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 12.12.12.12:5060;rport;branch=z9hG4bKPjdfdb155c-7caa-4a79-b064-e10d7656b973
From: <sip:CUCM01@22.22.22.22>;tag=69ff7039-b75f-418f-b5c0-49ff4ea43f7a
To: <sip:22.22.22.22>;tag=38523204-2242
Date: Thu, 02 Mar 2023 12:44:14 GMT
Call-ID: 33d0147d-8670-4ef5-81f0-2c196447d77a
Server: Cisco-SIPGateway/IOS-15.7.3.M3
CSeq: 57620 OPTIONS
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Accept: application/sdp
Supported: 100rel,timer,resource-priority,replaces,sdp-anat
Content-Type: application/sdp
Content-Length: 164

v=0
o=CiscoSystemsSIP-GW-UserAgent 1434 3645 IN IP4 11.11.11.11
s=SIP Call
c=IN IP4 11.11.11.11
t=0 0
m=audio 0 RTP/AVP 18 0 8 9 4 2 15
c=IN IP4 11.11.11.11

262138: *Mar 2 05:44:21.514: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:5039@10.65.12.3:5060 SIP/2.0
Via: SIP/2.0/TCP 10.65.102.11:5060;branch=z9hG4bK62061b05ba91
From: <sip:3373@10.65.102.11>;tag=25118~5f79ec65-2bba-42ae-995d-dd9649fc752b-46753485
To: <sip:5039@10.65.12.3>
Date: Thu, 02 Mar 2023 15:00:34 GMT
Call-ID: fa5cda80-4001ba12-60e6-b66410a@10.65.102.11
Supported: 100rel,timer,resource-priority,replaces
Min-SE: 1800
User-Agent: Cisco-CUCM8.6
Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY
CSeq: 101 INVITE
Expires: 180
Allow-Events: presence, kpml
Supported: X-cisco-srtp-fallback,X-cisco-original-called
Cisco-Guid: 4200389248-0000065536-0000000151-0191250698
Session-Expires: 1800
P-Asserted-Identity: <sip:3373@10.65.102.11>
Remote-Party-ID: <sip:3373@10.65.102.11>;party=calling;screen=yes;privacy=off
Contact: <sip:3373@10.65.102.11:5060;transport=tcp>
Max-Forwards: 70
Content-Type: application/sdp
Content-Length: 213

v=0
o=CiscoSystemsCCM-SIP 25118 1 IN IP4 10.65.102.11
s=SIP Call
c=IN IP4 10.65.102.10
t=0 0
m=audio 25110 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=ptime:20
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

262139: *Mar 2 05:44:21.514: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=5039, Called Number=5039, Peer Info Type=DIALPEER_INFO_SPEECH
262140: *Mar 2 05:44:21.514: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=5039
262141: *Mar 2 05:44:21.514: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
262142: *Mar 2 05:44:21.514: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=5039, saf_enabled=1, saf_dndb_lookup=1, dp_result=0
262143: *Mar 2 05:44:21.514: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=601
2: Dial-peer Tag=300
3: Dial-peer Tag=301
262144: *Mar 2 05:44:21.514: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=3373, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
262145: *Mar 2 05:44:21.514: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=300
262146: *Mar 2 05:44:21.514: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
262147: *Mar 2 05:44:21.518: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=3373, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
262148: *Mar 2 05:44:21.518: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=300
262149: *Mar 2 05:44:21.518: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
262150: *Mar 2 05:44:21.518: //-1/FA5CDA800000/DPM/dpAssociateIncomingPeerCore:
Calling Number=3373, Called Number=5039, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
262151: *Mar 2 05:44:21.518: //-1/FA5CDA800000/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=601
262152: *Mar 2 05:44:21.518: //-1/FA5CDA800000/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
262153: *Mar 2 05:44:21.518: //-1/xxxxxxxxxxxx/DPM/dpGetSIPUriProvisionBmp:
Tag=601
262154: *Mar 2 05:44:21.518: //-1/xxxxxxxxxxxx/DPM/dpGetSIPUriProvisionBmp:
Result=0 Bitmap=0x0
262155: *Mar 2 05:44:21.518: //-1/FA5CDA800000/CCAPI/cc_api_display_ie_subfields:
cc_api_call_setup_ind_common:
cisco-username=3373
----- ccCallInfo IE subfields -----
cisco-ani=3373
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=1
dest=5039
cisco-desttype=0
cisco-destplan=0
cisco-rdie=FFFFFFFF
cisco-rdn=
cisco-rdntype=0
cisco-rdnplan=0
cisco-rdnpi=-1
cisco-rdnsi=-1
cisco-redirectreason=-1 fwd_final_type =0
final_redirectNumber =
hunt_group_timeout =0

262156: *Mar 2 05:44:21.518: //-1/FA5CDA800000/CCAPI/cc_api_call_setup_ind_common:
Interface=0x22811784, Call Info(
Calling Number=3373,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=5039(TON=Unknown, NPI=Unknown),
Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE,
Incoming Dial-peer=601, Progress Indication=NULL(0), Calling IE Present=TRUE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=13957
262157: *Mar 2 05:44:21.518: //-1/FA5CDA800000/CCAPI/ccCheckClipClir:
In: Calling Number=3373(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
262158: *Mar 2 05:44:21.518: //-1/FA5CDA800000/CCAPI/ccCheckClipClir:
Out: Calling Number=3373(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
262159: *Mar 2 05:44:21.518: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

262160: *Mar 2 05:44:21.518: :cc_get_feature_vsa malloc success
262161: *Mar 2 05:44:21.518: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

262162: *Mar 2 05:44:21.518: cc_get_feature_vsa count is 1
262163: *Mar 2 05:44:21.518: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

262164: *Mar 2 05:44:21.518: :FEATURE_VSA attributes are: feature_name:0,feature_time:611953728,feature_id:931
262165: *Mar 2 05:44:21.518: //13957/FA5CDA800000/CCAPI/cc_api_call_setup_ind_common:
Set Up Event Sent;
Call Info(Calling Number=3373(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=5039(TON=Unknown, NPI=Unknown))
262166: *Mar 2 05:44:21.522: //13957/FA5CDA800000/CCAPI/cc_process_call_setup_ind:
Event=0x22FFD300
262167: *Mar 2 05:44:21.522: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
Try with the demoted called number 5039
262168: *Mar 2 05:44:21.522: //13957/FA5CDA800000/CCAPI/ccCallSetContext:
Context=0x23F02C84
262169: *Mar 2 05:44:21.522: //13957/FA5CDA800000/CCAPI/cc_process_call_setup_ind:
>>>>CCAPI handed cid 13957 with tag 601 to app "_ManagedAppProcess_Default"
262170: *Mar 2 05:44:21.522: //13957/FA5CDA800000/CCAPI/ccCallProceeding:
Progress Indication=NULL(0)
262171: *Mar 2 05:44:21.522: //-1/FA5CDA800000/DPM/dpMatchPeersCore:
Calling Number=, Called Number=5039, Peer Info Type=DIALPEER_INFO_SPEECH
262172: *Mar 2 05:44:21.522: //-1/FA5CDA800000/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=5039
262173: *Mar 2 05:44:21.522: //-1/FA5CDA800000/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
262174: *Mar 2 05:44:21.522: //-1/FA5CDA800000/DPM/dpMatchSafModulePlugin:
dialstring=5039, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
262175: *Mar 2 05:44:21.522: //-1/FA5CDA800000/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=601
2: Dial-peer Tag=300
3: Dial-peer Tag=301
262176: *Mar 2 05:44:21.522: //-1/FA5CDA800000/DPM/dpMatchPeersCore:
Calling Number=, Called Number=5039, Peer Info Type=DIALPEER_INFO_SPEECH
262177: *Mar 2 05:44:21.522: //-1/FA5CDA800000/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=5039
262178: *Mar 2 05:44:21.522: //-1/FA5CDA800000/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
262179: *Mar 2 05:44:21.522: //-1/FA5CDA800000/DPM/dpMatchSafModulePlugin:
dialstring=5039, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
262180: *Mar 2 05:44:21.522: //-1/FA5CDA800000/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=601
2: Dial-peer Tag=300
3: Dial-peer Tag=301
262181: *Mar 2 05:44:21.522: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=5039, Called Number=5039, Peer Info Type=DIALPEER_INFO_SPEECH
262182: *Mar 2 05:44:21.522: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=5039
262183: *Mar 2 05:44:21.522: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
262184: *Mar 2 05:44:21.522: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=5039, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
262185: *Mar 2 05:44:21.522: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=601
2: Dial-peer Tag=300
3: Dial-peer Tag=301
262186: *Mar 2 05:44:21.522: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=5039, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
262187: *Mar 2 05:44:21.526: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=601
262188: *Mar 2 05:44:21.526: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
262189: *Mar 2 05:44:21.526: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=5039, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
262190: *Mar 2 05:44:21.526: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=601
262191: *Mar 2 05:44:21.526: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
262192: *Mar 2 05:44:21.526: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=5039, Peer Info Type=DIALPEER_INFO_SPEECH
262193: *Mar 2 05:44:21.526: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=5039
262194: *Mar 2 05:44:21.526: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
262195: *Mar 2 05:44:21.526: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=5039, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
262196: *Mar 2 05:44:21.526: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=601
2: Dial-peer Tag=300
3: Dial-peer Tag=301
262197: *Mar 2 05:44:21.526: //-1/FA5CDA800000/DPM/dpMatchPeersCore:
Calling Number=, Called Number=5039, Peer Info Type=DIALPEER_INFO_SPEECH
262198: *Mar 2 05:44:21.526: //-1/FA5CDA800000/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=5039
262199: *Mar 2 05:44:21.526: //-1/FA5CDA800000/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
262200: *Mar 2 05:44:21.526: //-1/FA5CDA800000/DPM/dpMatchSafModulePlugin:
dialstring=5039, saf_enabled=1, saf_dndb_lookup=1, dp_result=0
262201: *Mar 2 05:44:21.526: //-1/FA5CDA800000/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=601
2: Dial-peer Tag=300
3: Dial-peer Tag=301
262202: *Mar 2 05:44:21.526: //13957/FA5CDA800000/CCAPI/ccCallSetupRequest:
Destination=, Calling IE Present=TRUE, Mode=0,
Outgoing Dial-peer=601, Params=0x23EFC03C, Progress Indication=NULL(0)
262203: *Mar 2 05:44:21.526: //13957/FA5CDA800000/CCAPI/ccCheckClipClir:
In: Calling Number=3373(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
262204: *Mar 2 05:44:21.526: //13957/FA5CDA800000/CCAPI/ccCheckClipClir:
Out: Calling Number=3373(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
262205: *Mar 2 05:44:21.526: //13957/FA5CDA800000/CCAPI/ccCallSetupRequest:
Destination Pattern=^5039, Called Number=5039, Digit Strip=FALSE
262206: *Mar 2 05:44:21.526: //13957/FA5CDA800000/CCAPI/ccCallSetupRequest:
Calling Number=3373(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=5039(TON=Unknown, NPI=Unknown),
Redirect Number=, Display Info=
Account Number=3373, Final Destination Flag=TRUE,
Guid=FA5CDA80-0001-0000-0000-00970B66410A, Outgoing Dial-peer=601
262207: *Mar 2 05:44:21.526: //13957/FA5CDA800000/CCAPI/cc_api_display_ie_subfields:
ccCallSetupRequest:
cisco-username=3373
----- ccCallInfo IE subfields -----
cisco-ani=3373
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=1
dest=5039
cisco-desttype=0
cisco-destplan=0
cisco-rdie=FFFFFFFF
cisco-rdn=
cisco-rdntype=0
cisco-rdnplan=0
cisco-rdnpi=-1
cisco-rdnsi=-1
cisco-redirectreason=-1 fwd_final_type =0
final_redirectNumber =
hunt_group_timeout =0

262208: *Mar 2 05:44:21.526: //13957/FA5CDA800000/CCAPI/ccIFCallSetupRequestPrivate:
Interface=0x22811784, Interface Type=3, Destination=, Mode=0x0,
Call Params(Calling Number=3373,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=5039(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing Dial-peer=601, Call Count On=FALSE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)
262209: *Mar 2 05:44:21.526: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

262210: *Mar 2 05:44:21.526: :cc_get_feature_vsa malloc success
262211: *Mar 2 05:44:21.526: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

262212: *Mar 2 05:44:21.526: cc_get_feature_vsa count is 2
262213: *Mar 2 05:44:21.526: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

262214: *Mar 2 05:44:21.526: :FEATURE_VSA attributes are: feature_name:0,feature_time:611952160,feature_id:932
262215: *Mar 2 05:44:21.526: //13957/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

262216: *Mar 2 05:44:21.526: cc_api_get_xcode_stream : 4981
262217: *Mar 2 05:44:21.526: //13957/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

262218: *Mar 2 05:44:21.526: cc_api_get_xcode_stream : 4981
262219: *Mar 2 05:44:21.526: //13957/FA5CDA800000/CCAPI/cc_api_event_indication:
Event=101, Call Id=13957
262220: *Mar 2 05:44:21.526: //13957/FA5CDA800000/CCAPI/cc_api_event_indication:
Event Is Sent To Conferenced SPI(s) Directly
262221: *Mar 2 05:44:21.526: //13958/FA5CDA800000/CCAPI/ccIFCallSetupRequestPrivate:
SPI Call Setup Request Is Success; Interface Type=3, FlowMode=1
262222: *Mar 2 05:44:21.526: //13958/FA5CDA800000/CCAPI/ccCallSetContext:
Context=0x23EFBFEC
262223: *Mar 2 05:44:21.526: //13957/FA5CDA800000/CCAPI/ccSaveDialpeerTag:
Outgoing Dial-peer=601
262224: *Mar 2 05:44:21.530: //13958/FA5CDA800000/CCAPI/ccGetMediaClassTag:
media class tag 0
262225: *Mar 2 05:44:21.530: //13958/FA5CDA800000/CCAPI/ccSetMediaclassIp2ipTags:
media class tags set: NR 0, ASP 0
262226: *Mar 2 05:44:21.530: //13957/FA5CDA800000/CCAPI/ccGetMediaClassTag:
media class tag 0
262227: *Mar 2 05:44:21.530: //13957/FA5CDA800000/CCAPI/ccSetMediaclassIp2ipTags:
media class tags set: NR 0, ASP 0
262228: *Mar 2 05:44:21.530: //13958/FA5CDA800000/CCAPI/ccGet_xc_nr_asp_info:
media class tags: NR 0, ASP 0
262229: *Mar 2 05:44:21.530: //13957/FA5CDA800000/CCAPI/ccGet_xc_nr_asp_info:
media class tags: NR 0, ASP 0
262230: *Mar 2 05:44:21.530: //-1/xxxxxxxxxxxx/CCAPI/cc_is_cng_fax_detect_active:
Call Id 13957
262231: *Mar 2 05:44:21.530: //13958/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

262232: *Mar 2 05:44:21.530: cc_api_get_xcode_stream : 4981
262233: *Mar 2 05:44:21.530: //13958/FA5CDA800000/CCAPI/cc_api_call_proceeding:
Interface=0x22811784, Progress Indication=NULL(0)
262234: *Mar 2 05:44:21.530: //13958/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

262235: *Mar 2 05:44:21.530: cc_api_get_xcode_stream : 4981
262236: *Mar 2 05:44:21.530: //13958/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

262237: *Mar 2 05:44:21.530: cc_api_get_xcode_stream : 4981
262238: *Mar 2 05:44:21.534: //13958/FA5CDA800000/SIP/Msg/ccsipDisplayMsg:
Sent:
INVITE sip:5039@12.12.12.12:5060 SIP/2.0
Via: SIP/2.0/UDP 22.22.22.22:5060;branch=z9hG4bK7557DF
From: <sip:3373@voip.itsp1.com>;tag=38524DD4-186F
To: <sip:5039@12.12.12.12>
Date: Thu, 02 Mar 2023 12:44:21 GMT
Call-ID: C9FF407A-B82E11ED-BBAFAB5E-195B8164@voip.itsp1.com
Supported: 100rel,timer,resource-priority,replaces,sdp-anat
Min-SE: 1800
Cisco-Guid: 4200389248-0000065536-0000000151-0191250698
User-Agent: Cisco-SIPGateway/IOS-15.7.3.M3
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
CSeq: 101 INVITE
Timestamp: 1677761061
Contact: <sip:3373@22.22.22.22:5060>
Expires: 180
Allow-Events: telephone-event
Max-Forwards: 69
P-Asserted-Identity: <sip:3373@voip.itsp1.com>
Session-ID: bec855b57ab451688867fbbadf5caadd;remote=00000000000000000000000000000000
Session-Expires: 1800
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 247

v=0
o=CiscoSystemsSIP-GW-UserAgent 8178 6912 IN IP4 22.22.22.22
s=SIP Call
c=IN IP4 22.22.22.22
t=0 0
m=audio 18054 RTP/AVP 0 101
c=IN IP4 22.22.22.22
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20

262239: *Mar 2 05:44:21.574: //13958/FA5CDA800000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 22.22.22.22:5060;rport=5060;received=22.22.22.22;branch=z9hG4bK7557DF
Call-ID: C9FF407A-B82E11ED-BBAFAB5E-195B8164@voip.itsp1.com
From: <sip:3373@voip.itsp1.com>;tag=38524DD4-186F
To: <sip:5039@12.12.12.12>
CSeq: 101 INVITE
Server: FPBX-15.0.23(16.28.0)
Content-Length: 0

262240: *Mar 2 05:44:21.666: //13958/FA5CDA800000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 22.22.22.22:5060;rport=5060;received=22.22.22.22;branch=z9hG4bK7557DF
Call-ID: C9FF407A-B82E11ED-BBAFAB5E-195B8164@voip.itsp1.com
From: <sip:3373@voip.itsp1.com>;tag=38524DD4-186F
To: <sip:5039@12.12.12.12>;tag=213a87e3-7ccd-469a-b177-88b4706f22ee
CSeq: 101 INVITE
Server: FPBX-15.0.23(16.28.0)
Contact: <sip:12.12.12.12:5060>
Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REFER
Supported: 100rel, timer, replaces, norefersub
Session-Expires: 1800;refresher=uac
Require: timer
Content-Type: application/sdp
Content-Length: 227

v=0
o=- 8178 6914 IN IP4 12.12.12.12
s=Asterisk
c=IN IP4 12.12.12.12
t=0 0
m=audio 15968 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=maxptime:150
a=sendrecv

262241: *Mar 2 05:44:21.666: //13958/FA5CDA800000/CCAPI/cc_api_caps_ind:

262242: *Mar 2 05:44:21.666: voice_rtp_nse_payload 100, voice_rtp_sse_payload 118 voice_sprt_payload 120
262243: *Mar 2 05:44:21.666: //13958/FA5CDA800000/CCAPI/cc_api_caps_ind:

262244: *Mar 2 05:44:21.666: sprt_latency 200, sprt_retries 12
262245: *Mar 2 05:44:21.666: //13958/FA5CDA800000/CCAPI/cc_api_caps_ind:
Destination Interface=0x0, Destination Call Id=-1, Source Call Id=13958,
Caps(Codec=0x1, Fax Rate=0x2, Fax Version:=0, Vad=0x2,
Modem=0x0, Codec Bytes=160, Signal Type=2)
262246: *Mar 2 05:44:21.666: //13958/FA5CDA800000/CCAPI/cc_api_caps_ind:
Caps(Playout Mode=1, Playout Initial=60(ms), Playout Min=40(ms),
Playout Max=1000(ms), Fax Nom=300(ms))
262247: *Mar 2 05:44:21.666: //13957/FA5CDA800000/CCAPI/cc_api_caps_ack:
Destination Interface=0x0, Destination Call Id=-1, Source Call Id=13957,
Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_NONE(0x1), Fax Version:=0, Vad=OFF(0x1),
Modem=OFF(0x0), Codec Bytes=160, Signal Type=2, Seq Num Start=5950)
262248: *Mar 2 05:44:21.666: //13957/FA5CDA800000/CCAPI/cc_api_caps_ack:
Destination Interface=0x0, Destination Call Id=-1, Source Call Id=13957,
Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_NONE(0x1), Fax Version:=0, Vad=OFF(0x1),
Modem=OFF(0x0), Codec Bytes=160, Signal Type=2, Seq Num Start=5950)
262249: *Mar 2 05:44:21.666: //13958/FA5CDA800000/CCAPI/cc_api_event_indication:
Event=103, Call Id=13958
262250: *Mar 2 05:44:21.666: //13958/FA5CDA800000/CCAPI/cc_api_event_indication:
Event Is Sent To Conferenced SPI(s) Directly
262251: *Mar 2 05:44:21.666: //13958/FA5CDA800000/CCAPI/cc_api_call_connected:
Interface=0x22811784, Data Bitmask=0x1, Progress Indication=NULL(0),
Connection Handle=0
262252: *Mar 2 05:44:21.666: //13958/FA5CDA800000/CCAPI/cc_api_call_connected:
Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0)
262253: *Mar 2 05:44:21.670: //13958/FA5CDA800000/CCAPI/cc_api_event_indication:
Event=111, Call Id=13958
262254: *Mar 2 05:44:21.670: //13958/FA5CDA800000/CCAPI/cc_api_event_indication:
Event Is Sent To Conferenced SPI(s) Directly
262255: *Mar 2 05:44:21.670: //13958/FA5CDA800000/CCAPI/cc_api_event_indication:
Event=213, Call Id=13958
262256: *Mar 2 05:44:21.670: //13958/FA5CDA800000/CCAPI/cc_api_event_indication:
Event Is Sent To Conferenced SPI(s) Directly
262257: *Mar 2 05:44:21.670: //13958/FA5CDA800000/SIP/Call/sipSPICallInfo:
The Call Setup Information is:
Call Control Block (CCB) : 0x0x237D5A10
State of The Call : STATE_ACTIVE
TCP Sockets Used : NO
Calling Number : 3373
Called Number : 5039
Source IP Address (Sig 22.22.22.22
Destn SIP Req Addr:Port : 12.12.12.12:5060
Destn SIP Resp Addr:Port : 12.12.12.12:5060
Destination Name : 12.12.12.12

262258: *Mar 2 05:44:21.670: //13958/FA5CDA800000/SIP/Call/sipSPIMediaCallInfo:
Number of Media Streams: 1
Media Stream : 1
Negotiated Codec : g711ulaw
Negotiated Codec Bytes : 160
Nego. Codec payload : 0 (tx), 0 (rx)
Negotiated Dtmf-relay : 6
Dtmf-relay Payload : 101 (tx), 101 (rx)
Source IP Address (Media): 22.22.22.22
Source IP Port (Media): 18054
Destn IP Address (Media): 12.12.12.12
Destn IP Port (Media): 15968
Orig Destn IP Address:Port (Media): [ - ]:0

262259: *Mar 2 05:44:21.670: //13958/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

262260: *Mar 2 05:44:21.670: cc_api_get_xcode_stream : 4981
262261: *Mar 2 05:44:21.670: //13957/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

262262: *Mar 2 05:44:21.670: cc_api_get_xcode_stream : 4981
262263: *Mar 2 05:44:21.670: //13957/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

262264: *Mar 2 05:44:21.670: cc_api_get_xcode_stream : 4981
262265: *Mar 2 05:44:21.670: //13958/FA5CDA800000/SIP/Msg/ccsipDisplayMsg:
Sent:
ACK sip:12.12.12.12:5060 SIP/2.0
Via: SIP/2.0/UDP 22.22.22.22:5060;branch=z9hG4bK75619CD
From: <sip:3373@voip.itsp1.com>;tag=38524DD4-186F
To: <sip:5039@12.12.12.12>;tag=213a87e3-7ccd-469a-b177-88b4706f22ee
Date: Thu, 02 Mar 2023 12:44:21 GMT
Call-ID: C9FF407A-B82E11ED-BBAFAB5E-195B8164@voip.itsp1.com
Max-Forwards: 70
CSeq: 101 ACK
Allow-Events: telephone-event
Session-ID: bec855b57ab451688867fbbadf5caadd;remote=5333aeefa2415ed8ba456255a062bc5f
Content-Length: 0

262266: *Mar 2 05:44:21.670: //13957/FA5CDA800000/CCAPI/ccConferenceCreate:
(confID=0xFFFFFFFF, callID1=0x3685, gcid=C9FD6C02-B82E11ED-BBACAB5E-195B8164, tag=0x0)
262267: *Mar 2 05:44:21.670: //13958/FA5CDA800000/CCAPI/ccConferenceCreate:
(confID=0xFFFFFFFF, callID2=0x3686, gcid=C9FD6C02-B82E11ED-BBACAB5E-195B8164, tag=0x0)
262268: *Mar 2 05:44:21.670: //13957/FA5CDA800000/CCAPI/ccConferenceCreate:
Conference Id=0xFFFFFFFF, Call Id1=13957, Call Id2=13958, Tag=0x0
262269: *Mar 2 05:44:21.670: //13957/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

262270: *Mar 2 05:44:21.670: cc_api_get_xcode_stream : 4981
262271: *Mar 2 05:44:21.670: //13958/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

262272: *Mar 2 05:44:21.670: cc_api_get_xcode_stream : 4981
262273: *Mar 2 05:44:21.670: //13957/FA5CDA800000/CCAPI/ccConferenceCreate:

262274: *Mar 2 05:44:21.670: ccConferenceCreate: ret1=0, codecMask1=1, bytes1=160, negot1=1, dtmf1=6
ret2=0, codecMask2=1, bytes2=160, negot2=1, dtmf2=6,
tx_dynamic_pt1=0, rx_dynamic_pt1=0, codec_mode1=0, params_bitmap1 =0
tx_dynamic_pt2=0, rx_dynamic_pt2=0, codec_mode2=0, params_bitmap2 =0
262275: *Mar 2 05:44:21.670: //13957/FA5CDA800000/CCAPI/ccGetMediaClassTag:
media class tag 0
262276: *Mar 2 05:44:21.670: //13957/FA5CDA800000/CCAPI/ccSetMediaclassIp2ipTags:
media class tags set: NR 0, ASP 0
262277: *Mar 2 05:44:21.670: //13958/FA5CDA800000/CCAPI/ccGetMediaClassTag:
media class tag 0
262278: *Mar 2 05:44:21.674: //13958/FA5CDA800000/CCAPI/ccSetMediaclassIp2ipTags:
media class tags set: NR 0, ASP 0
262279: *Mar 2 05:44:21.674: //13957/FA5CDA800000/CCAPI/ccGet_xc_nr_asp_info:
media class tags: NR 0, ASP 0
262280: *Mar 2 05:44:21.674: //13958/FA5CDA800000/CCAPI/ccGet_xc_nr_asp_info:
media class tags: NR 0, ASP 0
262281: *Mar 2 05:44:21.674: //-1/xxxxxxxxxxxx/CCAPI/cc_is_cng_fax_detect_active:
Call Id 13957
262282: *Mar 2 05:44:21.674: //13957/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:
xcoder inserted for preferred features w/ mask 0x0
262283: *Mar 2 05:44:21.674: //13957/FA5CDA800000/CCAPI/ccConferenceCreate:
delay media to slow start case, codec negotation is not done
262284: *Mar 2 05:44:21.674: //13957/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

262285: *Mar 2 05:44:21.674: cc_api_get_xcode_stream : 4981
262286: *Mar 2 05:44:21.674: //13957/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

262287: *Mar 2 05:44:21.674: cc_api_get_xcode_stream : 4981
262288: *Mar 2 05:44:21.674: //13957/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

262289: *Mar 2 05:44:21.674: cc_api_get_xcode_stream : 4981
262290: *Mar 2 05:44:21.674: //13957/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

262291: *Mar 2 05:44:21.674: cc_api_get_xcode_stream : 4981
262292: *Mar 2 05:44:21.674: //13957/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

262293: *Mar 2 05:44:21.674: cc_api_get_xcode_stream : 4981
262294: *Mar 2 05:44:21.674: //13957/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

262295: *Mar 2 05:44:21.674: cc_api_get_xcode_stream : 4981
262296: *Mar 2 05:44:21.674: //13957/FA5CDA800000/CCAPI/cc_api_bridge_done:
Conference Id=0x169, Source Interface=0x22811784, Source Call Id=13957,
Destination Call Id=13958, Disposition=0x0, Tag=0x0
262297: *Mar 2 05:44:21.674: //13958/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

262298: *Mar 2 05:44:21.674: cc_api_get_xcode_stream : 4981
262299: *Mar 2 05:44:21.674: //13958/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

262300: *Mar 2 05:44:21.674: cc_api_get_xcode_stream : 4981
262301: *Mar 2 05:44:21.674: //13958/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

262302: *Mar 2 05:44:21.674: cc_api_get_xcode_stream : 4981
262303: *Mar 2 05:44:21.674: //13958/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

262304: *Mar 2 05:44:21.674: cc_api_get_xcode_stream : 4981
262305: *Mar 2 05:44:21.674: //13958/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

262306: *Mar 2 05:44:21.674: cc_api_get_xcode_stream : 4981
262307: *Mar 2 05:44:21.674: //13958/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

262308: *Mar 2 05:44:21.674: cc_api_get_xcode_stream : 4981
262309: *Mar 2 05:44:21.674: //13958/FA5CDA800000/CCAPI/cc_api_bridge_done:
Conference Id=0x169, Source Interface=0x22811784, Source Call Id=13958,
Destination Call Id=13957, Disposition=0x0, Tag=0x0
262310: *Mar 2 05:44:21.674: //13957/FA5CDA800000/CCAPI/cc_generic_bridge_done:
Conference Id=0x169, Source Interface=0x22811784, Source Call Id=13958,
Destination Call Id=13957, Disposition=0x0, Tag=0x0
262311: *Mar 2 05:44:21.674: //13957/FA5CDA800000/CCAPI/ccConferenceCreate:
Call Entry(Conference Id=0x169, Destination Call Id=13958)
262312: *Mar 2 05:44:21.674: //13958/FA5CDA800000/CCAPI/ccConferenceCreate:
Call Entry(Conference Id=0x169, Destination Call Id=13957)
262313: *Mar 2 05:44:21.674: //13957/FA5CDA800000/CCAPI/ccConferenceCreate:

262314: *Mar 2 05:44:21.674: confID:0x169; callEntry1 callID1:0x3685, type:3; callEntry2 callID2:0x3686, type:3

262315: *Mar 2 05:44:21.674: //13957/FA5CDA800000/CCAPI/cc_process_notify_bridge_done:
Conference Id=0x169, Call Id1=13957, Call Id2=13958
262316: *Mar 2 05:44:21.674: //13958/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify:

262317: *Mar 2 05:44:21.674: call_info mainst_callID:0x3686, peer_callID:0x3685, confID:0x169, spi_type:4, media_flo_thru:1,num_streams:1, swmtpmsp_present:0

262318: *Mar 2 05:44:21.674: //13958/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify:

262319: *Mar 2 05:44:21.674: stream[0]: callid:0x3686, media_passthrough:1
262320: *Mar 2 05:44:21.674: //13957/FA5CDA800000/CCAPI/ccCallConnect:
Progress Indication=NULL(0), Data Bitmask=0x1
262321: *Mar 2 05:44:21.674: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=3373, Peer Info Type=DIALPEER_INFO_SPEECH
262322: *Mar 2 05:44:21.674: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=3373
262323: *Mar 2 05:44:21.674: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
262324: *Mar 2 05:44:21.674: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
262325: *Mar 2 05:44:21.674: //-1/xxxxxxxxxxxx/DPM/dpMatchPeers:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=300
2: Dial-peer Tag=301
262326: *Mar 2 05:44:21.674: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=5039, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
262327: *Mar 2 05:44:21.678: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=601
262328: *Mar 2 05:44:21.678: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
262329: *Mar 2 05:44:21.678: //13957/FA5CDA800000/CCAPI/ccCallConnect:
Call Entry(Connected=TRUE, Responsed=TRUE)
262330: *Mar 2 05:44:21.678: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=3373, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
262331: *Mar 2 05:44:21.678: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=300
262332: *Mar 2 05:44:21.678: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
262333: *Mar 2 12:44:26.522: %VOICE_IEC-3-GW: SIP: Internal Error (Socket error): IEC=1.1.186.7.7.5 on callID 13957 GUID=FA5CDA8000010000000000970B66410A
262334: *Mar 2 05:44:26.522: //13957/FA5CDA800000/CCAPI/cc_api_call_disconnected:
Cause Value=38, Interface=0x22811784, Call Id=13957
262335: *Mar 2 05:44:26.522: //13957/FA5CDA800000/CCAPI/cc_api_call_disconnected:
Call Entry(Responsed=TRUE, Cause Value=38, Retry Count=0)
262336: *Mar 2 05:44:26.522: //13957/FA5CDA800000/CCAPI/ccConferenceDestroy:
Conference Id=0x169, Tag=0x0
262337: *Mar 2 05:44:26.522: //13957/FA5CDA800000/CCAPI/ccConferenceDestroy:

262338: *Mar 2 05:44:26.522: confID:0x169; callEntry1 callID1:0x3685, type:3; callEntry2 callID2:0x3686, type:3

262339: *Mar 2 05:44:26.522: //13957/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

262340: *Mar 2 05:44:26.522: cc_api_get_xcode_stream : 4981
262341: *Mar 2 05:44:26.522: //13958/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

262342: *Mar 2 05:44:26.522: cc_api_get_xcode_stream : 4981
262343: *Mar 2 05:44:26.522: //13957/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

262344: *Mar 2 05:44:26.522: cc_api_get_xcode_stream : 4981
262345: *Mar 2 05:44:26.522: //13957/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

262346: *Mar 2 05:44:26.522: cc_api_get_xcode_stream : 4981
262347: *Mar 2 05:44:26.522: //13957/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

262348: *Mar 2 05:44:26.522: cc_api_get_xcode_stream : 4981
262349: *Mar 2 05:44:26.522: //13957/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

262350: *Mar 2 05:44:26.522: cc_api_get_xcode_stream : 4981
262351: *Mar 2 05:44:26.522: //13957/FA5CDA800000/CCAPI/cc_api_bridge_drop_done:
Conference Id=0x169, Source Interface=0x22811784, Source Call Id=13957,
Destination Call Id=13958, Disposition=0x0, Tag=0x0
262352: *Mar 2 05:44:26.522: //13958/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

262353: *Mar 2 05:44:26.522: cc_api_get_xcode_stream : 4981
262354: *Mar 2 05:44:26.522: //13957/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

262355: *Mar 2 05:44:26.522: cc_api_get_xcode_stream : 4981
262356: *Mar 2 05:44:26.522: //13958/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

262357: *Mar 2 05:44:26.522: cc_api_get_xcode_stream : 4981
262358: *Mar 2 05:44:26.522: //13958/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

262359: *Mar 2 05:44:26.522: cc_api_get_xcode_stream : 4981
262360: *Mar 2 05:44:26.522: //13958/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

262361: *Mar 2 05:44:26.522: cc_api_get_xcode_stream : 4981
262362: *Mar 2 05:44:26.522: //13958/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

262363: *Mar 2 05:44:26.522: cc_api_get_xcode_stream : 4981
262364: *Mar 2 05:44:26.522: //13958/FA5CDA800000/CCAPI/cc_api_bridge_drop_done:
Conference Id=0x169, Source Interface=0x22811784, Source Call Id=13958,
Destination Call Id=13957, Disposition=0x0, Tag=0x0
262365: *Mar 2 05:44:26.522: //13957/FA5CDA800000/CCAPI/cc_generic_bridge_done:
Conference Id=0x169, Source Interface=0x22811784, Source Call Id=13958,
Destination Call Id=13957, Disposition=0x0, Tag=0x0
262366: *Mar 2 05:44:26.522: //13957/FA5CDA800000/CCAPI/ccCallDisconnect:
Cause Value=38, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=38)
262367: *Mar 2 05:44:26.522: //13957/FA5CDA800000/CCAPI/ccCallDisconnect:
Cause Value=38, Call Entry(Responsed=TRUE, Cause Value=38)
262368: *Mar 2 05:44:26.522: //13958/FA5CDA800000/CCAPI/ccCallDisconnect:
Cause Value=38, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
262369: *Mar 2 05:44:26.522: //13958/FA5CDA800000/CCAPI/ccCallDisconnect:
Cause Value=38, Call Entry(Responsed=TRUE, Cause Value=38)
262370: *Mar 2 05:44:26.522: //13957/FA5CDA800000/CCAPI/cc_api_call_disconnect_done:
Disposition=-11, Interface=0x22811784, Tag=0x0, Call Id=13957,
Call Entry(Disconnect Cause=38, Voice Class Cause Code=0, Retry Count=0)
262371: *Mar 2 05:44:26.522: //13957/FA5CDA800000/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
262372: *Mar 2 05:44:26.526: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

262373: *Mar 2 05:44:26.526: :cc_free_feature_vsa freeing 2479AC38
262374: *Mar 2 05:44:26.526: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

262375: *Mar 2 05:44:26.526: vsacount in free is 1
262376: *Mar 2 05:44:26.526: //13957/FA5CDA800000/SIP/Call/sipSPICallInfo:
The Call Setup Information is:
Call Control Block (CCB) : 0x0x23802DE8
State of The Call : STATE_DEAD
TCP Sockets Used : YES
Calling Number : 3373
Called Number : 5039
Source IP Address (Sig 22.22.22.22
Destn SIP Req Addr:Port : 10.65.102.11:5060
Destn SIP Resp Addr:Port : 10.65.102.11:45137
Destination Name : 10.65.102.11

262377: *Mar 2 05:44:26.526: //13957/FA5CDA800000/SIP/Call/sipSPIMediaCallInfo:
Number of Media Streams: 1
Media Stream : 1
Negotiated Codec : g711ulaw
Negotiated Codec Bytes : 160
Nego. Codec payload : 0 (tx), 0 (rx)
Negotiated Dtmf-relay : 6
Dtmf-relay Payload : 101 (tx), 101 (rx)
Source IP Address (Media): 22.22.22.22
Source IP Port (Media): 18052
Destn IP Address (Media): 10.65.102.10
Destn IP Port (Media): 25110
Orig Destn IP Address:Port (Media): [ - ]:0

262378: *Mar 2 05:44:26.526: //13957/FA5CDA800000/SIP/Call/sipSPICallInfo:
Disconnect Cause (CC) : 38
Disconnect Cause (SIP) : 200

262379: *Mar 2 05:44:26.526: //13958/FA5CDA800000/SIP/Msg/ccsipDisplayMsg:
Sent:
BYE sip:12.12.12.12:5060 SIP/2.0
Via: SIP/2.0/UDP 22.22.22.22:5060;branch=z9hG4bK7571B04
From: <sip:3373@voip.itsp1.com>;tag=38524DD4-186F
To: <sip:5039@12.12.12.12>;tag=213a87e3-7ccd-469a-b177-88b4706f22ee
Date: Thu, 02 Mar 2023 12:44:21 GMT
Call-ID: C9FF407A-B82E11ED-BBAFAB5E-195B8164@voip.itsp1.com
User-Agent: Cisco-SIPGateway/IOS-15.7.3.M3
Max-Forwards: 70
P-Asserted-Identity: <sip:3373@voip.itsp1.com>
Timestamp: 1677761066
CSeq: 102 BYE
Reason: Q.850;cause=38
P-RTP-Stat: PS=0,OS=0,PR=218,OR=34880,PL=0,JI=0,LA=0,DU=4
Session-ID: bec855b57ab451688867fbbadf5caadd;remote=5333aeefa2415ed8ba456255a062bc5f
Content-Length: 0

262380: *Mar 2 05:44:26.558: //13958/FA5CDA800000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 22.22.22.22:5060;rport=5060;received=22.22.22.22;branch=z9hG4bK7571B04
Call-ID: C9FF407A-B82E11ED-BBAFAB5E-195B8164@voip.itsp1.com
From: <sip:3373@voip.itsp1.com>;tag=38524DD4-186F
To: <sip:5039@12.12.12.12>;tag=213a87e3-7ccd-469a-b177-88b4706f22ee
CSeq: 102 BYE
Server: FPBX-15.0.23(16.28.0)
Content-Length: 0

262381: *Mar 2 05:44:26.558: //13958/FA5CDA800000/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x22811784, Tag=0x0, Call Id=13958,
Call Entry(Disconnect Cause=38, Voice Class Cause Code=0, Retry Count=0)
262382: *Mar 2 05:44:26.558: //13958/FA5CDA800000/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
262383: *Mar 2 05:44:26.558: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

262384: *Mar 2 05:44:26.558: :cc_free_feature_vsa freeing 2479A618
262385: *Mar 2 05:44:26.558: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

262386: *Mar 2 05:44:26.558: vsacount in free is 0
262387: *Mar 2 05:44:26.558: //13958/FA5CDA800000/SIP/Call/sipSPICallInfo:
The Call Setup Information is:
Call Control Block (CCB) : 0x0x237D5A10
State of The Call : STATE_DEAD
TCP Sockets Used : NO
Calling Number : 3373
Called Number : 5039
Source IP Address (Sig 22.22.22.22
Destn SIP Req Addr:Port : 12.12.12.12:5060
Destn SIP Resp Addr:Port : 12.12.12.12:5060
Destination Name : 12.12.12.12

262388: *Mar 2 05:44:26.558: //13958/FA5CDA800000/SIP/Call/sipSPIMediaCallInfo:
Number of Media Streams: 1
Media Stream : 1
Negotiated Codec : g711ulaw
Negotiated Codec Bytes : 160
Nego. Codec payload : 0 (tx), 0 (rx)
Negotiated Dtmf-relay : 6
Dtmf-relay Payload : 101 (tx), 101 (rx)
Source IP Address (Media): 22.22.22.22
Source IP Port (Media): 18054
Destn IP Address (Media): 12.12.12.12
Destn IP Port (Media): 15968
Orig Destn IP Address:Port (Media): [ - ]:0

262389: *Mar 2 05:44:26.558: //13958/FA5CDA800000/SIP/Call/sipSPICallInfo:
Disconnect Cause (CC) : 38
Disconnect Cause (SIP) : 200

VoiceRouter1#
VoiceRouter1#sh deb
VoiceRouter1#sh debugging u
^
% Invalid input detected at '^' marker.

VoiceRouter1#
262390: *Mar 2 05:45:14.406: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
OPTIONS sip:22.22.22.22:5060 SIP/2.0
Via: SIP/2.0/UDP 12.12.12.12:5060;rport;branch=z9hG4bKPjbbe38e21-2fb2-44cb-ad82-3bc71ad14188
From: <sip:CUCM01@22.22.22.22>;tag=034f9797-0289-4483-8118-7824b425afc5
To: <sip:22.22.22.22>
Contact: <sip:CUCM01@12.12.12.12:5060>
Call-ID: f9ee1614-cb31-446a-8eb5-79e03354ba35
CSeq: 6733 OPTIONS
Max-Forwards: 70
User-Agent: FPBX-15.0.23(16.28.0)
Content-Length: 0

262391: *Mar 2 05:45:14.410: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=CUCM01, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
262392: *Mar 2 05:45:14.410: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
262393: *Mar 2 05:45:14.410: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
262394: *Mar 2 05:45:14.410: //-1/E98419E9BBB0/DPM/dpAssociateIncomingPeerCore:
Calling Number=CUCM01, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
262395: *Mar 2 05:45:14.410: //-1/E98419E9BBB0/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
262396: *Mar 2 05:45:14.410: //-1/E98419E9BBB0/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
262397: *Mar 2 05:45:14.410: //13959/E98419E9BBB0/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 12.12.12.12:5060;rport;branch=z9hG4bKPjbbe38e21-2fb2-44cb-ad82-3bc71ad14188
From: <sip:CUCM01@22.22.22.22>;tag=034f9797-0289-4483-8118-7824b425afc5
To: <sip:22.22.22.22>;tag=38531C64-1CF5
Date: Thu, 02 Mar 2023 12:45:14 GMT
Call-ID: f9ee1614-cb31-446a-8eb5-79e03354ba35
Server: Cisco-SIPGateway/IOS-15.7.3.M3
CSeq: 6733 OPTIONS
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
sh debugging Accept: application/sdp
Supported: 100rel,timer,resource-priority,replaces,sdp-anat
Content-Type: application/sdp
Content-Length: 163

v=0
o=CiscoSystemsSIP-GW-UserAgent 5334 674 IN IP4 11.11.11.11
s=SIP Call
c=IN IP4 11.11.11.11
t=0 0
m=audio 0 RTP/AVP 18 0 8 9 4 2 15
c=IN IP4 11.11.11.11

---

@b.winter wrote:

Ok, just assumed, because you wrote "DT".

Honest opinion:
Upgrade the router to the latest IOS and try to use tenant config.

About your problem:
You already have the logs of a call with the suggested debugs enabled?

---

[Roger Kallberg]

When you say it is not allowing you to upload the text file can you please share the error message that you get from the community. I suspect that you might not be waiting long enough for the built in virus scanner in the community to complete before you try to post the response.

[Robert Profit]

It looks like a connectivity issue as can be seen here on your CUBE, before you send the ISP the error 38:
262333: *Mar 2 12:44:26.522: %VOICE_IEC-3-GW: SIP: Internal Error (Socket error): IEC=1.1.186.7.7.5 on callID 13957 GUID=FA5CDA8000010000000000970B66410A

Incoming dialed number takes priority, thus it matching dial-peer 601 on both inbound and outbound call legs.

After the received invite from your CUCM, it matches on Inbound 601:
262138: *Mar 2 05:44:21.514: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:5039@10.65.12.3:5060 SIP/2.0
/snip/
262151: *Mar 2 05:44:21.518: //-1/FA5CDA800000/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=601

Then it matches the same dial-peer on the outbound:
262202: *Mar 2 05:44:21.526: //13957/FA5CDA800000/CCAPI/ccCallSetupRequest:
Destination=, Calling IE Present=TRUE, Mode=0,
Outgoing Dial-peer=601

I would modify your dial-peer 601 or modify your trunks from CUCM to point to that 0/0.10 IP address if that is the intended flow.

V/R,
Rob Profit

[Wonxie]

so the problem is with dial peer ? that it acts as inbound and outbound leg for same call ?

the call flow diagram is as per above where the ext 5039 is at the DT side.

and the ext 3373 is at our side on cucm. 

ext 3373 from cucm gets routed to CUBE and it forwards to the DT .

i did a test call and when in reverse directoin i.e. from DT to cucm and i was able to pick the call but i couldnt hear his voice he could hear me.

i can change the dial-peer as below does this look ok? in this case 601 will match on incoming side and 600 on outgoings side .will it work ?

dial-peer voice 601 voip
description ***Testing DT ****
incoming called-number 5039

session protocol sipv2

session transport udp
voice-class codec 1
dtmf-relay rtp-nte
no vad

!
dial-peer voice 600 voip
description SIP Outbound peer to DT-Network
destination-pattern 503.$
session protocol sipv2
session target ipv4:12.12.12.12:5060
session transport udp
voice-class codec 1
voice-class sip bind control source-interface GigabitEthernet0/0.10
voice-class sip bind media source-interface GigabitEthernet0/0.10
dtmf-relay rtp-nte
!
!

[Robert Profit · Accepted solution]

Dial-peers can be used for inbound and outbound, although I personally don't like doing it that way for troubleshooting purposes. I believe the dial-peer is creating a problem because it is trying to bind sip from the inside/inbound call leg to that (I am assuming) outside interface 0/0.10, even though it received it from your CUCM on the gi0/1 (10.65.12.3) interface.

So when CUBE attempts to create the session it may not have reachability from that outside interface, where the media is now bound on that call leg, to your inside network/subnet, just one example.

Additionally, just because the 'distant end' (DT) can call you, doesn't mean you can call them.

I would test again after that modification of your dial-peer.
V/R,
Rob Profit

[Dmytro Benda · Accepted solution]

Hi @Wonxie 

I completely agree with Robert Profit - your problem most likely because of the same dial-peer 601, which is selected for the inbound call leg and then for outbound call leg. Typically, CUBE establishes two Voip call legs and BRIDGES two different RTP stream together: 

1) RTP1 should be established between the CUBE and DT and it seems to be ok from your debug

262258: *Mar 2 05:44:21.670: //13958/FA5CDA800000/SIP/Call/sipSPIMediaCallInfo:
Number of Media Streams: 1
Media Stream : 1
Negotiated Codec : g711ulaw
Negotiated Codec Bytes : 160
Nego. Codec payload : 0 (tx), 0 (rx)
Negotiated Dtmf-relay : 6
Dtmf-relay Payload : 101 (tx), 101 (rx)
Source IP Address (Media): 22.22.22.22
Source IP Port (Media): 18054
Destn IP Address (Media): 12.12.12.12
Destn IP Port (Media): 15968
Orig Destn IP Address:Port (Media): [ - ]:0

2) RTP2 should be between the CUBE and internal phone or CUCM (in case if you use MTP in the call). But this RTP leg is not ok and we see it in the debug

262377: *Mar 2 05:44:26.526: //13957/FA5CDA800000/SIP/Call/sipSPIMediaCallInfo:
Number of Media Streams: 1
Media Stream : 1
Negotiated Codec : g711ulaw
Negotiated Codec Bytes : 160
Nego. Codec payload : 0 (tx), 0 (rx)
Negotiated Dtmf-relay : 6
Dtmf-relay Payload : 101 (tx), 101 (rx)
Source IP Address (Media): 22.22.22.22
Source IP Port (Media): 18052
Destn IP Address (Media): 10.65.102.10
Destn IP Port (Media): 25110
Orig Destn IP Address:Port (Media): [ - ]:0

The source media address is not correct in this RTP leg. It shows the external interface IP address, but for the second call leg the voice should be sent from internal interface Gi0/1 (10.65.12.3). This happened because you select the same dial-peer for inbound and outbound legs, and bind there Gi0/0.10 for media. That's why for both legs the CUBE uses same IP for media. Therefore the second RTP leg is not formed and call fails in 5 secs after connect. 

So, Robert already recommended you to change you dial-peer configuration to have two different dial-peers for inbound and outbound legs, which allows you to provide different IP's for media traffic. Let me offer you a variant of the configuration (you have to modify existing dial-peer 601 and use it as outbound dial-peer only. Also you will add a new dial-peer 602 as incoming dial-peer):

dial-peer voice 601 voip
description ***Testing DT ****
destination-pattern ^5039
session protocol sipv2
session target ipv4:12.12.12.12:5060
session transport udp
voice-class codec 1
voice-class sip bind control source-interface GigabitEthernet0/0.10
voice-class sip bind media source-interface GigabitEthernet0/0.10
dtmf-relay rtp-nte
no vad
!
dial-peer voice 602 voip
description Inbound testing peer match FROM CUCM to DT
session protocol sipv2
session target sip-server
session transport udp
incoming called-number 5039
voice-class codec 1
dtmf-relay rtp-nte
no vad

My Cisco Unified Communications Blog

[Wonxie]

Immense thanks @Robert Profit  @Dmytro Benda it realy was this dial peer issue. after applying the configurations its now resolved.

[Robert Profit]

Fantastic news and glad to hear everything is working now!

V/R,
Rob Profit