IMG 1010 - Call Trace - SIP to SS7
Below is a call trace which displays SIP to SS7 call.
See IMG 1010 - RADIUS - CDR Example - SIP to SS7 for the associated RADIUS CDR.
Trying 192.168.19.52... Connected to img (192.168.19.52). Escape character is '^]'. username: excel Password: *** NOT Clearing GEL Print Buffer *** > # Entering the SS7 menu > 3 L3> s # Enabling SS7 ISUP trace ss7> a0 ISUP/TUP SU Diagnostics Enabled for stack 0 # Exiting to main menu ss7> q L3> q # Entering the SIP menu > S # Enabling the SIP trace SIP> 1 SIP PDU Printing Enabled # Exiting to main menu SIP> q # Entering the call trace menu > W GEL> 29 # Enabling call tracing CALL> P -- Logging & Printing All Events -- 15:47:39.959 SIP (W) ---> [10.129.51.140, 1053] INVITE sip:5088625347@10.129.51.152:5060 SIP/2.0 Via: SIP/2.0/UDP 10.129.51.140 To: 5088625347<sip:5088625347@10.129.51.152:5060> From: 6175551212<sip:6175551212@10.129.51.140:5060>;tag=117938002f7f75 Call-ID: CSP20001.49b.3112821.830@10.129.51.140 Contact: 6175551212<sip:6175551212@10.129.51.140:5060> User-Agent: Excel_CSP/82.30.189 Supported: timer Session-Expires: 1800 Min-SE: 300 CSeq: 1 INVITE Content-Type: application/sdp Content-Length: 105 v=0 o=sip 0 0 IN IP4 10.129.51.140 s=SIP_Call c=IN IP4 10.129.51.144 t=0 0 m=audio 12716 RTP/AVP 0 15:47:39.959 SIP (W) <--- [10.129.51.140, 5060 <- 10.129.51.152, 5060] SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.129.51.140;received=10.129.51.140 Call-ID: CSP20001.49b.3112821.830@10.129.51.140 From: 6175551212<sip:6175551212@10.129.51.140:5060>;tag= 117938002f7f75 To: 5088625347<sip:5088625347@10.129.51.152:5060> CSeq: 1 INVITE Server: Cantata-SIP/10.3.2.105 E1_System 1 Content-Length: 0 15:47:39.959 CALL(SIP) (01:0001:00) RCVD INVITE from 10.129.51.140:0 UDP 15:47:39.959 CALL(SIP) (01:0001:00) with Via sent-by: 10.129.51.140 15:47:39.959 CALL(SIP) (01:0001:00) SENT 100 Trying to 10.129.51.140:0 15:47:39.959 CALL(SIP) (01:0001:00) SENT Route Ctrl to L4 15:47:39.959 CALL(L4) (01:0001:00) RCVD Route Control from SIP 15:47:39.959 CALL(L4) (01:0001:00) Accessing Route Table 2 15:47:39.959 CALL(L4) (01:0001:00) Accessing Resource Table 1 15:47:39.959 CALL(L4) (01:0001:00) IP Traffic Management: Resource Group ID: 2 568 Busy Count: 1 Channels Allowed: 1024 15:47:39.959 CALL(L4) (01:0001:00) SENT Route Control Ack to SIP 15:47:39.959 CALL(SIP) (01:0001:00) RCVD Route Ctrl Ack from L4 15:47:39.959 CALL(SIP) (01:0001:00) Session Group Profile ID is 0 15:47:39.959 CALL(SIP) (01:0001:00) SENT Route Ctrl to L4 15:47:39.959 CALL(L4) (01:0001:00) RCVD Route Control from SIP 15:47:39.959 CALL(L4) (01:0001:00) Accessing IP Bearer Profiles 15:47:39.959 CALL(L4) (01:0001:00) Profile Id 1 (RG 769) 15:47:39.959 CALL(L4) (01:0001:00) SENT Route Control Ack to SIP 15:47:39.959 CALL(SIP) (01:0001:00) RCVD Route Ctrl Ack from L4 15:47:39.959 CALL(SIP) (01:0001:00) m line codec list: 0 15:47:39.959 CALL(SIP) (01:0001:00) Leg 0 associated with hndl(19999), LTS(1792) 15:47:39.959 CALL(SIP) (01:0001:00) SENT Setup to L4 15:47:39.959 CALL(L4) (01:0001:00) RCVD Setup Ind from SIP 15:47:39.959 CALL(L4) (01:0001:00) SENT RFS to GCL 15:47:39.969 CALL(GCL) (01:0001:00) RCVD RFS DN=[5088625347] ANI=[6175551212] from L4 15:47:39.969 CALL(GCL) (01:0001:00) ANI APRI=[0],SI=[0],Category=[10] 15:47:39.969 CALL(GCL) (01:0001:00) Incoming Channel Group = 4 [SIP_CSP] 15:47:39.969 CALL(GCL) (01:0001:00) DPE Input :DN=[5088625347] ANI=[6175551212] 15:47:39.969 CALL(GCL) (01:0001:00) Invoke Incoming DPE 1; Channel Group 4 15:47:39.969 CALL(GCL) (01:0001:00) DPE response: Proc Complete 15:47:39.969 CALL(GCL) (01:0001:00) DPE Output:DN=[5088625347] ANI=[6175551212] 15:47:39.969 CALL(GCL) (01:0001:00) SENT RADIUS ACCT START 15:47:39.969 CALL(L4) (01:0001:00) RCVD RFS response from GCL 15:47:39.969 CALL(GCL) (01:0001:00) RCVD RADIUS ACCT START ACK 15:47:39.969 CALL(GCL) (01:0001:01) SENT Route Control to L4 15:47:39.969 CALL(L4) (01:0001:00) RCVD Route Control from GCL 15:47:39.969 CALL(L4) (01:0001:00) Accessing Route Table 5 15:47:39.969 CALL(L4) (01:0001:00) Accessing Route Table 5 15:47:39.969 CALL(GCL) (01:0001:01) RCVD Mid Stream Router Response 15:47:39.969 CALL(GCL) (01:0001:01) Outgoing Channel Group = 8 [SS7_E1_CSP] 15:47:39.969 CALL(GCL) (01:0001:01) SENT Route Control to L4 15:47:39.969 CALL(L4) (01:0001:01) RCVD Route Control from GCL 15:47:39.969 CALL(L4) (01:0001:01) Accessing Resource Table 1 15:47:39.969 CALL(L4) (01:0001:01) Resource Group ID is 40 15:47:39.969 CALL(L4) (01:0001:01) SENT Outseize Ctrl to SS7 15:47:39.969 CALL(GCL) (01:0001:01) RCVD CPE of ADDRESS INFO from L4 15:47:39.969 CALL(GCL) (01:0001:01) Leg 1 associated with LTS(386) 15:47:39.969 CALL(SS7) (01:0001:01) RCVD Outseize Ctrl from L4 (0x813:0x382b:0) 15:47:39.969 SS7 (U) XMT 1: sls:00 pri:00 (0000382b,0000) IAM [0020000a0002 0907031005882635740a070310165755212100] 15:47:39.969 CALL(SS7) (01:0001:01) SENT IAM (0x813:0x382b:0) 15:47:40.229 SS7 (U) RCV 1: sls:00 pri:00 (0000382b,0000) ACM [000400] 15:47:40.229 CALL(SS7) (01:0001:01) RCVD ACM (0x382b:0x813:0) 15:47:40.229 CALL(SS7) (01:0001:01) SENT Cut Thru to L4 (0x382b:0x813:0) 15:47:40.229 CALL(SS7) (01:0001:01) SENT Outseize ACK to L4 (0x382b:0x813:0) 15:47:40.229 CALL(L4) (01:0001:01) RCVD Cut Thru from SS7 15:47:40.239 CALL(L4) (01:0001:01) RCVD Outseize ACK from SS7 15:47:40.239 CALL(GCL) (01:0001:00) SENT Connect Tone to L4 15:47:40.239 CALL(L4) (01:0001:00) RCVD Connect Tone from GCL 15:47:40.239 CALL(L4) (01:0001:00) SENT Request DSP Service to SYSRM 15:47:40.239 CALL(MED) (01:0001:00) Transmitting tone 0x2 on (0x1,0x1,0x0) 15:47:40.239 CALL(MED) (01:0001:00) RCVD OUTPULSE CP 15:47:40.239 CALL(GCL) (01:0001:00) SENT Connect w/Pad Response to L4 15:47:40.239 CALL(L4) (01:0001:00) SENT Progress to SIP 15:47:40.239 CALL(SIP) (01:0001:00) RCVD Progress from L4 15:47:40.239 CALL(SIP) (01:0001:00) Set Fax Type to Relay T.38 15:47:40.239 CALL(SIP) (01:0001:00) SENT L3-L3 Outseize Ctrl to VPPL 15:47:40.239 CALL(IP) (01:0001:00) RCVD Outseize from L3, [vts 768] [m1.0.0] 15:47:40.239 CALL(IP) (01:0001:00) RTP: (Src) 10.129.51.134:8000 15:47:40.239 CALL(IP) (01:0001:00) RTP: (Dst) 10.129.51.144:12716 15:47:40.249 CALL(IP) (01:0001:00) VoIP Codec is G711Mulaw; Payload Size is 20ms 15:47:40.259 CALL(IP) (01:0001:00) SENT Outseize ACK to L3P, toPvid: x66 15:47:40.259 CALL(SIP) (01:0001:00) RCVD Outseize Ack from VPPL 15:47:40.259 CALL(SIP) (01:0001:00) SENT 183 Session Progress to 10.129.51.140:5060 UDP 15:47:40.259 SIP (W) <--- [10.129.51.140, 5060 <- 10.129.51.152, 5060] SIP/2.0 183 Session Progress Via: SIP/2.0/UDP 10.129.51.140;received=10.129.51.140 Contact: <sip:10.129.51.152:5060> Call-ID: CSP20001.49b.3112821.830@10.129.51.140 From: 6175551212<sip:6175551212@10.129.51.140:5060>;tag= 117938002f7f75 To: 5088625347<sip:5088625347@10.129.51.152:5060>;tag=a9 4c095b773be1dd6e8d668a785a9c843102 CSeq: 1 INVITE Server: Cantata-SIP/10.3.2.105 E1_System 1 Content-Type: application/sdp Content-Length: 176 v=0 o=Cantata_SDP 0 0 IN IP4 10.129.51.152 s=Cantata-SIP c=IN IP4 10.129.51.134 t=0 0 m=audio 8000 RTP/AVP 0 a=rtpmap:0 PCMU/8000 a=silenceSupp:off - - - - a=ptime:20 15:47:40.259 CALL(SIP) (01:0001:00) RCVD Cut Thru from VPPL 15:47:40.259 CALL(SIP) (01:0001:00) RCVD Connect from VPPL 15:47:43.869 SS7 (U) RCV 1: sls:00 pri:00 (0000382b,0000) ANM [00] 15:47:43.869 CALL(SS7) (01:0001:01) RCVD ANM (0x382b:0x813:0) 15:47:43.869 CALL(SS7) (01:0001:01) SENT Connect to L4 (0x382b:0x813:0) 15:47:43.869 CALL(L4) (01:0001:01) RCVD Connect from SS7 15:47:43.869 CALL(L4) (01:0001:01) SENT CPE of ANSWER to GCL 15:47:43.869 CALL(GCL) (01:0001:01) RCVD CPE of ANSWER from L4 15:47:43.869 CALL(GCL) (01:0001:01) SENT RADIUS ACCT START 15:47:43.869 CALL(GCL) (01:0001:01) RCVD RADIUS ACCT START ACK 15:47:43.869 CALL(GCL) (01:0001:00) SENT Disconnect Tone to L4 15:47:43.869 CALL(L4) (01:0001:00) RCVD Disconnect Tone from GCL 15:47:43.869 CALL(L4) (01:0001:00) SENT Cancel DSP Service to SYSRM 15:47:43.869 CALL(MED) (01:0001:00) RCVD CANCEL OUTPULSE CP 15:47:43.869 CALL(MED) (01:0001:00) RCVD CANCEL OUTPULSE DIGITS 15:47:43.869 CALL(GCL) (01:0001:00) SENT CPE of ANSWER to L4 15:47:43.869 CALL(L4) (01:0001:00) RCVD CPE of ANSWER from GCL 15:47:43.869 CALL(L4) (01:0001:00) SENT Connect to SIP 15:47:43.869 CALL(SIP) (01:0001:00) RCVD Connect from L4 15:47:43.869 CALL(SIP) (01:0001:00) SENT 200 OK to 10.129.51.140:5060 UDP 15:47:43.869 SIP (W) <--- [10.129.51.140, 5060 <- 10.129.51.152, 5060] SIP/2.0 200 OK Via: SIP/2.0/UDP 10.129.51.140;received=10.129.51.140 Contact: <sip:10.129.51.152:5060> Call-ID: CSP20001.49b.3112821.830@10.129.51.140 From: 6175551212<sip:6175551212@10.129.51.140:5060>;tag= 117938002f7f75 To: 5088625347<sip:5088625347@10.129.51.152:5060>;tag=a9 4c095b773be1dd6e8d668a785a9c843102 CSeq: 1 INVITE Server: Cantata-SIP/10.3.2.105 E1_System 1 Allow: INVITE, BYE, REGISTER, ACK, OPTIONS, CANCEL, INFO Supported: path Accept: application/sdp Content-Type: application/sdp Content-Length: 176 v=0 o=Cantata_SDP 0 1 IN IP4 10.129.51.152 s=Cantata-SIP c=IN IP4 10.129.51.134 t=0 0 m=audio 8000 RTP/AVP 0 a=rtpmap:0 PCMU/8000 a=silenceSupp:off - - - - a=ptime:20 15:47:43.899 SIP (W) ---> [10.129.51.140, 1053] ACK sip:10.129.51.152:5060 SIP/2.0 Via: SIP/2.0/UDP 10.129.51.140 To: 5088625347<sip:5088625347@10.129.51.152:5060>;tag=a9 4c095b773be1dd6e8d668a785a9c843102 From: 6175551212<sip:6175551212@10.129.51.140:5060>;tag= 117938002f7f75 Call-ID: CSP20001.49b.3112821.830@10.129.51.140 CSeq: 1 ACK Content-Length: 0 15:47:43.899 CALL(SIP) (01:0001:00) RCVD ACK from 10.129.51.140 UDP 15:47:43.899 CALL(SIP) (01:0001:00) with Via sent-by: 10.129.51.140 15:48:05.019 SIP (W) ---> [10.129.51.140, 1053] BYE sip:10.129.51.152:5060 SIP/2.0 Via: SIP/2.0/UDP 10.129.51.140 To: 5088625347<sip:5088625347@10.129.51.152:5060>;tag=a9 4c095b773be1dd6e8d668a785a9c843102 From: 6175551212<sip:6175551212@10.129.51.140:5060>;tag= 117938002f7f75 Call-ID: CSP20001.49b.3112821.830@10.129.51.140 CSeq: 2 BYE User-Agent: Excel_CSP/82.30.189 Content-Length: 0 15:48:05.019 CALL(SIP) (01:0001:00) RCVD BYE from 10.129.51.140:0 UDP 15:48:05.019 CALL(SIP) (01:0001:00) with Via sent-by: 10.129.51.140 UDP 15:48:05.019 CALL(SIP) (01:0001:00) SENT L3-L3 Clear Req to VPPL 15:48:05.019 CALL(IP) (01:0001:00) RCVD Clear Request from L3 15:48:05.019 CALL(IP) (01:0001:00) RTCP STATS: TxPackets=1237; RxPackets=1236; lostPackets=0 15:48:05.019 CALL(IP) (01:0001:00) RTCP/T38 Packet STATS: TxPackets=1237; RxPa ckets=1236; lostPackets=0 15:48:05.019 CALL(SIP) (01:0001:00) RCVD Clear Req from VPPL 15:48:05.019 CALL(SIP) (01:0001:00) SENT Clear Request to L4 15:48:05.019 CALL(SIP) (01:0001:00) SENT 200 OK to 10.129.51.140:5060 UDP 15:48:05.019 SIP (W) <--- [10.129.51.140, 5060 <- 10.129.51.152, 5060] SIP/2.0 200 OK Via: SIP/2.0/UDP 10.129.51.140;received=10.129.51.140 Contact: <sip:10.129.51.152:5060> Call-ID: CSP20001.49b.3112821.830@10.129.51.140 From: 6175551212<sip:6175551212@10.129.51.140:5060>;tag= 117938002f7f75 To: 5088625347<sip:5088625347@10.129.51.152:5060>;tag=a9 4c095b773be1dd6e8d668a785a9c843102 CSeq: 2 BYE Server: Cantata-SIP/10.3.2.105 E1_System 1 Content-Length: 0 15:48:05.019 CALL(L4) (01:0001:00) RCVD Clear request from SIP 15:48:05.019 CALL(L4) (01:0001:00) SENT Channel Release w/data to GCL 15:48:05.019 CALL(GCL) (01:0001:00) RCVD Channel Released w/data from L4 15:48:05.019 CALL(GCL) (01:0001:00) Call Duration(sec): 22 15:48:05.019 CALL(GCL) (01:0001:01) SENT Clear Response to L4 15:48:05.019 CALL(GCL) (01:0001:00) Radius Call Duration(sec): 22 15:48:05.019 CALL(GCL) (01:0001:00) SENT RADIUS ACCT STOP 15:48:05.019 CALL(L4) (01:0001:01) RCVD Clear from GCL 15:48:05.019 CALL(L4) (01:0001:01) SENT Clear Request to SS7 15:48:05.019 CALL(SS7) (01:0001:01) RCVD Clear Request from L4 (0x813:0x382b:0) 15:48:05.019 SS7 (U) XMT 1: sls:00 pri:00 (0000382b,0000) REL [0200028090] 15:48:05.019 CALL(SS7) (01:0001:01) SENT REL (0x813:0x382b:0) cause 16 15:48:05.089 SS7 (U) RCV 1: sls:00 pri:00 (0000382b,0000) RLC [00] 15:48:05.089 CALL(SS7) (01:0001:01) RCVD RLC (0x382b:0x813:0) 15:48:05.089 CALL(SS7) (01:0001:01) SENT Clear Request to L4 (0x382b:0x813:0) 15:48:05.089 CALL(L4) (01:0001:01) RCVD Clear request from SS7 15:48:05.089 CALL(L4) (01:0001:01) SENT Channel Release w/data to GCL 15:48:05.089 CALL(GCL) (01:0001:01) RCVD Channel Released w/data from L4 15:48:05.089 CALL(GCL) (01:0001:01) Call Duration(sec): 22 15:48:05.089 CALL(GCL) (01:0001:01) Radius Call Duration(sec): 22 15:48:05.089 CALL(GCL) (01:0001:01) SENT RADIUS ACCT STOP