call cannot be completed at this time... please try again later

voipeee · ‎02-26-2018

Hi Guys,

One of number's I call is saying your call can't be completed and afterward the call will disconnect. I collected the logs on gateways and it is showing normal call clearing. For privacy reasons I replaced last 6 digits of calling and called number with 6(xxxxxx) and 6(yyyyyy) respectively. All other similar patterns are working fine.

Call flow CUCM > SIP Trunk to SP.

Here are the Gateway logs:

From: <sip:9045xxxxxx@10.221.1.173>;tag=BEC912E9-996
To: <sip:9042yyyyyy@10.221.1.173>;tag=525587411-1519676286142
Call-ID: FCF6E2DF-1A6811E8-967BC22A-E49D705C@10.221.69.58
CSeq: 101 INVITE
Timestamp: 1519676285
Supported: timer
Contact: <sip:10.221.1.173:5060;transport=udp>
Call-Info: <sip:12.95.84.143>;appearance-index=1
Allow: INVITE,ACK,CANCEL,BYE,REFER,INFO,NOTIFY,PRACK,OPTIONS
Server: Cisco-CUCM10.5
Content-Length: 0

1888679: Feb 26 15:18:06.227: //6242021/25B242000000/CCAPI/cc_api_call_alert:
Interface=0x7F2612BAC260, Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)
1888680: Feb 26 15:18:06.227: //6242021/25B242000000/CCAPI/cc_api_call_alert:
Call Entry(Retry Count=0, Responsed=TRUE)
1888681: Feb 26 15:18:06.227: //6242020/25B242000000/CCAPI/ccCallAlert:
Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)
1888682: Feb 26 15:18:06.227: //6242020/25B242000000/CCAPI/ccCallAlert:
Call Entry(Responsed=TRUE, Alert Sent=TRUE)
1888683: Feb 26 15:18:06.227: //6242020/25B242000000/CCAPI/ccCallNotify:
Data Bitmask=0x7, Call Id=6242020
1888684: Feb 26 15:18:06.228: //6242020/25B242000000/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 180 Ringing
Via: SIP/2.0/TCP 172.20.160.12:5060;branch=z9hG4bKc73c168a16b
From: <sip:9045xxxxxx@172.20.160.12>;tag=5563822~77fad1d8-6360-4e54-adc6-de459223602b-41061407
To: <sip:9042yyyyyy@172.20.160.3>;tag=BEC914AC-239F
Date: Mon, 26 Feb 2018 15:18:05 GMT
Call-ID: 25b24200-a9416b7d-b3a2b-ca014ac@172.20.160.12
CSeq: 101 INVITE
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
P-Asserted-Identity: <sip:9042yyyyyy@172.20.160.3>
Contact: <sip:9042yyyyyy@172.20.160.3:5060;transport=tcp>
Server: Cisco-SIPGateway/IOS-15.5.3.S4b
Content-Length: 0

1888685: Feb 26 15:18:06.351: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
OPTIONS sip:172.20.160.3:5060 SIP/2.0
Via: SIP/2.0/TCP 172.20.160.12:5060;branch=z9hG4bKc73c24031b19a
From: <sip:172.20.160.12>;tag=1618084096
To: <sip:172.20.160.3>
Date: Mon, 26 Feb 2018 20:18:06 GMT
Call-ID: 264ad880-a9416b7e-b3a2c-ca014ac@172.20.160.12
User-Agent: Cisco-CUCM11.5
CSeq: 101 OPTIONS
Contact: <sip:172.20.160.12:5060;transport=tcp>
Max-Forwards: 0
Content-Length: 0

1888686: Feb 26 15:18:06.353: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/TCP 172.20.160.12:5060;branch=z9hG4bKc73c24031b19a
From: <sip:172.20.160.12>;tag=1618084096
To: <sip:172.20.160.3>;tag=BEC91528-958

Date: Mon, 26 Feb 2018 15:18:06 GMT
Call-ID: 264ad880-a9416b7e-b3a2c-ca014ac@172.20.160.12
Server: Cisco-SIPGateway/IOS-15.5.3.S4b
CSeq: 101 OPTIONS
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Accept: application/sdp
Supported: timer,resource-priority,replaces,sdp-anat
Content-Type: application/sdp
Content-Length: 373

v=0
o=CiscoSystemsSIP-GW-UserAgent 1585 5240 IN IP4 172.20.160.3
s=SIP Call
c=IN IP4 172.20.160.3
t=0 0
m=audio 0 RTP/AVP 18 0 8 9 4 2 15 3
c=IN IP4 172.20.160.3
m=image 0 udptl t38
c=IN IP4 172.20.160.3
a=T38FaxVersion:0

a=T38MaxBitRate:9600
a=T38FaxRateManagement:transferredTCF
a=T38FaxMaxBuffer:200
a=T38FaxMaxDatagram:320
a=T38FaxUdpEC:t38UDPRedundancy

