Solved: SIP Trunk works outbound to CME, not inbound to CUCM - Page 3

ken.montgomery · ‎03-11-2013

Hi Everyone,

I've been struggling with this for a while now, gave up on the h323 connectivity and went back to SIP. Here is the scenario: We have a Cisco Unified Communication Manager 6.1 system in one office, which is up and running and working fine. This system uses 4-digit dialing for internal extensions, all starting with 7XXX. We also have a CME running at a remote location, all extensions 8XXX. All is working fine there as well. We have an MPLS network between the two, and IP connectivity is solid and easily visible.

Now, What I'm trying to do is allow users at the main site to call the remote site using 228xxx, which is working. This is across SIP trunk to remote site. The remote site should be able to dial 217xxx and get the 4-digit extension at HQ. I see the SIP invites go out, yet the calls never go through. I'm struggling to find a cause in the CUCM that is denying the call...

The CME Relevant configurations:

Current configuration : 49762 bytes

voice service voip

ip address trusted list

ipv4 10.96.178.0 255.255.255.0

ipv4 <CUCM-Primary>

ipv4 <CUCM-Secondary>

allow-connections h323 to h323

allow-connections h323 to sip

allow-connections sip to sip

no supplementary-service sip moved-temporarily

no supplementary-service sip refer

fax protocol t38 version 0 ls-redundancy 0 hs-redundancy 0 fallback none

h323

h245 signal update

sip

registrar server expires max 3600 min 3600

voice translation-rule 10

rule 10 /^21/ //

!

voice translation-rule 11

rule 11 /^22/ //

!

voice translation-profile CUCM-INBOUND

translate called 11

!

voice translation-profile CUCM-OUTBOUND

translate calling 10

translate called 10

!

dial-peer voice 210 voip

description *** OUTBOUND to CUCM CALL MANAGER TEST ***

translation-profile outgoing CUCM-OUTBOUND

destination-pattern 21....

session protocol sipv2

session target ipv4:<CUCM-Subscriber>

voice-class codec 1

voice-class sip dtmf-relay force rtp-nte

dtmf-relay rtp-nte

no vad

!

dial-peer voice 211 voip

description *** INCOMING CALLS FROM CUCM ****

group-name FairlawnIncoming

destination-pattern 8...

session target ipv4:<CUCM-Subscriber>

incoming called-number .

voice-class codec 1

voice-class sip dtmf-relay force rtp-nte

dtmf-relay rtp-nte

no vad

!

sip-ua

no remote-party-id

retry invite 2

retry register 10

timers connect 100

host-registrar

!

telephony-service

no auto-reg-ephone

authentication credential cmeadmin d3l3m@

max-ephones 95

max-dn 95

ip source-address <CME IP Address> port 2000

timeouts interdigit 3

timeouts ringing 20

system message VEYANCE

time-zone 43

voicemail 7999

max-conferences 8 gain -6

call-forward pattern 4....

moh "flash:music-on-hold.au"

dn-webedit

time-webedit

transfer-system full-consult

secondary-dialtone 9

create cnf-files version-stamp 7960 Aug 20 2012 21:17:28

Call Manager has a SIP Trunk setup with a separate device pool, Call Classification "On Net"

Again, outbound calls to the CME work fine. Inbound from CME to CUCM do not work at all... here is a test, dialing 217163 on the CME:

I am running this with the following debugs enabled:

DIALPEER:

debug voip dialpeer detail 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 Events tracing is enabled (filter is OFF)

Here is the debug output from call from 8500 to 217163:

Mar 11 17:35:43.334: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

Match Rule=DP_MATCH_ANSWER; Calling Number=8500

Mar 11 17:35:43.334: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:

Is Incoming=TRUE, Number Expansion=FALSE

Mar 11 17:35:43.334: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:

Dial String=, Expanded String=, Calling Number=8500T

Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH

Mar 11 17:35:43.334: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:

Result=-1

Mar 11 17:35:43.334: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

Match Rule=DP_MATCH_ORIGINATE; Calling Number=8500

Mar 11 17:35:43.334: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:

Is Incoming=TRUE, Number Expansion=FALSE

Mar 11 17:35:43.334: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:

Dial String=, Expanded String=, Calling Number=8500T

Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH

Mar 11 17:35:43.334: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:

Result=Success(0); Incoming Dial-peer=211 Is Matched

Mar 11 17:35:43.334: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:

Result=Success(0); Incoming Dial-peer=20096 Is Matched

Mar 11 17:35:43.334: //-1/EF178EEE90B5/CCAPI/cc_api_display_ie_subfields:

cc_api_call_setup_ind_common:

cisco-username=

----- ccCallInfo IE subfields -----

cisco-ani=8500

cisco-anitype=0

cisco-aniplan=0

cisco-anipi=0

cisco-anisi=0

dest=

cisco-desttype=0

cisco-destplan=0

cisco-rdie=FFFFFFFF

cisco-rdn=

cisco-rdntype=0

cisco-rdnplan=0

cisco-rdnpi=0

cisco-rdnsi=0

cisco-redirectreason=0 fwd_final_type =0

final_redirectNumber =

hunt_group_timeout =0

Mar 11 17:35:43.334: //-1/EF178EEE90B5/CCAPI/cc_api_call_setup_ind_common:

Interface=0x1CA10704, Call Info(

Calling Number=8500,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),

Called Number=(TON=Unknown, NPI=Unknown),

Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=FALSE,

Incoming Dial-peer=20096, Progress Indication=ORIGINATING SIDE IS NON ISDN(3), Calling IE Present=TRUE,

Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=-1

Mar 11 17:35:43.334: //-1/EF178EEE90B5/CCAPI/ccCheckClipClir:

In: Calling Number=8500(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)

Mar 11 17:35:43.334: //-1/EF178EEE90B5/CCAPI/ccCheckClipClir:

Out: Calling Number=8500(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)

Mar 11 17:35:43.334: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Mar 11 17:35:43.334: :cc_get_feature_vsa malloc success

Mar 11 17:35:43.334: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Mar 11 17:35:43.334: cc_get_feature_vsa count is 1

Mar 11 17:35:43.334: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Mar 11 17:35:43.334: :FEATURE_VSA attributes are: feature_name:0,feature_time:504015312,feature_id:147573

Mar 11 17:35:43.334: //775709/EF178EEE90B5/CCAPI/cc_api_call_setup_ind_common:

Set Up Event Sent;

Call Info(Calling Number=8500(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),

Called Number=(TON=Unknown, NPI=Unknown))

Mar 11 17:35:43.334: //775709/EF178EEE90B5/CCAPI/cc_process_call_setup_ind:

Event=0x1620BED0

Mar 11 17:35:43.334: //775709/EF178EEE90B5/CCAPI/ccCallSetContext:

Context=0x117C92D4

Mar 11 17:35:43.334: //775709/EF178EEE90B5/CCAPI/cc_process_call_setup_ind:

>>>>CCAPI handed cid 775709 with tag 20096 to app "_ManagedAppProcess_Default"

Mar 11 17:35:43.334: //775709/EF178EEE90B5/CCAPI/ccCallSetupAck:

Call Id=775709

Mar 11 17:35:43.334: //775709/EF178EEE90B5/CCAPI/cc_api_set_transfer_info:

Transfer Number=, Transfer Reason=0x0

Mar 11 17:35:43.334: //775709/EF178EEE90B5/CCAPI/ccGenerateToneInfo:

Stop Tone On Digit=TRUE, Tone=Dial Tone,

Tone Direction=Network, Params=0x0, Call Id=775709

Mar 11 17:35:43.338: //775709/EF178EEE90B5/CCAPI/ccSetDigitTimeouts:

Initial Digit Timeout=-1000(ms), Inter Digit Timeout=-1000(ms)

Mar 11 17:35:43.338: //775709/EF178EEE90B5/CCAPI/ccSetDigitTimeouts:

Call Entry(Inter Digit Timeout=10000(ms), Initial Digit Timeout=10000(ms))

Mar 11 17:35:43.338: //775709/EF178EEE90B5/CCAPI/ccCallReportDigits:

(callID=0xBD61D, digit_event=0x1, enable=TRUE, consume=FALSE)

Mar 11 17:35:43.338: //775709/EF178EEE90B5/CCAPI/ccCallReportDigits:

Enabled=TRUE, Call Id=775709

Mar 11 17:35:43.338: //775709/EF178EEE90B5/CCAPI/cc_api_call_report_digits_done:

(vdbPtr=0x1CA10704, callID=0xBD61D, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE)

Mar 11 17:35:43.338: //775709/EF178EEE90B5/CCAPI/cc_api_call_report_digits_done:

Enabled=TRUE, Disposition=0x0, Interface=0x1CA10704, Call Id=775709

Mar 11 17:35:43.338: //775709/EF178EEE90B5/CCAPI/cc_api_call_report_digits_done:

Call Entry(Initial Digit Timeout=15000(ms), Inter Digit Timeout=3000(ms))

Mar 11 17:35:43.538: //775709/EF178EEE90B5/CCAPI/cc_api_call_digit_begin:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=775709, Digit=2, DigitBeginFlags=0x0,

Rtp Timestamp=0x21493D8, Rtp Expiration=0x0

Mar 11 17:35:43.538: //775709/EF178EEE90B5/CCAPI/cc_api_call_digit_end:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=775709, Digit=2, Duration=100,

Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF

Mar 11 17:35:43.538: //775709/EF178EEE90B5/CCAPI/cc_api_call_digit_end:

Call Entry(Handoff Depth=0)

Mar 11 17:35:43.538: //-1/EF178EEE90B5/DPM/dpMatchCore:

Veyance_CME#Dial String=2, Expanded String=2, Calling Number=

Timeout=FALSE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH

Mar 11 17:35:43.538: //-1/EF178EEE90B5/DPM/MatchNextPeer:

Result=MORE_DIGITS_NEEDED(1); Outgoing Dial-peer=210

Mar 11 17:35:43.538: //-1/EF178EEE90B5/DPM/dpMatchCore:

Result=1

Mar 11 17:35:43.738: //775709/EF178EEE90B5/CCAPI/cc_api_call_digit_begin:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=775709, Digit=1, DigitBeginFlags=0x0,

Rtp Timestamp=0x21510D8, Rtp Expiration=0x0

Mar 11 17:35:43.738: //775709/EF178EEE90B5/CCAPI/cc_api_call_digit_end:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=775709, Digit=1, Duration=100,

Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF

Mar 11 17:35:43.738: //775709/EF178EEE90B5/CCAPI/cc_api_call_digit_end:

Call Entry(Handoff Depth=0)

Mar 11 17:35:43.738: //-1/EF178EEE90B5/DPM/dpMatchCore:

Dial String=21, Expanded String=21, Calling Number=

Timeout=FALSE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH

Mar 11 17:35:43.738: //-1/EF178EEE90B5/DPM/MatchNextPeer:

Result=MORE_DIGITS_NEEDED(1); Outgoing Dial-peer=210

Mar 11 17:35:43.738: //-1/EF178EEE90B5/DPM/dpMatchCore:

Result=1

Mar 11 17:35:43.938: //775709/EF178EEE90B5/CCAPI/cc_api_call_digit_begin:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=775709, Digit=7, DigitBeginFlags=0x0,

Rtp Timestamp=0x2158DD8, Rtp Expiration=0x0

Mar 11 17:35:43.938: //775709/EF178EEE90B5/CCAPI/cc_api_call_digit_end:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=775709, Digit=7, Duration=100,

Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF

Mar 11 17:35:43.938: //775709/EF178EEE90B5/CCAPI/cc_api_call_digit_end:

Call Entry(Handoff Depth=0)

Mar 11 17:35:43.938: //-1/EF178EEE90B5/DPM/dpMatchCore:

Dial String=217, Expanded String=217, Calling Number=

Timeout=FALSE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH

Mar 11 17:35:43.938: //-1/EF178EEE90B5/DPM/MatchNextPeer:

Result=MORE_DIGITS_NEEDED(1); Outgoing Dial-peer=210

Mar 11 17:35:43.938: //-1/EF178EEE90B5/DPM/dpMatchCore:

Result=1

Mar 11 17:35:44.138: //775709/EF178EEE90B5/CCAPI/cc_api_call_digit_begin:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=775709, Digit=1, DigitBeginFlags=0x0,

Rtp Timestamp=0x2160AD8, Rtp Expiration=0x0

Mar 11 17:35:44.138: //775709/EF178EEE90B5/CCAPI/cc_api_call_digit_end:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=775709, Digit=1, Duration=100,

Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF

Mar 11 17:35:44.138: //775709/EF178EEE90B5/CCAPI/cc_api_call_digit_end:

Call Entry(Handoff Depth=0)

Mar 11 17:35:44.138: //-1/EF178EEE90B5/DPM/dpMatchCore:

Dial String=2171, Expanded String=2171, Calling Number=

Timeout=FALSE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH

Mar 11 17:35:44.138: //-1/EF178EEE90B5/DPM/MatchNextPeer:

Result=MORE_DIGITS_NEEDED(1); Outgoing Dial-peer=210

Mar 11 17:35:44.138: //-1/EF178EEE90B5/DPM/dpMatchCore:

Result=1

Mar 11 17:35:44.338: //775709/EF178EEE90B5/CCAPI/cc_api_call_digit_begin:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=775709, Digit=6, DigitBeginFlags=0x0,

Rtp Timestamp=0x21687D8, Rtp Expiration=0x0

Mar 11 17:35:44.338: //775709/EF178EEE90B5/CCAPI/cc_api_call_digit_end:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=775709, Digit=6, Duration=100,

Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF

Mar 11 17:35:44.338: //775709/EF178EEE90B5/CCAPI/cc_api_call_digit_end:

Call Entry(Handoff Depth=0)

Mar 11 17:35:44.338: //-1/EF178EEE90B5/DPM/dpMatchCore:

Dial String=21716, Expanded String=21716, Calling Number=

Timeout=FALSE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH

Mar 11 17:35:44.338: //-1/EF178EEE90B5/DPM/MatchNextPeer:

Result=MORE_DIGITS_NEEDED(1); Outgoing Dial-peer=210

Mar 11 17:35:44.338: //-1/EF178EEE90B5/DPM/dpMatchCore:

Result=1

Mar 11 17:35:44.538: //775709/EF178EEE90B5/CCAPI/cc_api_call_digit_begin:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=775709, Digit=3, DigitBeginFlags=0x0,

Rtp Timestamp=0x21704D8, Rtp Expiration=0x0

Mar 11 17:35:44.538: //775709/EF178EEE90B5/CCAPI/cc_api_call_digit_end:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=775709, Digit=3, Duration=100,

Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF

Mar 11 17:35:44.538: //775709/EF178EEE90B5/CCAPI/cc_api_call_digit_end:

Call Entry(Handoff Depth=0)

Mar 11 17:35:44.538: //-1/EF178EEE90B5/DPM/dpMatchCore:

Dial String=217163, Expanded String=217163, Calling Number=

Timeout=FALSE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH

Mar 11 17:35:44.538: //-1/EF178EEE90B5/DPM/MatchNextPeer:

Result=Success(0); Outgoing Dial-peer=210 Is Matched

Mar 11 17:35:44.538: //775709/EF178EEE90B5/CCAPI/ccCallReportDigits:

(callID=0xBD61D, digit_event=0x0, enable=FALSE, consume=FALSE)

Mar 11 17:35:44.538: //775709/EF178EEE90B5/CCAPI/ccCallReportDigits:

Enabled=TRUE, Call Id=775709

Mar 11 17:35:44.538: //775709/EF178EEE90B5/CCAPI/cc_api_call_report_digits_done:

(vdbPtr=0x1CA10704, callID=0xBD61D, disp=0, digit_event=0x0, enable=FALSE, consume=FALSE)

Mar 11 17:35:44.538: //775709/EF178EEE90B5/CCAPI/cc_api_call_report_digits_done:

Enabled=TRUE, Disposition=0x0, Interface=0x1CA10704, Call Id=775709

Mar 11 17:35:44.538: //775709/EF178EEE90B5/CCAPI/cc_api_call_report_digits_done:

Call Entry(Initial Digit Timeout=15000(ms), Inter Digit Timeout=3000(ms))

Mar 11 17:35:44.538: //775709/EF178EEE90B5/CCAPI/ccCallProceeding:

Progress Indication=NULL(0)

Mar 11 17:35:44.538: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

Match Rule=DP_MATCH_ANSWER; Calling Number=8500

Mar 11 17:35:44.538: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:

Is Incoming=TRUE, Number Expansion=FALSE

Mar 11 17:35:44.538: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:

Dial String=, Expanded String=, Calling Number=8500T

Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH

Mar 11 17:35:44.538: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:

Result=-1

Mar 11 17:35:44.538: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

Match Rule=DP_MATCH_ORIGINATE; Calling Number=8500

Mar 11 17:35:44.538: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:

Is Incoming=TRUE, Number Expansion=FALSE

Mar 11 17:35:44.538: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:

Dial String=, Expanded String=, Calling Number=8500T

Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH

Mar 11 17:35:44.538: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:

Result=Success(0); Incoming Dial-peer=211 Is Matched

Mar 11 17:35:44.538: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:

Result=Success(0); Incoming Dial-peer=20096 Is Matched

Mar 11 17:35:44.538: //-1/EF178EEE90B5/DPM/dpMatchCore:

Dial String=217163, Expanded String=217163, Calling Number=

Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH

Mar 11 17:35:44.538: //-1/EF178EEE90B5/DPM/MatchNextPeer:

Result=Success(0); Outgoing Dial-peer=210 Is Matched

Mar 11 17:35:44.538: //-1/EF178EEE90B5/DPM/dpMatchCore:

Dial String=217163, Expanded String=217163, Calling Number=

Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH

Mar 11 17:35:44.538: //-1/EF178EEE90B5/DPM/MatchNextPeer:

Result=Success(0); Outgoing Dial-peer=210 Is Matched

Mar 11 17:35:44.538: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:

Dial String=217163, Expanded String=217163, Calling Number=217163T

Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH

Mar 11 17:35:44.538: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:

Result=Success(0); Outgoing Dial-peer=210 Is Matched

Mar 11 17:35:44.538: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

Match Rule=DP_MATCH_ANSWER; Calling Number=217163

Mar 11 17:35:44.538: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:

Is Incoming=TRUE, Number Expansion=FALSE

Mar 11 17:35:44.538: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:

Dial String=, Expanded String=, Calling Number=217163T

Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH

Mar 11 17:35:44.538: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:

Result=-1

Mar 11 17:35:44.538: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

Match Rule=DP_MATCH_ORIGINATE; Calling Number=217163

Mar 11 17:35:44.538: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:

Is Incoming=TRUE, Number Expansion=FALSE

Mar 11 17:35:44.538: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:

Dial String=, Expanded String=, Calling Number=217163T

Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH

Mar 11 17:35:44.538: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:

Result=Success(0); Incoming Dial-peer=210 Is Matched

Mar 11 17:35:44.538: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:

Dial String=217163, Expanded String=217163, Calling Number=

Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH

Mar 11 17:35:44.538: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:

Result=Success(0); Outgoing Dial-peer=210 Is Matched

Mar 11 17:35:44.538: //-1/EF178EEE90B5/DPM/dpMatchCore:

Dial String=217163, Expanded String=217163, Calling Number=

Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH

Mar 11 17:35:44.538: //-1/EF178EEE90B5/DPM/MatchNextPeer:

Result=Success(0); Outgoing Dial-peer=210 Is Matched

Mar 11 17:35:44.538: //775709/EF178EEE90B5/CCAPI/ccCallSetupRequest:

Destination=, Calling IE Present=TRUE, Mode=0,

Outgoing Dial-peer=210, Params=0x3AFB7F8, Progress Indication=ORIGINATING SIDE IS NON ISDN(3)

Mar 11 17:35:44.538: //775709/EF178EEE90B5/CCAPI/ccCheckClipClir:

In: Calling Number=8500(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)

Mar 11 17:35:44.538: //775709/EF178EEE90B5/CCAPI/ccCheckClipClir:

Out: Calling Number=8500(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)

Mar 11 17:35:44.538: //775709/EF178EEE90B5/CCAPI/ccCallSetupRequest:

Destination Pattern=21...., Called Number=7163, Digit Strip=FALSE

Mar 11 17:35:44.538: //775709/EF178EEE90B5/CCAPI/ccCallSetupRequest:

Calling Number=8500(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),

Called Number=7163(TON=Unknow

Veyance_CME#n, NPI=Unknown),

Redirect Number=, Display Info=Testing

Account Number=, Final Destination Flag=FALSE,

Guid=EF178EEE-89A8-11E2-90B5-9DDB61BF2B12, Outgoing Dial-peer=210

Mar 11 17:35:44.542: //775709/EF178EEE90B5/CCAPI/cc_api_display_ie_subfields:

ccCallSetupRequest:

cisco-username=

----- ccCallInfo IE subfields -----

cisco-ani=8500

cisco-anitype=0

cisco-aniplan=0

cisco-anipi=0

cisco-anisi=0

dest=7163

cisco-desttype=0

cisco-destplan=0

cisco-rdie=FFFFFFFF

cisco-rdn=

cisco-rdntype=0

cisco-rdnplan=0

cisco-rdnpi=0

cisco-rdnsi=0

cisco-redirectreason=0 fwd_final_type =0

final_redirectNumber =

hunt_group_timeout =0

Mar 11 17:35:44.542: //775709/EF178EEE90B5/CCAPI/ccIFCallSetupRequestPrivate:

Interface=0x24701AC, Interface Type=3, Destination=, Mode=0x0,

Call Params(Calling Number=8500,(Calling Name=Testing)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),

Called Number=7163(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,

Subscriber Type Str=RegularLine, FinalDestinationFlag=FALSE, Outgoing Dial-peer=210, Call Count On=FALSE,

Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)

Mar 11 17:35:44.542: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Mar 11 17:35:44.542: :cc_get_feature_vsa malloc success

Mar 11 17:35:44.542: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Mar 11 17:35:44.542: cc_get_feature_vsa count is 2

Mar 11 17:35:44.542: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Mar 11 17:35:44.542: :FEATURE_VSA attributes are: feature_name:0,feature_time:504012176,feature_id:147574

Mar 11 17:35:44.542: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_SETUP

Mar 11 17:35:44.542: //775710/EF178EEE90B5/CCAPI/ccIFCallSetupRequestPrivate:

SPI Call Setup Request Is Success; Interface Type=3, FlowMode=1

Mar 11 17:35:44.542: //775710/EF178EEE90B5/CCAPI/ccCallSetContext:

Context=0x3AFB7A8

Mar 11 17:35:44.542: //775709/EF178EEE90B5/CCAPI/ccSaveDialpeerTag:

Outgoing Dial-peer=210

Mar 11 17:35:44.542: //775710/EF178EEE90B5/CCAPI/cc_api_call_proceeding:

Interface=0x24701AC, Progress Indication=NULL(0)

Mar 11 17:35:44.542: //775710/EF178EEE90B5/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SESSION_REFRESH_REQ

Mar 11 17:35:44.542: //775710/EF178EEE90B5/SIP/Event/Session-Timer/sipSTSLMain: dir:1, method:102, resp_code:0, container:154D2CFC

Mar 11 17:35:44.878: //775710/EF178EEE90B5/CCAPI/cc_api_call_disconnected:

Cause Value=63, Interface=0x24701AC, Call Id=775710

Mar 11 17:35:44.878: //775710/EF178EEE90B5/CCAPI/cc_api_call_disconnected:

Call Entry(Responsed=TRUE, Cause Value=63, Retry Count=0)

Mar 11 17:35:44.878: //775709/EF178EEE90B5/CCAPI/ccCallReleaseResources:

release reserved xcoding resource.

Mar 11 17:35:44.878: //775710/EF178EEE90B5/CCAPI/ccCallSetAAA_Accounting:

Accounting=0, Call Id=775710

Mar 11 17:35:44.878: //775710/EF178EEE90B5/CCAPI/ccCallDisconnect:

Cause Value=63, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=63)

Mar 11 17:35:44.878: //775710/EF178EEE90B5/CCAPI/ccCallDisconnect:

Cause Value=63, Call Entry(Responsed=TRUE, Cause Value=63)

Mar 11 17:35:44.878: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT

Mar 11 17:35:44.878: //775710/EF178EEE90B5/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SPI_EVENT

