cancel
Showing results for 
Search instead for 
Did you mean: 
cancel
2128
Views
0
Helpful
8
Replies

CUCM outbound call fails CUCM->SIP trunk->Gateway->FXO

cisco.smj
Level 1
Level 1

Scenario:

Outbound calls fail.  User hears a couple seconds of dead air followed by fast busy.

 

Environment:

(CUCM12.5) -> (SIP trunk) -> (4351 ISR) -> (FXO to PSTN)

 

Details:

  • SIP trunk is configured and registered using a loopback interface of the ISR.
  • Route pattern is configured to match test number (885733034458).  This route pattern points to a route list that points to a route group that points to the SIP trunk to reach the ISR.
  • Communication between CUCM and the ISR have been checked.  No ACLS blocking traffic between the devices.

ISR config:

voice service voip
ip address trusted list
ipv4 <IP address range of CUCM cluster>
srtp fallback
allow-connections h323 to h323
allow-connections h323 to sip
allow-connections sip to h323
allow-connections sip to sip
fax protocol t38 version 0 ls-redundancy 0 hs-redundancy 0 fallback none
h323
h225 timeout setup 3
h225 display-ie ccm-compatible
call preserve limit-media-detection
trace
sip
bind control source-interface Loopback0
bind media source-interface Loopback0
registrar server expires max 600 min 60
early-offer forced
midcall-signaling passthru
g729 annexb-all

!

!

dial-peer voice 920 voip
description Call leg facing CUCM
preference 1
session protocol sipv2
session target ipv4:<CUCM.IP>
incoming uri via cucm1
voice-class sip options-keepalive
dtmf-relay rtp-nte
 voice-class codec 1
ip qos dscp cs6 signaling
no vad

!

!

dial-peer voice 880 pots
description - Outside leg facing PSTN
destination-pattern 88[2-9]..[2-9]......$
port 0/3/5
prefix 8

!

!

CCSIP Messages debug output:

057328: Sep 15 16:03:09.156 CDT: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:885733034458@<GATEWAY.IP>:5060 SIP/2.0
Via: SIP/2.0/UDP <CUCM.IP>:5060;branch=z9hG4bK14132639d7cd9a
From: "JONES M" <sip:37639@<CUCM.IP>>;tag=32965113~0542a993-8140-48c9-a304-98fbf3fb195b-56156563
To: <sip:885733034458@<GATEWAY.IP>>
Date: Wed, 15 Sep 2021 21:03:09 GMT
Call-ID: 532daa00-1ed1346b-e793d-8301bc37@<CUCM.IP>
Supported: 100rel,timer,resource-priority,replaces
Min-SE: 1800
User-Agent: Cisco-CUCM12.5
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
Call-Info: <sip:<CUCM.IP>:5060>;method="NOTIFY;Event=telephone-event;Duration=500"
Call-Info: <urn:x-cisco-remotecc:callinfo>;x-cisco-video-traffic-class=DESKTOP
Session-ID: 0f14450d00105000a0004c710df86ad9;remote=00000000000000000000000000000000
Cisco-Guid: 1395501568-0000065536-0000002164-2197929015
Session-Expires: 1800
P-Asserted-Identity: "JONES M" <sip:37639@<CUCM.IP>>
Remote-Party-ID: "JONES M" <sip:37639@<CUCM.IP>>;party=calling;screen=yes;privacy=off
Contact: <sip:37639@<CUCM.IP>:5060>;+u.sip!devicename.ccm.cisco.com="SEP4C710DF86AD9"
Max-Forwards: 69
Content-Length: 0


057329: Sep 15 16:03:09.163 CDT: //80404/532DAA000000/SIP/Msg/ccsipDisplayMsg:
Sent:

SIP/2.0 100 Trying
Via: SIP/2.0/UDP <CUCM.IP>:5060;branch=z9hG4bK14132639d7cd9a
From: "JONES M" <sip:37639@<CUCM.IP>>;tag=32965113~0542a993-8140-48c9-a304-98fbf3fb195b-56156563
To: <sip:885733034458@<GATEWAY.IP>>
Date: Wed, 15 Sep 2021 21:03:09 GMT
Call-ID: 532daa00-1ed1346b-e793d-8301bc37@<CUCM.IP>
CSeq: 101 INVITE
Allow-Events: telephone-event
Server: Cisco-SIPGateway/IOS-17.3.3
Session-ID: 00000000000000000000000000000000;remote=0f14450d00105000a0004c710df86ad9
Content-Length: 0

 