1888687: Feb 26 15:18:06.361: //6242021/25B242000000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 10.221.69.58:5060;branch=z9hG4bK5589AA55B
From: <sip:9045xxxxxx@10.221.1.173>;tag=BEC912E9-996
To: <sip:9042yyyyyy@10.221.1.173>;tag=525587411-1519676286142
Call-ID: FCF6E2DF-1A6811E8-967BC22A-E49D705C@10.221.69.58
CSeq: 101 INVITE
Timestamp: 1519676285
Supported: timer
Contact: <sip:10.221.1.173:5060;transport=udp>
Call-Info: <sip:12.95.84.143>;appearance-index=1
Allow: INVITE,ACK,CANCEL,BYE,REFER,INFO,NOTIFY,PRACK,UPDATE,OPTIONS
Content-Length: 0

1888688: Feb 26 15:18:06.386: //6242021/25B242000000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.221.69.58:5060;branch=z9hG4bK5589AA55B
From: <sip:9045xxxxxx@10.221.1.173>;tag=BEC912E9-996
To: <sip:9042yyyyyy@10.221.1.173>;tag=525587411-1519676286142
Call-ID: FCF6E2DF-1A6811E8-967BC22A-E49D705C@10.221.69.58
CSeq: 101 INVITE
Timestamp: 1519676285
Require: timer
Session-Expires: 1800;refresher=uas
Allow: INVITE,ACK,CANCEL,BYE,REFER,INFO,NOTIFY,PRACK,UPDATE,OPTIONS
Supported: timer
Accept: application/dtmf-relay, application/media_control+xml, application/sdp
Contact: <sip:10.221.1.173:5060;transport=udp>
Call-Info: <sip:12.95.84.143>;appearance-index=1
Content-Length: 251
Content-Type: application/sdp

v=0
o=BroadWorks 954633586 1 IN IP4 10.221.1.173
s=-
c=IN IP4 10.221.1.173

t=0 0
m=audio 48164 RTP/AVP 18 0 8 101
a=rtpmap:18 G729/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20

1888689: Feb 26 15:18:06.387: //6242021/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

1888690: Feb 26 15:18:06.387: cc_api_get_xcode_stream : 4983
1888691: Feb 26 15:18:06.387: //6242021/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

1888692: Feb 26 15:18:06.387: cc_api_get_xcode_stream : 4983
1888693: Feb 26 15:18:06.387: //6242021/25B242000000/CCAPI/cc_api_caps_ind:
Destination Interface=0x0, Destination Call Id=-1, Source Call Id=6242021,
Caps(Codec=0x1, Fax Rate=0x2, Fax Version:=0, Vad=0x1,
Modem=0x0, Codec Bytes=160, Signal Type=2)
1888694: Feb 26 15:18:06.387: //6242021/25B242000000/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))
1888695: Feb 26 15:18:06.387: //6242020/25B242000000/CCAPI/cc_api_caps_ack:
Destination Interface=0x0, Destination Call Id=-1, Source Call Id=6242020,
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=5316)
1888696: Feb 26 15:18:06.387: //6242020/25B242000000/CCAPI/cc_api_caps_ack:
Destination Interface=0x0, Destination Call Id=-1, Source Call Id=6242020,
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=5316)
1888697: Feb 26 15:18:06.387: //6242021/25B242000000/CCAPI/cc_api_event_indication:
Event=101, Call Id=6242021
1888698: Feb 26 15:18:06.387: //6242021/25B242000000/CCAPI/cc_api_event_indication:
Event Is Sent To Conferenced SPI(s) Directly
1888699: Feb 26 15:18:06.387: //6242021/25B242000000/CCAPI/cc_api_call_connected:
Interface=0x7F2612BAC260, Data Bitmask=0x1, Progress Indication=NULL(0),
Connection Handle=0
1888700: Feb 26 15:18:06.388: //6242021/25B242000000/CCAPI/cc_api_call_connected:
Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0)
1888701: Feb 26 15:18:06.388: //6242020/25B242000000/CCAPI/ccGetMediaClassTag:
media class tag 0
1888702: Feb 26 15:18:06.388: //6242020/25B242000000/CCAPI/ccSetMediaclassIp2ipTags:
media class tags set: NR 0, ASP 0
1888703: Feb 26 15:18:06.388: //6242021/25B242000000/CCAPI/ccGetMediaClassTag:
media class tag 0
1888704: Feb 26 15:18:06.388: //6242021/25B242000000/CCAPI/ccSetMediaclassIp2ipTags:
media class tags set: NR 0, ASP 0
1888705: Feb 26 15:18:06.388: //6242020/25B242000000/CCAPI/ccGet_xc_nr_asp_info:
media class tags: NR 0, ASP 0
1888706: Feb 26 15:18:06.388: //6242021/25B242000000/CCAPI/ccGet_xc_nr_asp_info:
media class tags: NR 0, ASP 0
1888707: Feb 26 15:18:06.388: //-1/xxxxxxxxxxxx/CCAPI/cc_is_cng_fax_detect_active:
Call Id 6242021
1888708: Feb 26 15:18:06.388: //6242020/25B242000000/CCAPI/ccGetMediaClassTag:
media class tag 0
1888709: Feb 26 15:18:06.388: //6242020/25B242000000/CCAPI/ccSetMediaclassIp2ipTags:
media class tags set: NR 0, ASP 0
1888710: Feb 26 15:18:06.388: //6242021/25B242000000/CCAPI/ccGetMediaClassTag:
media class tag 0
1888711: Feb 26 15:18:06.388: //6242021/25B242000000/CCAPI/ccSetMediaclassIp2ipTags:
media class tags set: NR 0, ASP 0
1888712: Feb 26 15:18:06.388: //6242020/25B242000000/CCAPI/ccGet_xc_nr_asp_info:
media class tags: NR 0, ASP 0
1888713: Feb 26 15:18:06.388: //6242021/25B242000000/CCAPI/ccGet_xc_nr_asp_info:
media class tags: NR 0, ASP 0
1888714: Feb 26 15:18:06.388: //-1/xxxxxxxxxxxx/CCAPI/cc_is_cng_fax_detect_active:
Call Id 6242020
1888715: Feb 26 15:18:06.388: //-1/xxxxxxxxxxxx/CCAPI/cc_is_cng_fax_detect_active:
Call Id 6242021
1888716: Feb 26 15:18:06.389: //6242020/25B242000000/CCAPI/ccConferenceCreate:
(confID=0xFFFFFFFFFFFFFFFF, callID1=0x5F3EE4, gcid=FCF646B7-1A6811E8-9678C22A-E49D705C, tag=0x0)
1888717: Feb 26 15:18:06.389: //6242021/25B242000000/CCAPI/ccConferenceCreate:
(confID=0xFFFFFFFFFFFFFFFF, callID2=0x5F3EE5, gcid=FCF646B7-1A6811E8-9678C22A-E49D705C, tag=0x0)
1888718: Feb 26 15:18:06.389: //6242020/25B242000000/CCAPI/ccConferenceCreate:
Conference Id=0xFFFFFFFFFFFFFFFF, Call Id1=6242020, Call Id2=6242021, Tag=0x0
1888719: Feb 26 15:18:06.389: //6242020/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

