SIP Trunk Incoming Call Problem

[Fernaldy Fernaldy]

Hi, I have issue on SIP Trunk Call Problem.

For outgoing call, I can call easily, but for Incoming call from my phone to IP Phone, it's always failed.

From the debug I knew the dial peer is failed, but I don't know on what's dial peer that error.

I call from Phone number to Cisco IP Phone.

Heres the debug : 

*Jan 10 02:38:26.383: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:02185220091@10.14.7.98:5060 SIP/2.0
Via: SIP/2.0/UDP 10.14.7.97:5060;branch=z9hG4bK08Bb0e91b55b79a8beb
From: <sip:081316793938@10.14.7.97>;tag=gK083b61f1
To: <sip:02185220091@10.14.7.98>
Call-ID: 21507918_132904385@10.14.7.97
CSeq: 902669 INVITE
Max-Forwards: 70
Allow: INVITE,ACK,CANCEL,BYE,REGISTER,REFER,INFO,SUBSCRIBE,NOTIFY,PRACK,UPDATE,O PTIONS,MESSAGE,PUBLISH
Accept: application/sdp, application/isup, application/dtmf, application/dtmf-re lay, multipart/mixed
Contact: <sip:081316793938@10.14.7.97:5060>
P-Preferred-Identity: <sip:081316793938@10.14.7.97:5060>
Supported: timer,100rel,precondition,replaces
Session-Expires: 1800
Min-SE: 90
Content-Length: 258
Content-Disposition: session; handling=required
Content-Type: application/sdp

v=0
o=Sonus_UAC 964876 307958 IN IP4 10.14.7.97
s=SIP Media Capabilities
c=IN IP4 10.14.7.97
t=0 0
m=audio 16562 RTP/AVP 8 0 100
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:100 telephone-event/8000
a=fmtp:100 0-15
a=sendrecv
a=maxptime:20

*Jan 10 02:38:26.384: //20992/2B472CFE936D/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SESSION_REFRESH_REQ
*Jan 10 02:38:26.384: //20992/2B472CFE936D/SIP/Event/Session-Timer/sipSTSLMain: dir:2, method:102, resp_code:0, container:7F76E323CE30
*Jan 10 02:38:26.384: //20992/2B472CFE936D/SIP/Event/Session-Timer/sipSTSLPrintT DContainer: Peer-Event: E_STSL_PASS_ST_PARAMS, SE Value:1800, SE Refresher:none, Min-SE Value:1800, flags:2001
*Jan 10 02:38:26.385: //20992/2B472CFE936D/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SESSION_REFRESH_RESP
*Jan 10 02:38:26.385: //20992/2B472CFE936D/SIP/Event/Session-Timer/sipSTSLMain: dir:1, method:102, resp_code:100, container:7F76E323AC78
*Jan 10 02:38:26.385: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_PROCEEDING
*Jan 10 02:38:26.386: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_IWF
*Jan 10 02:38:26.387: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_SETUP
*Jan 10 02:38:26.387: //20993/2B472CFE936D/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SESSION_REFRESH_REQ
*Jan 10 02:38:26.387: //20993/2B472CFE936D/SIP/Event/Session-Timer/sipSTSLMain: dir:1, method:102, resp_code:0, container:7F76E323CB58
*Jan 10 02:38:26.387: //20993/2B472CFE936D/SIP/Event/Session-Timer/sipSTSLPrintT DContainer: Peer-Event: E_STSL_PASS_ST_PARAMS, SE Value:1800, SE Refresher:none, Min-SE Value:1800, flags:2001
*Jan 10 02:38:26.388: //20993/2B472CFE936D/SIP/Event/sipSPICreateRpid: Received Octet3A=0x80 -> Setting ;screen=no ;privacy=off
*Jan 10 02:38:26.388: //20993/2B472CFE936D/SIP/Msg/ccsipDisplayMsg:
Sent:
INVITE sip:5091@10.103.255.11:5060 SIP/2.0
Via: SIP/2.0/TCP 10.106.62.10:5060;branch=z9hG4bK641822
Remote-Party-ID: <sip:9081316793938@10.106.62.10>;party=calling;screen=no;privac y=off
From: <sip:9081316793938@10.106.62.10>;tag=DB4DD1C-1552
To: <sip:5091@10.103.255.11>
Date: Wed, 10 Jan 2018 09:38:26 GMT
Call-ID: 2B47C925-F4E611E7-93739079-FAD80D8@10.106.62.10
Supported: timer,resource-priority,replaces,sdp-anat
Min-SE: 1800
Cisco-Guid: 0726084862-4108718567-2473431161-0263028952
User-Agent: Cisco-SIPGateway/IOS-15.5.3.S4b
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIF Y, INFO, REGISTER
CSeq: 101 INVITE
Timestamp: 1515551906
Contact: <sip:9081316793938@10.106.62.10:5060;transport=tcp>
Expires: 180
Allow-Events: telephone-event
Max-Forwards: 69
Session-Expires: 1800
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 295

