CCME | ISP VOIP | Can make calls, can't receive them Multiple DP Match

[LuisReis08240]

Hello there!

Just got my CCME working with my C7970 and registering on the ISP VOIP trunk.

Can make calls but can't receive them. For what I can see with the debug voice dialpeer all, I get a match on multiple dial-peers:

------------------------------------------------------

May 7 23:44:58.826: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:+3519999999@192.168.1.26:5060 SIP/2.0
Via: SIP/2.0/UDP 194.65.2.241:5070;branch=z9hG4bKfpecfv20egdiibjt73j0.1
To: <sip:302004889;phone-context=ims.mnc006.mcc268.3gppnetwork.org@ims.mnc006.mcc268.3gppnetwork.org;user=phone>
From: sip:965432532@ims.mnc006.mcc268.3gppnetwork.org;tag=p65541t1683503098m837421c7294s1_472871982-1146061305
Call-ID: p65541t1683503098m837421c7294s2
CSeq: 1 INVITE
Max-Forwards: 58
Content-Length: 311
Contact: <sip:p65541t1683503098m837421c7294s1@194.65.2.241:5070;transport=udp>;+g.3gpp.accesstype="cellular";+sip.instance="<urn:gsma:imei:35052585-166354-0>";+g.3gpp.icsi-ref="urn%3Aurn-7%3A3gpp-service.ims.icsi.mmtel"
Content-Type: application/sdp
Allow: REGISTER, REFER, NOTIFY, SUBSCRIBE, UPDATE, INFO, MESSAGE, PRACK, INVITE, ACK, OPTIONS, CANCEL, BYE
Accept: application/sdp
Supported: timer, 100rel, sec-agree
Min-SE: 900
Session-Expires: 1800
P-Called-Party-ID: <sip:+3519999999@sip.sapo.pt>
P-Early-Media: supported
Session-ID: 919d162b3a7ae5b8d931f271f2e5dd0c
P-Charging-Vector: icid-value=7m0ft146p1ds2rk6ehcs2pg6hhoamfde9rpulo462rgmb6o5;icid-generated-at=194.65.2.241

v=0
o=- 841751549426025 476783893 IN IP4 194.65.2.241
s=SS VOIP
c=IN IP4 194.65.2.241
t=0 0
m=audio 36976 RTP/AVP 8 0 18 110
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=yes
a=rtpmap:110 telephone-event/8000
a=fmtp:110 0-15
a=sendrecv
a=maxptime:40
a=ptime:20