1888720: Feb 26 15:18:06.389: cc_api_get_xcode_stream : 4983
1888721: Feb 26 15:18:06.389: //6242021/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

1888722: Feb 26 15:18:06.389: cc_api_get_xcode_stream : 4983
1888723: Feb 26 15:18:06.389: //6242020/25B242000000/CCAPI/ccConferenceCreate:

1888724: Feb 26 15:18:06.389: ccConferenceCreate: ret1=0, codecMask1=1, bytes1=160, negot1=0, dtmf1=6
ret2=0, codecMask2=1, bytes2=160, negot2=1, dtmf2=6,
tx_dynamic_pt1=0, rx_dynamic_pt1=0, codec_mode1=0, params_bitmap1 =0
tx_dynamic_pt2=0, rx_dynamic_pt2=0, codec_mode2=0, params_bitmap2 =0
1888725: Feb 26 15:18:06.389: //6242020/25B242000000/CCAPI/ccGetMediaClassTag:
media class tag 0
1888726: Feb 26 15:18:06.389: //6242020/25B242000000/CCAPI/ccSetMediaclassIp2ipTags:
media class tags set: NR 0, ASP 0
1888727: Feb 26 15:18:06.389: //6242021/25B242000000/CCAPI/ccGetMediaClassTag:
media class tag 0
1888728: Feb 26 15:18:06.389: //6242021/25B242000000/CCAPI/ccSetMediaclassIp2ipTags:
media class tags set: NR 0, ASP 0
1888729: Feb 26 15:18:06.389: //6242020/25B242000000/CCAPI/ccGet_xc_nr_asp_info:
media class tags: NR 0, ASP 0
1888730: Feb 26 15:18:06.389: //6242021/25B242000000/CCAPI/ccGet_xc_nr_asp_info:
media class tags: NR 0, ASP 0
1888731: Feb 26 15:18:06.389: //6242020/25B242000000/CCAPI/ccConferenceCreate:
delay media to slow start case, codec negotation is not done
1888732: Feb 26 15:18:06.389: //6242020/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

1888733: Feb 26 15:18:06.389: cc_api_get_xcode_stream : 4983
1888734: Feb 26 15:18:06.389: //6242020/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

1888735: Feb 26 15:18:06.389: cc_api_get_xcode_stream : 4983
1888736: Feb 26 15:18:06.389: //6242020/25B242000000/CCAPI/cc_api_bridge_done:
Conference Id=0xC18F, Source Interface=0x7F2612BAC260, Source Call Id=6242020,
Destination Call Id=6242021, Disposition=0x0, Tag=0x0
1888737: Feb 26 15:18:06.389: //6242021/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

1888738: Feb 26 15:18:06.389: cc_api_get_xcode_stream : 4983
1888739: Feb 26 15:18:06.389: //6242021/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

1888740: Feb 26 15:18:06.389: cc_api_get_xcode_stream : 4983
1888741: Feb 26 15:18:06.390: //6242021/25B242000000/CCAPI/cc_api_bridge_done:
Conference Id=0xC18F, Source Interface=0x7F2612BAC260, Source Call Id=6242021,
Destination Call Id=6242020, Disposition=0x0, Tag=0x0
1888742: Feb 26 15:18:06.390: //6242020/25B242000000/CCAPI/cc_generic_bridge_done:
Conference Id=0xC18F, Source Interface=0x7F2612BAC260, Source Call Id=6242021,
Destination Call Id=6242020, Disposition=0x0, Tag=0x0
1888743: Feb 26 15:18:06.390: //6242020/25B242000000/CCAPI/ccConferenceCreate:
Call Entry(Conference Id=0xC18F, Destination Call Id=6242021)
1888744: Feb 26 15:18:06.390: //6242021/25B242000000/CCAPI/ccConferenceCreate:
Call Entry(Conference Id=0xC18F, Destination Call Id=6242020)
1888745: Feb 26 15:18:06.390: //6242020/25B242000000/CCAPI/ccConferenceCreate:

1888746: Feb 26 15:18:06.390: confID:0xC18F; callEntry1 callID1:0x5F3EE4, type:3; callEntry2 callID2:0x5F3EE5, type:3

1888747: Feb 26 15:18:06.390: //6242020/25B242000000/CCAPI/cc_process_notify_bridge_done:
Conference Id=0xC18F, Call Id1=6242020, Call Id2=6242021
1888748: Feb 26 15:18:06.390: //6242020/25B242000000/CCAPI/ccCallConnect:
Progress Indication=NULL(0), Data Bitmask=0x1
1888749: Feb 26 15:18:06.390: //6242020/25B242000000/CCAPI/ccCallConnect:
Call Entry(Connected=TRUE, Responsed=TRUE)
1888750: Feb 26 15:18:06.392: //6242020/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

1888751: Feb 26 15:18:06.392: cc_api_get_xcode_stream : 4983
1888752: Feb 26 15:18:06.392: //6242020/25B242000000/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/TCP 172.20.160.12:5060;branch=z9hG4bKc73c168a16b
From: <sip:9045xxxxxx@172.20.160.12>;tag=5563822~77fad1d8-6360-4e54-adc6-de459223602b-41061407
To: <sip:9042yyyyyy@172.20.160.3>;tag=BEC914AC-239F
Date: Mon, 26 Feb 2018 15:18:05 GMT
Call-ID: 25b24200-a9416b7d-b3a2b-ca014ac@172.20.160.12
CSeq: 101 INVITE
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER

Allow-Events: telephone-event
P-Asserted-Identity: <sip:9042yyyyyy@172.20.160.3>
Contact: <sip:9042yyyyyy@172.20.160.3:5060;transport=tcp>
Supported: replaces
Supported: sdp-anat
Server: Cisco-SIPGateway/IOS-15.5.3.S4b
Session-Expires: 1800;refresher=uas
Require: timer
Supported: timer
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 247

v=0
o=CiscoSystemsSIP-GW-UserAgent 2160 8980 IN IP4 172.20.160.3
s=SIP Call
c=IN IP4 172.20.160.3
t=0 0
m=audio 42600 RTP/AVP 0 101
c=IN IP4 172.20.160.3
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-15
a=ptime:20

1888753: Feb 26 15:18:06.407: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
ACK sip:9042yyyyyy@172.20.160.3:5060;transport=tcp SIP/2.0
Via: SIP/2.0/TCP 172.20.160.12:5060;branch=z9hG4bKc73c322b573d7
From: <sip:9045xxxxxx@172.20.160.12>;tag=5563822~77fad1d8-6360-4e54-adc6-de459223602b-41061407
To: <sip:9042yyyyyy@172.20.160.3>;tag=BEC914AC-239F
Date: Mon, 26 Feb 2018 20:18:05 GMT
Call-ID: 25b24200-a9416b7d-b3a2b-ca014ac@172.20.160.12
User-Agent: Cisco-CUCM11.5
Max-Forwards: 70
CSeq: 101 ACK
Allow-Events: presence, kpml
Session-ID: 97e44c6786537f0c0dda24fb7e654ba0;remote=1b2f3479b0db2a5aa6f4d75ab5563822
Content-Type: application/sdp
Content-Length: 249

v=0

o=CiscoSystemsCCM-SIP 5563822 1 IN IP4 172.20.160.12
s=SIP Call
c=IN IP4 172.19.10.189
b=TIAS:64000
b=CT:64
b=AS:64
t=0 0
m=audio 24596 RTP/AVP 0 101
a=ptime:20
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

1888754: Feb 26 15:18:06.408: //6242020/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

1888755: Feb 26 15:18:06.408: cc_api_get_xcode_stream : 4983
1888756: Feb 26 15:18:06.408: //6242020/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

1888757: Feb 26 15:18:06.408: cc_api_get_xcode_stream : 4983
1888758: Feb 26 15:18:06.409: //6242020/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

1888759: Feb 26 15:18:06.409: cc_api_get_xcode_stream : 4983
1888760: Feb 26 15:18:06.409: //6242020/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

1888761: Feb 26 15:18:06.409: cc_api_get_xcode_stream : 4983
1888762: Feb 26 15:18:06.409: //6242020/25B242000000/CCAPI/cc_api_caps_ind:
Destination Interface=0x7F2612BAC260, Destination Call Id=6242021, Source Call Id=6242020,
Caps(Codec=0x1, Fax Rate=0x2, Fax Version:=0, Vad=0x2,
Modem=0x0, Codec Bytes=160, Signal Type=2)
1888763: Feb 26 15:18:06.409: //6242020/25B242000000/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))
1888764: Feb 26 15:18:06.409: //6242021/25B242000000/CCAPI/cc_api_caps_ack:
Destination Interface=0x7F2612BAC260, Destination Call Id=6242020, Source Call Id=6242021,
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=4217)
1888765: Feb 26 15:18:06.409: //6242021/25B242000000/CCAPI/cc_api_caps_ack:
Destination Interface=0x7F2612BAC260, Destination Call Id=6242020, Source Call Id=6242021,
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=4217)
1888766: Feb 26 15:18:06.409: //6242020/25B242000000/CCAPI/cc_api_event_indication:
Event=103, Call Id=6242020
1888767: Feb 26 15:18:06.409: //6242020/25B242000000/CCAPI/cc_api_event_indication:
Event Is Sent To Conferenced SPI(s) Directly
1888768: Feb 26 15:18:06.409: //6242020/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

1888769: Feb 26 15:18:06.409: cc_api_get_xcode_stream : 4983
1888770: Feb 26 15:18:06.409: //6242020/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

1888771: Feb 26 15:18:06.409: cc_api_get_xcode_stream : 4983
1888772: Feb 26 15:18:06.409: //6242020/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

1888773: Feb 26 15:18:06.409: cc_api_get_xcode_stream : 4983
1888774: Feb 26 15:18:06.409: //6242020/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

1888775: Feb 26 15:18:06.409: cc_api_get_xcode_stream : 4983
1888776: Feb 26 15:18:06.410: //6242020/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify:

1888777: Feb 26 15:18:06.410: call_info mainst_callID:0x5F3EE4, peer_callID:0x5F3EE5, confID:0xC18F, spi_type:4, media_flo_thru:1,num_streams:1, swmtpmsp_present:0

1888778: Feb 26 15:18:06.410: //6242020/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify:

1888779: Feb 26 15:18:06.410: stream[0]: callid:0x5F3EE4, media_passthrough:1
1888780: Feb 26 15:18:06.410: //6242020/25B242000000/CCAPI/cc_api_event_indication:
Event=111, Call Id=6242020
1888781: Feb 26 15:18:06.410: //6242020/25B242000000/CCAPI/cc_api_event_indication:
Event Is Sent To Conferenced SPI(s) Directly
1888782: Feb 26 15:18:06.410: //6242020/25B242000000/CCAPI/cc_api_event_indication:
Event=213, Call Id=6242020
1888783: Feb 26 15:18:06.410: //6242020/25B242000000/CCAPI/cc_api_event_indication:
Event Is Sent To Conferenced SPI(s) Directly
1888784: Feb 26 15:18:06.410: //6242020/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

1888785: Feb 26 15:18:06.410: cc_api_get_xcode_stream : 4983
1888786: Feb 26 15:18:06.410: //6242020/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

1888787: Feb 26 15:18:06.410: cc_api_get_xcode_stream : 4983
1888788: Feb 26 15:18:06.410: //6242021/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

1888789: Feb 26 15:18:06.410: cc_api_get_xcode_stream : 4983
1888790: Feb 26 15:18:06.410: //6242021/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

1888791: Feb 26 15:18:06.410: cc_api_get_xcode_stream : 4983
1888792: Feb 26 15:18:06.410: //6242021/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

1888793: Feb 26 15:18:06.410: cc_api_get_xcode_stream : 4983
1888794: Feb 26 15:18:06.410: //6242021/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

1888795: Feb 26 15:18:06.411: cc_api_get_xcode_stream : 4983
1888796: Feb 26 15:18:06.411: //6242021/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

1888797: Feb 26 15:18:06.411: cc_api_get_xcode_stream : 4983
1888798: Feb 26 15:18:06.411: //6242021/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

1888799: Feb 26 15:18:06.411: cc_api_get_xcode_stream : 4983
1888800: Feb 26 15:18:06.411: //6242021/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify:

1888801: Feb 26 15:18:06.411: call_info mainst_callID:0x5F3EE5, peer_callID:0x5F3EE4, confID:0xC18F, spi_type:4, media_flo_thru:1,num_streams:1, swmtpmsp_present:0

1888802: Feb 26 15:18:06.412: //6242021/xxxxxxxxxxxx/CCAPI/cc_api_ha_call_active_notify:

1888803: Feb 26 15:18:06.412: stream[0]: callid:0x5F3EE5, media_passthrough:1
1888804: Feb 26 15:18:06.412: //6242021/25B242000000/CCAPI/cc_api_event_indication:
Event=111, Call Id=6242021
1888805: Feb 26 15:18:06.412: //6242021/25B242000000/CCAPI/cc_api_event_indication:
Event Is Sent To Conferenced SPI(s) Directly
1888806: Feb 26 15:18:06.412: //6242021/25B242000000/CCAPI/cc_api_event_indication:
Event=213, Call Id=6242021
1888807: Feb 26 15:18:06.412: //6242021/25B242000000/CCAPI/cc_api_event_indication:
Event Is Sent To Conferenced SPI(s) Directly
1888808: Feb 26 15:18:06.412: //6242021/25B242000000/SIP/Msg/ccsipDisplayMsg:
Sent:
ACK sip:10.221.1.173:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 10.221.69.58:5060;branch=z9hG4bK5589ABFEC
From: <sip:9045xxxxxx@10.221.1.173>;tag=BEC912E9-996
To: <sip:9042yyyyyy@10.221.1.173>;tag=525587411-1519676286142
Date: Mon, 26 Feb 2018 15:18:05 GMT
Call-ID: FCF6E2DF-1A6811E8-967BC22A-E49D705C@10.221.69.58
Max-Forwards: 70
CSeq: 101 ACK
Allow-Events: telephone-event
Content-Type: application/sdp
Content-Length: 247

