Call Trace - SIP to SS7

Below is a call trace which displays SIP to SS7 call. See the links below:

 

To see the associated RADIUS CDR, click here.

 

 

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