Mar 11 17:35:44.878: //775710/EF178EEE90B5/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x24701AC, Tag=0x0, Call Id=775710,

Call Entry(Disconnect Cause=63, Voice Class Cause Code=0, Retry Count=0)

Mar 11 17:35:44.878: //775710/EF178EEE90B5/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

Mar 11 17:35:44.878: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Mar 11 17:35:44.878: :cc_free_feature_vsa freeing 1E0A9D88

Mar 11 17:35:44.878: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Mar 11 17:35:44.878: vsacount in free is 1

Mar 11 17:35:44.878: //775710/EF178EEE90B5/SIP/Call/sipSPICallInfo:

The Call Setup Information is:

Call Control Block (CCB) : 0x0x166A6DC8

State of The Call : STATE_DEAD

TCP Sockets Used : NO

Calling Number : 8500

Called Number : 7163

Source IP Address (Sig ): 10.96.178.2

Destn SIP Req Addr:Port : 10.96.210.2:5060

Destn SIP Resp Addr:Port : 10.96.210.2:5060

Destination Name : 10.96.210.2

Mar 11 17:35:44.878: //775710/EF178EEE90B5/SIP/Call/sipSPIMediaCallInfo:

Number of Media Streams: 1

Media Stream : 1

Negotiated Codec : No Codec

Negotiated Codec Bytes : 0

Nego. Codec payload : 255 (tx), 255 (rx)

Negotiated Dtmf-relay : 0

Dtmf-relay Payload : 0 (tx), 0 (rx)

Source IP Address (Media): 10.96.178.2

Source IP Port (Media): 29942

Destn IP Address (Media): -

Destn IP Port (Media): 0

Orig Destn IP Address:Port (Media): [ - ]:0

Mar 11 17:35:44.878: //775710/EF178EEE90B5/SIP/Call/sipSPICallInfo:

Disconnect Cause (CC) : 63

Disconnect Cause (SIP) : 503

Veyance_CME#

Mar 11 17:35:44.878: //775709/EF178EEE90B5/CCAPI/ccCallDisconnect:

Cause Value=63, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)

Mar 11 17:35:44.878: //775709/EF178EEE90B5/CCAPI/ccCallDisconnect:

Cause Value=63, Call Entry(Responsed=TRUE, Cause Value=63)

Mar 11 17:35:44.878: //775709/EF178EEE90B5/CCAPI/cc_api_get_transfer_info:

Transfer Number=NULL

Veyance_CME#

Mar 11 17:35:46.306: //775709/EF178EEE90B5/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x1CA10704, Tag=0x0, Call Id=775709,

Call Entry(Disconnect Cause=63, Voice Class Cause Code=0, Retry Count=0)

Mar 11 17:35:46.306: //775709/EF178EEE90B5/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

Mar 11 17:35:46.306: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Mar 11 17:35:46.306: :cc_free_feature_vsa freeing 1E0AA9C8

Mar 11 17:35:46.306: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Mar 11 17:35:46.306: vsacount in free is 0

In previous debugs, I did come across a SIP/2.0 503 error as well, but not at this time...

Any help/suggestions are appreciated.

Ayodeji Okanlawon · ‎03-14-2013

ok..the manila device pool..assinged to the trunk has a cucm group in it....

go to the cucm group and ensure the subscriber is in that cucm group

Please rate all useful posts

"opportunity is a haughty goddess who waste no time with those who are unprepared"

Please rate all useful posts

bernhardczapp · ‎03-14-2013

The Problem is, that the INVITE phone number is different from the TO phone number?!

did you try

voice service voip

sip

call-route p-called-party-id

?

ken.montgomery · ‎03-14-2013

Ok, entered that command bernhard, made no difference. Removed it to be safe it doesn't mess with anything on the sip provider we also use.

ken.montgomery · ‎03-14-2013

Ok, found it and set it, no change.

Ayodeji Okanlawon · ‎03-14-2013

Ken,

you need to reset the sip trunk after you made the change. Please reset the sip trunk..do another test call and send me debug ccsip messages.

That command suggested wont help this case because the Invite and TO fields are the same..

Please rate all useful posts

"opportunity is a haughty goddess who waste no time with those who are unprepared"

Please rate all useful posts

ken.montgomery · ‎03-14-2013

Well call me Shirley... that worked.

Wow.

Thanks for the help everyone, I'm shocked, and thrilled that it is working.

Anas Abueideh · ‎03-14-2013

Hi Ken,

Can you summerize the answer that solve the issue, since there are alot of helping posts at this thread.

Thanks

Anas

ken.montgomery · ‎03-14-2013

Sure, let me try to summarize into one post that has the answer in it.

The working configuration consists of a CUCM at our corporate office connected to a CME at a remote office, via MPLS network connection. I verified that both can communicate through simple routing, both are pingable and traceroute properly. Next, the CME was configured as below:

In the voice service voip section:

ip address trusted list

ipv4 <--- IP Address of CUCM Publisher --->

ipv4 <--- IP Address of CUCM Subscriber --->

allow-connections h323 to h323

allow-connections h323 to sip

allow-connections sip to sip

h323

h245 signal update

sip

bind control source-interface GigabitEthernet0/2 <--- this is the interface with the IP expected in Call Manager --->

bind media source-interface GigabitEthernet0/2

voice translation-rule 10

rule 10 /^21/ // <--- this rule strips the 21 prefix for the corporate office --->

!

voice translation-rule 11

rule 11 /^22/ // <--- this rule strips the 22 prefix for the branch office --->

voice translation-profile <--- Coporate Office Inbound --->

translate called 11

!

voice translation-profile <--- Corporate Office Outbound --->

translate called 10

dial-peer voice 210 voip

description *** OUTBOUND to Corporate Office ***

translation-profile outgoing <--- Corporate Office Outbound --->

destination-pattern 21....

session protocol sipv2

session target ipv4:<---IP Address of CUCM Subscriber --->

session transport tcp

voice-class codec 1

voice-class sip dtmf-relay force rtp-nte

voice-class sip bind control source-interface GigabitEthernet0/2

voice-class sip bind media source-interface GigabitEthernet0/2

dtmf-relay rtp-nte

no vad

!

dial-peer voice 211 voip

description *** INCOMING CALLS FROM Corporate ****

group-name <--- Name of Corporate Incoming Group --->

destination-pattern 8...

session protocol sipv2

session target ipv4:10.96.210.2

incoming called-number .

voice-class codec 1

voice-class sip dtmf-relay force rtp-nte

dtmf-relay rtp-nte

no vad

Some of the configuration there may be extraneous, I haven't cleaned it all down to bare basics... there is translation on called being done in the Call Manager to only send 4 digits into the CME now, originally we were transferring it all.

In the call manager, have built the following

-Interbranch CCM Group, including both publisher and subscriber servers

-CME Device Pool, which is associated with Interbranch CCM Group

-Route Pattern, with 22.XXXX for the pattern, which indicates 22 is the prefix. has the branch office SIP in the Gateway/Route list area, is considered OnNet and does pre-dot stripping

-Under Device, a Trunk, SIP Trunk for Branch office was created, using CME device pool named above, OnNet, with location specified, with CSS specified (see below), IP address of CME

-Finally, a Inbound_SIP_CSS was specified, with all of the locally accessible CSS added as being accessible

That is all I can find that we configured. I won't say it is all necessary, but that is what I currently have.

Ayodeji Okanlawon · ‎03-14-2013

Ken,

Thats a piece of good news.

The clue to solving the problem was this..

Warning: 399 "Routing failed: ccbid=17238 socket=:5060

Usually when you get that error it is indicative of three things..

1. Wrong Ip address configured on the sip trunk..In your case since we saw that the outbound call works with the correct ip that was not the case

2. The CUCM server your dial-peer points to is not in the CUCM group of the sip trunk (and you dont have "route on all active nodes" checked (your version doesnt have it)----This was the issue for your case..when you added the cucm subscriber to the cucm group calls started working

3. Incorrect bind config.

Anyways glad that its done and dusted...Dont forget to rate any other useful post! I think this thread deserves more than one rating

Please rate all useful posts

"opportunity is a haughty goddess who waste no time with those who are unprepared"

Please rate all useful posts