May 7 23:44:58.826: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Calling Number=+3519999999, Called Number=+3519999999, Peer Info Type=DIALPEER_INFO_SPEECH
May 7 23:44:58.826: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Match Rule=DP_MATCH_DEST; Called Number=+3519999999
May 7 23:44:58.826: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=+3519999999, Expanded String=+3519999999, Calling Number=+3519999999T
Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
May 7 23:44:58.826: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Outgoing Dial-peer=20001 Is Matched
May 7 23:44:58.826: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
Result=Success(0) after DP_MATCH_DEST
May 7 23:44:58.826: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=+3519999999, saf_enabled=1, saf_dndb_lookup=1, dp_result=0
May 7 23:44:58.826: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
Result=SUCCESS(0)
List of Matched Outgoing Dial-peer(s):
1: Dial-peer Tag=20001
May 7 23:44:58.830: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=965432532, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
May 7 23:44:58.830: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ANSWER; Calling Number=965432532
May 7 23:44:58.830: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
May 7 23:44:58.830: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=965432532T
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
May 7 23:44:58.830: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Result=-1
May 7 23:44:58.830: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@6076
May 7 23:44:58.830: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ORIGINATE; Calling Number=965432532
May 7 23:44:58.830: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
May 7 23:44:58.830: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=965432532T
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
May 7 23:44:58.830: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Incoming Dial-peer=9 Is Matched
May 7 23:44:58.830: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@6076
May 7 23:44:58.830: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=9
May 7 23:44:58.830: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
May 7 23:44:58.830: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:exit@6704
May 7 23:44:58.830: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=965432532, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
May 7 23:44:58.830: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ANSWER; Calling Number=965432532
May 7 23:44:58.830: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
May 7 23:44:58.830: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=965432532T
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
May 7 23:44:58.830: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Result=-1
May 7 23:44:58.830: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@6076
May 7 23:44:58.830: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ORIGINATE; Calling Number=965432532
May 7 23:44:58.830: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
May 7 23:44:58.830: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=965432532T
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
May 7 23:44:58.830: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Incoming Dial-peer=9 Is Matched
May 7 23:44:58.830: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@6076
May 7 23:44:58.830: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=9
May 7 23:44:58.830: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
May 7 23:44:58.830: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:exit@6704
May 7 23:44:58.830: //-1/02EE1EEA8062/DPM/dpAssociateIncomingPeerCore:
Calling Number=, Called Number=+3519999999, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
May 7 23:44:58.830: //-1/02EE1EEA8062/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_INCOMING_DNIS; Called Number=+3519999999
May 7 23:44:58.830: //-1/02EE1EEA8062/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
May 7 23:44:58.830: //-1/02EE1EEA8062/DPM/dpMatchCore:
Dial String=+3519999999, Expanded String=+3519999999, Calling Number=
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
May 7 23:44:58.830: //-1/02EE1EEA8062/DPM/MatchNextPeer:
Result=Success(0); Incoming Dial-peer=11 Is Matched
May 7 23:44:58.830: //-1/02EE1EEA8062/DPM/dpMatchPeertype:exit@6076
May 7 23:44:58.830: //-1/02EE1EEA8062/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=11
May 7 23:44:58.830: //-1/02EE1EEA8062/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
May 7 23:44:58.830: //-1/02EE1EEA8062/DPM/dpAssociateIncomingPeerSPI:exit@6655
May 7 23:44:58.830: //-1/02EE1EEA8062/DPM/dpAssociateIncomingPeerCore:
Calling Number=965432532, Called Number=+3519999999, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
May 7 23:44:58.830: //-1/02EE1EEA8062/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_VIA_URI; URI=sip:194.65.2.241:5070
May 7 23:44:58.830: //-1/02EE1EEA8062/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
May 7 23:44:58.830: //-1/02EE1EEA8062/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
May 7 23:44:58.834: //-1/02EE1EEA8062/DPM/dpMatchCore:
Result=-1
May 7 23:44:58.834: //-1/02EE1EEA8062/DPM/dpMatchPeertype:exit@6076
May 7 23:44:58.834: //-1/02EE1EEA8062/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_REQUEST_URI; URI=sip:+3519999999@192.168.1.26:5060
May 7 23:44:58.834: //-1/02EE1EEA8062/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
May 7 23:44:58.834: //-1/02EE1EEA8062/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
May 7 23:44:58.834: //-1/02EE1EEA8062/DPM/dpMatchCore:
Result=-1
May 7 23:44:58.834: //-1/02EE1EEA8062/DPM/dpMatchPeertype:exit@6076
May 7 23:44:58.834: //-1/02EE1EEA8062/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_TO_URI; URI=sip:302004889;phone-context=ims.mnc006.mcc268.3gppnetwork.org@ims.mnc006.mcc268.3gppnetwork.org;user=phone
May 7 23:44:58.834: //-1/02EE1EEA8062/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
May 7 23:44:58.834: //-1/02EE1EEA8062/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
May 7 23:44:58.834: //-1/02EE1EEA8062/DPM/dpMatchCore:
Result=-1
May 7 23:44:58.834: //-1/02EE1EEA8062/DPM/dpMatchPeertype:exit@6076
May 7 23:44:58.834: //-1/02EE1EEA8062/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_FROM_URI; URI=sip:965432532@ims.mnc006.mcc268.3gppnetwork.org
May 7 23:44:58.834: //-1/02EE1EEA8062/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
May 7 23:44:58.834: //-1/02EE1EEA8062/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
May 7 23:44:58.834: //-1/02EE1EEA8062/DPM/dpMatchCore:
Result=-1
May 7 23:44:58.834: //-1/02EE1EEA8062/DPM/dpMatchPeertype:exit@6076
May 7 23:44:58.834: //-1/02EE1EEA8062/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_INCOMING_DNIS; Called Number=+3519999999
May 7 23:44:58.834: //-1/02EE1EEA8062/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
May 7 23:44:58.834: //-1/02EE1EEA8062/DPM/dpMatchCore:
Dial String=+3519999999, Expanded String=+3519999999, Calling Number=
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
May 7 23:44:58.834: //-1/02EE1EEA8062/DPM/MatchNextPeer:
Result=Success(0); Incoming Dial-peer=11 Is Matched
May 7 23:44:58.834: //-1/02EE1EEA8062/DPM/dpMatchPeertype:exit@6076
May 7 23:44:58.834: //-1/02EE1EEA8062/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=11
May 7 23:44:58.834: //-1/02EE1EEA8062/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
May 7 23:44:58.834: //-1/02EE1EEA8062/DPM/dpAssociateIncomingPeerSPI:exit@6655
May 7 23:44:58.834: //-1/02EE1EEA8062/CCAPI/cc_api_display_ie_subfields:
cc_api_call_setup_ind_common:
cisco-username=965432532
----- ccCallInfo IE subfields -----
cisco-ani=sip:965432532@ims.mnc006.mcc268.3gppnetwork.org;tag=p65541t1683503098m837421c7294s1_472871982-1146061305
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=0
dest=sip:+3519999999@192.168.1.26:5060
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

