Call Trace - 180 Ringing (F-6480)
In the call trace below, the 2020 IMG receives a 180 Ringing message with SDP from egress or B leg of a call. The 180 Ringing Behavior field is set to 180 Ringing. The 180 Ringing message is propagated and regenerated on the ingress or A leg of the call as a 180 Ringing with ring tone but without SDP.
07:05:46.836 CALL(SIP) (00:00013:00) RCVD INVITE W/SDP from 10.129.47.141:5060 udp CSeq:1
07:05:46.836 CALL(SIP) (00:00013:00) with Via sent-by: 10.129.47.141
07:05:46.836 CALL(SIP) (00:00013:00) Call-id: 1-5160@10.129.47.141
07:05:46.836 CALL(SIP) (00:00013:00) and Msg Size: 534
07:05:46.836 CALL(SIP) (00:00013:00)
---> [10.129.47.141, 5060]
INVITE sip:50885968@10.129.47.177:5060 SIP/2.0\r\n
Via: SIP/2.0/UDP 10.129.47.141:5060;branch=z9hG4bK-5160-1-0\r\n
From: sipp <sip:sipp@10.129.47.141:5060>;tag=5160SIPpTag001\r\n
To: sut <sip:50885968@10.129.47.177:5060>\r\n
Call-ID: 1-5160@10.129.47.141\r\n
CSeq: 1 INVITE\r\n
Contact: sip:sipp@10.129.47.141:5060\r\n
Max-Forwards: 70\r\n
Subject: Performance Test\r\n
Content-Type: application/sdp\r\n
Content-Length: 135\r\n
\r\n
v=0\r\n
o=user1 53655765 2353687637 IN IP4 10.129.47.141\r\n
s=-\r\n
c=IN IP4 10.129.47.141\r\n
t=0 0\r\n
m=audio 6001 RTP/AVP 0\r\n
a=rtpmap:0 PCMU/8000
07:05:46.836 CALL(SIP) (00:00013:00) SENT 100 Trying to 10.129.47.141:5060
07:05:46.836 CALL(SIP) (00:00013:00)
<--- [10.129.47.141, 5060 <- 10.129.47.177, 5060]
SIP/2.0 100 Trying\r\n
Via: SIP/2.0/UDP 10.129.47.141:5060;branch=z9hG4bK-5160-1-0;received=10.129.47.141\r\n
Call-ID: 1-5160@10.129.47.141\r\n
From: sipp <sip:sipp@10.129.47.141:5060>;tag=5160SIPpTag001\r\n
To: sut <sip:50885968@10.129.47.177:5060>\r\n
CSeq: 1 INVITE\r\n
Content-Length: 0\r\n
\r\n
07:05:46.836 CALL(SIP) (00:00013:00) SENT Route Control to L4
07:05:46.836 CALL(L4) (00:00013:00) RCVD Route Control from SIP
07:05:46.836 CALL(L4) (00:00013:00) Accessing Route Table 2(m)
07:05:46.836 CALL(L4) (00:00013:00) Accessing Resource Table 149
07:05:46.836 CALL(L4) (00:00013:00) Hunting Algorithm is Round Robin
07:05:46.836 CALL(L4) (00:00013:00) SENT Route Control Ack to SIP
07:05:46.836 CALL(SIP) (00:00013:00) RCVD Route Control Ack from L4
07:05:46.836 CALL(SIP) (00:00013:00) Session Group Profile ID is 1
07:05:46.836 CALL(SIP) (00:00013:00) SENT Route Control to L4
07:05:46.836 CALL(L4) (00:00013:00) RCVD Route Control from SIP
07:05:46.836 CALL(L4) (00:00013:00) Accessing IP Bearer Profiles
07:05:46.836 CALL(L4) (00:00013:00) Profile Id 1 (RG 769)
07:05:46.836 CALL(SIP) (00:00013:00) RCVD Route Control Ack from L4
07:05:46.836 CALL(SIP) (00:00013:00) Checking if GW 1024(10.129.47.141:5060) is in blocking mode (0)
07:05:46.836 CALL(SIP) (00:00013:00) m line (audio) codec list: 0
07:05:46.836 CALL(SIP) (00:00013:00) RTP Type: 0, name: PCMU, clk: 8000
07:05:46.836 CALL(SIP) (00:00013:00) CODEC Negotiation Priority LOCAL
07:05:46.836 CALL(SIP) (00:00013:00) SRTP configuration Disabled
07:05:46.836 CALL(SIP) (00:00013:00) SDP Audio Codec Overlap Success
07:05:46.836 CALL(SIP) (00:00013:00) Local Media Capability is Audio Only
07:05:46.836 CALL(SIP) (00:00013:00) SENT iL3-L3 Reserve Resource Request to VPPL
07:05:46.836 CALL(VPP) (00:00013:00) RCVD Reserve Resource [its 14] from L3P
07:05:46.836 CALL(VPP) (00:00013:00) SENT Reserve Resource Request to CM
07:05:46.836 CALL(XCM) (00:00013:00) LTS:3470 Processing RESERVE request for xCM_PACKET_AUDIO_CHANNEL
07:05:46.836 CALL(XCM) (00:00013:00) SENT MEDAUDIO_MODM_CHAN_RESERVE_REQ chanType 1 groupId 0x1
07:05:46.836 CALL(XCM) (00:00013:00) LTS:3470 RCV :0x2f01(MEDAUDIO_MODM_CHAN_RESERVE_RESP) in state:xCM_CHANNEL_RESERVING
07:05:46.836 CALL(XCM) (00:00013:00) Sending message:0x2b17(XCM_CHANNEL_RESERVE_RSP), result=0x0
07:05:46.836 CALL(VPP) (00:00013:00) RCVD Reserve Resp from CM
07:05:46.836 CALL(VPP) (00:00013:00) SENT Reserve ACK (toPvid: x66) to L3P
07:05:46.836 CALL(SIP) (00:00013:00) RCVD Reserve Resource ACK from VPPL
07:05:46.836 CALL(SIP) (00:00013:00) 1: CPC (Default)
07:05:46.836 CALL(SIP) (00:00013:00) Leg 0 associated with hndl(21975), LTS(3470)(span 8208 chan 14)
07:05:46.836 CALL(SIP) (00:00013:00) SENT Setup to L4
07:05:46.846 CALL(L4) (00:00013:00) RCVD Setup Ind from SIP
07:05:46.846 CALL(L4) (00:00013:00)
07:05:46.846 CALL(L4) (00:00013:00) SENT RFS to GCL
07:05:46.846 CALL(GCL) (00:00013:00) RCVD RFS DN=[50885968] ANI=[] GN=[] from L4
07:05:46.846 CALL(GCL) (00:00013:00) DN NOA=[3]
07:05:46.846 CALL(GCL) (00:00013:00) Incoming Channel Group = 0 [SIP1]
07:05:46.846 CALL(L4) (00:00013:00) RCVD RFS response from GCL
07:05:46.846 CALL(GCL) (00:00013:00) SENT Make Call Request to GCL
07:05:46.846 CALL(GCL) (00:00013:01) SENT Route Control to L4
07:05:46.846 CALL(L4) (00:00013:00) RCVD Route Control from GCL
07:05:46.846 CALL(L4) (00:00013:00) Accessing Route Table 5
07:05:46.846 CALL(GCL) (00:00013:01) RCVD Route Control Ack (Mid Stream Router) from L4
07:05:46.846 CALL(GCL) (00:00013:01) Outgoing Channel Group = 1 [SIP2]
07:05:46.846 CALL(GCL) (00:00013:01) SENT Route Control to L4
07:05:46.846 CALL(L4) (00:00013:01) RCVD Route Control from GCL
07:05:46.846 CALL(L4) (00:00013:01) Accessing Resource Table 149
07:05:46.846 CALL(L4) (00:00013:01) Resource Group ID is 1
07:05:46.846 CALL(L4) (00:00013:01) Hunting Algorithm is Round Robin
07:05:46.856 CALL(GCL) (00:00013:01) RCVD CPE of ADDRESS INFO from L4
07:05:46.856 CALL(GCL) (00:00013:01) Leg 1 associated with LTS(3471)(span 8208 chan 15)
07:05:46.856 CALL(L4) (00:00013:01) SENT Outseize Ctrl to SIP
07:05:46.856 CALL(SIP) (00:00013:01) RCVD Outseize Ctrl from L4
07:05:46.856 CALL(SIP) (00:00013:01) SENT Route Control to L4
07:05:46.856 CALL(L4) (00:00013:01) RCVD Route Control from SIP
07:05:46.856 CALL(L4) (00:00013:01) Accessing GatewayId to IP Tables
07:05:46.856 CALL(L4) (00:00013:01) Gateway ID is 1025
07:05:46.856 CALL(SIP) (00:00013:01) RCVD Route Control Ack from L4
07:05:46.856 CALL(SIP) (00:00013:01) Session Group Profile ID is 1
07:05:46.856 CALL(SIP) (00:00013:01) SENT Route Control to L4
07:05:46.856 CALL(L4) (00:00013:01) RCVD Route Control from SIP
07:05:46.856 CALL(L4) (00:00013:01) Accessing IP Bearer Profiles
07:05:46.856 CALL(L4) (00:00013:01) Profile Id 1 (RG 769)
07:05:46.856 CALL(SIP) (00:00013:01) RCVD Route Control Ack from L4
07:05:46.856 CALL(SIP) (00:00013:01) Remote Media Capability is audio Only
07:05:46.856 CALL(SIP) (00:00013:01) Local Media Capability is Audio Only
07:05:46.856 CALL(SIP) (00:00013:01) SENT iL3-L3 Reserve Resource Request to VPPL
07:05:46.856 CALL(VPP) (00:00013:01) RCVD Reserve Resource [its 15] from L3P
07:05:46.856 CALL(VPP) (00:00013:01) SENT Reserve Resource Request to CM
07:05:46.856 CALL(XCM) (00:00013:01) LTS:3471 Processing RESERVE request for xCM_PACKET_AUDIO_CHANNEL
07:05:46.856 CALL(XCM) (00:00013:01) SENT MEDAUDIO_MODM_CHAN_RESERVE_REQ chanType 1 groupId 0x1
07:05:46.856 CALL(XCM) (00:00013:01) LTS:3471 RCV :0x2f01(MEDAUDIO_MODM_CHAN_RESERVE_RESP) in state:xCM_CHANNEL_RESERVING
07:05:46.856 CALL(XCM) (00:00013:01) Sending message:0x2b17(XCM_CHANNEL_RESERVE_RSP), result=0x0
07:05:46.856 CALL(VPP) (00:00013:01) RCVD Reserve Resp from CM
07:05:46.856 CALL(VPP) (00:00013:01) SENT Reserve ACK (toPvid: x66) to L3P
07:05:46.856 CALL(SIP) (00:00013:01) RCVD Reserve Resource ACK from VPPL
07:05:46.856 CALL(SIP) (00:00013:01) REM TP port=6060 transport:0
07:05:46.856 CALL(SIP) (00:00013:01) Remote media capabilities:Audio local media capabilities:Audio
07:05:46.856 CALL(SIP) (00:00013:01) SENT INVITE W/SDP to 10.129.47.141:6060 Cseq:1
07:05:46.856 CALL(SIP) (00:00013:01) with R-URI: 10.129.47.141:6060 UDP
07:05:46.856 CALL(SIP) (00:00013:01) and Call-id: 4039-d8f-91020127546-Node0-0-10.129.47.177
07:05:46.856 CALL(SIP) (00:00013:01)
<--- [10.129.47.141, 6060 <- 10.129.47.177, 5060]
INVITE sip:50885968@10.129.47.141:6060 SIP/2.0\r\n
Via: SIP/2.0/UDP 10.129.47.177:5060;rport;branch=z9hG4bK-16b7-1349852746-21974-31\r\n
Call-ID: 4039-d8f-91020127546-Node0-0-10.129.47.177\r\n
CSeq: 1 INVITE\r\n
Max-Forwards: 70\r\n
To: "sut"<sip:50885968@10.129.47.141:6060>\r\n
From: "unavailable"<sip:unavailable@10.129.47.177>;tag=58e8025a0fa07a00f9bd9fc91ed98963caff8e95\r\n
User-Agent: Dialogic-SIP/2.2.0.27 Node0 0\r\n
Privacy: none\r\n
Contact: <sip:unavailable@10.129.47.177:5060>\r\n
Allow: INVITE, BYE, REGISTER, ACK, OPTIONS, CANCEL, SUBS
CRIBE, NOTIFY, INFO, REFER, UPDATE\r\n
Supported: path, replaces, timer, tdialog\r\n
Session-Expires: 1800\r\n
Expires: 300\r\n
Organization: Dialogic\r\n
Content-Type: application/sdp\r\n
Content-Length: 168\r\n
\r\n
v=0\r\n
o=Dialogic_SDP 25 0 IN IP4 10.129.47.177\r\n
s=Dialogic-SIP\r\n
c=IN IP4 10.129.47.178\r\n
t=0 0\r\n
m=audio 12216 RTP/AVP 0\r\n
a=rtpmap:0 PCMU/8000\r\n
a=silenceSupp:off - - - -\r\n
Â
Relevant section
07:05:46.876 CALL(SIP) (00:00013:01) RCVD 180 Ringing W/SDP from 10.129.47.141:6060 udp CSeq:1 Msg Size:577
07:05:46.876 CALL(SIP) (00:00013:01)
---> [10.129.47.141, 6060 -> 10.129.47.177, 5060]
SIP/2.0 180 Ringing\r\n
Via: SIP/2.0/UDP 10.129.47.177:5060;rport;branch=z9hG4bK-16b7-1349852746-21974-31\r\n
From: "unavailable"<sip:unavailable@10.129.47.177>;tag=58e8025a0fa07a00f9bd9fc91ed98963caff8e95\r\n
To: "sut"<sip:50885968@10.129.47.141:6060>;tag=4868SIPpTag011\r\n
Call-ID: 4039-d8f-91020127546-Node0-0-10.129.47.177\r\n
CSeq: 1 INVITE\r\n
Contact: <sip:10.129.47.141:6060;transport=UDP>\r\n
Content-Type: application/sdp\r\n
Content-Length: 139\r\n
\r\n
v=0\r\n
o=user1 53655765 2353687637 IN IP4 10.129.47.141\r\n
s=-\r\n
c=IN IP4 10.129.47.141\r\n
t=0 0\r\n
m=audio 6000 RTP/AVP 0\r\n
a=rtpmap:0 PCMU/8000\r\n
\r\n
07:05:46.876 CALL(SIP) (00:00013:01) SENT Outseize Ack to L4
07:05:46.876 CALL(SIP) (00:00013:01) 180 Ringing Behavior : Send 180 Ringing
07:05:46.876 CALL(SIP) (00:00013:01) SENT Alerting to L4
07:05:46.876 CALL(SIP) (00:00013:01) RCVD 200 OK INVITE W/SDP from 10.129.47.141:6060 udp CSeq:1 Msg Size:568
07:05:46.876 CALL(SIP) (00:00013:01)
---> [10.129.47.141, 6060 -> 10.129.47.177, 5060]
SIP/2.0 200 OK\r\n
Via: SIP/2.0/UDP 10.129.47.177:5060;rport;branch=z9hG4bK-16b7-1349852746-21974-31\r\n
From: "unavailable"<sip:unavailable@10.129.47.177>;tag=58e8025a0fa07a00f9bd9fc91ed98963caff8e95\r\n
To: "sut"<sip:50885968@10.129.47.141:6060>;tag=4868SIPpTag011\r\n
Call-ID: 4039-d8f-91020127546-Node0-0-10.129.47.177\r\n
CSeq: 1 INVITE\r\n
Contact: <sip:10.129.47.141:6060;transport=UDP>\r\n
Content-Type: application/sdp\r\n
Content-Length: 135\r\n
\r\n
v=0\r\n
o=user1 53655765 2353687637 IN IP4 10.129.47.141\r\n
s=-\r\n
c=IN IP4 10.129.47.141\r\n
t=0 0\r\n
m=audio 6000 RTP/AVP 0\r\n
a=rtpmap:0 PCMU/8000
07:05:46.876 CALL(SIP) (00:00013:01) Session Refresh is not Needed
07:05:46.876 CALL(SIP) (00:00013:01) SDP Audio Codec Overlap Success
07:05:46.876 CALL(SIP) (00:00013:01) SENT Connect to L4
07:05:46.876 CALL(SIP) (00:00013:01) SENT ACK WO/SDP to 10.129.47.141:6060 Cseq:1
07:05:46.876 CALL(SIP) (00:00013:01) with R-URI: 10.129.47.141:6060 UDP
07:05:46.876 CALL(SIP) (00:00013:01)
<--- [10.129.47.141, 6060 <- 10.129.47.177, 5060]
ACK sip:10.129.47.141:6060 SIP/2.0\r\n
Via: SIP/2.0/UDP 10.129.47.177:5060;rport;branch=z9hG4bK-e3a-1349852746-21974-31\r\n
Call-ID: 4039-d8f-91020127546-Node0-0-10.129.47.177\r\n
CSeq: 1 ACK\r\n
Max-Forwards: 70\r\n
To: <sip:50885968@10.129.47.141:6060>;tag=4868SIPpTag011\r\n
From: <sip:unavailable@10.129.47.177>;tag=58e8025a0fa07a00f9bd9fc91ed98963caff8e95\r\n
User-Agent: Dialogic-SIP/2.2.0.27 Node0 0\r\n
Content-Length: 0\r\n
\r\n
07:05:46.876 CALL(SIP) (00:00013:01) SENT CDR Msg Event to L4
07:05:46.876 CALL(SIP) (00:00013:01) Set audio media src IP 10.129.47.178:12216
07:05:46.876 CALL(SIP) (00:00013:01) Set audio media dst IP 10.129.47.141:6000
07:05:46.876 CALL(SIP) (00:00013:01) CODEC Negotiation Priority LOCAL
07:05:46.876 CALL(SIP) (00:00013:01) Set media PLD Size 20
07:05:46.876 CALL(SIP) (00:00013:01) Set media PLD ID 2
07:05:46.876 CALL(SIP) (00:00013:01) RFC 2833 DTMF Relay Disabled,method=0
07:05:46.876 CALL(SIP) (00:00013:01) Set Fax Type to Bypass
07:05:46.876 CALL(SIP) (00:00013:01) Source Port Validation : Enabled
07:05:46.876 CALL(SIP) (00:00013:01) Set media dst IP 10.129.47.141:(rtp:6000)
07:05:46.876 CALL(SIP) (00:00013:01) Set media PLD ID 2
07:05:46.876 CALL(SIP) (00:00013:01) SENT iL3-L3 Outseize Ctrl to VPPL
07:05:46.876 CALL(L4) (00:00013:01) RCVD Outseize ACK from SIP
07:05:46.876 CALL(L4) (00:00013:01) Set Call Answer Timeout : 180 sec
07:05:46.876 CALL(L4) (00:00013:01) RVCD Alerting from SIP
07:05:46.876 CALL(L4) (00:00013:01) RCVD Connect from SIP
07:05:46.876 CALL(L4) (00:00013:01) SENT CDR Msg Event to GCL
07:05:46.876 CALL(GCL) (00:00013:01) RCVD CDR Msg Event from L4
07:05:46.876 CALL(GCL) (00:00013:00) SENT Connect w/Pad Response to L4
07:05:46.876 CALL(L4) (00:00013:01) SENT Alerting to L4
07:05:46.876 CALL(L4) (00:00013:01) Start Call Answer Timer (from L3 config): 180 secs
07:05:46.876 CALL(L4) (00:00013:01) SENT CPE of ANSWER to GCL
07:05:46.876 CALL(L4) (00:00013:00) SENT Alerting to SIP
07:05:46.876 CALL(L4) (00:00013:00) SENT Conn 1Way to xCM r_ts=0xd8f,l_ts=0xd8e
07:05:46.876 CALL(L4) (00:00013:00) SENT Channel Connect Req to xCM
07:05:46.876 CALL(L4) (00:00013:01) SENT Conn 1Way to xCM r_ts=0xd8e,l_ts=0xd8f
07:05:46.876 CALL(L4) (00:00013:01) SENT Channel Connect Req to xCM
07:05:46.876 CALL(VPP) (00:00013:01) RCVD Outseize [its 15] from L3P
07:05:46.876 CALL(SIP) (00:00013:01) RCVD Host Connect from L4
07:05:46.876 CALL(VPP) (00:00013:01) SENT Channel Open Request to CM
07:05:46.876 CALL(SIP) (00:00013:00) RCVD Alerting from L4
07:05:46.876 CALL(SIP) (00:00013:00) Call Answer Timeout from L4 : 180(0) secs
07:05:46.876 CALL(XCM) (00:00013:00) XCM CONNECT request, channel in state xCM_CHANNEL_RESERVED, not ready, Postponing.
07:05:46.876 CALL(XCM) (00:00013:01) XCM CONNECT request, channel in state xCM_CHANNEL_RESERVED, not ready, Postponing.
07:05:46.876 CALL(XCM) (00:00013:01) LTS:3471 Processing OPEN request
07:05:46.876 CALL(XCM) (00:00013:01) SENT MEDAUDIO_MODM_CHAN_OPEN_REQ
07:05:46.876 CALL(AUD) (00:00013:01) RCVD OPEN REQ [ResID x1001041A; CID 1050; TransID 945254592]
07:05:46.876 CALL(SIP) (00:00013:00) Unsupported Header Relaying Disabled
07:05:46.876 CALL(SIP) (00:00013:00) SENT 180 Ringing WO/SDP to 10.129.47.141:5060 UDP
07:05:46.876 CALL(GCL) (00:00013:01) RCVD CPE of ANSWER from L4
07:05:46.876 CALL(GCL) (00:00013:01) SENT Call Answered to GCL
Relevant section
Â