v=0
o=CiscoSystemsSIP-GW-UserAgent 1955 8735 IN IP4 10.221.69.58
s=SIP Call
c=IN IP4 10.221.69.58
t=0 0
m=audio 43358 RTP/AVP 0 101
c=IN IP4 10.221.69.58
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20

1888809: Feb 26 15:18:20.177: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
BYE sip:9042yyyyyy@172.20.160.3:5060;transport=tcp SIP/2.0
Via: SIP/2.0/TCP 172.20.160.12:5060;branch=z9hG4bKc73c416a15160
From: <sip:9045xxxxxx@172.20.160.12>;tag=5563822~77fad1d8-6360-4e54-adc6-de459223602b-41061407
To: <sip:9042yyyyyy@172.20.160.3>;tag=BEC914AC-239F
Date: Mon, 26 Feb 2018 20:18:05 GMT
Call-ID: 25b24200-a9416b7d-b3a2b-ca014ac@172.20.160.12

User-Agent: Cisco-CUCM11.5
Max-Forwards: 70
P-Asserted-Identity: <sip:9045xxxxxx@172.20.160.12>
CSeq: 102 BYE
Reason: Q.850;cause=16
Session-ID: 97e44c6786537f0c0dda24fb7e654ba0;remote=1b2f3479b0db2a5aa6f4d75ab5563822
Content-Length: 0

1888810: Feb 26 15:18:20.177: //6242021/25B242000000/CCAPI/ccGenerateToneInfo:
Stop Tone On Digit=FALSE, Tone=Null,
Tone Direction=Sum Network, Params=0x0, Call Id=6242021
1888811: Feb 26 15:18:20.177: //6242020/25B242000000/CCAPI/cc_api_call_disconnected:
Cause Value=16, Interface=0x7F2612BAC260, Call Id=6242020
1888812: Feb 26 15:18:20.177: //6242020/25B242000000/CCAPI/cc_api_call_disconnected:
Call Entry(Responsed=TRUE, Cause Value=16, Retry Count=0)
1888813: Feb 26 15:18:20.177: //6242020/25B242000000/CCAPI/ccConferenceDestroy:
Conference Id=0xC18F, Tag=0x0
1888814: Feb 26 15:18:20.177: //6242020/25B242000000/CCAPI/ccConferenceDestroy:

1888815: Feb 26 15:18:20.177: confID:0xC18F; callEntry1 callID1:0x5F3EE4, type:3; callEntry2 callID2:0x5F3EE5, type:3

1888816: Feb 26 15:18:20.178: //6242020/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

1888817: Feb 26 15:18:20.178: cc_api_get_xcode_stream : 4983
1888818: Feb 26 15:18:20.178: //6242021/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

1888819: Feb 26 15:18:20.178: cc_api_get_xcode_stream : 4983
1888820: Feb 26 15:18:20.178: //6242020/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

1888821: Feb 26 15:18:20.178: cc_api_get_xcode_stream : 4983
1888822: Feb 26 15:18:20.178: //6242020/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

1888823: Feb 26 15:18:20.178: cc_api_get_xcode_stream : 4983
1888824: Feb 26 15:18:20.178: //6242020/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

1888825: Feb 26 15:18:20.178: cc_api_get_xcode_stream : 4983
1888826: Feb 26 15:18:20.178: //6242020/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

1888827: Feb 26 15:18:20.178: cc_api_get_xcode_stream : 4983
1888828: Feb 26 15:18:20.178: //6242020/25B242000000/CCAPI/cc_api_bridge_drop_done:
Conference Id=0xC18F, Source Interface=0x7F2612BAC260, Source Call Id=6242020,
Destination Call Id=6242021, Disposition=0x0, Tag=0x0
1888829: Feb 26 15:18:20.178: //6242021/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

1888830: Feb 26 15:18:20.178: cc_api_get_xcode_stream : 4983
1888831: Feb 26 15:18:20.178: //6242020/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

1888832: Feb 26 15:18:20.178: cc_api_get_xcode_stream : 4983
1888833: Feb 26 15:18:20.178: //6242021/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

1888834: Feb 26 15:18:20.178: cc_api_get_xcode_stream : 4983
1888835: Feb 26 15:18:20.178: //6242021/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

1888836: Feb 26 15:18:20.178: cc_api_get_xcode_stream : 4983
1888837: Feb 26 15:18:20.178: //6242021/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

1888838: Feb 26 15:18:20.178: cc_api_get_xcode_stream : 4983
1888839: Feb 26 15:18:20.178: //6242021/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