May 7 23:44:58.838: //-1/02EE1EEA8062/CCAPI/cc_api_call_setup_ind_common:
Interface=0x8B4216EC, Call Info(
Calling Number=sip:965432532@ims.mnc006.mcc268.3gppnetwork.org;tag=p65541t1683503098m837421c7294s1_472871982-1146061305,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=sip:+3519999999@192.168.1.26:5060(TON=Unknown, NPI=Unknown),
Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE,
Incoming Dial-peer=11, Progress Indication=NULL(0), Calling IE Present=TRUE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=96
May 7 23:44:58.838: //-1/02EE1EEA8062/CCAPI/ccCheckClipClir:
In: Calling Number=sip:965432532@ims.mnc006.mcc268.3gppnetwork.org;tag=p65541t1683503098m837421c7294s1_472871982-1146061305(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
May 7 23:44:58.838: //-1/02EE1EEA8062/CCAPI/ccCheckClipClir:
Out: Calling Number=sip:965432532@ims.mnc006.mcc268.3gppnetwork.org;tag=p65541t1683503098m837421c7294s1_472871982-1146061305(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
May 7 23:44:58.838: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

May 7 23:44:58.838: :cc_get_feature_vsa malloc success
May 7 23:44:58.838: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

May 7 23:44:58.838: cc_get_feature_vsa count is 1
May 7 23:44:58.838: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

May 7 23:44:58.838: :FEATURE_VSA attributes are: feature_name:0,feature_time:2277942160,feature_id:23
May 7 23:44:58.838: //96/02EE1EEA8062/CCAPI/cc_api_call_setup_ind_common:
Set Up Event Sent;
Call Info(Calling Number=(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=(TON=Unknown, NPI=Unknown))
May 7 23:44:58.838: //96/02EE1EEA8062/CCAPI/cc_process_call_setup_ind:
Event=0x8C1A5478
May 7 23:44:58.838: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
Try with the demoted called number +3519999999
May 7 23:44:58.838: //96/02EE1EEA8062/CCAPI/ccCallSetContext:
Context=0x87C7FA04
May 7 23:44:58.838: //96/02EE1EEA8062/CCAPI/cc_process_call_setup_ind:
>>>>CCAPI handed cid 96 with tag 11 to app "_ManagedAppProcess_TOLLFRAUD_APP"
May 7 23:44:58.838: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Calling Number=965432532, Called Number=, Voice-Interface=0x0,
Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
Peer Info Type=DIALPEER_INFO_SPEECH
May 7 23:44:58.838: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ANSWER; Calling Number=965432532
May 7 23:44:58.838: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
May 7 23:44:58.838: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=965432532T
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
May 7 23:44:58.838: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Result=-1
May 7 23:44:58.838: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@6076
May 7 23:44:58.838: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Match Rule=DP_MATCH_ORIGINATE; Calling Number=965432532
May 7 23:44:58.838: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
Is Incoming=TRUE, Number Expansion=FALSE
May 7 23:44:58.838: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
Dial String=, Expanded String=, Calling Number=965432532T
Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
May 7 23:44:58.838: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
Result=Success(0); Incoming Dial-peer=9 Is Matched
May 7 23:44:58.838: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@6076
May 7 23:44:58.838: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=9
May 7 23:44:58.838: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
May 7 23:44:58.838: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:exit@6704
May 7 23:44:58.838: //96/02EE1EEA8062/CCAPI/ccCallDisconnect:
Cause Value=21, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
May 7 23:44:58.838: //96/02EE1EEA8062/CCAPI/ccCallDisconnect:
Cause Value=21, Call Entry(Responsed=TRUE, Cause Value=21)
May 7 23:44:58.842: //96/02EE1EEA8062/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 194.65.2.241:5070;branch=z9hG4bKfpecfv20egdiibjt73j0.1
From: sip:965432532@ims.mnc006.mcc268.3gppnetwork.org;tag=p65541t1683503098m837421c7294s1_472871982-1146061305
To: <sip:302004889;phone-context=ims.mnc006.mcc268.3gppnetwork.org@ims.mnc006.mcc268.3gppnetwork.org;user=phone>
Date: Sun, 07 May 2023 23:44:58 GMT
Call-ID: p65541t1683503098m837421c7294s2
CSeq: 1 INVITE
Allow-Events: telephone-event
Server: Cisco-SIPGateway/IOS-15.2.4.M6
Content-Length: 0

May 7 23:44:58.842: //96/02EE1EEA8062/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 403 Forbidden
Via: SIP/2.0/UDP 194.65.2.241:5070;branch=z9hG4bKfpecfv20egdiibjt73j0.1
From: sip:965432532@ims.mnc006.mcc268.3gppnetwork.org;tag=p65541t1683503098m837421c7294s1_472871982-1146061305
To: <sip:302004889;phone-context=ims.mnc006.mcc268.3gppnetwork.org@ims.mnc006.mcc268.3gppnetwork.org;user=phone>;tag=401AB8-468
Date: Sun, 07 May 2023 23:44:58 GMT
Call-ID: p65541t1683503098m837421c7294s2
CSeq: 1 INVITE
Allow-Events: telephone-event
Server: Cisco-SIPGateway/IOS-15.2.4.M6
Reason: Q.850;cause=21
Content-Length: 0

May 7 23:44:58.846: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
ACK sip:+3519999999@192.168.1.26:5060 SIP/2.0
Via: SIP/2.0/UDP 194.65.2.241:5070;branch=z9hG4bKfpecfv20egdiibjt73j0.1
CSeq: 1 ACK
To: <sip:302004889;phone-context=ims.mnc006.mcc268.3gppnetwork.org@ims.mnc006.mcc268.3gppnetwork.org;user=phone>;tag=401AB8-468
From: sip:965432532@ims.mnc006.mcc268.3gppnetwork.org;tag=p65541t1683503098m837421c7294s1_472871982-1146061305
Call-ID: p65541t1683503098m837421c7294s2
Max-Forwards: 58
Content-Length: 0
P-Charging-Vector: icid-value=7m0ft146p1ds2rk6ehcs2pg6hhoamfde9rpulo462rgmb6o5;icid-generated-at=194.65.2.241;term-ioi=internet

May 7 23:44:58.850: //96/02EE1EEA8062/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x8B4216EC, Tag=0x0, Call Id=96,
Call Entry(Disconnect Cause=21, Voice Class Cause Code=0, Retry Count=0)
May 7 23:44:58.850: //96/02EE1EEA8062/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
May 7 23:44:58.850: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

May 7 23:44:58.850: :cc_free_feature_vsa freeing 87C6A388
May 7 23:44:58.850: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

May 7 23:44:58.850: vsacount in free is 0

-------------------------------------

SH RUN ----.

Current configuration : 6588 bytes
!
! Last configuration change at 01:19:19 GMT Mon May 8 2023
! NVRAM config last updated at 01:30:11 GMT Mon May 8 2023
! NVRAM config last updated at 01:30:11 GMT Mon May 8 2023
version 15.2
service timestamps debug datetime msec
service timestamps log datetime msec
no service password-encryption
!
hostname siprouter
!
boot-start-marker
boot-end-marker
!
!
enable secret 5 $1$HKPe$8C/sx8eW4TXa8uKrEVs/r/
enable password $8C/sx8eW4TXa8uKrEVs
!
no aaa new-model
clock timezone GMT 0 0
clock summer-time GMT recurring
!
!
no ip source-route
no ip routing
no ip cef
!
!
!
!

!
!
!
!
ip name-server 8.8.8.8
no ipv6 cef
!
!
!
!
!
multilink bundle-name authenticated
!
!
!
voice service voip
allow-connections h323 to h323
allow-connections h323 to sip
allow-connections sip to h323
allow-connections sip to sip
supplementary-service h450.12
no supplementary-service sip moved-temporarily
no supplementary-service sip refer
fax h323
fax sip
header-passing
registrar server expires max 3600 min 3600
outbound-proxy dns:proxy.sip.sapo.pt:5070
h323
sip
header-passing
registrar server expires max 3600 min 3600
outbound-proxy dns:proxy.sip.sapo.pt:5070
!
voice class codec 1
codec preference 1 g711ulaw
codec preference 2 g729r8
codec preference 3 g711alaw
codec preference 4 g729br8
!
!
voice register global
mode cme
max-dn 5
max-pool 3
load 7970 SCCP70.9-2-1S.loads
tftp-path flash:
create profile sync 0009111433217793
!
voice register pool 1
id mac 001B.5452.C01D
type 7970
dtmf-relay sip-notify
codec g711ulaw
!
!
voice translation-rule 1
rule 1 /^2/ /003512/
!
voice translation-rule 2
rule 1 /^9/ /003519/
!
voice translation-rule 3
rule 1 /^0/ /0/
!
voice translation-rule 4
rule 1 /^/ //
!
!
voice translation-profile entrada
translate calling 4
!
voice translation-profile internacionais
translate calling 3
!
voice translation-profile nacionais
translate calling 1
!
voice translation-profile telemovel
translate calling 2
!
!
license udi pid CISCO892-K9 sn FCZ1816C1CU
!
!
file privilege 0
username Bubas privilege 15 secret 5 $1$U27j$igDvyy7AFK2DuQnvUfyx./
!
redundancy
!
!
!
!
!
!
!
!
!
!
!
!
!
!
interface BRI0
no ip address
encapsulation hdlc
no ip route-cache
shutdown
isdn termination multidrop
!
interface FastEthernet0
no ip address
shutdown
!
interface FastEthernet1
no ip address
shutdown
!
interface FastEthernet2
no ip address
shutdown
!
interface FastEthernet3
no ip address
shutdown
!
interface FastEthernet4
no ip address
shutdown
!
interface FastEthernet5
no ip address
shutdown
!
interface FastEthernet6
no ip address
shutdown
!
interface FastEthernet7
no ip address
shutdown
!
interface FastEthernet8
no ip address
no ip route-cache
shutdown
duplex auto
speed auto
!
interface GigabitEthernet0
ip address 192.168.1.26 255.255.255.0
no ip route-cache
duplex auto
speed auto
!
interface Vlan1
no ip address
no ip route-cache
!
ip default-gateway 192.168.1.254
ip forward-protocol nd
ip http server
ip http authentication local
no ip http secure-server
ip http path flash:
!
!
!
!
!
tftp-server term70.default.loads
tftp-server term71.default.loads
tftp-server apps70.8-5-2TH1-9.sbn
tftp-server cnu70.8-5-2TH1-9.sbn
tftp-server cvm70sccp.8-5-2TH1-9.sbn
tftp-server jar70sccp.8-5-2TH1-9.sbn
tftp-server SCCP70.8-5-2S.loads
tftp-server apps70.9-2-1TH1-13.sbn
tftp-server cnu70.9-2-1TH1-13.sbn
tftp-server cvm70sccp.9-2-1TH1-13.sbn
tftp-server dsp70.9-2-1TH1-13.sbn
tftp-server jar70sccp.9-2-1TH1-13.sbn
tftp-server SCCP70.9-2-1S.loads
!
control-plane
!
!
!
!
mgcp profile default
!
!
!
dial-peer voice 10 voip
description Voip.Sapo Outbound
translation-profile outgoing nacionais
destination-pattern 2T
progress_ind setup enable 3
session protocol sipv2
session target dns:sip.sapo.pt
session transport udp
voice-class codec 1
dtmf-relay rtp-nte
clid network-number +351302004889
no vad
!
dial-peer voice 11 voip
description Incoming Voip Sapo
translation-profile incoming entrada
session protocol sipv2
session target dns:sip.sapo.pt
session transport udp
incoming called-number +351302004889
voice-class codec 1
dtmf-relay sip-notify rtp-nte
no vad
!
dial-peer voice 9 voip
description Voip.Sapo Outbound Telemovel
translation-profile outgoing telemovel
destination-pattern 9T
progress_ind setup enable 3
session protocol sipv2
session target dns:sip.sapo.pt
session transport udp
voice-class codec 1
dtmf-relay rtp-nte
clid network-number +351302004889
no vad
!
dial-peer voice 8 voip
description Voip.Sapo Outbound Internacionais
translation-profile outgoing internacionais
destination-pattern 0T
progress_ind setup enable 3
session protocol sipv2
session target dns:proxy.sip.sapo.pt:5070
voice-class codec 1
dtmf-relay rtp-nte
clid network-number +351302004889
no vad
!
!
gateway
timer receive-rtp 1200
!
sip-ua
credentials username +351302004889 password 7 072334455D584B5654 realm sip.sapo.pt
authentication username +351302004889 password 7 0028060F170A59554C
nat symmetric role passive
nat symmetric check-media-src
no remote-party-id
retry invite 3
retry response 3
retry bye 3
retry cancel 3
retry register 10
timers expires 300000
timers connect 100
registrar dns:sip.sapo.pt:5070 expires 3600
sip-server dns:sip.sapo.pt:5070
connection-reuse
host-registrar
!
!
telephony-service
no privacy
max-ephones 3
max-dn 5
ip source-address 192.168.1.26 port 2000
auto assign 1 to 1
calling-number initiator
service phone webAccess 0
timeouts interdigit 5
system message Welcome Home
url services http://phone-xml.berbee.com/menu.xml
cnf-file location flash:
user-locale PT
network-locale PT
load 7970 SCCP70.8-5-2S.loads
time-format 24
date-format dd-mm-yy
max-conferences 0 gain -6
moh "music-on-hold.au"
web admin system name Bubas secret 5 $1$LrSn$lzixDjSNzcZMZxDkBVww2/
dn-webedit
time-webedit
transfer-system full-consult
directory entry 1 003519999999 name Luis Reis Mobile
create cnf-files version-stamp 7960 May 07 2023 23:35:53
!
!
ephone-template 1
!
!
ephone-dn 1 dual-line
number +351302004889
label (Voip) 302004889 - MEO
name +351302004889
hold-alert 15 originator
!
!
ephone-dn 2
number 601
label Line (Int)
description Linha Interna
name Int.601
!
!
ephone 1
device-security-mode none
mac-address 001B.5452.C01D
after-hours exempt
type 7970
button 1:1 2:2
!
!
!
banner motd ^CAdmin Access only!^C
!
line con 0
line aux 0
line vty 0 4
password ")(E)"(E)IUIEUIW
login
transport input all
!
ntp update-calendar
ntp server 1.pt.pool.ntp.org prefer
ntp server 192.168.1.254
!
end

-----------------------------------------------------------------

Is there a way to get this working? I've reached my knowledge limit.

Thanks is advance for any help you can give.

KR,

Luis

[b.winter]

Hi,
please provide the debug output with following debugs enabled, "debug voice dialpeer all" is unnecessary long.
And pleeease, don't post the output or config in the message, this is very unpractical. Save them as a txt file and upload it here.

debug ccsip messages
debug voice ccapi ind 1
debug voice ccapi ind 2
debug voice ccapi ind 74
debug voice translations

[LuisReis08240]

Hi B.Winter,

Eish... Sorry... n00b mistake.

I've attached the debug output. I've made a call, let it ring and stop. Then I've tried to call back. Calling out rings on the remote, calling in does nothing.

Thank you(!!) for helping.

[b.winter]

Multiple things:
Your dial-peer 11 is an incoming dial-peer, so you don't need the "session target dns:sip.sapo.pt" here. That's only used on outgoing dial-peers.

As you are using CME and CUBE co-located, you need to use tenant configuration. Check the cisco docs and forum entries about the configuration.

Your incoming call is "forbidden" and gets rejected.
I think you need to make another inbound call with debug ccsip all enabled, instead of debug ccsip messages. Leave all the other debugs enabled.
In debug ccsip messages, we don't see the root cause.
After you took the logs, you should disable debug ccsip all again, as it can cause a heavy workload on the router.

I don't know which router model you have, but you should definitely make an IOS upgrade to the latest one.

[LuisReis08240]

Thank you for replying so fast! I'm going to read up on the tenant configuration (thanks!)

It's an old c892 running 15.2. I'll check what's the latest IOS supported and make it happen.

Removed session target from dial-peer 11. enabled debug, made an inbound call and tried to attached the result.Getting an error about the file content not matching the extension. I'll work on this and upload it in a jiffy.

Thank you very much for giving you attention to this!!

[b.winter · Accepted solution]

Then maybe, even in the latest version, you will not have the tenant feature ... because your router is so old. Probably already end of everything since years.

About your problem: the debug doesn't really say anything.
Maybe you need to do the following:

voice service voip
 ip address trusted list
  ipv4 194.65.2.241 255.255.255.255

[LuisReis08240]

Sir! You rock! Now I can get inbound calls!!!! Thank you!

I've picked up the call but now sound on both ends. No one can ear me and I can't ear them. Could it be the codecs or something?

[b.winter]

Sounds good.
Be aware, that you need to allow all the signaling IP's of your provider. Either one line per IP or one line per range. If you don't know them, you need to ask your provider.

About the no audio: Maybe you need to allow the RTP range in your FW. You can control the RTP in your CUBE with the following command:

voice service voip
 rtp-port range 16384 32766

But which RTP range your provider uses, again you have to ask them.

But for verification, you can allow everything from the IPs of your provider in the FW. And if it's working then, you definitely have a FW issue.

[LuisReis08240]

I've messed around with the FW. Going to mess around a bit more!

Btw, even without sound, the call disconnects after 30s flat.

[b.winter]

Who is disonnecting the call? the router or provider?
Check the output of debug ccsip messages

[LuisReis08240]

Great question! I got this:

May 9 10:13:22.779: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
BYE sip:+351302004889@192.168.1.26:5060 SIP/2.0
Via: SIP/2.0/UDP 194.65.2.241:5070;branch=z9hG4bKv3k7kb1028g22k5o9th0cd0000g10.1
To: <sip:302004889;phone-context=ims.mnc006.mcc268.3gppnetwork.org@ims.mnc006.mcc268.3gppnetwork.org;user=phone>;tag=22A400-12C1
From: sip:924395857@ims.mnc006.mcc268.3gppnetwork.org;tag=p65541t1683627167m747753c56119s1_4282695549-1185667871
Call-ID: p65541t1683627167m747753c56119s2
CSeq: 3 BYE
Max-Forwards: 59
Content-Length: 0
Allow: REGISTER, REFER, NOTIFY, SUBSCRIBE, UPDATE, INFO, MESSAGE, PRACK, INVITE, ACK, OPTIONS, CANCEL, BYE
Reason: SIP;cause=481;text="Call/Transaction Does Not Exist (1:25)"
P-Charging-Vector: icid-value=drpit146p1ds2rk6ehcs2pg6hhoamfde9rpulo462jdru906;icid-generated-at=194.65.2.241;term-ioi=internet

----------

Not sure about who did what. Got a Bye (supposedly) from the caller number. Can't really tell

[LuisReis08240]

I'm a few steps closer to having this working. Thank you very much for you help, time and attention on this problem of mine!!!!

[Roger Kallberg]

The sound issue is likely because the phone device(s) cannot send media traffic, aka RTP to your service provider. As you're CME/Cube is setup with one interface the traffic will not flow through it as it is not enabled for Cube functionality. Try adding mode border-element command under voice service voip. Ideally you should use two interfaces in your SBC, one connected to the inside network, this is where you have your phones, and another one that is connected to your service provider. Without this you're opening up you network to the service provider and that might not be the best.

It could also be related to if you have anything in-between your SBC and the connection with the SP that does NAT. If you have you either need the FW, or whatever is doing the NAT, to support ALG or you need to use SIP profiles in the SBC to rewrite the content of the SIP dialog to change the private IP to the one used publicly and the other way around. Based on that you sound like your new to this that might be a stretched target for you to sort out. The general recommendation is to keep away from NAT whenever possible when it comes to SIP.

[b.winter]

@Roger Kallberg good point, didn't pay attention to that.

[LuisReis08240]

Here! It was not easy