10-30-2014 03:25 PM - edited 03-17-2019 12:45 AM
Hi guys
I have a call flow as per the below for a SIP PSTN call inbound to my CUCM cluster via CUBE.
ITSIP --> CUBE --> CUCM
I am getting a wierd signalling delay 8-10 sec before CUBE sends on the invite to the CUCM. the experience for the external calling party is no ring back until the cisco phone starts ringing, once connected the call proceeds ok.
currently i am running IOS-15.2.4.M5 on a 2921, am about to try an upgrading the IOS.
Anyone seen this before? see below sip debugs from the CUBE gateway
Debug ccsip mmessage
ICSOCD-G1#
ICSOCD-G1# call hits Gateway
ICSOCD-G1#
Oct 30 03:28:22.566: //754/000000000000/SIP/Msg/ccsipDisplayMsg:
Sent:
OPTIONS sip:XXXXXXXXXtelecom.co.nz:5060 SIP/2.0
Via: SIP/2.0/UDP 10.11.1.2:5060;branch=z9hG4bK28BB19
From: <sip:telecom.co.nz>;tag=1026294-12C3
To: <sip:XXXXXXXXXtelecom.co.nz>
Date: Thu, 30 Oct 2014 03:28:22 GMT
Call-ID: A2A88BC9-5F1B11E4-84328433-E2EC68BC@telecom.co.nz
User-Agent: Cisco-SIPGateway/IOS-15.2.4.M5
Max-Forwards: 70
CSeq: 101 OPTIONS
Contact: <sip:10.11.1.2:5060>
Content-Length: 0
Oct 30 03:28:22.598: //754/000000000000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.11.1.2:5060;received=172.18.11.94;branch=z9hG4bK28BB19;rport=1024
From: <sip:telecom.co.nz>;tag=1026294-12C3
To: <sip:XXXXXXXXXtelecom.co.nz>;tag=SDv9gt099-
Call-ID: A2A88BC9-5F1B11E4-84328433-E2EC68BC@telecom.co.nz
CSeq: 101 OPTIONS
Content-Length: 0
Oct 30 03:28:24.558: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:36869742@10.11.1.2:5060 SIP/2.0
Via: SIP/2.0/UDP X.X.X.167:5060;branch=z9hG4bKp82vgq30eon0biot95c0.1
From: <sip:0274325257@122.56.252.6;user=phone>;tag=SDd42qd01-1669661342-1414639740383-
To: "Glenavy User"<sip:36869742@telecom.co.nz;user=phone>
Call-ID: SDd42qd01-c56647f4fc366a8cc25d1792a66df01f-v300g00
CSeq: 797750000 INVITE
Contact: <sip:0274325257@X.X.X.167:5060;transport=udp>
Supported: 100rel
Allow: ACK,BYE,CANCEL,INFO,INVITE,OPTIONS,PRACK,REFER,NOTIFY,UPDATE
Accept: application/media_control+xml,application/sdp,multipart/mixed
Max-Forwards: 69
Content-Type: application/sdp
Content-Length: 166
v=0
o=BroadWorks 141259785 1 IN IP4 X.X.X.167
s=-
c=IN IP4 X.X.X.167
t=0 0
m=audio 18964 RTP/AVP 8 101
a=rtpmap:101 telephone-event/8000
a=ptime:20
Oct 30 03:28:24.566: //755/A3D87FE18433/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP X.X.X.167:5060;branch=z9hG4bKp82vgq30eon0biot95c0.1
From: <sip:0274325257@122.56.252.6;user=phone>;tag=SDd42qd01-1669661342-1414639740383-
To: "Glenavy User"<sip:36869742@telecom.co.nz;user=phone>
Date: Thu, 30 Oct 2014 03:28:24 GMT
Call-ID: SDd42qd01-c56647f4fc366a8cc25d1792a66df01f-v300g00
CSeq: 797750000 INVITE
Allow-Events: telephone-event
Server: Cisco-SIPGateway/IOS-15.2.4.M5
Content-Length: 0
Oct 30 03:28:24.578: //755/A3D87FE18433/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP X.X.X.167:5060;branch=z9hG4bKp82vgq30eon0biot95c0.1
From: <sip:0274325257@122.56.252.6;user=phone>;tag=SDd42qd01-1669661342-1414639740383-
To: "Glenavy User"<sip:36869742@telecom.co.nz;user=phone>;tag=1026A70-2193
Date: Thu, 30 Oct 2014 03:28:24 GMT
Call-ID: SDd42qd01-c56647f4fc366a8cc25d1792a66df01f-v300g00
CSeq: 797750000 INVITE
Require: 100rel
RSeq: 6594
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Contact: <sip:36869742@10.11.1.2:5060>
Supported: sdp-anat
Reason: Q.850;cause=1
Server: Cisco-SIPGateway/IOS-15.2.4.M5
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 238
v=0
o=CiscoSystemsSIP-GW-UserAgent 9076 2916 IN IP4 10.11.1.2
s=SIP Call
c=IN IP4 10.11.1.2
t=0 0
m=audio 16948 RTP/AVP 8 101
c=IN IP4 10.11.1.2
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
Oct 30 03:28:24.638: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
PRACK sip:36869742@10.11.1.2:5060 SIP/2.0
Via: SIP/2.0/UDP X.X.X.167:5060;branch=z9hG4bKqo82340008l0fisto5r1.1
From: <sip:0274325257@122.56.252.6;user=phone>;tag=SDd42qd01-1669661342-1414639740383-
To: "Glenavy User"<sip:36869742@telecom.co.nz;user=phone>;tag=1026A70-2193
Call-ID: SDd42qd01-c56647f4fc366a8cc25d1792a66df01f-v300g00
CSeq: 797750001 PRACK
RAck: 6594 797750000 INVITE
Max-Forwards: 69
Content-Length: 0
Oct 30 03:28:24.638: //755/A3D87FE18433/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP X.X.X.167:5060;branch=z9hG4bKqo82340008l0fisto5r1.1
From: <sip:0274325257@122.56.252.6;user=phone>;tag=SDd42qd01-1669661342-1414639740383-
To: "Glenavy User"<sip:36869742@telecom.co.nz;user=phone>;tag=1026A70-2193
Date: Thu, 30 Oct 2014 03:28:24 GMT
Call-ID: SDd42qd01-c56647f4fc366a8cc25d1792a66df01f-v300g00
Server: Cisco-SIPGateway/IOS-15.2.4.M5
CSeq: 797750001 PRACK
Content-Length: 0
ICSOCD-G1#
ICSOCD-G1#
ICSOCD-G1# dead air 8-10sec then ringtone at same time as phone rings
ICSOCD-G1#
ICSOCD-G1#
Oct 30 03:28:36.598: //757/A3D87FE18433/SIP/Msg/ccsipDisplayMsg:
Sent:
INVITE sip:+6436869742@10.11.4.24:5060 SIP/2.0
Via: SIP/2.0/UDP 10.11.4.6:5060;branch=z9hG4bK28C19E1
From: <sip:+64274325257@10.11.4.6>;tag=1029964-1DE0
To: <sip:+6436869742@10.11.4.24>
Date: Thu, 30 Oct 2014 03:28:36 GMT
Call-ID: AB05A8A2-5F1B11E4-843A8433-E2EC68BC@10.11.4.6
Supported: 100rel,timer,resource-priority,replaces,sdp-anat
Min-SE: 1800
Cisco-Guid: 2748874721-1595609572-2217968691-3807144124
User-Agent: Cisco-SIPGateway/IOS-15.2.4.M5
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
CSeq: 101 INVITE
Timestamp: 1414639716
Contact: <sip:+64274325257@10.11.4.6:5060>
Expires: 180
Allow-Events: telephone-event
Max-Forwards: 68
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 286
v=0
o=CiscoSystemsSIP-GW-UserAgent 5091 281 IN IP4 10.11.4.6
s=SIP Call
c=IN IP4 10.11.4.6
t=0 0
m=audio 16952 RTP/AVP 8 100 101
c=IN IP4 10.11.4.6
a=rtpmap:8 PCMA/8000
a=rtpmap:100 X-NSE/8000
a=fmtp:100 192-194
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20
Oct 30 03:28:36.602: //757/A3D87FE18433/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 10.11.4.6:5060;branch=z9hG4bK28C19E1
From: <sip:+64274325257@10.11.4.6>;tag=1029964-1DE0
To: <sip:+6436869742@10.11.4.24>
Date: Thu, 30 Oct 2014 03:29:12 GMT
Call-ID: AB05A8A2-5F1B11E4-843A8433-E2EC68BC@10.11.4.6
CSeq: 101 INVITE
Allow-Events: presence
Content-Length: 0
Oct 30 03:28:36.614: //757/A3D87FE18433/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 10.11.4.6:5060;branch=z9hG4bK28C19E1
From: <sip:+64274325257@10.11.4.6>;tag=1029964-1DE0
To: <sip:+6436869742@10.11.4.24>;tag=155985~54404872-5088-445b-a841-89c1d4c577c0-31853076
Date: Thu, 30 Oct 2014 03:29:12 GMT
Call-ID: AB05A8A2-5F1B11E4-843A8433-E2EC68BC@10.11.4.6
CSeq: 101 INVITE
Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY
Allow-Events: presence
Supported: X-cisco-srtp-fallback
Supported: Geolocation
P-Asserted-Identity: "Dryer_Tower 3" <sip:3042@10.11.4.24>
Remote-Party-ID: "Dryer_Tower 3" <sip:3042@10.11.4.24>;party=called;screen=yes;privacy=off
Contact: <sip:+6436869742@10.11.4.24:5060>
Content-Length: 0
Oct 30 03:28:36.614: //755/A3D87FE18433/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP X.X.X.167:5060;branch=z9hG4bKp82vgq30eon0biot95c0.1
From: <sip:0274325257@122.56.252.6;user=phone>;tag=SDd42qd01-1669661342-1414639740383-
To: "Glenavy User"<sip:36869742@telecom.co.nz;user=phone>;tag=1026A70-2193
Date: Thu, 30 Oct 2014 03:28:24 GMT
Call-ID: SDd42qd01-c56647f4fc366a8cc25d1792a66df01f-v300g00
CSeq: 797750000 INVITE
Require: 100rel
RSeq: 6595
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Contact: <sip:+6436869742@10.11.1.2:5060>
Server: Cisco-SIPGateway/IOS-15.2.4.M5
Content-Length: 0
Oct 30 03:28:36.650: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
PRACK sip:+6436869742@10.11.1.2:5060 SIP/2.0
Via: SIP/2.0/UDP X.X.X.167:5060;branch=z9hG4bKa1pqat30002hbiku05l1.1
From: <sip:0274325257@122.56.252.6;user=phone>;tag=SDd42qd01-1669661342-1414639740383-
To: "Glenavy User"<sip:36869742@telecom.co.nz;user=phone>;tag=1026A70-2193
Call-ID: SDd42qd01-c56647f4fc366a8cc25d1792a66df01f-v300g00
CSeq: 797750002 PRACK
RAck: 6595 797750000 INVITE
Max-Forwards: 69
Content-Length: 0
Oct 30 03:28:36.650: //755/A3D87FE18433/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP X.X.X.167:5060;branch=z9hG4bKa1pqat30002hbiku05l1.1
From: <sip:0274325257@122.56.252.6;user=phone>;tag=SDd42qd01-1669661342-1414639740383-
To: "Glenavy User"<sip:36869742@telecom.co.nz;user=phone>;tag=1026A70-2193
Date: Thu, 30 Oct 2014 03:28:36 GMT
Call-ID: SDd42qd01-c56647f4fc366a8cc25d1792a66df01f-v300g00
Server: Cisco-SIPGateway/IOS-15.2.4.M5
CSeq: 797750002 PRACK
Content-Length: 0
ICSOCD-G1#
ICSOCD-G1#
ICSOCD-G1# Released/hung up call
ICSOCD-G1#
ICSOCD-G1#
10-30-2014 09:48 PM
Hi,
Is this how the log appears? on the gateway. This doesn't look right to me. CUBE cant send session progress when it has not received any. Ie the order of this log is wrong..
Can you please do the ff: attach the log here..include calling and called number
conf t
service sequence-numbers
service timestamps debug datetime localtime msec
logging buffered 10000000 debug
no logging console
no logging monitor
default logging rate-limit
default logging queue-limit
Then..
<Enable debugs, then test again.>
debug ccsip all
<Enable session capture to txt file in terminal program.> (such as Putty)
then do the ff:
terminal length 0
show logging
11-02-2014 11:25 PM
Hi guys,
Thanks for responding, i have found the solution to this problem. we were missing the sip protocol on the first choice dial-peer to the CUCM.
10-30-2014 10:04 PM
is it a really big problem 10 seconds delay ?
do you see first invite from pstn without delay ?
do you see delayed invite fron cube to cucm ?
Discover and save your favorite ideas. Come back to expert answers, step-by-step guides, recent topics, and more.
New here? Get started with these tips. How to use Community New member guide