1888840: Feb 26 15:18:20.178: cc_api_get_xcode_stream : 4983
1888841: Feb 26 15:18:20.178: //6242021/25B242000000/CCAPI/cc_api_bridge_drop_done:
Conference Id=0xC18F, Source Interface=0x7F2612BAC260, Source Call Id=6242021,
Destination Call Id=6242020, Disposition=0x0, Tag=0x0
1888842: Feb 26 15:18:20.178: //6242020/25B242000000/CCAPI/cc_generic_bridge_done:
Conference Id=0xC18F, Source Interface=0x7F2612BAC260, Source Call Id=6242021,
Destination Call Id=6242020, Disposition=0x0, Tag=0x0
1888843: Feb 26 15:18:20.179: //6242020/25B242000000/CCAPI/ccCallDisconnect:
Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16)
1888844: Feb 26 15:18:20.179: //6242020/25B242000000/CCAPI/ccCallDisconnect:
Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
1888845: Feb 26 15:18:20.179: //6242021/25B242000000/CCAPI/ccCallDisconnect:
Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
1888846: Feb 26 15:18:20.179: //6242021/25B242000000/CCAPI/ccCallDisconnect:
Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
1888847: Feb 26 15:18:20.180: //6242020/25B242000000/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/TCP 172.20.160.12:5060;branch=z9hG4bKc73c416a15160
From: <sip:9045xxxxxx@172.20.160.12>;tag=5563822~77fad1d8-6360-4e54-adc6-de459223602b-41061407
To: <sip:9042yyyyyy@172.20.160.3>;tag=BEC914AC-239F
Date: Mon, 26 Feb 2018 15:18:20 GMT
Call-ID: 25b24200-a9416b7d-b3a2b-ca014ac@172.20.160.12
Server: Cisco-SIPGateway/IOS-15.5.3.S4b
CSeq: 102 BYE
Reason: Q.850;cause=16
Content-Length: 0

1888848: Feb 26 15:18:20.180: //6242021/25B242000000/SIP/Msg/ccsipDisplayMsg:
Sent:
BYE sip:10.221.1.173:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 10.221.69.58:5060;branch=z9hG4bK5589BBF87
From: <sip:9045xxxxxx@10.221.1.173>;tag=BEC912E9-996
To: <sip:9042yyyyyy@10.221.1.173>;tag=525587411-1519676286142
Date: Mon, 26 Feb 2018 15:18:05 GMT
Call-ID: FCF6E2DF-1A6811E8-967BC22A-E49D705C@10.221.69.58
User-Agent: Cisco-SIPGateway/IOS-15.5.3.S4b
Timestamp: 1519676300
CSeq: 102 BYE
Reason: Q.850;cause=16
P-Asserted-Identity: <sip:9045xxxxxx@172.20.160.12>
Session-ID: 97e44c6786537f0c0dda24fb7e654ba0;remote=1b2f3479b0db2a5aa6f4d75ab5563822
Max-Forwards: 70
Content-Length: 0

1888849: Feb 26 15:18:20.181: //6242020/25B242000000/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x7F2612BAC260, Tag=0x0, Call Id=6242020,
Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
1888850: Feb 26 15:18:20.181: //6242020/25B242000000/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
1888851: Feb 26 15:18:20.181: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

1888852: Feb 26 15:18:20.181: :cc_free_feature_vsa freeing 7F2614124B40
1888853: Feb 26 15:18:20.181: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

1888854: Feb 26 15:18:20.181: vsacount in free is 3
1888855: Feb 26 15:18:20.226: //6242021/25B242000000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.221.69.58:5060;branch=z9hG4bK5589BBF87
From: <sip:9045xxxxxx@10.221.1.173>;tag=BEC912E9-996
To: <sip:9042yyyyyy@10.221.1.173>;tag=525587411-1519676286142
Call-ID: FCF6E2DF-1A6811E8-967BC22A-E49D705C@10.221.69.58
Timestamp: 1519676300
CSeq: 102 BYE
Content-Length: 0

1888856: Feb 26 15:18:20.226: //6242021/25B242000000/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x7F2612BAC260, Tag=0x0, Call Id=6242021,
Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
1888857: Feb 26 15:18:20.226: //6242021/25B242000000/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
1888858: Feb 26 15:18:20.226: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

1888859: Feb 26 15:18:20.226: :cc_free_feature_vsa freeing 7F2614124600
1888860: Feb 26 15:18:20.226: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

1888861: Feb 26 15:18:20.226: vsacount in free is 2
1888862: Feb 26 15:18:25.562: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
OPTIONS sip:10.221.69.58:5060 SIP/2.0
Via: SIP/2.0/UDP 10.221.1.173:5060;branch=z9hG4bK83n3mb30c0u1li3um5i1
Call-ID: 9b6b4fd48153f76b95259681f640209d0006pt2@10.221.1.173
To: sip:ping@10.221.69.58:5060
From: <sip:ping@10.221.1.173:5060>;tag=4e4ad5833cc26b1c68924268a9aa1e1d0006pt2
Max-Forwards: 0
CSeq: 37819 OPTIONS

1888863: Feb 26 15:18:25.563: //6242038/08C1FE59968C/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK

Via: SIP/2.0/UDP 10.221.1.173:5060;branch=z9hG4bK83n3mb30c0u1li3um5i1
From: <sip:ping@10.221.1.173:5060>;tag=4e4ad5833cc26b1c68924268a9aa1e1d0006pt2
To: sip:ping@10.221.69.58:5060;tag=BEC96033-1724
Date: Mon, 26 Feb 2018 15:18:25 GMT
Call-ID: 9b6b4fd48153f76b95259681f640209d0006pt2@10.221.1.173
Server: Cisco-SIPGateway/IOS-15.5.3.S4b
CSeq: 37819 OPTIONS
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Accept: application/sdp
Supported: timer,resource-priority,replaces,sdp-anat
Content-Type: application/sdp
Content-Length: 373

v=0
o=CiscoSystemsSIP-GW-UserAgent 7391 9992 IN IP4 10.221.69.58
s=SIP Call
c=IN IP4 10.221.69.58
t=0 0
m=audio 0 RTP/AVP 18 0 8 9 4 2 15 3
c=IN IP4 10.221.69.58

m=image 0 udptl t38
c=IN IP4 10.221.69.58
a=T38FaxVersion:0
a=T38MaxBitRate:9600
a=T38FaxRateManagement:transferredTCF
a=T38FaxMaxBuffer:200
a=T38FaxMaxDatagram:320
a=T38FaxUdpEC:t38UDPRedundancy

1888864: Feb 26 15:18:31.046: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
OPTIONS sip:172.20.160.3:5060 SIP/2.0
Via: SIP/2.0/TCP 172.20.160.11:5060;branch=z9hG4bK1248c25bc32692
From: <sip:172.20.160.11>;tag=1749232306
To: <sip:172.20.160.3>
Date: Mon, 26 Feb 2018 20:18:31 GMT
Call-ID: 35318b00-a9416b97-9ddfa-ba014ac@172.20.160.11
User-Agent: Cisco-CUCM11.5
CSeq: 101 OPTIONS
Contact: <sip:172.20.160.11:5060;transport=tcp>
Max-Forwards: 0
Content-Length: 0

1888865: Feb 26 15:18:31.049: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/TCP 172.20.160.11:5060;branch=z9hG4bK1248c25bc32692
From: <sip:172.20.160.11>;tag=1749232306
To: <sip:172.20.160.3>;tag=BEC9759F-A33
Date: Mon, 26 Feb 2018 15:18:31 GMT
Call-ID: 35318b00-a9416b97-9ddfa-ba014ac@172.20.160.11
Server: Cisco-SIPGateway/IOS-15.5.3.S4b
CSeq: 101 OPTIONS
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Accept: application/sdp
Supported: timer,resource-priority,replaces,sdp-anat
Content-Type: application/sdp
Content-Length: 373

v=0
o=CiscoSystemsSIP-GW-UserAgent 6704 5610 IN IP4 172.20.160.3

s=SIP Call
c=IN IP4 172.20.160.3
t=0 0
m=audio 0 RTP/AVP 18 0 8 9 4 2 15 3
c=IN IP4 172.20.160.3
m=image 0 udptl t38
c=IN IP4 172.20.160.3
a=T38FaxVersion:0
a=T38MaxBitRate:9600
a=T38FaxRateManagement:transferredTCF
a=T38FaxMaxBuffer:200
a=T38FaxMaxDatagram:320
a=T38FaxUdpEC:t38UDPRedundancy

GCS-ISR4331#

Dennis Mink · ‎02-26-2018

the party that is sending the bye:

Received:
BYE sip:9042yyyyyy@172.20.160.3:5060;transport=tcp SIP/2.0
Via: SIP/2.0/TCP 172.20.160.12:5060;branch=z9hG4bKc73c416a15160
From: <sip:9045xxxxxx@172.20.160.12>;tag=5563822~77fad1d8-6360-4e54-adc6-de459223602b-41061407
To: <sip:9042yyyyyy@172.20.160.3>;tag=BEC914AC-239F
Date: Mon, 26 Feb 2018 20:18:05 GMT
Call-ID: 25b24200-a9416b7d-b3a2b-ca014ac@172.20.160.12

User-Agent: Cisco-CUCM11.5
Max-Forwards: 70
P-Asserted-Identity: <sip:9045xxxxxx@172.20.160.12>
CSeq: 102 BYE
Reason: Q.850;cause=16
Session-ID: 97e44c6786537f0c0dda24fb7e654ba0;remote=1b2f3479b0db2a5aa6f4d75ab5563822
Content-Length: 0

is that your cucm?

in which case you might want to do a trace to see why it is disconnecting

Please remember to rate useful posts, by clicking on the stars below.

voipeee · ‎02-27-2018

Hi @Dennis Mink,

Thanks for your help.

The calling number is 9045xxxxxx called number is 9042yyyyyy. 172.20.160.12 is Communications manager Subscriber. I am so concerned why there is no dial-peer in the traces.

Thanks,

Muk

Dennis Mink · ‎02-27-2018

OK, so your called number 9042yyyyyy

when you check the cucm route plan report, does that number exist at all?

can you rting it internally from a phone on the SAME partition and CSS?

cheers

Please remember to rate useful posts, by clicking on the stars below.

voipeee · ‎02-28-2018

Hi @Dennis Mink,

Thanks for your help!

9042yyyyyy is external number. Yes, there is Route pattern for this which points to the SIP Trunk and on the gateway there is dial-peer to Service provider.

Also if I dial same pattern number for example 9042zzzzzz that uses the same pattern and works fine.

Thanks,

Muk