057330: Sep 15 16:03:12.999 CDT: //80404/532DAA000000/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP <CUCM.IP>:5060;branch=z9hG4bK14132639d7cd9a
From: "JONES M" <sip:37639@<CUCM.IP>>;tag=32965113~0542a993-8140-48c9-a304-98fbf3fb195b-56156563
To: <sip:885733034458@<GATEWAY.IP>>;tag=DB7BF7B4-23F1
Date: Wed, 15 Sep 2021 21:03:09 GMT
Call-ID: 532daa00-1ed1346b-e793d-8301bc37@<CUCM.IP>
CSeq: 101 INVITE
Require: 100rel
RSeq: 1410
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Remote-Party-ID: <sip:85733014438@<GATEWAY.IP>>;party=called;screen=no;privacy=off
Contact: <sip:885733034458@<GATEWAY.IP>:5060>
Supported: sdp-anat
Server: Cisco-SIPGateway/IOS-17.3.3
Session-ID: f3453e5db6c15eb6a1d78cf17ba617ac;remote=0f14450d00105000a0004c710df86ad9
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 986

v=0
o=CiscoSystemsSIP-GW-UserAgent 1562 8883 IN IP4 <GATEWAY.IP>
s=SIP Call
c=IN IP4 <GATEWAY.IP>
t=0 0
m=audio 8496 RTP/SAVP 18 98 99 102 0 8 15 4 3 103 125 117 116 101
c=IN IP4 <GATEWAY.IP>
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:98 G726-16/8000
a=rtpmap:99 G726-24/8000
a=rtpmap:102 G726-32/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:15 G728/8000
a=rtpmap:4 G723/8000
a=fmtp:4 bitrate=6.3;annexa=no
a=rtpmap:3 GSM/8000
a=rtpmap:103 GSM-EFR/8000
a=rtpmap:125 X-CCD/8000
a=rtpmap:117 AMR/8000
a=fmtp:117
a=rtpmap:116 iLBC/8000
a=fmtp:116
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=crypto:1 AEAD_AES_256_GCM inline:xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
a=crypto:2 AEAD_AES_128_GCM inline:xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
a=crypto:3 AES_CM_128_HMAC_SHA1_80 inline:xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
a=crypto:4 AES_CM_128_HMAC_SHA1_32 inline:xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx

057331: Sep 15 16:03:13.029 CDT: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
CANCEL sip:885733034458@<GATEWAY.IP>:5060 SIP/2.0
Via: SIP/2.0/UDP <CUCM.IP>:5060;branch=z9hG4bK14132639d7cd9a
From: "JONES M" <sip:37639@<CUCM.IP>>;tag=32965113~0542a993-8140-48c9-a304-98fbf3fb195b-56156563
To: <sip:885733034458@<GATEWAY.IP>>
Date: Wed, 15 Sep 2021 21:03:09 GMT
Call-ID: 532daa00-1ed1346b-e793d-8301bc37@<CUCM.IP>
User-Agent: Cisco-CUCM12.5
CSeq: 101 CANCEL
Max-Forwards: 70
Reason: Q.850;cause=47
Session-ID: 0f14450d00105000a0004c710df86ad9;remote=f3453e5db6c15eb6a1d78cf17ba617ac
Content-Length: 0


057332: Sep 15 16:03:13.032 CDT: //80404/532DAA000000/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP <CUCM.IP>:5060;branch=z9hG4bK14132639d7cd9a
From: "JONES M" <sip:37639@<CUCM.IP>>;tag=32965113~0542a993-8140-48c9-a304-98fbf3fb195b-56156563
To: <sip:885733034458@<GATEWAY.IP>>
Date: Wed, 15 Sep 2021 21:03:13 GMT
Call-ID: 532daa00-1ed1346b-e793d-8301bc37@<CUCM.IP>
CSeq: 101 CANCEL
Session-ID: 0f14450d00105000a0004c710df86ad9;remote=f3453e5db6c15eb6a1d78cf17ba617ac
Content-Length: 0