v=0
o=CiscoSystemsSIP-GW-UserAgent 2810 4252 IN IP4 10.106.62.10
s=SIP Call
c=IN IP4 10.106.62.10
t=0 0
m=audio 8338 RTP/AVP 0 100 101
c=IN IP4 10.106.62.10
a=rtpmap:0 PCMU/8000
a=rtpmap:100 X-NSE/8000
a=fmtp:100 192-194
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20

*Jan 10 02:38:26.389: //20992/2B472CFE936D/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 10.14.7.97:5060;branch=z9hG4bK08Bb0e91b55b79a8beb
From: <sip:081316793938@10.14.7.97>;tag=gK083b61f1
To: <sip:02185220091@10.14.7.98>
Date: Wed, 10 Jan 2018 09:38:26 GMT
Call-ID: 21507918_132904385@10.14.7.97
CSeq: 902669 INVITE
Allow-Events: telephone-event
Server: Cisco-SIPGateway/IOS-15.5.3.S4b
Content-Length: 0

*Jan 10 02:38:26.393: //20993/2B472CFE936D/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 100 Trying
Via: SIP/2.0/TCP 10.106.62.10:5060;branch=z9hG4bK641822
From: <sip:9081316793938@10.106.62.10>;tag=DB4DD1C-1552
To: <sip:5091@10.103.255.11>
Date: Wed, 10 Jan 2018 02:37:54 GMT
Call-ID: 2B47C925-F4E611E7-93739079-FAD80D8@10.106.62.10
CSeq: 101 INVITE
Allow-Events: presence
Content-Length: 0

*Jan 10 02:38:26.396: //20993/2B472CFE936D/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 404 Not Found
Via: SIP/2.0/TCP 10.106.62.10:5060;branch=z9hG4bK641822
From: <sip:9081316793938@10.106.62.10>;tag=DB4DD1C-1552
To: <sip:5091@10.103.255.11>;tag=440835~f1f50254-faab-4904-8c2b-9027004dbbb4-245 66484
Date: Wed, 10 Jan 2018 02:37:54 GMT
Call-ID: 2B47C925-F4E611E7-93739079-FAD80D8@10.106.62.10
CSeq: 101 INVITE
Allow-Events: presence
Reason: Q.850;cause=1
Server: Cisco-CUCM10.5
Content-Length: 0

