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

 

Return to Documentation Home I Return to Sangoma Support