057333: Sep 15 16:03:13.046 CDT: //80404/532DAA000000/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 487 Request Cancelled
Via: SIP/2.0/UDP <CUCM.IP>:5060;branch=z9hG4bK14132639d7cd9a
From: "JONES M" <sip:37639@<CUCM.IP>>;tag=32965113~0542a993-8140-48c9-a304-98fbf3fb195b-56156563
To: <sip:885733034458@<GATEWAY.IP>>;tag=DB7BF7B4-23F1
Date: Wed, 15 Sep 2021 21:03:13 GMT
Call-ID: 532daa00-1ed1346b-e793d-8301bc37@<CUCM.IP>
CSeq: 101 INVITE
Allow-Events: telephone-event
Server: Cisco-SIPGateway/IOS-17.3.3
Reason: Q.850;cause=16
Session-ID: 0f14450d00105000a0004c710df86ad9;remote=f3453e5db6c15eb6a1d78cf17ba617ac
Content-Length: 0


057334: Sep 15 16:03:13.059 CDT: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:

ACK sip:885733034458@<GATEWAY.IP>:5060 SIP/2.0
Via: SIP/2.0/UDP <CUCM.IP>:5060;branch=z9hG4bK14132639d7cd9a
From: "JONES M" <sip:37639@<CUCM.IP>>;tag=32965113~0542a993-8140-48c9-a304-98fbf3fb195b-56156563
To: <sip:885733034458@<GATEWAY.IP>>;tag=DB7BF7B4-23F1
Date: Wed, 15 Sep 2021 21:03:09 GMT
Call-ID: 532daa00-1ed1346b-e793d-8301bc37@<CUCM.IP>
User-Agent: Cisco-CUCM12.5
Max-Forwards: 70
CSeq: 101 ACK
Allow-Events: presence, kpml
Content-Length:

 

VOICE CCAPI INOUT debug output:

057336: Sep 15 16:09:39.400 CDT: //-1/3BA2F1000000/CCAPI/cc_api_display_ie_subfields:
cc_api_call_setup_ind_common:
cisco-username=37639
----- ccCallInfo IE subfields -----
cisco-ani=37639
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=1
dest=885733034458
cisco-desttype=0
cisco-destplan=0
cisco-rdie=FFFFFFFFFFFFFFFF
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

