Outgoing call not working in voip gateway

[dgonzalez1]

Hello, I'm troubleshuting a call in a voip gateway which is falling with 500-Internal Server Error message.

The call is made to number 0145 from extension 21999. The call is release stright away with no delay. I have an outgoing dial-peer which matches this number:

dial-peer voice 2006 pots
trunkgroup M-MARINGA-VIVO
description ## Salientes Otros Servicios#
preference 1
destination-pattern 01[1-80].
direct-inward-dial
forward-digits 3

Is in the traces that this dial-peer is selected but then an error came up (which I'm not undestanding) and message 500 is sent. This is attached trace 145-Nok1.txt

I tried adding an addicional dial-peer which matches the calling number more accurately:

dial-peer voice 2060 pots
trunkgroup M-MARINGA-VIVO
description ## Salientes 0145 ##
preference 1
destination-pattern 0145$
direct-inward-dial
forward-digits 3

In this case a call back tone is heard by a couple of seconds and then the call is release anyway also with 500-Internal Server Error message. In the traces I see that new dial-peer 2060 is selected first but then old dial-peer 2006 is reselected. This is attached trace 145-Nok2.txt

I tried then shuting down the old dial-peer 2006 and now the call is again release stright away, as in the first case, with no call back tone, despite I see in the trace that new dial-peer 2060 is selected. This is attached trace 145-Nok3.txt

I'm also ataching the complete config of the router.

(I had to compress the trace files because they wren't recognized as plain text and were removed.)

IOS version is:

RO-MATRIZ-MARINGA#show version
Cisco IOS XE Software, Version 16.03.07
Cisco IOS Software [Denali], ISR Software (X86_64_LINUX_IOSD-UNIVERSALK9-M), Version 16.3.7, RELEASE SOFTWARE (fc4)

If someone know what could be happening here I will appreciate any help.

Thank you in advance.

[collinks2]

Refer to this link to see if it can help you

https://community.cisco.com/t5/unified-communications/cube-and-cucm-intigration-with-sip-trunk-from-isp/m-p/4524710#M169577

[dgonzalez1]

Thank you Colling. I made a "debug vtsp all" and I see that all digits are being sent to the pstn and then a release occurs, I guess from the pstn. Is that what "Release Source=Called Party-PSTN" means:

//72996756/E0390C800001/VTSP:(0/1/0:0):2:1:3/vtsp_process_event:
[state:S_SETUP_REQ_PROC, event:E_TSP_DISCONNECT_IND]
027256: Dec 30 17:21:15.085: //72996756/E0390C800001/VTSP:(0/1/0:0):2:1:3/act_setup_pend_release_ind:
Cause Value=0
027257: Dec 30 17:21:15.085: //72996756/E0390C800001/VTSP:(0/1/0:0):2:1:3/vtsp_ring_noan_timer_stop:
Timer Stop Time=3920538644
027258: Dec 30 17:21:15.086: //72996756/E0390C800001/VTSP:(0/1/0:0):2:1:3/vtsp_cc_call_disconnected:
027259: Dec 30 17:21:15.086: //72996756/E0390C800001/VTSP:(0/1/0:0):2:1:3/vtsp_cc_call_disconnected:
Cause Value=0
027260: Dec 30 17:21:15.086: //72996756/E0390C800001/VTSP:(0/1/0:0):2:1:3/vtsp_set_release_source:
Release Direction=PSTN, Release Source=Called Party-PSTN

Thanks.

[dgonzalez1]

Thank you.

[dgonzalez1]

By the way, I made a "debug vtsp all" and I see that all digits are sent to the pstn, after what a release occurs, apparently from the pstn. Is that what "Release Source=Called Party-PSTN" means?:

027255: Dec 30 17:21:15.085: //72996756/E0390C800001/VTSP:(0/1/0:0):2:1:3/vtsp_process_event:
[state:S_SETUP_REQ_PROC, event:E_TSP_DISCONNECT_IND]
027256: Dec 30 17:21:15.085: //72996756/E0390C800001/VTSP:(0/1/0:0):2:1:3/act_setup_pend_release_ind:
Cause Value=0
027257: Dec 30 17:21:15.085: //72996756/E0390C800001/VTSP:(0/1/0:0):2:1:3/vtsp_ring_noan_timer_stop:
Timer Stop Time=3920538644
027258: Dec 30 17:21:15.086: //72996756/E0390C800001/VTSP:(0/1/0:0):2:1:3/vtsp_cc_call_disconnected:
027259: Dec 30 17:21:15.086: //72996756/E0390C800001/VTSP:(0/1/0:0):2:1:3/vtsp_cc_call_disconnected:
Cause Value=0
027260: Dec 30 17:21:15.086: //72996756/E0390C800001/VTSP:(0/1/0:0):2:1:3/vtsp_set_release_source:
Release Direction=PSTN, Release Source=Called Party-PSTN

Thank you.

[dgonzalez1]

By the way, I made a "debug vtsp all" and see that all digits are sent to the pstn, after what a release occurs, apparently from the pstn. Is that what "Release Direction=PSTN, Release Source=Called Party-PSTN" means?

Thank you.

[Roger Kallberg]

None of these dial peers has a port or session destination, so they can not be used in the outbound direction. Please have a look at this excellent document for details on how to configure call routing in IOS.

https://www.cisco.com/c/en/us/support/docs/voice/ip-telephony-voice-over-ip-voip/211306-In-Depth-Explanation-of-Cisco-IOS-and-IO.html

[dgonzalez1]

Thank you Roger, but a successull call to number 0190 is going out through dial-peer 2005 and both are configured the same:

dial-peer voice 2005 pots
trunkgroup M-MARINGA-VIVO
description ## Salientes Emergencias ##
preference 1
destination-pattern 019.$
direct-inward-dial
forward-digits 3
!
dial-peer voice 2006 pots
trunkgroup M-MARINGA-VIVO
description ## Salientes Otros Servicios#
preference 1
destination-pattern 01[1-80].
direct-inward-dial
forward-digits 3

Also all local, national calls, etc, are working fine.

Thanks.

[Roger Kallberg]

Please show the output of debug voip ccapi inout of a successful call that uses these dial peers. As said none of these can work as outbound with the configuration shared.

[dgonzalez1]

Sure, here you are.

Thank you for your time.

[Roger Kallberg]

For some reason the file can not be opened on a mobile device. Can you please repost it as a txt file?

[dgonzalez1]

Ok, I did so because otherway the file is removed because it's nor recognized as plain text:

I'll paste it here:

RO-MATRIZ-MARINGA#
RO-MATRIZ-MARINGA#debug voip ccapi inout
voip ccapi inout debugging is on
RO-MATRIZ-MARINGA#debug ccsip messages
SIP Call messages tracing is enabled
RO-MATRIZ-MARINGA#ter mon

027501: Dec 30 18:52:58.137: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:0190@172.23.80.12:5060 SIP/2.0
Via: SIP/2.0/UDP 172.23.80.38:5060;branch=z9hG4bK19bbc5b30c22fc9
From: <sip:21999@172.23.80.38>;tag=129457360~9544df53-d8df-44ee-8445-a1593fdd0243-77158469
To: <sip:0190@172.23.80.12>
Date: Thu, 30 Dec 2021 18:52:58 GMT
Call-ID: b33f2680-1ce1000a-15b5bce-265017ac@172.23.80.38
Supported: timer,resource-priority,replaces
Min-SE: 600
User-Agent: Cisco-CUCM11.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:172.23.80.38:5060>;method="NOTIFY;Event=telephone-event;Duration=500"
Call-Info: <urn:x-cisco-remotecc:callinfo>;x-cisco-video-traffic-class=DESKTOP;x-cisco-qos-tcl=true
Session-ID: 4a01a51700105000a0002a3197743d2a;remote=00000000000000000000000000000000
Cisco-Guid: 3007260288-0000065536-0000088779-0642783148
Session-Expires: 1800
P-Asserted-Identity: <sip:21999@172.23.80.38>
Remote-Party-ID: <sip:21999@172.23.80.38>;party=calling;screen=yes;privacy=off
Contact: <sip:21999@172.23.80.38:5060>;video;audio;+u.sip!devicename.ccm.cisco.com="BOTJPNAVARRO";bfcp
Max-Forwards: 13
Content-Length: 0

027502: Dec 30 18:52:58.139: //-1/B33F26800001/CCAPI/cc_api_display_ie_subfields:
cc_api_call_setup_ind_common:
cisco-username=21999
----- ccCallInfo IE subfields -----
cisco-ani=21999
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=1
dest=0190
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

027503: Dec 30 18:52:58.140: //-1/B33F26800001/CCAPI/cc_api_call_setup_ind_common:
Interface=0x7FB161A4CFF8, Call Info(
Calling Number=21999,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=0190(TON=Unknown, NPI=Unknown),
Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE,
Incoming Dial-peer=1000, Progress Indication=NULL(0), Calling IE Present=TRUE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=72999626
027504: Dec 30 18:52:58.140: //-1/B33F26800001/CCAPI/ccCheckClipClir:
In: Calling Number=21999(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
027505: Dec 30 18:52:58.140: //-1/B33F26800001/CCAPI/ccCheckClipClir:
Out: Calling Number=21999(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
027506: Dec 30 18:52:58.140: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

027507: Dec 30 18:52:58.140: :cc_get_feature_vsa malloc success
027508: Dec 30 18:52:58.140: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

027509: Dec 30 18:52:58.140: cc_get_feature_vsa count is 1
027510: Dec 30 18:52:58.140: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

027511: Dec 30 18:52:58.140: :FEATURE_VSA attributes are: feature_name:0,feature_time:140399896222980,feature_id:862707
027512: Dec 30 18:52:58.141: //72999626/B33F26800001/CCAPI/cc_api_call_setup_ind_common:
Set Up Event Sent;
Call Info(Calling Number=21999(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=0190(TON=Unknown, NPI=Unknown))
027513: Dec 30 18:52:58.141: //72999626/B33F26800001/CCAPI/cc_process_call_setup_ind:
Event=0x7FB165F31180
027514: Dec 30 18:52:58.141: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
Try with the demoted called number 0190
027515: Dec 30 18:52:58.142: //72999626/B33F26800001/CCAPI/ccCallSetContext:
Context=0x7FB16CA661E8
027516: Dec 30 18:52:58.142: //72999626/B33F26800001/CCAPI/cc_process_call_setup_ind:
>>>>CCAPI handed cid 72999626 with tag 1000 to app "_ManagedAppProcess_Default"
027517: Dec 30 18:52:58.142: //72999626/B33F26800001/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 172.23.80.38:5060;branch=z9hG4bK19bbc5b30c22fc9
From: <sip:21999@172.23.80.38>;tag=129457360~9544df53-d8df-44ee-8445-a1593fdd0243-77158469
To: <sip:0190@172.23.80.12>
Date: Thu, 30 Dec 2021 18:52:58 GMT
Call-ID: b33f2680-1ce1000a-15b5bce-265017ac@172.23.80.38
CSeq: 101 INVITE
Allow-Events: telephone-event
Server: Cisco-SIPGateway/IOS-16.3.7
Session-ID: 00000000000000000000000000000000;remote=4a01a51700105000a0002a3197743d2a
Content-Length: 0

027518: Dec 30 18:52:58.143: //72999626/B33F26800001/CCAPI/ccCallProceeding:
Progress Indication=NULL(0)
027519: Dec 30 18:52:58.143: //72999626/B33F26800001/CCAPI/ccCallSetupRequest:
Destination=, Calling IE Present=TRUE, Mode=0,
Outgoing Dial-peer=2005, Params=0x7FB16B1EB6E8, Progress Indication=NULL(0)
027520: Dec 30 18:52:58.143: //72999626/B33F26800001/CCAPI/cc_fill_tg_params:
Not a cic call
027521: Dec 30 18:52:58.143: //72999626/B33F26800001/CCAPI/ccCallSetupRequest:
Trunk Group Select Interface Success;
Interface=0x7FB169F6C628, Selected Interface=1, Selected DSL=-1
027522: Dec 30 18:52:58.144: //72999626/B33F26800001/CCAPI/ccCheckClipClir:
In: Calling Number=21999(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
027523: Dec 30 18:52:58.144: //72999626/B33F26800001/CCAPI/ccCheckClipClir:
Out: Calling Number=21999(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
027524: Dec 30 18:52:58.144: //72999626/B33F26800001/CCAPI/ccCallSetupRequest:
Destination Pattern=019.$, Called Number=0190, Digit Strip=TRUE
027525: Dec 30 18:52:58.144: //72999626/B33F26800001/CCAPI/ccCallSetupRequest:
Calling Number=21999(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=0190(TON=Unknown, NPI=Unknown),
Redirect Number=, Display Info=
Account Number=21999, Final Destination Flag=TRUE,
Guid=B33F2680-0001-0000-0001-5ACB265017AC, Outgoing Dial-peer=2005
027526: Dec 30 18:52:58.144: //72999626/B33F26800001/CCAPI/cc_api_display_ie_subfields:
ccCallSetupRequest:
cisco-username=21999
----- ccCallInfo IE subfields -----
cisco-ani=21999
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=1
dest=0190
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

027527: Dec 30 18:52:58.145: //72999626/B33F26800001/CCAPI/ccIFCallSetupRequestPrivate:
Interface=0x7FB169F6C628, Interface Type=6, Destination=, Mode=0x0,
Call Params(Calling Number=21999,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=0190(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing Dial-peer=2005, Call Count On=FALSE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=M-MARINGA-VIVO, tg_label_flag=2, Application Call Id=)
027528: Dec 30 18:52:58.145: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

027529: Dec 30 18:52:58.145: :cc_get_feature_vsa malloc success
027530: Dec 30 18:52:58.145: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

027531: Dec 30 18:52:58.145: cc_get_feature_vsa count is 2
027532: Dec 30 18:52:58.145: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

027533: Dec 30 18:52:58.145: :FEATURE_VSA attributes are: feature_name:0,feature_time:140399896235972,feature_id:862708
027534: Dec 30 18:52:58.145: //72999627/B33F26800001/CCAPI/ccIFCallSetupRequestPrivate:
SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1
027535: Dec 30 18:52:58.145: //72999626/B33F26800001/CCAPI/ccCallSetupRequest:

RO-MATRIZ-MARINGA#Trunk Group Call Setting;
Call Entry(Retry Count=0, Voice Class Cause Code=0)
027536: Dec 30 18:52:58.145: //72999627/B33F26800001/CCAPI/ccCallSetContext:
Context=0x7FB16B1EB668
027537: Dec 30 18:52:58.145: //72999626/B33F26800001/CCAPI/ccSaveDialpeerTag:
Outgoing Dial-peer=2005
027538: Dec 30 18:52:58.146: //72999626/B33F26800001/CCAPI/cc_api_modify_tgt_cid_call_active_record:
Target Trkgrp Route Label=M-MARINGA-VIVO, tg_label_flag=TRUE, Call Id=0x459E2CA
027539: Dec 30 18:52:58.600: //72999627/B33F26800001/CCAPI/cc_api_call_proceeding:
Interface=0x7FB169F6C628, Progress Indication=NULL(0)
027540: Dec 30 18:52:58.600: //72999627/B33F26800001/CCAPI/ccSetCallGCID:
Update GCID [FFFFFFFF8A22961B-68D811EC-FFFFFFFFBE66F36B-FFFFFFFFBDC2265E] with [FFFFFFFF8A22961B-68D811EC-FFFFFFFFBE66F36B-FFFFFFFFBDC2265E]
027541: Dec 30 18:52:59.153: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:

027549: Dec 30 18:53:01.334: //72999627/B33F26800001/CCAPI/cc_api_call_alert:
Interface=0x7FB169F6C628, Progress Indication=INBAND(8), Signal Indication=SIGNAL RINGBACK(1)
027550: Dec 30 18:53:01.334: //72999627/B33F26800001/CCAPI/cc_api_call_alert:
Call Entry(Retry Count=0, Responsed=TRUE)
027551: Dec 30 18:53:01.334: //72999627/B33F26800001/CCAPI/ccSetCallGCID:
Update GCID [FFFFFFFF8A22961B-68D811EC-FFFFFFFFBE66F36B-FFFFFFFFBDC2265E] with [FFFFFFFF8A22961B-68D811EC-FFFFFFFFBE66F36B-FFFFFFFFBDC2265E]
027552: Dec 30 18:53:01.334: //72999626/B33F26800001/CCAPI/ccCallAlert:
Progress Indication=INBAND(8), Signal Indication=SIGNAL RINGBACK(1)
027553: Dec 30 18:53:01.334: //72999626/B33F26800001/CCAPI/ccCallAlert:
Call Entry(Responsed=TRUE, Alert Sent=TRUE)
027554: Dec 30 18:53:01.335: //72999626/B33F26800001/CCAPI/ccConferenceCreate:
(confID=0xFFFFFFFFFFFFFFFF, callID1=0x459E2CA, gcid=8A22961B-68D811EC-BE66F36B-BDC2265E, tag=0x0)
027555: Dec 30 18:53:01.335: //72999627/B33F26800001/CCAPI/ccConferenceCreate:
(confID=0xFFFFFFFFFFFFFFFF, callID2=0x459E2CB, gcid=8A22961B-68D811EC-BE66F36B-BDC2265E, tag=0x0)
027556: Dec 30 18:53:01.335: //72999626/B33F26800001/CCAPI/ccConferenceCreate:
Conference Id=0xFFFFFFFFFFFFFFFF, Call Id1=72999626, Call Id2=72999627, Tag=0x0
027557: Dec 30 18:53:01.335: //72999626/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

027558: Dec 30 18:53:01.335: cc_api_get_xcode_stream : 4999
027559: Dec 30 18:53:01.335: //72999626/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

027560: Dec 30 18:53:01.335: cc_api_get_xcode_stream : 4999
027561: Dec 30 18:53:01.335: //72999626/B33F26800001/CCAPI/cc_api_bridge_done:
Conference Id=0x35151, Source Interface=0x7FB161A4CFF8, Source Call Id=72999626,
Destination Call Id=72999627, Disposition=0x0, Tag=0x0
027562: Dec 30 18:53:01.335: //72999627/B33F26800001/CCAPI/cc_api_bridge_done:
Conference Id=0x35151, Source Interface=0x7FB169F6C628, Source Call Id=72999627,
Destination Call Id=72999626, Disposition=0x0, Tag=0xFFFFFFFFFFFFFFFF
027563: Dec 30 18:53:01.335: //72999626/B33F26800001/CCAPI/cc_generic_bridge_done:
Conference Id=0x35151, Source Interface=0x7FB169F6C628, Source Call Id=72999627,
Destination Call Id=72999626, Disposition=0x0, Tag=0xFFFFFFFFFFFFFFFF
027564: Dec 30 18:53:01.336: //72999626/B33F26800001/CCAPI/ccConferenceCreate:
Call Entry(Conference Id=0x35151, Destination Call Id=72999627)
027565: Dec 30 18:53:01.336: //72999627/B33F26800001/CCAPI/ccConferenceCreate:
Call Entry(Conference Id=0x35151, Destination Call Id=72999626)
027566: Dec 30 18:53:01.336: //72999626/B33F26800001/CCAPI/ccConferenceCreate:

027567: Dec 30 18:53:01.336: confID:0x35151; callEntry1 callID1:0x459E2CA, type:3; callEntry2 callID2:0x459E2CB, type:6

027568: Dec 30 18:53:01.336: //72999627/B33F26800001/CCAPI/ccSetCallGCID:
Update GCID [FFFFFFFF8A22961B-68D811EC-FFFFFFFFBE66F36B-FFFFFFFFBDC2265E] with [FFFFFFFF8A22961B-68D811EC-FFFFFFFFBE66F36B-FFFFFFFFBDC2265E]
027569: Dec 30 18:53:01.336: //72999627/B33F26800001/CCAPI/cc_api_caps_ind:

027570: Dec 30 18:53:01.336: voice_rtp_nse_payload 0, voice_rtp_sse_payload 118 voice_sprt_payload 120
027571: Dec 30 18:53:01.336: //72999627/B33F26800001/CCAPI/cc_api_caps_ind:

027572: Dec 30 18:53:01.336: sprt_latency 200, sprt_retries 12
027573: Dec 30 18:53:01.336: //72999627/B33F26800001/CCAPI/cc_api_caps_ind:
Destination Interface=0x7FB161A4CFF8, Destination Call Id=72999626, Source Call Id=72999627,
Caps(Codec=0x1, Fax Rate=0x1, Fax Version:=0, Vad=0x1,
Modem=0x2, Codec Bytes=20, Signal Type=3)
027574: Dec 30 18:53:01.336: //72999627/B33F26800001/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))
027575: Dec 30 18:53:01.337: //72999626/B33F26800001/CCAPI/cc_api_caps_ind:

027576: Dec 30 18:53:01.337: voice_rtp_nse_payload 100, voice_rtp_sse_payload 118 voice_sprt_payload 120
027577: Dec 30 18:53:01.337: //72999626/B33F26800001/CCAPI/cc_api_caps_ind:

027578: Dec 30 18:53:01.337: sprt_latency 200, sprt_retries 12
027579: Dec 30 18:53:01.337: //72999626/B33F26800001/CCAPI/cc_api_caps_ind:
Destination Interface=0x7FB169F6C628, Destination Call Id=72999627, Source Call Id=72999626,
Caps(Codec=0x1, Fax Rate=0x2, Fax Version:=0, Vad=0x1,
Modem=0x0, Codec Bytes=160, Signal Type=2)
027580: Dec 30 18:53:01.337: //72999626/B33F26800001/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))
027581: Dec 30 18:53:01.337: //72999626/B33F26800001/CCAPI/cc_api_caps_ack:
Destination Interface=0x7FB169F6C628, Destination Call Id=72999627, Source Call Id=72999626,
Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Fax Version:=0, Vad=OFF(0x1),
Modem=OFF(0x0), Codec Bytes=160, Signal Type=2, Seq Num Start=8006)
027582: Dec 30 18:53:01.337: //72999627/B33F26800001/CCAPI/cc_api_caps_ack:
Destination Interface=0x7FB161A4CFF8, Destination Call Id=72999626, Source Call Id=72999627,
Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Fax Version:=0, Vad=OFF(0x1),
Modem=OFF(0x0), Codec Bytes=160, Signal Type=2, Seq Num Start=8006)
027583: Dec 30 18:53:01.337: //72999626/B33F26800001/CCAPI/ccCallNotify:
Data Bitmask=0x7, Call Id=72999626
027584: Dec 30 18:53:01.339: //72999626/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

027585: Dec 30 18:53:01.339: cc_api_get_xcode_stream : 4999
027586: Dec 30 18:53:01.339: //72999626/B33F26800001/CCAPI/cc_process_notify_bridge_done:
Conference Id=0x35151, Call Id1=72999626, Call Id2=72999627
027587: Dec 30 18:53:01.341: //72999627/B33F26800001/CCAPI/cc_api_voice_mode_event:
Call Id=72999627
027588: Dec 30 18:53:01.341: //72999627/B33F26800001/CCAPI/cc_api_voice_mode_event:
Call Entry(Context=0x7FB16B1EB668)
027589: Dec 30 18:53:01.341: //72999626/B33F26800001/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 172.23.80.38:5060;branch=z9hG4bK19bbc5b30c22fc9
From: <sip:21999@172.23.80.38>;tag=129457360~9544df53-d8df-44ee-8445-a1593fdd0243-77158469
To: <sip:0190@172.23.80.12>;tag=2128E688-BE1
Date: Thu, 30 Dec 2021 18:52:58 GMT
Call-ID: b33f2680-1ce1000a-15b5bce-265017ac@172.23.80.38
CSeq: 101 INVITE
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Remote-Party-ID: <sip:190@172.23.80.12>;party=called;screen=no;privacy=off
Contact: <sip:0190@172.23.80.12:5060>
Supported: sdp-anat
Server: Cisco-SIPGateway/IOS-16.3.7
Session-ID: c595b13b7558505dbf1e4bacc3a79924;remote=4a01a51700105000a0002a3197743d2a
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 444

v=0
o=CiscoSystemsSIP-GW-UserAgent 95 7050 IN IP4 172.23.80.12
s=SIP Call
c=IN IP4 172.23.80.12
t=0 0
m=audio 20232 RTP/AVP 0 100 101
c=IN IP4 172.23.80.12
a=rtpmap:0 PCMU/8000
a=rtpmap:100 X-NSE/8000
a=fmtp:100 192-194,200-202
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=X-sqn:0
a=X-cap: 1 audio RTP/AVP 100
a=X-cpar: a=rtpmap:100 X-NSE/8000
a=X-cpar: a=fmtp:100 192-194,200-202
a=X-cap: 2 image udptl t38

027590: Dec 30 18:53:01.342: //72999627/B33F26800001/CCAPI/ccSetCallGCID:
Update GCID [FFFFFFFF8A22961B-68D811EC-FFFFFFFFBE66F36B-FFFFFFFFBDC2265E] with [FFFFFFFF8A22961B-68D811EC-FFFFFFFFBE66F36B-FFFFFFFFBDC2265E]
027591: Dec 30 18:53:01.342: //72999627/B33F26800001/CCAPI/ccSetCallGCID:

RO-MATRIZ-MARINGA#Update GCID [FFFFFFFF8A22961B-68D811EC-FFFFFFFFBE66F36B-FFFFFFFFBDC2265E] with [FFFFFFFF8A22961B-68D811EC-FFFFFFFFBE66F36B-FFFFFFFFBDC2265E]
027592: Dec 30 18:53:01.342: //72999627/B33F26800001/CCAPI/ccSetCallGCID:
Update GCID [FFFFFFFF8A22961B-68D811EC-FFFFFFFFBE66F36B-FFFFFFFFBDC2265E] with [FFFFFFFF8A22961B-68D811EC-FFFFFFFFBE66F36B-FFFFFFFFBDC2265E]
027593: Dec 30 18:53:02.438: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:

RO-MATRIZ-MARINGA#
027595: Dec 30 18:53:09.480: //72999627/B33F26800001/CCAPI/cc_api_call_connected:
Interface=0x7FB169F6C628, Data Bitmask=0x1, Progress Indication=DESTINATION IS NON ISDN(2),
Connection Handle=0
027596: Dec 30 18:53:09.480: //72999627/B33F26800001/CCAPI/cc_api_call_connected:
Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0)
027597: Dec 30 18:53:09.480: //72999627/B33F26800001/CCAPI/ccSetCallGCID:
Update GCID [FFFFFFFF8A22961B-68D811EC-FFFFFFFFBE66F36B-FFFFFFFFBDC2265E] with [FFFFFFFF8A22961B-68D811EC-FFFFFFFFBE66F36B-FFFFFFFFBDC2265E]
027598: Dec 30 18:53:09.480: //72999626/B33F26800001/CCAPI/ccCallConnect:
Progress Indication=DESTINATION IS NON ISDN(2), Data Bitmask=0x1
027599: Dec 30 18:53:09.481: //72999626/B33F26800001/CCAPI/ccCallConnect:
Call Entry(Connected=TRUE, Responsed=TRUE)
027600: Dec 30 18:53:09.481: //72999626/B33F26800001/CCAPI/ccCallNotify:
Data Bitmask=0x7, Call Id=72999626
027601: Dec 30 18:53:09.482: //72999626/B33F26800001/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.23.80.38:5060;branch=z9hG4bK19bbc5b30c22fc9
From: <sip:21999@172.23.80.38>;tag=129457360~9544df53-d8df-44ee-8445-a1593fdd0243-77158469
To: <sip:0190@172.23.80.12>;tag=2128E688-BE1
Date: Thu, 30 Dec 2021 18:52:58 GMT
Call-ID: b33f2680-1ce1000a-15b5bce-265017ac@172.23.80.38
CSeq: 101 INVITE
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Remote-Party-ID: <sip:190@172.23.80.12>;party=called;screen=no;privacy=off
Contact: <sip:0190@172.23.80.12:5060>
Supported: replaces
Supported: sdp-anat
Server: Cisco-SIPGateway/IOS-16.3.7
Session-ID: c595b13b7558505dbf1e4bacc3a79924;remote=4a01a51700105000a0002a3197743d2a
Require: timer
Session-Expires: 1800;refresher=uac
Supported: timer
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 444

v=0
o=CiscoSystemsSIP-GW-UserAgent 95 7050 IN IP4 172.23.80.12
s=SIP Call
c=IN IP4 172.23.80.12
t=0 0
m=audio 20232 RTP/AVP 0 100 101
c=IN IP4 172.23.80.12
a=rtpmap:0 PCMU/8000
a=rtpmap:100 X-NSE/8000
a=fmtp:100 192-194,200-202
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=X-sqn:0
a=X-cap: 1 audio RTP/AVP 100
a=X-cpar: a=rtpmap:100 X-NSE/8000
a=X-cpar: a=fmtp:100 192-194,200-202
a=X-cap: 2 image udptl t38

027602: Dec 30 18:53:09.496: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
ACK sip:0190@172.23.80.12:5060 SIP/2.0
Via: SIP/2.0/UDP 172.23.80.38:5060;branch=z9hG4bK19bbc5e2c53d4cf
From: <sip:21999@172.23.80.38>;tag=129457360~9544df53-d8df-44ee-8445-a1593fdd0243-77158469
To: <sip:0190@172.23.80.12>;tag=2128E688-BE1
Date: Thu, 30 Dec 2021 18:52:58 GMT
Call-ID: b33f2680-1ce1000a-15b5bce-265017ac@172.23.80.38
User-Agent: Cisco-CUCM11.5
Max-Forwards: 70
CSeq: 101 ACK
Allow-Events: presence, kpml
Session-ID: 4a01a51700105000a0002a3197743d2a;remote=c595b13b7558505dbf1e4bacc3a79924
Content-Type: application/sdp
Content-Length: 414

v=0
o=CiscoSystemsCCM-SIP 129457360 1 IN IP4 172.23.80.38
s=SIP Call
c=IN IP4 172.20.101.26
b=TIAS:64000
b=AS:64
t=0 0
a=cisco-mari:v1
a=cisco-mari-rate
m=audio 49730 RTP/AVP 0 101
a=extmap:14/sendrecv http://protocols.cisco.com/timestamp#100us
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=trafficclass:conversational.audio.aq:admitted
a=rtcp:49731 IN IP4 172.20.101.26

027603: Dec 30 18:53:09.497: //72999626/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

027604: Dec 30 18:53:09.497: cc_api_get_xcode_stream : 4999
027605: Dec 30 18:53:09.497: //72999626/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

027606: Dec 30 18:53:09.497: cc_api_get_xcode_stream : 4999
027607: Dec 30 18:53:09.497: //72999626/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

027608: Dec 30 18:53:09.498: cc_api_get_xcode_stream : 4999
027609: Dec 30 18:53:09.498: //72999626/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

027610: Dec 30 18:53:09.498: cc_api_get_xcode_stream : 4999
027611: Dec 30 18:53:09.498: //72999626/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

027612: Dec 30 18:53:09.498: cc_api_get_xcode_stream : 4999
027613: Dec 30 18:53:09.498: //72999626/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

027614: Dec 30 18:53:09.498: cc_api_get_xcode_stream : 4999
027615: Dec 30 18:53:09.498: //72999626/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

027616: Dec 30 18:53:09.498: cc_api_get_xcode_stream : 4999
027617: Dec 30 18:53:09.498: //72999626/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

027618: Dec 30 18:53:09.498: cc_api_get_xcode_stream : 4999
027619: Dec 30 18:53:09.498: //72999626/B33F26800001/CCAPI/cc_api_caps_ind:

027620: Dec 30 18:53:09.498: voice_rtp_nse_payload 0, voice_rtp_sse_payload 118 voice_sprt_payload 120
027621: Dec 30 18:53:09.498: //72999626/B33F26800001/CCAPI/cc_api_caps_ind:

027622: Dec 30 18:53:09.498: sprt_latency 200, sprt_retries 12
027623: Dec 30 18:53:09.499: //72999626/B33F26800001/CCAPI/cc_api_caps_ind:
Destination Interface=0x7FB169F6C628, Destination Call Id=72999627, Source Call Id=72999626,
Caps(Codec=0x1, Fax Rate=0x2, Fax Version:=0, Vad=0x1,
Modem=0x0, Codec Bytes=160, Signal Type=2)
027624: Dec 30 18:53:09.499: //72999626/B33F26800001/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))
027625: Dec 30 18:53:09.499: //72999626/B33F26800001/CCAPI/cc_api_caps_ack:
Destination Interface=0x7FB169F6C628, Destination Call Id=72999627, Source Call Id=72999626,
Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Fax Version:=0, Vad=OFF(0x1),
Modem=OFF(0x0), Codec Bytes=160, Signal Type=2, Seq Num Start=6971)
027626: Dec 30 18:53:09.499: //72999627/B33F26800001/CCAPI/cc_api_caps_ack:
Destination Interface=0x7FB161A4CFF8, Destination Call Id=72999626, Source Call Id=72999627,
Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Fax Version:=0, Vad=OFF(0x1),
Modem=OFF(0x0), Codec Bytes=160, Signal Type=2, Seq Num Start=6971)
027627: Dec 30 18:53:09.501: //72999627/B33F26800001/CCAPI/cc_api_voice_mode_event:

RO-MATRIZ-MARINGA#Call Id=72999627
027628: Dec 30 18:53:09.501: //72999627/B33F26800001/CCAPI/cc_api_voice_mode_event:
Call Entry(Context=0x7FB16B1EB668)
RO-MATRIZ-MARINGA#
027629: Dec 30 18:53:11.946: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:

027654: Dec 30 18:53:31.951: //72999627/B33F26800001/CCAPI/ccGenerateToneInfo:
Stop Tone On Digit=FALSE, Tone=Null,
Tone Direction=Sum Network, Params=0x0, Call Id=72999627
027655: Dec 30 18:53:31.951: //72999626/B33F26800001/CCAPI/cc_api_call_disconnected:
Cause Value=16, Interface=0x7FB161A4CFF8, Call Id=72999626
027656: Dec 30 18:53:31.951: //72999626/B33F26800001/CCAPI/cc_api_call_disconnected:
Call Entry(Responsed=TRUE, Cause Value=16, Retry Count=0)
027657: Dec 30 18:53:31.952: //72999626/B33F26800001/CCAPI/ccConferenceDestroy:
Conference Id=0x35151, Tag=0x0
027658: Dec 30 18:53:31.952: //72999626/B33F26800001/CCAPI/ccConferenceDestroy:

027659: Dec 30 18:53:31.952: confID:0x35151; callEntry1 callID1:0x459E2CA, type:3; callEntry2 callID2:0x459E2CB, type:6

027660: Dec 30 18:53:31.952: //72999626/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

027661: Dec 30 18:53:31.952: cc_api_get_xcode_stream : 4999
027662: Dec 30 18:53:31.953: //72999627/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

027663: Dec 30 18:53:31.953: cc_api_get_xcode_stream : 4999
027664: Dec 30 18:53:31.953: //72999626/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

027665: Dec 30 18:53:31.953: cc_api_get_xcode_stream : 4999
027666: Dec 30 18:53:31.953: //72999626/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

027667: Dec 30 18:53:31.953: cc_api_get_xcode_stream : 4999
027668: Dec 30 18:53:31.953: //72999626/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

027669: Dec 30 18:53:31.953: cc_api_get_xcode_stream : 4999
027670: Dec 30 18:53:31.953: //72999626/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

027671: Dec 30 18:53:31.953: cc_api_get_xcode_stream : 4999
027672: Dec 30 18:53:31.953: //72999626/B33F26800001/CCAPI/cc_api_bridge_drop_done:
Conference Id=0x35151, Source Interface=0x7FB161A4CFF8, Source Call Id=72999626,
Destination Call Id=72999627, Disposition=0x0, Tag=0x0
027673: Dec 30 18:53:31.953: //72999627/B33F26800001/CCAPI/cc_api_bridge_drop_done:
Conference Id=0x35151, Source Interface=0x7FB169F6C628, Source Call Id=72999627,
Destination Call Id=72999626, Disposition=0x0, Tag=0x0
027674: Dec 30 18:53:31.953: //72999626/B33F26800001/CCAPI/cc_generic_bridge_done:
Conference Id=0x35151, Source Interface=0x7FB169F6C628, Source Call Id=72999627,
Destination Call Id=72999626, Disposition=0x0, Tag=0x0
027675: Dec 30 18:53:31.955: //72999626/B33F26800001/CCAPI/ccCallDisconnect:
Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16)
027676: Dec 30 18:53:31.955: //72999626/B33F26800001/CCAPI/ccCallDisconnect:
Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
027677: Dec 30 18:53:31.955: //72999627/B33F26800001/CCAPI/ccCallDisconnect:
Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
027678: Dec 30 18:53:31.955: //72999627/B33F26800001/CCAPI/ccCallDisconnect:
Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
027679: Dec 30 18:53:31.955: //72999627/B33F26800001/CCAPI/cc_api_get_transfer_info:
Transfer Number=NULL
027680: Dec 30 18:53:31.998: //72999626/B33F26800001/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x7FB161A4CFF8, Tag=0x0, Call Id=72999626,
Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
027681: Dec 30 18:53:31.998: //72999626/B33F26800001/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
027682: Dec 30 18:53:31.998: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

027683: Dec 30 18:53:31.998: :cc_free_feature_vsa freeing 7FB165F0DCF8
027684: Dec 30 18:53:31.998: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

027685: Dec 30 18:53:31.998: vsacount in free is 1
027686: Dec 30 18:53:31.999: //72999626/B33F26800001/SIP/Msg/ccsipDisplayMsg:
Sent:

RO-MATRIZ-MARINGA#SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.23.80.38:5060;branch=z9hG4bK19bbc67492313ed
From: <sip:21999@172.23.80.38>;tag=129457360~9544df53-d8df-44ee-8445-a1593fdd0243-77158469
To: <sip:0190@172.23.80.12>;tag=2128E688-BE1
Date: Thu, 30 Dec 2021 18:53:31 GMT
Call-ID: b33f2680-1ce1000a-15b5bce-265017ac@172.23.80.38
Server: Cisco-SIPGateway/IOS-16.3.7
CSeq: 102 BYE
Reason: Q.850;cause=16
P-RTP-Stat: PS=1122,OS=224400,PR=1093,OR=218600,PL=0,JI=5,LA=0,DU=22
Session-ID: c595b13b7558505dbf1e4bacc3a79924;remote=c595b13b7558505dbf1e4bacc3a79924
Content-Length: 0

027687: Dec 30 18:53:32.311: //72999627/B33F26800001/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x7FB169F6C628, Tag=0x0, Call Id=72999627,
Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
027688: Dec 30 18:53:32.311: //72999627/B33F26800001/CCAPI/cc_api_call_disconnect_done:
tg for this call is M-MARINGA-VIVO success/fail is 1
027689: Dec 30 18:53:32.311: //72999627/B33F26800001/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
027690: Dec 30 18:53:32.311: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

027691: Dec 30 18:53:32.311: :cc_free_feature_vsa freeing 7FB165F10FB8
027692: Dec 30 18:53:32.311: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

RO-MATRIZ-MARINGA#
027693: Dec 30 18:53:32.311: vsacount in free is 0
RO-MATRIZ-MARINGA#und

Thank you

[Roger Kallberg]

For a short while there was a post with the output of the debug directly in the post, but now it’s oddly enough gone. Nevertheless I saw that I overlooked the fact that there is a trunk group on the dial peers. As such they do work as outbound dial peers. Do you have the output of the same debug for a failed call?

[dgonzalez1]

Yes, I don't know why some post are deleted.

This is the failing trace:

RO-MATRIZ-MARINGA#debug voip ccapi inout
voip ccapi inout debugging is on
RO-MATRIZ-MARINGA#debug ccsip mess
RO-MATRIZ-MARINGA#debug ccsip messages
SIP Call messages tracing is enabled
RO-MATRIZ-MARINGA#ter mon
RO-MATRIZ-MARINGA#

025850: Dec 30 12:42:42.391: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:0145@172.23.80.12:5060 SIP/2.0
Via: SIP/2.0/UDP 172.23.80.38:5060;branch=z9hG4bK19ba5a323813f04
From: <sip:21999@172.23.80.38>;tag=129436899~9544df53-d8df-44ee-8445-a1593fdd0243-77157907
To: <sip:0145@172.23.80.12>
Date: Thu, 30 Dec 2021 12:42:42 GMT
Call-ID: f97a9280-1cd1a942-15b48fb-265017ac@172.23.80.38
Supported: timer,resource-priority,replaces
Min-SE: 600
User-Agent: Cisco-CUCM11.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:172.23.80.38:5060>;method="NOTIFY;Event=telephone-event;Duration=500"
Call-Info: <urn:x-cisco-remotecc:callinfo>;x-cisco-video-traffic-class=DESKTOP;x-cisco-qos-tcl=true
Session-ID: 1340511100105000a0002a3197743d2a;remote=00000000000000000000000000000000
Cisco-Guid: 4185559680-0000065536-0000088737-0642783148
Session-Expires: 1800
P-Asserted-Identity: <sip:21999@172.23.80.38>
Remote-Party-ID: <sip:21999@172.23.80.38>;party=calling;screen=yes;privacy=off
Contact: <sip:21999@172.23.80.38:5060>;video;audio;+u.sip!devicename.ccm.cisco.com="BOTJPNAVARRO";bfcp
Max-Forwards: 13
Content-Length: 0

025851: Dec 30 12:42:42.393: //-1/F97A92800001/CCAPI/cc_api_display_ie_subfields:
cc_api_call_setup_ind_common:
cisco-username=21999
----- ccCallInfo IE subfields -----
cisco-ani=21999
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=1
dest=0145
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

025852: Dec 30 12:42:42.394: //-1/F97A92800001/CCAPI/cc_api_call_setup_ind_common:
Interface=0x7FB161A4CFF8, Call Info(
Calling Number=21999,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=0145(TON=Unknown, NPI=Unknown),
Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE,
Incoming Dial-peer=1000, Progress Indication=NULL(0), Calling IE Present=TRUE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=72988421
025853: Dec 30 12:42:42.394: //-1/F97A92800001/CCAPI/ccCheckClipClir:
In: Calling Number=21999(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
025854: Dec 30 12:42:42.394: //-1/F97A92800001/CCAPI/ccCheckClipClir:
Out: Calling Number=21999(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
025855: Dec 30 12:42:42.394: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

025856: Dec 30 12:42:42.394: :cc_get_feature_vsa malloc success
025857: Dec 30 12:42:42.394: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

025858: Dec 30 12:42:42.394: cc_get_feature_vsa count is 1
025859: Dec 30 12:42:42.394: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

025860: Dec 30 12:42:42.394: :FEATURE_VSA attributes are: feature_name:0,feature_time:140399896233732,feature_id:862237
025861: Dec 30 12:42:42.394: //72988421/F97A92800001/CCAPI/cc_api_call_setup_ind_common:
Set Up Event Sent;
Call Info(Calling Number=21999(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=0145(TON=Unknown, NPI=Unknown))
025862: Dec 30 12:42:42.395: //72988421/F97A92800001/CCAPI/cc_process_call_setup_ind:
Event=0x7FB165F2CD40
025863: Dec 30 12:42:42.395: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
Try with the demoted called number 0145
025864: Dec 30 12:42:42.395: //72988421/F97A92800001/CCAPI/ccCallSetContext:
Context=0x7FB16B470F80
025865: Dec 30 12:42:42.395: //72988421/F97A92800001/CCAPI/cc_process_call_setup_ind:
>>>>CCAPI handed cid 72988421 with tag 1000 to app "_ManagedAppProcess_Default"
025866: Dec 30 12:42:42.395: //72988421/F97A92800001/CCAPI/ccCallProceeding:
Progress Indication=NULL(0)
025867: Dec 30 12:42:42.396: //72988421/F97A92800001/CCAPI/ccCallSetupRequest:
Destination=, Calling IE Present=TRUE, Mode=0,
Outgoing Dial-peer=2006, Params=0x7FB16CA56670, Progress Indication=NULL(0)
025868: Dec 30 12:42:42.396: //72988421/F97A92800001/CCAPI/cc_fill_tg_params:
Not a cic call
025869: Dec 30 12:42:42.396: //72988421/F97A92800001/CCAPI/ccCallSetupRequest:
Trunk Group Select Interface Success;
Interface=0x7FB169F6C628, Selected Interface=1, Selected DSL=-1
025870: Dec 30 12:42:42.396: //72988421/F97A92800001/CCAPI/ccCheckClipClir:
In: Calling Number=21999(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
025871: Dec 30 12:42:42.397: //72988421/F97A92800001/CCAPI/ccCheckClipClir:
Out: Calling Number=21999(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
025872: Dec 30 12:42:42.397: //72988421/F97A92800001/CCAPI/ccCallSetupRequest:
Destination Pattern=01[1-80]., Called Number=0145, Digit Strip=TRUE
025873: Dec 30 12:42:42.397: //72988421/F97A92800001/CCAPI/ccCallSetupRequest:
Calling Number=21999(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=0145(TON=Unknown, NPI=Unknown),
Redirect Number=, Display Info=
Account Number=21999, Final Destination Flag=TRUE,
Guid=F97A9280-0001-0000-0001-5AA1265017AC, Outgoing Dial-peer=2006
025874: Dec 30 12:42:42.397: //72988421/F97A92800001/CCAPI/cc_api_display_ie_subfields:
ccCallSetupRequest:
cisco-username=21999
----- ccCallInfo IE subfields -----
cisco-ani=21999
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=1
dest=0145
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

025875: Dec 30 12:42:42.398: //72988421/F97A92800001/CCAPI/ccIFCallSetupRequestPrivate:
Interface=0x7FB169F6C628, Interface Type=6, Destination=, Mode=0x0,
Call Params(Calling Number=21999,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=0145(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing Dial-peer=2006, Call Count On=FALSE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=M-MARINGA-VIVO, tg_label_flag=2, Application Call Id=)
025876: Dec 30 12:42:42.398: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

025877: Dec 30 12:42:42.398: :cc_get_feature_vsa malloc success
025878: Dec 30 12:42:42.398: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

025879: Dec 30 12:42:42.398: cc_get_feature_vsa count is 2
025880: Dec 30 12:42:42.398: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

025881: Dec 30 12:42:42.398: :FEATURE_VSA attributes are: feature_name:0,feature_time:140399896223876,feature_id:862238
025882: Dec 30 12:42:42.398: //72988422/F97A92800001/CCAPI/ccIFCallSetupRequestPrivate:
SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1
025883: Dec 30 12:42:42.398: //72988421/F97A92800001/CCAPI/ccCallSetupRequest:
Trunk Group Call Setting;
Call Entry(Retry Count=0, Voice Class Cause Code=0)
025884: Dec 30 12:42:42.398: //72988422/F97A92800001/CCAPI/ccCallSetContext:
Context=0x7FB16CA565F0
025885: Dec 30 12:42:42.398: //72988421/F97A92800001/CCAPI/ccSaveDialpeerTag:
Outgoing Dial-peer=2006
025886: Dec 30 12:42:42.399: //72988421/F97A92800001/CCAPI/cc_api_modify_tgt_cid_call_active_record:
Target Trkgrp Route Label=M-MARINGA-VIVO, tg_label_flag=TRUE, Call Id=0x459B705
025887: Dec 30 12:42:42.400: //72988421/F97A92800001/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 172.23.80.38:5060;branch=z9hG4bK19ba5a323813f04
From: <sip:21999@172.23.80.38>;tag=129436899~9544df53-d8df-44ee-8445-a1593fdd0243-77157907
To: <sip:0145@172.23.80.12>
Date: Thu, 30 Dec 2021 12:42:42 GMT
Call-ID: f97a9280-1cd1a942-15b48fb-265017ac@172.23.80.38
CSeq: 101 INVITE
Allow-Events: telephone-event
Server: Cisco-SIPGateway/IOS-16.3.7
Session-ID: 00000000000000000000000000000000;remote=1340511100105000a0002a3197743d2a
Content-Length: 0

025890: Dec 30 12:42:42.850: //72988422/F97A92800001/CCAPI/cc_api_call_proceeding:
Interface=0x7FB169F6C628, Progress Indication=NULL(0)
025891: Dec 30 12:42:42.850: //72988422/F97A92800001/CCAPI/ccSetCallGCID:

025896: Dec 30 12:42:46.575: //72988422/F97A92800001/CCAPI/cc_api_call_disconnected:
Cause Value=0, Interface=0x7FB169F6C628, Call Id=72988422
025897: Dec 30 12:42:46.575: //72988422/F97A92800001/CCAPI/cc_api_call_disconnected:
Call Entry(Responsed=TRUE, Cause Value=0, Retry Count=0)
025898: Dec 30 12:42:46.575: //72988422/F97A92800001/CCAPI/ccSetCallGCID:
Update GCID [FFFFFFFFD084C361-68A411EC-FFFFFFFF90D5F36B-FFFFFFFFBDC2265E] with [FFFFFFFFD084C361-68A411EC-FFFFFFFF90D5F36B-FFFFFFFFBDC2265E]
025899: Dec 30 12:42:46.575: //72988421/F97A92800001/CCAPI/ccCallReleaseResources:
release reserved xcoding resource.
025900: Dec 30 12:42:46.576: //72988422/F97A92800001/CCAPI/ccCallSetAAA_Accounting:
Accounting=0, Call Id=72988422
025901: Dec 30 12:42:46.576: //72988422/F97A92800001/CCAPI/ccCallDisconnect:
Cause Value=0, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
RO-MATRIZ-MARINGA#
025902: Dec 30 12:42:46.576: //72988422/F97A92800001/CCAPI/ccCallDisconnect:
Cause Value=0, Call Entry(Responsed=TRUE, Cause Value=0)
025903: Dec 30 12:42:46.576: //72988422/F97A92800001/CCAPI/cc_api_get_transfer_info:
Transfer Number=NULL
025904: Dec 30 12:42:46.578: //72988422/F97A92800001/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x7FB169F6C628, Tag=0x0, Call Id=72988422,
Call Entry(Disconnect Cause=0, Voice Class Cause Code=0, Retry Count=0)
025905: Dec 30 12:42:46.578: //72988422/F97A92800001/CCAPI/cc_api_call_disconnect_done:
tg for this call is M-MARINGA-VIVO success/fail is 0
025906: Dec 30 12:42:46.579: //72988422/F97A92800001/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
025907: Dec 30 12:42:46.579: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

025908: Dec 30 12:42:46.579: :cc_free_feature_vsa freeing 7FB165F0E078
025909: Dec 30 12:42:46.579: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

025910: Dec 30 12:42:46.579: vsacount in free is 1
025911: Dec 30 12:42:46.579: //72988421/F97A92800001/CCAPI/ccCallDisconnect:
Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
025912: Dec 30 12:42:46.579: //72988421/F97A92800001/CCAPI/ccCallDisconnect:
Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
025913: Dec 30 12:42:46.580: //72988421/F97A92800001/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 500 Internal Server Error
Via: SIP/2.0/UDP 172.23.80.38:5060;branch=z9hG4bK19ba5a323813f04
From: <sip:21999@172.23.80.38>;tag=129436899~9544df53-d8df-44ee-8445-a1593fdd0243-77157907
To: <sip:0145@172.23.80.12>;tag=1FD5EE22-12DF
Date: Thu, 30 Dec 2021 12:42:42 GMT
Call-ID: f97a9280-1cd1a942-15b48fb-265017ac@172.23.80.38
CSeq: 101 INVITE
Allow-Events: telephone-event
Server: Cisco-SIPGateway/IOS-16.3.7
Reason: Q.850;cause=16
Session-ID: 1340511100105000a0002a3197743d2a;remote=cf8207ed9165505888b281689b8b9dd1
Content-Length: 0

025914: Dec 30 12:42:46.582: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
ACK sip:0145@172.23.80.12:5060 SIP/2.0
Via: SIP/2.0/UDP 172.23.80.38:5060;branch=z9hG4bK19ba5a323813f04
From: <sip:21999@172.23.80.38>;tag=129436899~9544df53-d8df-44ee-8445-a1593fdd0243-77157907
To: <sip:0145@172.23.80.12>;tag=1FD5EE22-12DF
Date: Thu, 30 Dec 2021 12:42:42 GMT
Call-ID: f97a9280-1cd1a942-15b48fb-265017ac@172.23.80.38
User-Agent: Cisco-CUCM11.5
Max-Forwards: 70
CSeq: 101 ACK
Allow-Events: presence, kpml
Content-Length: 0

025915: Dec 30 12:42:46.583: //72988421/F97A92800001/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x7FB161A4CFF8, Tag=0x0, Call Id=72988421,
Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
025916: Dec 30 12:42:46.583: //72988421/F97A92800001/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
025917: Dec 30 12:42:46.583: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

025918: Dec 30 12:42:46.583: :cc_free_feature_vsa freeing 7FB165F106F8
025919: Dec 30 12:42:46.583: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

025920: Dec 30 12:42:46.583: vsacount in free is 0
RO-MATRIZ-MARINGA#
RO-MATRIZ-MARINGA#show dialplan number 0145
Macro Exp.: 0145

VoiceEncapPeer2006
peer type = voice, system default peer = FALSE, information type = voice,
description = `## Salientes Otros Servicios#',
tag = 2006, destination-pattern = `01[1-80].',
voice reg type = 0, corresponding tag = 0,
allow watch = FALSE
answer-address = `', preference=1,
CLID Restriction = None
CLID Network Number = `'
CLID Second Number sent
CLID Override RDNIS = disabled,
rtp-ssrc mux = system
source carrier-id = `', target carrier-id = `',
source trunk-group-label = `', target trunk-group-label = `',
numbering Type = `unknown'
group = 2006, Admin state is up, Operation state is up,
Outbound state is up,
incoming called-number = `',
connections/maximum = 0/unlimited,
DTMF Relay = disabled,
URI classes:
Destination =
Destination route-string = None
huntstop = disabled,
in bound application associated: 'DEFAULT'
out bound application associated: ''
dnis-map =
permission :both
incoming COR list:maximum capability
outgoing COR list:minimum requirement
Translation profile (Incoming):
Translation profile (Outgoing):
incoming call blocking:
translation-profile = `'
disconnect-cause = `no-service'
advertise 0x40 capacity_update_timer 25 addrFamily 0 oldAddrFamily 0
mailbox selection policy: none
trunk-group:
id = `M-MARINGA-VIVO', preference = `'
type = pots, prefix = `',
forward-digits 3
session-target = `', voice-port = `',
direct-inward-dial = enabled,
digit_strip = enabled,
register E.164 number with H323 GK and/or SIP Registrar = TRUE
fax rate = system, payload size = 20 bytes
supported-language = ''
preemption level = `routine'
bandwidth:
maximum = 64 KBits/sec, minimum = 64 KBits/sec
voice class called-number:
inbound = `', outbound = `'
dial tone generation after remote onhook = enabled
mobility=0, snr=, snr_noan=, snr_delay=0, snr_timeout=0
snr calling-number local=disabled, snr ring-stop=disabled, snr answer-too-soon timer=0
Time elapsed since last clearing of voice call statistics never
Connect Time = 207486, Charged Units = 0,
Successful Calls = 17, Failed Calls = 71, Incomplete Calls = 0
Accepted Calls = 0, Refused Calls = 0,
Last Disconnect Cause is "0 ",
Last Disconnect Text is "",
Last Setup Time = 3913055617.
Last Disconnect Time = 3913056070.
Matched: 0145 Digits: 3 Matched pattern: Preference: 1
Target:

RO-MATRIZ-MARINGA#

[dgonzalez1]

I posted it, but it was deleted again.

It is in a zip file in the original post, but you can't open it.