Call Trace Reason Header
Below is call trace displaying the Reason Header.
Advanced Debug> 00:12:14.490 CALL(SIP)
(00:00006:00) RCVD INVITE W/SDP from 10.10.1.4:5060 udp CSeq:1
00:12:14.490 CALL(SIP)
(00:00006:00) with Via sent-by: 10.10.1.4
00:12:14.490 CALL(SIP)
(00:00006:00) Call-id: 1-6440@10.10.1.4
00:12:14.490 CALL(SIP)
(00:00006:00) and Msg Size: 565
00:12:14.490 CALL(SIP)
(00:00006:00)
--->
[10.10.1.4, 5060]
INVITE
sip:5555@10.10.1.173:5060 SIP/2.0\r\n
Via:
SIP/2.0/UDP 10.10.1.4:5060;branch=z9hG4bK-6440-1-0\r\n
From:
sipp <sip:sipp@10.10.1.4:5060>;tag=6440SIPpTag001\r\n
To:
sut <sip:5555@10.10.1.173:5060>\r\n
Call-ID:
1-6440@10.10.1.4\r\n
CSeq:
1 INVITE\r\n
Contact:
sip:sipp@10.10.1.4:5060\r\n
Max-Forwards:
70\r\n
Diversion:
<sip:13216938059@voxbone.com>;counter=1;reason=unknown\r\n
Subject:
Performance Test\r\n
Content-Type:
application/sdp\r\n
Content-Length:
127\r\n
\r\n
v=0\r\n
o=user1
53655765 2353687637 IN IP4 10.10.1.4\r\n
s=-\r\n
c=IN
IP4 10.10.1.4\r\n
t=0
0\r\n
m=audio
6000 RTP/AVP 0\r\n
a=rtpmap:0
PCMU/8000
00:12:14.490 CALL(SIP)
(00:00006:00) SENT 100 Trying to 10.10.1.4:5060
00:12:14.490 CALL(SIP)
(00:00006:00)
<---
[10.10.1.4, 5060 <- 10.10.1.173, 5060]
SIP/2.0
100 Trying\r\n
Via:
SIP/2.0/UDP 10.10.1.4:5060;branch=z9hG4bK-6440-1-0;received=10.10.1.4\r\n
Call-ID:
1-6440@10.10.1.4\r\n
From:
sipp <sip:sipp@10.10.1.4:5060>;tag=6440SIPpTag001\r\n
To:
sut <sip:5555@10.10.1.173:5060>\r\n
CSeq:
1 INVITE\r\n
Server:
Dialogic-SIP/10.5.3.105 VINN 0\r\n
Content-Length:
0\r\n
\r\n
00:12:14.490 CALL(SIP)
(00:00006:00) SENT Route Control to L4
00:12:14.490 CALL(L4) (00:00006:00)
RCVD Route Control from SIP
00:12:14.490 CALL(L4) (00:00006:00)
Accessing Route Table 2
00:12:14.490 CALL(L4) (00:00006:00)
Accessing Resource Table 1
00:12:14.490 CALL(L4) (00:00006:00)
Hunting Algorithm is Round Robin
00:12:14.490 CALL(L4) (00:00006:00)
SENT Route Control Ack to SIP
00:12:14.490 CALL(SIP)
(00:00006:00) RCVD Route Control Ack from L4
00:12:14.490 CALL(SIP)
(00:00006:00) Session Group Profile ID is 1
00:12:14.490 CALL(SIP)
(00:00006:00) SENT Route Control to L4
00:12:14.490 CALL(L4) (00:00006:00)
RCVD Route Control from SIP
00:12:14.490 CALL(L4) (00:00006:00)
Accessing IP Bearer Profiles
00:12:14.490 CALL(L4) (00:00006:00)
Profile Id 1 (RG 769)
00:12:14.490 CALL(L4) (00:00006:00)
SENT Route Control Ack to SIP
00:12:14.490 CALL(SIP)
(00:00006:00) RCVD Route Control Ack from L4
00:12:14.490 CALL(SIP)
(00:00006:00) m line (audio) codec list: 0
00:12:14.490 CALL(SIP)
(00:00006:00) RTP Type: 0, name: PCMU, clk: 8000
00:12:14.490 CALL(SIP)
(00:00006:00) CODEC Negotiation Priority LOCAL
00:12:14.490 CALL(SIP)
(00:00006:00) CPC (Default)
00:12:14.490 CALL(SIP)
(00:00006:00) Leg 0 associated with hndl(4994), LTS(1797)(span 4128 chan
5)
00:12:14.490 CALL(SIP)
(00:00006:00) SENT Setup to L4
00:12:14.490 CALL(L4) (00:00006:00)
RCVD Setup Ind from SIP
00:12:14.490 CALL(L4) (00:00006:00)
SENT RFS to GCL
00:12:14.500 CALL(GCL)
(00:00006:00) RCVD RFS DN=[5555] ANI=[] from L4
00:12:14.500 CALL(GCL)
(00:00006:00) DN NOA=[3]
00:12:14.500 CALL(GCL)
(00:00006:00) Incoming Channel Group = 15 [SIP]
00:12:14.500 CALL(GCL)
(00:00006:00) SENT Make Call Request to GCL
00:12:14.500 CALL(GCL)
(00:00006:01) SENT Route Control to L4
00:12:14.500 CALL(L4) (00:00006:00)
RCVD RFS response from GCL
00:12:14.500 CALL(L4) (00:00006:00)
RCVD Route Control from GCL
00:12:14.500 CALL(L4) (00:00006:00)
Accessing Route Table 7(m)
00:12:14.500 CALL(GCL)
(00:00006:01) RCVD Route Control Ack (Mid Stream Router)from L4
00:12:14.500 CALL(GCL)
(00:00006:01) Outgoing Channel Group = 0 [SS71]
00:12:14.500 CALL(GCL)
(00:00006:01) SENT Route Control to L4
00:12:14.500 CALL(L4) (00:00006:01)
RCVD Route Control from GCL
00:12:14.500 CALL(L4) (00:00006:01)
Accessing Resource Table 1
00:12:14.500 CALL(L4) (00:00006:01)
Resource Group ID is 32
00:12:14.500 CALL(L4) (00:00006:01)
Hunting Algorithm is ROUND_ROBIN_CLOCKWISE
00:12:14.500 CALL(L4) (00:00006:01)
RCVD Ported Number
00:12:14.500 CALL(L4) (00:00006:01)
IW: Override TMR (03)
00:12:14.500 CALL(L4) (00:00006:01)
IW: Override BC/USI
00:12:14.500 CALL(L4) (00:00006:01)
SENT Outseize Ctrl to SS7
00:12:14.500 CALL(GCL)
(00:00006:01) RCVD CPE of ADDRESS INFO from L4
00:12:14.500 CALL(GCL)
(00:00006:01) Leg 1 associated with LTS(5)(span 28 chan 5)
00:12:14.500 CALL(SS7)
(00:00006:01) RCVD Outseize Ctrl (0x1:0x2:3) from L4
00:12:14.500 CALL(SS7)
(00:00006:01)
SENT:
STK: 0 PRI: 00
DPC:
0-0-2 OPC: 0-0-1
SLS:
0x03 CIC: 00003 (0x0003)
IAM
[0048000a0302000403105555]
00:12:14.500 CALL(SS7)
(00:00006:01) SENT IAM to (0x1:0x2:3)
00:12:14.560 CALL(SS7)
(00:00006:01)
RCVD:
STK: 0 PRI: 00
DPC:
0-0-1 OPC: 0-0-2
SLS:
0x00 CIC: 00003 (0x0003)
REL
[0200058091010203]
00:12:14.560 CALL(SS7)
(00:00006:01) RCVD REL cause 17 from (0x2:0x1:3)
00:12:14.560 CALL(SS7)
(00:00006:01) SENT Clear Req w/info (0x2:0x1:3) to L4
00:12:14.560 CALL(SS7)
(00:00006:01)
SENT:
STK: 0 PRI: 00
DPC:
0-0-2 OPC: 0-0-1
SLS:
0x03 CIC: 00003 (0x0003)
RLC
[00]
00:12:14.560 CALL(SS7)
(00:00006:01) SENT RLC to (0x1:0x2:3)
00:12:14.560 CALL(L4) (00:00006:01)
RCVD Clear request from SS7
00:12:14.560 CALL(L4) (00:00006:01)
SENT Route Control NACK(27) to GCL
00:12:14.560 CALL(GCL)
(00:00006:00) SENT Clear Response, cause=17 to L4
00:12:14.560 CALL(L4) (00:00006:00)
RCVD Clear, cause=17 from GCL
00:12:14.560 CALL(L4) (00:00006:00)
SENT Clear Request, cause=17 to SIP
00:12:14.560 CALL(SIP)
(00:00006:00) RCVD Clear Request from L4
00:12:14.560
CALL(SIP) (00:00006:00) Call processing released
00:12:14.560
CALL(SIP) (00:00006:00) Reason Hdr: "User busy"
00:12:14.560 CALL(SIP)
(00:00006:00) SENT Clear Request to L4
00:12:14.560 CALL(SIP)
(00:00006:00) SENT 486 Busy Here [Call processing released] to 10.10.1.4:5060
Cseq:1
00:12:14.560 CALL(SIP)
(00:00006:00)
<---
[10.10.1.4, 5060 <- 10.10.1.173, 5060]
SIP/2.0
486 Busy Here [Call processing released]\r\n
Via:
SIP/2.0/UDP 10.10.1.4:5060;branch=z9hG4bK-6440-1-0;received=10.10.1.4\r\n
Call-ID:
1-6440@10.10.1.4\r\n
From:
sipp <sip:sipp@10.10.1.4:5060>;tag=6440SIPpTag001\r\n
To:
sut <sip:5555@10.10.1.173:5060>;tag=a94c095b773be1dd6e8d668a785a9c84da0492b1\r\n
Contact:
<sip:5555@10.10.1.173:5060>\r\n
CSeq:
1 INVITE\r\n
Server:
Dialogic-SIP/10.5.3.105 VINN 0\r\n
Reason: Q.850 ;cause=17 ;text="User
busy" ;extension= 01 02 03\r\n
Content-Length:
0\r\n
\r\n
00:12:14.560 CALL(L4) (00:00006:00)
RCVD Clear request from SIP
00:12:14.560 CALL(L4) (00:00006:00)
SENT Channel Release w/data to GCL
00:12:14.560 CALL(GCL)
(00:00006:00) RCVD Channel Released w/data, cause=17 from L4
00:12:14.560 CALL(GCL)
(00:00006:00) Call Duration(sec): 0
00:12:14.570 CALL(SIP)
(00:00006:00) RCVD ACK from 10.10.1.4:5060 udp CSeq:1 Msg Size:410
00:12:14.570 CALL(SIP)
(00:00006:00) with Via sent-by: 10.10.1.4
udp
00:12:14.570 CALL(SIP)
(00:00006:00)
--->
[10.10.1.4, 5060]
ACK
sip:5555@10.10.1.173:5060 SIP/2.0\r\n
Via:
SIP/2.0/UDP 10.10.1.4:5060;branch=z9hG4bK-6440-1-0;
received=10.10.1.4\r\n
From:
sipp <sip:sipp@10.10.1.4:5060>;tag=6440SIPpTag001\r\n
To:
sut <sip:5555@10.10.1.173:5060>;tag=a94c095b773be1dd6e8d668a785a9c84da0492b1\r\n
Call-ID:
1-6440@10.10.1.4\r\n
CSeq:
1 ACK\r\n
Contact:
<sip:sipp@10.10.1.4:5060;transport=UDP>\r\n
Max-Forwards:
70\r\n
Subject:
Performance Test\r\n
Content-Length:
0\r\n
\r\n