057337: Sep 15 16:09:39.401 CDT: //-1/3BA2F1000000/CCAPI/cc_api_call_setup_ind_common:
Interface=0x7FB03F6BECD8, Call Info(
Calling Number=37639,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=885733034458(TON=Unknown, NPI=Unknown),
Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE,
Incoming Dial-peer=920, Progress Indication=NULL(0), Calling IE Present=TRUE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=80446
057338: Sep 15 16:09:39.401 CDT: //-1/3BA2F1000000/CCAPI/ccCheckClipClir:
In: Calling Number=37639(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
057339: Sep 15 16:09:39.401 CDT: //-1/3BA2F1000000/CCAPI/ccCheckClipClir:
Out: Calling Number=37639(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
057340: Sep 15 16:09:39.401 CDT: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

057341: Sep 15 16:09:39.401 CDT: :cc_get_feature_vsa malloc success
057342: Sep 15 16:09:39.401 CDT: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

057343: Sep 15 16:09:39.401 CDT: cc_get_feature_vsa count is 1
057344: Sep 15 16:09:39.401 CDT: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

057345: Sep 15 16:09:39.401 CDT: :FEATURE_VSA attributes are: feature_name:0,feature_time:140394978015340,feature_id:446
057346: Sep 15 16:09:39.401 CDT: //80446/3BA2F1000000/CCAPI/cc_api_call_setup_ind_common:
Set Up Event Sent;
Call Info(Calling Number=37639(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=885733034458(TON=Unknown, NPI=Unknown))
057347: Sep 15 16:09:39.402 CDT: //80446/3BA2F1000000/CCAPI/cc_process_call_setup_ind:
Event=0x7FB040CD2B60
057348: Sep 15 16:09:39.402 CDT: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
Try with the demoted called number 885733034458
057349: Sep 15 16:09:39.403 CDT: //80446/3BA2F1000000/CCAPI/ccCallSetContext:
Context=0x7FB04CE47E98
057350: Sep 15 16:09:39.403 CDT: //80446/3BA2F1000000/CCAPI/cc_process_call_setup_ind:
>>>>CCAPI handed cid 80446 with tag 920 to app "_ManagedAppProcess_Default"
057351: Sep 15 16:09:39.404 CDT: //80446/3BA2F1000000/CCAPI/ccCallProceeding:
Progress Indication=NULL(0)
057352: Sep 15 16:09:39.405 CDT: //80446/3BA2F1000000/CCAPI/ccCallSetupRequest:
Destination=, Calling IE Present=TRUE, Mode=0,
Outgoing Dial-peer=880, Params=0x7FB04CE54EA8, Progress Indication=NULL(0)
057353: Sep 15 16:09:39.405 CDT: //80446/3BA2F1000000/CCAPI/ccCheckClipClir:
In: Calling Number=37639(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
057354: Sep 15 16:09:39.405 CDT: //80446/3BA2F1000000/CCAPI/ccCheckClipClir:
Out: Calling Number=37639(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
057355: Sep 15 16:09:39.405 CDT: //80446/3BA2F1000000/CCAPI/ccCallSetupRequest:
Destination Pattern=88[2-9]..[2-9]......$, Called Number=885733034458, Digit Strip=TRUE
057356: Sep 15 16:09:39.405 CDT: //80446/3BA2F1000000/CCAPI/ccCallSetupRequest:
Calling Number=37639(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=885733034458(TON=Unknown, NPI=Unknown),
Redirect Number=, Display Info=Jones M
Account Number=37639, Final Destination Flag=TRUE,
Guid=3BA2F100-0001-0000-0000-08758301BC37, Outgoing Dial-peer=880
057357: Sep 15 16:09:39.405 CDT: //80446/3BA2F1000000/CCAPI/cc_api_display_ie_subfields:
ccCallSetupRequest:
cisco-username=37639
----- ccCallInfo IE subfields -----
cisco-ani=37639
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=1
dest=885733034458
cisco-desttype=0
cisco-destplan=0
cisco-rdie=FFFFFFFFFFFFFFFF
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

057358: Sep 15 16:09:39.406 CDT: //80446/3BA2F1000000/CCAPI/ccIFCallSetupRequestPrivate:
Interface=0x7FB040E69690, Interface Type=6, Destination=, Mode=0x0,
Call Params(Calling Number=37639,(Calling Name=Jones M)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=885733034458(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing Dial-peer=880, Call Count On=FALSE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)
057359: Sep 15 16:09:39.406 CDT: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

057360: Sep 15 16:09:39.406 CDT: :cc_get_feature_vsa malloc success
057361: Sep 15 16:09:39.406 CDT: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

057362: Sep 15 16:09:39.406 CDT: cc_get_feature_vsa count is 2
057363: Sep 15 16:09:39.406 CDT: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

057364: Sep 15 16:09:39.406 CDT: :FEATURE_VSA attributes are: feature_name:0,feature_time:140394978015564,feature_id:447
057365: Sep 15 16:09:39.406 CDT: //80447/3BA2F1000000/CCAPI/ccIFCallSetupRequestPrivate:
SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1
057366: Sep 15 16:09:39.406 CDT: //80447/3BA2F1000000/CCAPI/ccCallSetContext:
Context=0x7FB04CE54E28

057367: Sep 15 16:09:39.406 CDT: //80446/3BA2F1000000/CCAPI/ccSaveDialpeerTag:
Outgoing Dial-peer=880
057368: Sep 15 16:09:39.408 CDT: //80447/3BA2F1000000/CCAPI/cc_api_call_proceeding:
Interface=0x7FB040E69690, Progress Indication=NULL(0)
057369: Sep 15 16:09:39.408 CDT: //80447/3BA2F1000000/CCAPI/cc_api_event_indication:
Event=92, Call Id=80447
057370: Sep 15 16:09:39.408 CDT: //80447/3BA2F1000000/CCAPI/cc_api_event_indication:
Event Is Sent To Conferenced SPI(s) Directly

057371: Sep 15 16:09:43.250 CDT: //80447/3BA2F1000000/CCAPI/cc_api_call_cut_progress:
Interface=0x7FB040E69690, Progress Indication=INBAND(8), Signal Indication=INTERCEPT(2),
Cause Value=0
057372: Sep 15 16:09:43.250 CDT: //80447/3BA2F1000000/CCAPI/cc_api_call_cut_progress:
Call Entry(Responsed=TRUE)
057373: Sep 15 16:09:43.251 CDT: //80446/3BA2F1000000/CCAPI/ccCallCutProgress:
Progress Indication=INBAND(8), Signal Indication=INTERCEPT(2), Cause Value=0
Voice Call Send Alert=FALSE, Call Entry(Alert Sent=FALSE)
057374: Sep 15 16:09:43.251 CDT: //80446/3BA2F1000000/CCAPI/ccCallCutProgress:
Call Entry(Responsed=TRUE)
057375: Sep 15 16:09:43.251 CDT: //80446/3BA2F1000000/CCAPI/ccConferenceCreate:
(confID=0xFFFFFFFFFFFFFFFF, callID1=0x13A3E, gcid=12AE3374-15A011EC-BCC0DFDF-A52292CF, tag=0x0)
057376: Sep 15 16:09:43.251 CDT: //80447/3BA2F1000000/CCAPI/ccConferenceCreate:
(confID=0xFFFFFFFFFFFFFFFF, callID2=0x13A3F, gcid=12AE3374-15A011EC-BCC0DFDF-A52292CF, tag=0x0)
057377: Sep 15 16:09:43.251 CDT: //80446/3BA2F1000000/CCAPI/ccConferenceCreate:
Conference Id=0xFFFFFFFFFFFFFFFF, Call Id1=80446, Call Id2=80447, Tag=0x0
057378: Sep 15 16:09:43.251 CDT: //80446/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

057379: Sep 15 16:09:43.251 CDT: cc_api_get_xcode_stream : 5013
057380: Sep 15 16:09:43.251 CDT: //80446/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

057381: Sep 15 16:09:43.251 CDT: cc_api_get_xcode_stream : 5013
057382: Sep 15 16:09:43.251 CDT: //80446/3BA2F1000000/CCAPI/cc_api_bridge_done:
Conference Id=0xBC, Source Interface=0x7FB03F6BECD8, Source Call Id=80446,
Destination Call Id=80447, Disposition=0x0, Tag=0x0
057383: Sep 15 16:09:43.251 CDT: //80447/3BA2F1000000/CCAPI/cc_api_bridge_done:
Conference Id=0xBC, Source Interface=0x7FB040E69690, Source Call Id=80447,
Destination Call Id=80446, Disposition=0x0, Tag=0xFFFFFFFFFFFFFFFF
057384: Sep 15 16:09:43.251 CDT: //80446/3BA2F1000000/CCAPI/cc_generic_bridge_done:
Conference Id=0xBC, Source Interface=0x7FB040E69690, Source Call Id=80447,
Destination Call Id=80446, Disposition=0x0, Tag=0xFFFFFFFFFFFFFFFF
057385: Sep 15 16:09:43.252 CDT: //80446/3BA2F1000000/CCAPI/ccConferenceCreate:
Call Entry(Conference Id=0xBC, Destination Call Id=80447)
057386: Sep 15 16:09:43.252 CDT: //80447/3BA2F1000000/CCAPI/ccConferenceCreate:
Call Entry(Conference Id=0xBC, Destination Call Id=80446)
057387: Sep 15 16:09:43.252 CDT: //80446/3BA2F1000000/CCAPI/ccConferenceCreate:

057388: Sep 15 16:09:43.252 CDT: confID:0xBC; callEntry1 callID1:0x13A3E, type:3; callEntry2 callID2:0x13A3F, type:6

057389: Sep 15 16:09:43.252 CDT: //80447/3BA2F1000000/CCAPI/cc_api_caps_ind:

057390: Sep 15 16:09:43.252 CDT: voice_rtp_nse_payload 0, voice_rtp_sse_payload 118 voice_sprt_payload 120
057391: Sep 15 16:09:43.252 CDT: //80447/3BA2F1000000/CCAPI/cc_api_caps_ind:

057392: Sep 15 16:09:43.252 CDT: sprt_latency 200, sprt_retries 12
057393: Sep 15 16:09:43.252 CDT: //80447/3BA2F1000000/CCAPI/cc_api_caps_ind:
Destination Interface=0x7FB03F6BECD8, Destination Call Id=80446, Source Call Id=80447,
Caps(Codec=0x1, Fax Rate=0x1, Fax Version:=0, Vad=0x1,
Modem=0x2, Codec Bytes=20, Signal Type=3)
057394: Sep 15 16:09:43.252 CDT: //80447/3BA2F1000000/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))
057395: Sep 15 16:09:43.252 CDT: //80446/3BA2F1000000/CCAPI/cc_api_caps_ind:

057396: Sep 15 16:09:43.252 CDT: voice_rtp_nse_payload 100, voice_rtp_sse_payload 118 voice_sprt_payload 120
057397: Sep 15 16:09:43.252 CDT: //80446/3BA2F1000000/CCAPI/cc_api_caps_ind:

057398: Sep 15 16:09:43.252 CDT: sprt_latency 200, sprt_retries 12
057399: Sep 15 16:09:43.252 CDT: //80446/3BA2F1000000/CCAPI/cc_api_caps_ind:
Destination Interface=0x7FB040E69690, Destination Call Id=80447, Source Call Id=80446,
Caps(Codec=0xFFFFFFFF, Fax Rate=0x2, Fax Version:=0, Vad=0x1,
Modem=0x0, Codec Bytes=0, Signal Type=2)
057400: Sep 15 16:09:43.252 CDT: //80446/3BA2F1000000/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))
057401: Sep 15 16:09:43.252 CDT: //80446/3BA2F1000000/CCAPI/cc_api_caps_ack:
Destination Interface=0x7FB040E69690, Destination Call Id=80447, Source Call Id=80446,
Caps(Codec=No Codec (0xFFFFFFFF), Fax Rate=FAX_RATE_VOICE(0x2), Fax Version:=0, Vad=OFF(0x1),
Modem=OFF(0x0), Codec Bytes=0, Signal Type=2, Seq Num Start=7741)
057402: Sep 15 16:09:43.253 CDT: //80447/3BA2F1000000/CCAPI/cc_api_caps_ack:
Destination Interface=0x7FB03F6BECD8, Destination Call Id=80446, Source Call Id=80447,
Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Fax Version:=0, Vad=OFF(0x1),
Modem=OFF(0x0), Codec Bytes=20, Signal Type=2, Seq Num Start=7741)
057403: Sep 15 16:09:43.253 CDT: //80446/3BA2F1000000/CCAPI/ccCallNotify:
Data Bitmask=0x7, Call Id=80446
057404: Sep 15 16:09:43.255 CDT: //80446/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

057405: Sep 15 16:09:43.255 CDT: cc_api_get_xcode_stream : 5013
057406: Sep 15 16:09:43.255 CDT: //80446/3BA2F1000000/CCAPI/cc_process_notify_bridge_done:
Conference Id=0xBC, Call Id1=80446, Call Id2=80447
057407: Sep 15 16:09:43.257 CDT: //80447/3BA2F1000000/CCAPI/cc_api_voice_mode_event:
Call Id=80447
057408: Sep 15 16:09:43.257 CDT: //80447/3BA2F1000000/CCAPI/cc_api_voice_mode_event:
Call Entry(Context=0x7FB04CE54E28)
057409: Sep 15 16:09:43.257 CDT: //80447/3BA2F1000000/CCAPI/cc_api_call_connected:
Interface=0x7FB040E69690, Data Bitmask=0x1, Progress Indication=DESTINATION IS NON ISDN(2),
Connection Handle=0
057410: Sep 15 16:09:43.257 CDT: //80447/3BA2F1000000/CCAPI/cc_update_call_type_and_count:
TDM call count: 1 after call connected
057411: Sep 15 16:09:43.257 CDT: //80447/3BA2F1000000/CCAPI/cc_api_call_connected:
Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0)
057412: Sep 15 16:09:43.259 CDT: //80446/3BA2F1000000/CCAPI/ccCallConnect:
Progress Indication=DESTINATION IS NON ISDN(2), Data Bitmask=0x1
057413: Sep 15 16:09:43.259 CDT: //80446/3BA2F1000000/CCAPI/ccCallConnect:
Call Entry(Connected=TRUE, Responsed=TRUE)
057414: Sep 15 16:09:43.259 CDT: //80446/3BA2F1000000/CCAPI/ccCallNotify:
Data Bitmask=0x7, Call Id=80446
057415: Sep 15 16:09:43.285 CDT: //80446/3BA2F1000000/CCAPI/cc_api_call_disconnected:
Cause Value=16, Interface=0x7FB03F6BECD8, Call Id=80446
057416: Sep 15 16:09:43.285 CDT: //80446/3BA2F1000000/CCAPI/cc_api_call_disconnected:
Call Entry(Responsed=TRUE, Cause Value=16, Retry Count=0)
057417: Sep 15 16:09:43.285 CDT: //80446/3BA2F1000000/CCAPI/ccConferenceDestroy:
Conference Id=0xBC, Tag=0x0
057418: Sep 15 16:09:43.285 CDT: //80446/3BA2F1000000/CCAPI/ccConferenceDestroy:

057419: Sep 15 16:09:43.285 CDT: confID:0xBC; callEntry1 callID1:0x13A3E, type:3; callEntry2 callID2:0x13A3F, type:6

057420: Sep 15 16:09:43.286 CDT: //80446/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

057421: Sep 15 16:09:43.286 CDT: cc_api_get_xcode_stream : 5013
057422: Sep 15 16:09:43.286 CDT: //80447/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

057423: Sep 15 16:09:43.286 CDT: cc_api_get_xcode_stream : 5013
057424: Sep 15 16:09:43.286 CDT: //80446/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

057425: Sep 15 16:09:43.286 CDT: cc_api_get_xcode_stream : 5013
057426: Sep 15 16:09:43.286 CDT: //80446/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

057427: Sep 15 16:09:43.286 CDT: cc_api_get_xcode_stream : 5013
057428: Sep 15 16:09:43.286 CDT: //80446/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

057429: Sep 15 16:09:43.286 CDT: cc_api_get_xcode_stream : 5013
057430: Sep 15 16:09:43.286 CDT: //80446/3BA2F1000000/CCAPI/cc_api_bridge_drop_done:
Conference Id=0xBC, Source Interface=0x7FB03F6BECD8, Source Call Id=80446,
Destination Call Id=80447, Disposition=0x0, Tag=0x0
057431: Sep 15 16:09:43.286 CDT: //80447/3BA2F1000000/CCAPI/cc_api_bridge_drop_done:
Conference Id=0xBC, Source Interface=0x7FB040E69690, Source Call Id=80447,
Destination Call Id=80446, Disposition=0x0, Tag=0x0
057432: Sep 15 16:09:43.286 CDT: //80446/3BA2F1000000/CCAPI/cc_generic_bridge_done:
Conference Id=0xBC, Source Interface=0x7FB040E69690, Source Call Id=80447,
Destination Call Id=80446, Disposition=0x0, Tag=0x0
057433: Sep 15 16:09:43.286 CDT: //80447/3BA2F1000000/CCAPI/cc_update_call_count_stats:
TDM call count: 0 after call disconnected

057434: Sep 15 16:09:43.288 CDT: //80446/3BA2F1000000/CCAPI/ccCallDisconnect:
Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16)
057435: Sep 15 16:09:43.288 CDT: //80446/3BA2F1000000/CCAPI/ccCallDisconnect:
Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
057436: Sep 15 16:09:43.289 CDT: //80447/3BA2F1000000/CCAPI/ccCallDisconnect:
Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
057437: Sep 15 16:09:43.289 CDT: //80447/3BA2F1000000/CCAPI/ccCallDisconnect:
Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
057438: Sep 15 16:09:43.289 CDT: //80447/3BA2F1000000/CCAPI/cc_api_get_transfer_info:
Transfer Number=NULL
057439: Sep 15 16:09:43.327 CDT: //80446/3BA2F1000000/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x7FB03F6BECD8, Tag=0x0, Call Id=80446,
Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
057440: Sep 15 16:09:43.327 CDT: //80446/3BA2F1000000/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
057441: Sep 15 16:09:43.328 CDT: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

057442: Sep 15 16:09:43.328 CDT: :cc_free_feature_vsa freeing 7FB040CAF860
057443: Sep 15 16:09:43.328 CDT: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

057444: Sep 15 16:09:43.328 CDT: vsacount in free is 1
057445: Sep 15 16:09:43.367 CDT: //80447/3BA2F1000000/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x7FB040E69690, Tag=0x0, Call Id=80447,
Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)

057446: Sep 15 16:09:43.367 CDT: //80447/3BA2F1000000/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
057447: Sep 15 16:09:43.367 CDT: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

057448: Sep 15 16:09:43.367 CDT: :cc_free_feature_vsa freeing 7FB040CAF940
057449: Sep 15 16:09:43.367 CDT: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

057450: Sep 15 16:09:43.367 CDT: vsacount in free is 0

 

 

1 Accepted Solution

Accepted Solutions

 

Can you check the codec in use between the end user phone and trunk? 

Can you also try test call with MTP checked on  the SIP trunk?  also see what you configured Early Offer support for voice and video calls in the sup profile assigned to the trunk. 

 

Regards

View solution in original post

8 Replies 8

Steven L
Spotlight
Spotlight

this looks like a CODEC mis-match.

 

how is " voice-class codec 1" defined?

 

The FXO will require G711, it looks like your SDP is offering everything.....

 

You might want to scale down accepted CODECs to those you know you will need to support and ensure there are transcoding resources available.

 

Steve

Voice class codec config:

voice class codec 1
codec preference 1 g711ulaw
codec preference 2 g729r8

 

Transcoder config:

dspfarm profile 3 transcode universal
codec g711alaw
codec g711ulaw
codec g729br8
codec g729r8
maximum sessions 5
associate application SCCP

 

DSPFARM all output:

Profile ID = 3, Service =Universal TRANSCODING, Resource ID = 3
Profile Service Mode : Non Secure
Profile Admin State : UP
Profile Operation State : ACTIVE
Application : CUBE Status : ASSOCIATED
Resource Provider : FLEX_DSPRM Status : UP
Total Number of Resources Configured : 5
Total Number of Resources Available : 5
Total Number of Resources Out of Service : 0
Total Number of Resources Active : 0
Codec Configuration: num_of_codecs:4
Codec : g729r8, Maximum Packetization Period : 60
Codec : g729br8, Maximum Packetization Period : 60
Codec : g711ulaw, Maximum Packetization Period : 30
Codec : g711alaw, Maximum Packetization Period : 30
Dspfarm Profile Configuration

Profile ID = 1, Service = CONFERENCING, Resource ID = 1
Profile Service Mode : Non Secure
Profile Admin State : UP
Profile Operation State : ACTIVE
Application : SCCP Status : ASSOCIATED
Resource Provider : FLEX_DSPRM Status : UP
Total Number of Resources Configured : 4
Total Number of Resources Available : 4
Total Number of Resources Out of Service : 0
Total Number of Resources Active : 0
Maximum conference participants : 8
Codec Configuration: num_of_codecs:2
Codec : g729r8, Maximum Packetization Period : 60 , Transcoder: Not Required
Codec : g711ulaw, Maximum Packetization Period : 30 , Transcoder: Not Required
Dspfarm Profile Configuration

Profile ID = 2, Service = MTP, Resource ID = 2
Profile Service Mode : Non Secure
Profile Admin State : UP
Profile Operation State : ACTIVE
Application : SCCP Status : ASSOCIATED
Resource Provider : FLEX_DSPRM Status : UP
Total Number of Resources Configured : 10
Total Number of Resources Available : 10
Total Number of Resources Out of Service : 0
Total Number of Resources Active : 0
Hardware Configured Resources : 10
Hardware Resources Out of Service: 0
Software Configured Resources : 0

 

The media resource list containing this transcoder are associated to the SIP trunk in CUCM.

 

Can you check the codec in use between the end user phone and trunk? 

Can you also try test call with MTP checked on  the SIP trunk?  also see what you configured Early Offer support for voice and video calls in the sup profile assigned to the trunk. 

 

Regards

I checked the box for MTP required and reset the trunk.  That did the trick.  Seems like it's always some checkbox somewhere in CUCM.

 

Was there anything that point to the MTP requirement in debugs?  I saw the cause code 47 and thought about transcoding resources but not MTP.

MTP required is not a fix. It is just masking the real issue. I would check the CUCM SIP trunk and enable early offer from there with only the CODECs defined in your gateway.

 

voice class codec 1
codec preference 1 g711ulaw
codec preference 2 g729r8

Scott Leport
Level 7
Level 7

Hi,

 

I wouldn’t set MTP required on the trunk. Basically MTP is being used for all calls, whether it’s required or not. If you have high call volumes and use the CUCM MTPs, you can run out of MTP resource and it can cause some issues, like dropping calls on hold. The early offer setting in the SIP profile would be better. 

 

Also check the device pools configured on the phone and trunk and check what the region relationship is between the two. If G.729, consider changing to G.722 / G.711. 

cisco.smj
Level 1
Level 1

I will remove the MTP required, enable early offer, and test.

 

The trunk and phone are in the same device pool for testing purposes.  G.711 is top of the preference list.

 

 

you need to remove undesired CODECs, not just rely on the highest preference. I would only allow G711ulaw, G729 and, maybe, G722.

 

The POTS line would probably be best if you just used G711 with no other options.

Getting Started

Find answers to your questions by entering keywords or phrases in the Search bar above. New here? Use these resources to familiarize yourself with the community: