Call Trace - RADIUS - Pre-Paid

Owned by Kelli Higdon
Last updated: Mar 06, 2024
5 min read

 

Look for "RADIUS" traces in the following log.

 

18:07:02.077 CALL(SIP) (01:0001:00) RCVD INVITE from 10.129.44.226:5060 UDP 18:07:02.077 CALL(SIP) (01:0001:00) with Via sent-by: 10.129.44.226 18:07:02.077 CALL(SIP) (01:0001:00) SENT 100 Trying [] to 10.129.44.226:5060 18:07:02.077 CALL(SIP) (01:0001:00) SENT Route Ctrl to L4 18:07:02.077 CALL(L4) (01:0001:00) RCVD Route Control from SIP 18:07:02.077 CALL(L4) (01:0001:00) Accessing Route Table 2 18:07:02.077 CALL(L4) (01:0001:00) Accessing Resource Table 1 18:07:02.077 CALL(L4) (01:0001:00) SENT Route Control Ack to SIP 18:07:02.077 CALL(SIP) (01:0001:00) RCVD Route Ctrl Ack from L4 18:07:02.077 CALL(SIP) (01:0001:00) Session Group Profile ID is 0 18:07:02.077 CALL(SIP) (01:0001:00) SENT Route Ctrl to L4 18:07:02.077 CALL(L4) (01:0001:00) RCVD Route Control from SIP 18:07:02.077 CALL(L4) (01:0001:00) Accessing IP Bearer Profiles 18:07:02.077 CALL(L4) (01:0001:00) Profile Id 1 (RG 769) 18:07:02.077 CALL(L4) (01:0001:00) SENT Route Control Ack to SIP 18:07:02.077 CALL(SIP) (01:0001:00) RCVD Route Ctrl Ack from L4 18:07:02.077 CALL(SIP) (01:0001:00) m line codec list: 0 101 18:07:02.077 CALL(SIP) (01:0001:00) RTP Type: 0, name: PCMU, clk: 8000 18:07:02.077 CALL(SIP) (01:0001:00) RTP Type: 101, name: telephone-event, clk: 8000 18:07:02.077 CALL(SIP) (01:0001:00) Leg 0 associated with hndl(19999), LTS(1792) 18:07:02.077 CALL(SIP) (01:0001:00) SENT Setup to L4 18:07:02.077 CALL(L4) (01:0001:00) RCVD Setup Ind from SIP 18:07:02.077 CALL(L4) (01:0001:00) SENT RFS to GCL 18:07:02.077 CALL(GCL) (00:0000:00) SENT RADIUS AUTH REQUEST 18:07:02.077 CALL(GCL) (01:0001:00) RCVD RFS DN=[4004] ANI=[5084444444] from L4 18:07:02.077 CALL(GCL) (01:0001:00) ANI APRI=[0],SI=[0],Category=[10] 18:07:02.077 CALL(GCL) (01:0001:00) Incoming Channel Group = 10 [4001_SIP_Snom200] 18:07:02.087 CALL(GCL) (01:0001:00) RCVD RADIUS AUTH ACCEPT ACK 18:07:02.087 CALL(GCL) (01:0001:00) Using Radius MaxDurCall=1 seconds 18:07:02.087 CALL(GCL) (01:0001:00) SENT RADIUS ACCT START 18:07:02.087 CALL(L4) (01:0001:00) RCVD RFS response from GCL 18:07:02.397 CALL(GCL) (01:0001:00) RCVD RADIUS ACCT START 18:07:02.397 CALL(GCL) (01:0001:01) SENT Route Control to L4 18:07:02.397 CALL(L4) (01:0001:00) RCVD Route Control from GCL 18:07:02.397 CALL(L4) (01:0001:00) Accessing Route Table 5 18:07:02.397 CALL(GCL) (01:0001:01) RCVD Mid Stream Router Response 18:07:02.397 CALL(GCL) (01:0001:01) Outgoing Channel Group = 13 [SIP_IBM_5060] 18:07:02.397 CALL(GCL) (01:0001:01) SENT Route Control to L4 18:07:02.397 CALL(L4) (01:0001:01) RCVD Route Control from GCL 18:07:02.397 CALL(L4) (01:0001:01) Accessing Resource Table 1 18:07:02.397 CALL(L4) (01:0001:01) Resource Group ID is 1 18:07:02.407 CALL(L4) (01:0001:01) SENT Outseize Ctrl to SIP 18:07:02.407 CALL(GCL) (01:0001:01) RCVD CPE of ADDRESS INFO from L4 18:07:02.407 CALL(GCL) (01:0001:01) Leg 1 associated with LTS(1024) 18:07:02.407 CALL(SIP) (01:0001:01) RCVD Outseize Ctrl from L4 18:07:02.407 CALL(SIP) (01:0001:01) SENT Route Ctrl to L4 18:07:02.407 CALL(L4) (01:0001:01) RCVD Route Control from SIP 18:07:02.407 CALL(L4) (01:0001:01) Accessing GatewayId to IP Tables 18:07:02.407 CALL(L4) (01:0001:01) Gateway ID is 1028 18:07:02.407 CALL(L4) (01:0001:01) SENT Route Control Ack to SIP 18:07:02.407 CALL(SIP) (01:0001:01) RCVD Route Ctrl Ack from L4 18:07:02.407 CALL(SIP) (01:0001:01) Session Group Profile ID is 0 18:07:02.407 CALL(SIP) (01:0001:01) SENT Route Ctrl to L4 18:07:02.407 CALL(L4) (01:0001:01) RCVD Route Control from SIP 18:07:02.407 CALL(L4) (01:0001:01) Accessing IP Bearer Profiles 18:07:02.407 CALL(L4) (01:0001:01) Profile Id 1 (RG 769) 18:07:02.407 CALL(L4) (01:0001:01) SENT Route Control Ack to SIP 18:07:02.407 CALL(SIP) (01:0001:01) RCVD Route Ctrl Ack from L4 18:07:02.407 CALL(SIP) (01:0001:01) SENT INVITE to 10.129.44.254:5060 UDP 18:07:02.407 CALL(SIP) (01:0001:01) with R-URI: 10.129.44.254:5060 UDP 18:07:02.517 CALL(SIP) (01:0001:01) RCVD 180 Ringing from 10.129.44.254:5060 UDP 18:07:02.517 CALL(SIP) (01:0001:01) SENT Outseize Ack to L4 18:07:02.517 CALL(SIP) (01:0001:01) SENT Alerting to L4 18:07:02.517 CALL(L4) (01:0001:01) RCVD Outseize ACK from SIP 18:07:02.517 CALL(L4) (01:0001:01) RVCD Alerting from SIP 18:07:02.517 CALL(GCL) (01:0001:00) SENT Connect Tone to L4 18:07:02.517 CALL(L4) (01:0001:00) RCVD Connect Tone from GCL 18:07:02.517 CALL(L4) (01:0001:00) SENT Request DSP Service to SYSRM 18:07:02.517 CALL(MED) (01:0001:00) Transmitting tone 0x2 on (0x1,0x1,0x0) 18:07:02.517 CALL(MED) (01:0001:00) RCVD OUTPULSE CP 18:07:02.517 CALL(GCL) (01:0001:00) SENT Connect w/Pad Response to L4 18:07:02.517 CALL(L4) (01:0001:00) SENT Progress to SIP 18:07:02.517 CALL(SIP) (01:0001:01) RCVD Host Connect from L4 18:07:02.517 CALL(SIP) (01:0001:00) RCVD Progress from L4 18:07:02.517 CALL(SIP) (01:0001:00) Set Fax Type to Bypass 18:07:02.517 CALL(SIP) (01:0001:00) SENT L3-L3 Outseize Ctrl to VPPL 18:07:02.517 CALL(IP) (01:0001:00) RCVD Outseize from L3, [vts 768] [m1.0.1] 18:07:02.517 CALL(IP) (01:0001:00) RTP: (Src) 10.129.44.251:11072 18:07:02.517 CALL(IP) (01:0001:00) RTP: (Dst) 10.129.44.226:10002 18:07:02.527 CALL(IP) (01:0001:00) VoIP Codec is G711Mulaw; Payload Size is 20ms 18:07:02.527 CALL(IP) (01:0001:00) RFC2833 DTMF Relay in use, Dynamic Payload Type is 101 18:07:02.547 CALL(IP) (01:0001:00) SENT Outseize ACK to L3P, toPvid: x66 18:07:02.547 CALL(SIP) (01:0001:00) RCVD Outseize Ack from VPPL 18:07:02.547 CALL(SIP) (01:0001:00) SENT 183 Session Progress to 10.129.44.226:5060 UDP 18:07:02.547 CALL(SIP) (01:0001:00) RCVD Cut Thru from VPPL 18:07:02.547 CALL(SIP) (01:0001:00) RCVD Connect from VPPL 18:07:06.337 CALL(SIP) (01:0001:01) RCVD 200 OK from 10.129.44.254:5060 UDP 18:07:06.337 CALL(SIP) (01:0001:01) SENT ACK to 10.129.44.254:5060 UDP 18:07:06.337 CALL(SIP) (01:0001:01) with R-URI: 10.129.44.254:5060 UDP 18:07:06.337 CALL(SIP) (01:0001:01) m line codec list: 0 18:07:06.337 CALL(SIP) (01:0001:01) RTP Type: 0, name: PCMU, clk: 8000 18:07:06.337 CALL(SIP) (01:0001:01) Set Fax Type to Bypass 18:07:06.337 CALL(SIP) (01:0001:01) SENT L3-L3 Outseize Ctrl to VPPL 18:07:06.337 CALL(IP) (01:0001:01) RCVD Outseize from L3, [vts 0] [m0.0.1] 18:07:06.337 CALL(IP) (01:0001:01) RTP: (Src) 10.129.44.241:8000 18:07:06.337 CALL(IP) (01:0001:01) RTP: (Dst) 10.129.44.254:29574 18:07:06.347 CALL(IP) (01:0001:01) VoIP Codec is G711Mulaw; Payload Size is 20ms 18:07:06.347 CALL(IP) (01:0001:01) RFC2833 DTMF Relay in use, Dynamic Payload Type is 120 18:07:06.367 CALL(IP) (01:0001:01) SENT Outseize ACK to L3P, toPvid: x66 18:07:06.367 CALL(SIP) (01:0001:01) RCVD Outseize Ack from VPPL 18:07:06.367 CALL(SIP) (01:0001:01) SENT Connect to L4 18:07:06.367 CALL(SIP) (01:0001:01) RCVD Cut Thru from VPPL 18:07:06.367 CALL(SIP) (01:0001:01) RCVD Connect from VPPL 18:07:06.367 CALL(L4) (01:0001:01) RCVD Connect from SIP 18:07:06.367 CALL(L4) (01:0001:01) SENT CPE of ANSWER to GCL 18:07:06.367 CALL(GCL) (01:0001:01) RCVD CPE of ANSWER from L4 18:07:06.367 CALL(GCL) (01:0001:01) SENT RADIUS ACCT START 18:07:06.367 CALL(GCL) (01:0001:01) RCVD RADIUS ACCT START 18:07:06.367 CALL(GCL) (01:0001:00) SENT Disconnect Tone to L4 18:07:06.367 CALL(L4) (01:0001:00) RCVD Disconnect Tone from GCL 18:07:06.367 CALL(L4) (01:0001:00) SENT Cancel DSP Service to SYSRM 18:07:06.367 CALL(MED) (01:0001:00) RCVD CANCEL OUTPULSE CP 18:07:06.367 CALL(MED) (01:0001:00) RCVD CANCEL OUTPULSE DIGITS 18:07:06.367 CALL(GCL) (01:0001:00) SENT CPE of ANSWER to L4 18:07:06.367 CALL(L4) (01:0001:00) RCVD CPE of ANSWER from GCL 18:07:06.367 CALL(L4) (01:0001:00) SENT Connect to SIP 18:07:06.367 CALL(L4) (01:0001:01) SENT Connect to SIP 18:07:06.367 CALL(SIP) (01:0001:00) RCVD Connect from L4 18:07:06.367 CALL(SIP) (01:0001:00) SENT 200 OK to 10.129.44.226:5060 UDP 18:07:06.367 CALL(SIP) (01:0001:01) RCVD Connect from L4 18:07:06.437 CALL(SIP) (01:0001:00) RCVD ACK from 10.129.44.226 UDP 18:07:06.437 CALL(SIP) (01:0001:00) with Via sent-by: 10.129.44.226 18:07:07.387 CALL(GCL) (01:0001:00) Releasing Call,Radius MaxCallDuration=1 secs elapsed 18:07:07.387 CALL(GCL) (01:0001:00) SENT Park Port to L4 18:07:07.387 CALL(L4) (01:0001:00) RCVD Park from GCL 18:07:07.387 CALL(GCL) (01:0001:01) SENT Clear Response to L4 18:07:07.387 CALL(GCL) (01:0001:00) SENT Clear Response to L4 18:07:07.387 CALL(L4) (01:0001:01) RCVD Clear from GCL 18:07:07.387 CALL(L4) (01:0001:01) SENT Clear Request to SIP 18:07:07.387 CALL(L4) (01:0001:00) RCVD Clear from GCL 18:07:07.387 CALL(L4) (01:0001:00) SENT Clear Request to SIP 18:07:07.387 CALL(SIP) (01:0001:01) RCVD Clear Request from L4 18:07:07.387 CALL(SIP) (01:0001:01) SENT BYE to 10.129.44.254:5060 UDP 18:07:07.387 CALL(SIP) (01:0001:01) with R-URI: 10.129.44.254:5060 UDP 18:07:07.387 CALL(SIP) (01:0001:00) RCVD Clear Request from L4 18:07:07.387 CALL(SIP) (01:0001:00) SENT BYE to 10.129.44.226:5060 UDP 18:07:07.387 CALL(SIP) (01:0001:00) with R-URI: 10.129.44.226:5060 UDP 18:07:07.437 CALL(SIP) (01:0001:00) RCVD 200 OK from 10.129.44.226:5060 UDP 18:07:07.437 CALL(SIP) (01:0001:00) SENT L3-L3 Clear Req to VPPL 18:07:07.437 CALL(IP) (01:0001:00) RCVD Clear Request from L3 18:07:07.437 CALL(IP) (01:0001:00) RTCP STATS: TxPackets=244; RxPackets=241; lostPackets=0 18:07:07.437 CALL(IP) (01:0001:00) RTCP/T38 Packet STATS: TxPackets=244; RxPackets=241; lostPackets=0 18:07:07.437 CALL(SIP) (01:0001:00) RCVD Clear Req from VPPL 18:07:07.437 CALL(SIP) (01:0001:00) SENT Clear Request to L4 18:07:07.437 CALL(L4) (01:0001:00) RCVD Clear request from SIP 18:07:07.437 CALL(L4) (01:0001:00) SENT Channel Release w/data to GCL 18:07:07.437 CALL(GCL) (01:0001:00) RCVD Channel Released w/data from L4 18:07:07.437 CALL(GCL) (01:0001:00) Call Duration(sec): 1 18:07:07.437 CALL(GCL) (01:0001:00) Radius Call Duration(sec): 1 18:07:07.437 CALL(GCL) (01:0001:00) SENT RADIUS ACCT STOP 18:07:07.557 CALL(SIP) (01:0001:01) RCVD 200 OK from 10.129.44.254:5060 UDP 18:07:07.557 CALL(SIP) (01:0001:01) SENT L3-L3 Clear Req to VPPL 18:07:07.557 CALL(IP) (01:0001:01) RCVD Clear Request from L3 18:07:07.557 CALL(IP) (01:0001:01) RTCP STATS: TxPackets=59; RxPackets=49; lostPackets=0 18:07:07.557 CALL(IP) (01:0001:01) RTCP/T38 Packet STATS: TxPackets=59; RxPackets=49; lostPackets=0 18:07:07.557 CALL(SIP) (01:0001:01) RCVD Clear Req from VPPL 18:07:07.557 CALL(SIP) (01:0001:01) SENT Clear Request to L4 18:07:07.557 CALL(L4) (01:0001:01) RCVD Clear request from SIP 18:07:07.557 CALL(L4) (01:0001:01) SENT Channel Release w/data to GCL 18:07:07.557 CALL(GCL) (01:0001:01) RCVD Channel Released w/data from L4 18:07:07.557 CALL(GCL) (01:0001:01) Call Duration(sec): 1 18:07:07.557 CALL(GCL) (01:0001:01) Radius Call Duration(sec): 1 18:07:07.557 CALL(GCL) (01:0001:01) SENT RADIUS ACCT STOP

Return to Documentation Home I Return to Sangoma Support