*Jan 10 02:38:26.396: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT
*Jan 10 02:38:26.396: //20993/2B472CFE936D/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SPI_EVENT
*Jan 10 02:38:26.397: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT
*Jan 10 02:38:26.397: //20992/2B472CFE936D/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SESSION_REFRESH_RESP
*Jan 10 02:38:26.397: //20992/2B472CFE936D/SIP/Event/Session-Timer/sipSTSLMain: dir:1, method:102, resp_code:404, container:7F76E323B7D8
*Jan 10 02:38:26.397: //20992/2B472CFE936D/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 404 Not Found
Via: SIP/2.0/UDP 10.14.7.97:5060;branch=z9hG4bK08Bb0e91b55b79a8beb
From: <sip:081316793938@10.14.7.97>;tag=gK083b61f1
To: <sip:02185220091@10.14.7.98>;tag=DB4DD25-14C9
Date: Wed, 10 Jan 2018 09:38:26 GMT
Call-ID: 21507918_132904385@10.14.7.97
CSeq: 902669 INVITE
Allow-Events: telephone-event
Warning: 399 10.14.7.98 "No matching outgoing dial-peer"
Server: Cisco-SIPGateway/IOS-15.5.3.S4b
Reason: Q.850;cause=1
Content-Length: 0

*Jan 10 02:38:26.398: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
ACK sip:5091@10.103.255.11:5060 SIP/2.0
Via: SIP/2.0/TCP 10.106.62.10:5060;branch=z9hG4bK641822
From: <sip:9081316793938@10.106.62.10>;tag=DB4DD1C-1552
To: <sip:5091@10.103.255.11>;tag=440835~f1f50254-faab-4904-8c2b-9027004dbbb4-245 66484
Date: Wed, 10 Jan 2018 09:38:26 GMT
Call-ID: 2B47C925-F4E611E7-93739079-FAD80D8@10.106.62.10
Max-Forwards: 70
CSeq: 101 ACK
Allow-Events: telephone-event
Content-Length: 0

*Jan 10 02:38:26.400: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
ACK sip:02185220091@10.14.7.98:5060 SIP/2.0
Via: SIP/2.0/UDP 10.14.7.97:5060;branch=z9hG4bK08Bb0e91b55b79a8beb
From: <sip:081316793938@10.14.7.97>;tag=gK083b61f1
To: <sip:02185220091@10.14.7.98>;tag=DB4DD25-14C9
Call-ID: 21507918_132904385@10.14.7.97
CSeq: 902669 ACK
Max-Forwards: 70
Content-Length: 0

*Jan 10 02:45:39.128: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=02185220091, Called Number=02185220091, Peer Info Type=DIALPEE R_INFO_SPEECH
*Jan 10 02:45:39.128: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=02185220091
*Jan 10 02:45:39.128: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
No Outgoing Dial-peer Is Matched; Result=NO_MATCH(-1)
*Jan 10 02:45:39.128: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=02185220091, saf_enabled=1, saf_dndb_lookup=1, dp_result=-1
*Jan 10 02:45:39.128: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=NO_MATCH(-1)
*Jan 10 02:45:39.129: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=081316793938, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
*Jan 10 02:45:39.129: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
*Jan 10 02:45:39.129: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
*Jan 10 02:45:39.129: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=081316793938, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
*Jan 10 02:45:39.129: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=NO_MATCH(-1) After All Match Rules Attempt
*Jan 10 02:45:39.129: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
*Jan 10 02:45:39.129: //-1/2D36D2E19389/DPM/dpAssociateIncomingPeerCore:
Calling Number=081316793938, Called Number=02185220091, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
*Jan 10 02:45:39.129: //-1/2D36D2E19389/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_FROM_URI; Incoming Dial-peer=210
*Jan 10 02:45:39.129: //-1/2D36D2E19389/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
*Jan 10 02:45:39.130: //-1/xxxxxxxxxxxx/DPM/dpGetSIPUriProvisionBmp:
Tag=210
*Jan 10 02:45:39.130: //-1/xxxxxxxxxxxx/DPM/dpGetSIPUriProvisionBmp:
Result=0 Bitmap=0x0
*Jan 10 02:45:39.132: //-1/2D36D2E19389/DPM/dpMatchPeersCore:
Calling Number=, Called Number=5091, Peer Info Type=DIALPEER_INFO_SPEECH
*Jan 10 02:45:39.132: //-1/2D36D2E19389/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=5091
*Jan 10 02:45:39.132: //-1/2D36D2E19389/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
*Jan 10 02:45:39.132: //-1/2D36D2E19389/DPM/dpMatchSafModulePlugin:
dialstring=5091, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
*Jan 10 02:45:39.132: //-1/2D36D2E19389/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=300
*Jan 10 02:45:39.132: //-1/2D36D2E19389/DPM/dpMatchPeersCore:
Calling Number=, Called Number=5091, Peer Info Type=DIALPEER_INFO_SPEECH
*Jan 10 02:45:39.132: //-1/2D36D2E19389/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=5091
*Jan 10 02:45:39.132: //-1/2D36D2E19389/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
*Jan 10 02:45:39.132: //-1/2D36D2E19389/DPM/dpMatchSafModulePlugin:
dialstring=5091, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
*Jan 10 02:45:39.132: //-1/2D36D2E19389/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=300
*Jan 10 02:45:39.132: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=5091, Called Number=5091, Peer Info Type=DIALPEER_INFO_SPEECH
*Jan 10 02:45:39.132: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=5091
*Jan 10 02:45:39.132: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
*Jan 10 02:45:39.132: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=5091, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
*Jan 10 02:45:39.132: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=300
*Jan 10 02:45:39.132: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=5091, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
*Jan 10 02:45:39.132: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=300
*Jan 10 02:45:39.132: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
*Jan 10 02:45:39.132: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=5091, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
*Jan 10 02:45:39.132: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=300
*Jan 10 02:45:39.132: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
*Jan 10 02:45:39.132: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=, Called Number=5091, Peer Info Type=DIALPEER_INFO_SPEECH
*Jan 10 02:45:39.132: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=5091
*Jan 10 02:45:39.132: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
*Jan 10 02:45:39.132: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=5091, saf_enabled=0, saf_dndb_lookup=1, dp_result=0
*Jan 10 02:45:39.133: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=300
*Jan 10 02:45:39.133: //-1/2D36D2E19389/DPM/dpMatchPeersCore:
Calling Number=, Called Number=5091, Peer Info Type=DIALPEER_INFO_SPEECH
*Jan 10 02:45:39.133: //-1/2D36D2E19389/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=5091
*Jan 10 02:45:39.133: //-1/2D36D2E19389/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
*Jan 10 02:45:39.133: //-1/2D36D2E19389/DPM/dpMatchSafModulePlugin:
dialstring=5091, saf_enabled=1, saf_dndb_lookup=1, dp_result=0
*Jan 10 02:45:39.133: //-1/2D36D2E19389/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=300

My VG Config is on attachment.

Please help regarding the issue.

[Aeby Vinod · Accepted solution]

The issue is not with your gateway config, the called number was translated to 5091 as configured on the translation rules on the gw and send to CUCM, and CUCM send a 404 not found with a reason code of 1.

*Jan 10 02:38:26.396: //20993/2B472CFE936D/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 404 Not Found
Via: SIP/2.0/TCP 10.106.62.10:5060;branch=z9hG4bK641822
From: <sip:9081316793938@10.106.62.10>;tag=DB4DD1C-1552
To: <sip:5091@10.103.255.11>;tag=440835~f1f50254-faab-4904-8c2b-9027004dbbb4-245 66484
Date: Wed, 10 Jan 2018 02:37:54 GMT
Call-ID: 2B47C925-F4E611E7-93739079-FAD80D8@10.106.62.10
CSeq: 101 INVITE
Allow-Events: presence
Reason: Q.850;cause=1
Server: Cisco-CUCM10.5
Content-Length: 0

Check if the incoming CSS on the SIP Trunk has the partition of the DN assigned to the Phone, If it already has it, reset the SIP Trunk and make a call, if that still doesn't help please attach Callmanger traces for a failed call and we can see whats happening

Regards,

Aeby

[Fernaldy Fernaldy]

Thank you, this solution was working right now

Regards,
Fernaldy