cancel
Showing results for 
Search instead for 
Did you mean: 
cancel
631
Views
10
Helpful
3
Replies

Cisco Cube 408 Bad request and 500 Internal server error

SPoodari
Beginner
Beginner

Cisco CIPC registered in SIP mode. FIrst registered as SCCP and as i got issue, changed it to SIP. 

 

When making outbound calls i'm facing 408 and 500 Internal server errors. I'm literally clueless as the cause codes leave me no where. 

Q850, Cause 111 and 127. 

 

Please have a look and let me know what is that silly thing i'm missing. 

 

Architecture: CUCM --> SIP Trunk --> Cube --> SIP Trunk --> ITSP

#########Can't attach debugs, hence pasting here####################


Router#debug voice ccapi inout
voip ccapi inout debugging is on
Router#
Router#
*Nov 6 13:43:00.234: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
hello
*Nov 6 13:43:14.652: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:08121184994@172.50.0.10:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.20.7:5060;branch=z9hG4bK607c8388f2f82
From: "1999" <sip:1999@192.168.20.7>;tag=3592873~3d47287c-b89e-4db4-8a1a-3e320ddb5a60-25260127
To: <sip:08121184994@172.50.0.10>
Date: Sat, 06 Nov 2021 13:32:24 GMT
Call-ID: f8952980-10001-603e9-93f492a@192.168.20.7
Supported: 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
Supported: X-cisco-srtp-fallback,X-cisco-original-called
Call-Info: <urn:x-cisco-remotecc:callinfo>;x-cisco-video-traffic-class=DESKTOP
Session-ID: d735702e84f7e185706cb30aa3592802;remote=00000000000000000000000000000000
Cisco-Guid: 4170525056-0000065536-0000000112-0118794432
Session-Expires: 1800
P-Asserted-Identity: "1999" <sip:1999@192.168.20.7>
Remote-Party-ID: "1999" <sip:1999@192.168.20.7>;party=calling;screen=yes;privacy=off
Contact: <sip:1999@192.168.20.7:5060>
Max-Forwards: 69
Content-Length: 0


*Nov 6 13:43:14.654: //-1/F89529800000/CCAPI/cc_api_display_ie_subfields:
cc_api_call_setup_ind_common:
cisco-username=1999
----- ccCallInfo IE subfields -----
cisco-ani=1999
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=1
dest=08121184994
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

*Nov 6 13:43:14.655: //-1/F89529800000/CCAPI/cc_api_call_setup_ind_common:
Interface=0x7F0E723BAAE8, Call Info(
Calling Number=1999,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=08121184994(TON=Unknown, NPI=Unknown),
Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE,
Incoming Dial-peer=102, Progress Indication=NULL(0), Calling IE Present=TRUE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=1948
*Nov 6 13:43:14.655: //-1/F89529800000/CCAPI/ccCheckClipClir:
In: Calling Number=1999(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
*Nov 6 13:43:14.656: //-1/F89529800000/CCAPI/ccCheckClipClir:
Out: Calling Number=1999(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
*Nov 6 13:43:14.656: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Nov 6 13:43:14.656: :cc_get_feature_vsa malloc success
*Nov 6 13:43:14.656: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Nov 6 13:43:14.656: cc_get_feature_vsa count is 1
*Nov 6 13:43:14.656: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Nov 6 13:43:14.656: :FEATURE_VSA attributes are: feature_name:0,feature_time:139700095634700,feature_id:239
*Nov 6 13:43:14.656: //1948/F89529800000/CCAPI/cc_api_call_setup_ind_common:
Set Up Event Sent;
Call Info(Calling Number=1999(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=08121184994(TON=Unknown, NPI=Unknown))
*Nov 6 13:43:14.657: //1948/F89529800000/CCAPI/cc_process_call_setup_ind:
Event=0x7F0E771A8028
*Nov 6 13:43:14.657: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
Try with the demoted called number 08121184994
*Nov 6 13:43:14.657: //1948/F89529800000/CCAPI/ccCallSetContext:
Context=0x7F0E68D64FA0
*Nov 6 13:43:14.657: //1948/F89529800000/CCAPI/cc_process_call_setup_ind:
>>>>CCAPI handed cid 1948 with tag 102 to app "_ManagedAppProcess_Default"
*Nov 6 13:43:14.658: //1948/F89529800000/CCAPI/ccCallProceeding:
Progress Indication=NULL(0)
*Nov 6 13:43:14.658: //1948/F89529800000/CCAPI/ccCallSetupRequest:
Destination=, Calling IE Present=TRUE, Mode=0,
Outgoing Dial-peer=100, Params=0x7F0E68D63930, Progress Indication=NULL(0)
*Nov 6 13:43:14.658: //1948/F89529800000/CCAPI/ccCheckClipClir:
In: Calling Number=+918818255000(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
*Nov 6 13:43:14.659: //1948/F89529800000/CCAPI/ccCheckClipClir:
Out: Calling Number=+918818255000(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
*Nov 6 13:43:14.659: //1948/F89529800000/CCAPI/ccCallSetupRequest:
Destination Pattern=0.........., Called Number=08121184994, Digit Strip=FALSE
*Nov 6 13:43:14.659: //1948/F89529800000/CCAPI/ccCallSetupRequest:
Calling Number=+918818255000(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=08121184994(TON=Unknown, NPI=Unknown),
Redirect Number=, Display Info=1999
Account Number=1999, Final Destination Flag=TRUE,
Guid=F8952980-0001-0000-0000-00700714A8C0, Outgoing Dial-peer=100
*Nov 6 13:43:14.659: //1948/F89529800000/CCAPI/cc_api_display_ie_subfields:
ccCallSetupRequest:
cisco-username=1999
----- ccCallInfo IE subfields -----
cisco-ani=+918818255000
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=1
dest=08121184994
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

*Nov 6 13:43:14.660: //1948/F89529800000/CCAPI/ccIFCallSetupRequestPrivate:
Interface=0x7F0E723BAAE8, Interface Type=3, Destination=, Mode=0x0,
Call Params(Calling Number=+918818255000,(Calling Name=1999)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=08121184994(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing Dial-peer=100, Call Count On=FALSE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)
*Nov 6 13:43:14.660: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Nov 6 13:43:14.660: :cc_get_feature_vsa malloc success
*Nov 6 13:43:14.660: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Nov 6 13:43:14.660: cc_get_feature_vsa count is 2
*Nov 6 13:43:14.660: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Nov 6 13:43:14.660: :FEATURE_VSA attributes are: feature_name:0,feature_time:139700095634476,feature_id:240
*Nov 6 13:43:14.660: //1949/F89529800000/CCAPI/ccIFCallSetupRequestPrivate:
SPI Call Setup Request Is Success; Interface Type=3, FlowMode=1
*Nov 6 13:43:14.661: //1949/F89529800000/CCAPI/ccCallSetContext:
Context=0x7F0E68D638B0
*Nov 6 13:43:14.661: //1948/F89529800000/CCAPI/ccSaveDialpeerTag:
Outgoing Dial-peer=100
*Nov 6 13:43:14.662: //1949/F89529800000/CCAPI/cc_api_call_proceeding:
Interface=0x7F0E723BAAE8, Progress Indication=NULL(0)
*Nov 6 13:43:14.663: //1949/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*Nov 6 13:43:14.663: cc_api_get_xcode_stream : 4981
*Nov 6 13:43:14.663: //1948/F89529800000/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.20.7:5060;branch=z9hG4bK607c8388f2f82
From: "1999" <sip:1999@192.168.20.7>;tag=3592873~3d47287c-b89e-4db4-8a1a-3e320ddb5a60-25260127
To: <sip:08121184994@172.50.0.10>
Date: Sat, 06 Nov 2021 13:43:14 GMT
Call-ID: f8952980-10001-603e9-93f492a@192.168.20.7
CSeq: 101 INVITE
Allow-Events: telephone-event
Server: Cisco-SIPGateway/IOS-16.6.4
Session-ID: 00000000000000000000000000000000;remote=d735702e84f7e185706cb30aa3592802
Content-Length: 0


*Nov 6 13:43:14.664: //1949/F89529800000/SIP/Msg/ccsipDisplayMsg:
Sent:
INVITE sip:08121184994@ap.stbi.ims.bsnl.in:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.100.84:5060;branch=z9hG4bK1031E1A
Remote-Party-ID: "1999" <sip:+918818255000@192.168.100.84>;party=calling;screen=yes;privacy=off
From: "1999" <sip:+918818255000@ap.stbi.ims.bsnl.in>;tag=1538B3A5-249F
To: <sip:08121184994@ap.stbi.ims.bsnl.in>
Date: Sat, 06 Nov 2021 13:43:14 GMT
Call-ID: 5336868A-3E3E11EC-895AE0F6-BF41D9A9@192.168.100.84
Supported: 100rel,timer,resource-priority,replaces,sdp-anat
Min-SE: 1800
Cisco-Guid: 4170525056-0000065536-0000000112-0118794432
User-Agent: Cisco-SIPGateway/IOS-16.6.4
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
CSeq: 101 INVITE
Timestamp: 1636206194
Contact: <sip:+918818255000@192.168.100.84:5060>
Expires: 600
Allow-Events: telephone-event
Max-Forwards: 68
Session-ID: d735702e84f7e185706cb30aa3592802;remote=00000000000000000000000000000000
Session-Expires: 1800
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 195

v=0
o=CiscoSystemsSIP-GW-UserAgent 383 8957 IN IP4 192.168.100.84
s=SIP Call
c=IN IP4 192.168.100.84
t=0 0
m=audio 8390 RTP/AVP 0
c=IN IP4 192.168.100.84
a=rtpmap:0 PCMU/8000
a=ptime:20

*Nov 6 13:43:14.694: //1949/F89529800000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.100.84:5060;branch=z9hG4bK1031E1A;received=172.23.16.63
Call-ID: 5336868A-3E3E11EC-895AE0F6-BF41D9A9@192.168.100.84
From: "1999"<sip:+918818255000@ap.stbi.ims.bsnl.in>;tag=1538B3A5-249F
To: <sip:08121184994@ap.stbi.ims.bsnl.in>
CSeq: 101 INVITE
Content-Length: 0


*Nov 6 13:43:16.927: //1949/F89529800000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 400 Bad Request
Via: SIP/2.0/UDP 192.168.100.84:5060;branch=z9hG4bK1031E1A;received=172.23.16.63
Call-ID: 5336868A-3E3E11EC-895AE0F6-BF41D9A9@192.168.100.84
From: "1999"<sip:+918818255000@ap.stbi.ims.bsnl.in>;tag=1538B3A5-249F
To: <sip:08121184994@ap.stbi.ims.bsnl.in>;tag=sbc0804ararr4nr
CSeq: 101 INVITE
Warning: 399 CGPV1R2.SIPCOM "3944 SIPAPP_CR_ReleaseRecv 3079 Recv rel from CC and SIPSL rel point."
Reason: Q.850;cause=111;text="Protocol error, unspecified",SIP;cause=400;text="Bad request"
Content-Length: 0


*Nov 6 13:43:16.928: //1949/F89529800000/CCAPI/cc_api_call_disconnected:
Cause Value=127, Interface=0x7F0E723BAAE8, Call Id=1949
*Nov 6 13:43:16.928: //1949/F89529800000/CCAPI/cc_api_call_disconnected:
Call Entry(Responsed=TRUE, Cause Value=127, Retry Count=0)
*Nov 6 13:43:16.928: //1948/F89529800000/CCAPI/ccCallReleaseResources:
release reserved xcoding resource.
*Nov 6 13:43:16.928: //1949/F89529800000/CCAPI/ccCallSetAAA_Accounting:
Accounting=0, Call Id=1949
*Nov 6 13:43:16.928: //1949/F89529800000/CCAPI/ccCallDisconnect:
Cause Value=127, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=127)
*Nov 6 13:43:16.928: //1949/F89529800000/CCAPI/ccCallDisconnect:
Cause Value=127, Call Entry(Responsed=TRUE, Cause Value=127)
*Nov 6 13:43:16.929: //1949/F89529800000/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x7F0E723BAAE8, Tag=0x0, Call Id=1949,
Call Entry(Disconnect Cause=127, Voice Class Cause Code=0, Retry Count=0)
*Nov 6 13:43:16.929: //1949/F89529800000/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
*Nov 6 13:43:16.929: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Nov 6 13:43:16.929: :cc_free_feature_vsa freeing 7F0E76934C20
*Nov 6 13:43:16.929: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Nov 6 13:43:16.929: vsacount in free is 1
*Nov 6 13:43:16.930: //1948/F89529800000/CCAPI/ccCallDisconnect:
Cause Value=127, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
*Nov 6 13:43:16.930: //1948/F89529800000/CCAPI/ccCallDisconnect:
Cause Value=127, Call Entry(Responsed=TRUE, Cause Value=127)
*Nov 6 13:43:16.931: //1949/F89529800000/SIP/Msg/ccsipDisplayMsg:
Sent:
ACK sip:08121184994@ap.stbi.ims.bsnl.in:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.100.84:5060;branch=z9hG4bK1031E1A
From: "1999" <sip:+918818255000@ap.stbi.ims.bsnl.in>;tag=1538B3A5-249F
To: <sip:08121184994@ap.stbi.ims.bsnl.in>;tag=sbc0804ararr4nr
Date: Sat, 06 Nov 2021 13:43:14 GMT
Call-ID: 5336868A-3E3E11EC-895AE0F6-BF41D9A9@192.168.100.84
Max-Forwards: 70
CSeq: 101 ACK
Allow-Events: telephone-event
Session-ID: ;remote=
Content-Length: 0


*Nov 6 13:43:16.931: //1948/F89529800000/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 500 Internal Server Error
Via: SIP/2.0/UDP 192.168.20.7:5060;branch=z9hG4bK607c8388f2f82
From: "1999" <sip:1999@192.168.20.7>;tag=3592873~3d47287c-b89e-4db4-8a1a-3e320ddb5a60-25260127
To: <sip:08121184994@172.50.0.10>;tag=1538BC81-AA9
Date: Sat, 06 Nov 2021 13:43:14 GMT
Call-ID: f8952980-10001-603e9-93f492a@192.168.20.7
CSeq: 101 INVITE
Allow-Events: telephone-event
Server: Cisco-SIPGateway/IOS-16.6.4
Reason: Q.850;cause=127
Session-ID: 00000000000000000000000000000000;remote=d735702e84f7e185706cb30aa3592802
Content-Length: 0


*Nov 6 13:43:16.932: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
ACK sip:08121184994@172.50.0.10:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.20.7:5060;branch=z9hG4bK607c8388f2f82
From: "1999" <sip:1999@192.168.20.7>;tag=3592873~3d47287c-b89e-4db4-8a1a-3e320ddb5a60-25260127
To: <sip:08121184994@172.50.0.10>;tag=1538BC81-AA9
Date: Sat, 06 Nov 2021 13:32:24 GMT
Call-ID: f8952980-10001-603e9-93f492a@192.168.20.7
User-Agent: Cisco-CUCM12.5
Max-Forwards: 70
CSeq: 101 ACK
Allow-Events: presence
Content-Length: 0


*Nov 6 13:43:16.933: //1948/F89529800000/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x7F0E723BAAE8, Tag=0x0, Call Id=1948,
Call Entry(Disconnect Cause=127, Voice Class Cause Code=0, Retry Count=0)
*Nov 6 13:43:16.933: //1948/F89529800000/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
*Nov 6 13:43:16.933: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Nov 6 13:43:16.933: :cc_free_feature_vsa freeing 7F0E76934D00
*Nov 6 13:43:16.933: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Nov 6 13:43:16.933: vsacount in free is 0
*Nov 6 13:43:30.235: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:

3 Replies 3

SPoodari
Beginner
Beginner

Here are some additional debugs: 
https://drive.google.com/file/d/1VZom4LdjrD3rytGr0hk7OAkxnoDizPXQ/view?usp=sharing

pcap attached in above link

 

in CUCM trace getting 

1459617833 CCM_SIP_503_SERVICE_UNAVAILABLE

 

SPoodari
Beginner
Beginner

on further diagnosis i'm thinking its Codec mismatch 

this is codec i sent

v=0
o=CiscoSystemsSIP-GW-UserAgent 4048 4740 IN IP4 192.168.100.84
s=SIP Call
c=IN IP4 192.168.100.84
t=0 0
m=audio 8472 RTP/AVP 18
c=IN IP4 192.168.100.84
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=ptime:20

 

 

 

this is the one i'm receiving

v=0
o=- 239036723 239036723 IN IP4 10.191.4.9
s=SBC call
c=IN IP4 10.191.4.9
t=0 0
m=audio 31842 RTP/AVP 18
a=ptime:20
a=rtpmap:18 G729/8000/1

 

how can i match them to get the call succeded?

SPoodari
Beginner
Beginner

Very Coooollllllllllllllll

during the research found in one of the support forum that one guy had the issue and its resolved by adding the following command voice-class sip early-offer forced, but unfortunately i already have the command added. 

 

Without much options left, i though if adding resolved for that guy, how about i remove the command???????

I did, and voilaaaaa.......it worked. 

 

Now my calls works. 

Cheers

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:

Recognize Your Peers