[Serusers] How to config SER + RADIUS + GW AS5350

Nguyen Dang Tien dangtien at gmail.com
Thu Jan 13 05:40:51 CET 2005 

Dear all , I have a problem:
My Network Diagram:

IP for SER Server, Radiusclient (the same computer): 203.160.97.232
IP for Radius Server (Radiator): 203.160.97.234
IP for GW SIP to PSTN (Cisco AS5350) 203.160.97.233
IP for IP PHONE (Zyxel 2000W) 203.160.97.236

* Compile Radius client: radiusclient-0.4.8.tar.gz
      ./configure
      Make all
      Make install
* Compile SER
      -- Change Exclude modules in Makefiles: remove "auth_radius",
"group_radius", and "uri_radius" among excluded modules.

      Make proper
      Make all
      Make install
* I have change Radiusclient.conf (in /usr/local/etc/radiusclient)for
Radiusclient :

....
      Authserver        203.160.97.234:1645
      Accserver         203.160.97.234:1646

      Binaddr           203.160.97.232

* Add line in file dictionary
      $INCLUDE          /usr/local/etc/radiusclient/dictionary.ser

* Change file servers
      203.160.97.234          mysecret
add line /usr/local/lib in file /etc/ld.so.conf
ldconfig –v

1:  I use IP phone or SIP soft phone (xten) dial directly to GW as5350
and don't use SIP server + radius, everything is OK
2:  IP phone or SIP client authenicated by radius radiator OK
3:  Make a call by IP phone --->SIP(SER + Radius)---->GW----->PSTN : OK 

But time for call is only 8 second  --  But time for call is only 8 second
 
This is my ser.cfg:
#
# $Id: ser.cfg,v 1.21.4.1 2003/11/10 15:35:15 andrei Exp $
# ----------- global configuration parameters ------------------------

debug=8
fork=yes
log_stderror=yes

check_via=no      # (cmd. line: -v)
dns=no           # (cmd. line: -r)
rev_dns=no      # (cmd. line: -R)
port=5060
#children=4
fifo="/tmp/ser_fifo"

# ------------------ module loading ----------------------------------
loadmodule "/usr/local/lib/ser/modules/sl.so"
loadmodule "/usr/local/lib/ser/modules/tm.so"
loadmodule "/usr/local/lib/ser/modules/rr.so"
loadmodule "/usr/local/lib/ser/modules/maxfwd.so"
loadmodule "/usr/local/lib/ser/modules/usrloc.so"
loadmodule "/usr/local/lib/ser/modules/registrar.so"

loadmodule "/usr/local/lib/ser/modules/auth.so"
loadmodule "/usr/local/lib/ser/modules/auth_radius.so"

# ----------------- setting module-specific parameters ---------------
modparam("usrloc", "db_mode",   0)
modparam("rr", "enable_full_lr", 1)
# -------------------------  request routing logic -------------------

route{

      if (!mf_process_maxfwd_header("10")) {
            sl_send_reply("483","Too Many Hops");
            break;
      };
      if ( msg:len > max_len ) {
            sl_send_reply("513", "Message too big");
            break;
      };

      # authenticate register message using radius
      if (method=="REGISTER") {
            # Uncomment this if you want to use digest authentication
            if (!radius_www_authorize("")) {
                  www_challenge("", "0");
                  break;
            };
            save("location");
            break;
      };

      # route (proxy) invite message to gateway
      if (method=="INVITE") || (method=="CANCEL") {
            # Uncomment this if you want to use digest authentication
            if (!radius_www_authorize("")) {
                  www_challenge("", "0");
                  break;
            };
            
            record_route();
            rewritehostport("203.160.97.233:5060");
            if (!t_relay()) {
                  sl_reply_error();
            };
            break;
      };

}

My Debug session:

[root at SIP-SG01 sbin]# 15(21163) SIP Request:
15(21163)  method:  <INVITE>
15(21163)  uri:     <sip:08117 at 203.160.97.232:5060>
15(21163)  version: <SIP/2.0>
15(21163) parse_headers: flags=1
15(21163) Found param type 232, <branch> = <z9hG4bkbd74baa8656e3b>; state=16
15(21163) end of header reached, state=5
15(21163) parse_headers: Via found, flags=1
15(21163) parse_headers: this is the first via
15(21163) After parse_msg...
15(21163) preparing to run routing scripts...
15(21163) DEBUG : is_maxfwd_present: searching for max_forwards header
15(21163) parse_headers: flags=128
15(21163) end of header reached, state=9
15(21163) DEBUG: get_hdr_field: <To> [39];
uri=[sip:08117 at 203.160.97.232;user=phone]
15(21163) DEBUG: to body [<sip:08117 at 203.160.97.232;user=phone>
]
15(21163) get_hdr_field: cseq <CSeq>: <1> <INVITE>
15(21163) DEBUG: is_maxfwd_present: value = 70 
15(21163) DEBUG: add_param: tag=35A33BE5CA4876B7CB
15(21163) end of header reached, state=29
15(21163) parse_headers: flags=4096
15(21163) DEBUG: get_hdr_body : content_length=201
15(21163) found end of header
15(21163) pre_auth(): Credentials with given realm not found
15(21163) build_auth_hf(): 'WWW-Authenticate: Digest
realm="203.160.97.232",
nonce="425850ba29ebdf74552c5946fe7de2bed452d4d6"
'
15(21163) parse_headers: flags=-1
15(21163) check_via_address(203.160.97.236, 203.160.97.236, 0)
15(21163) DEBUG:destroy_avp_list: destroing list (nil)
15(21163) receive_msg: cleaning up
 9(21157) SIP Request:
 9(21157)  method:  <ACK>
 9(21157)  uri:     <sip:08117 at 203.160.97.232:5060>
 9(21157)  version: <SIP/2.0>
 9(21157) parse_headers: flags=1
 9(21157) Found param type 232, <branch> = <z9hG4bkbd74baa8656e3b>; state=16
 9(21157) end of header reached, state=5
 9(21157) parse_headers: Via found, flags=1
 9(21157) parse_headers: this is the first via
 9(21157) After parse_msg...
 9(21157) preparing to run routing scripts...
 9(21157) parse_headers: flags=4
 9(21157) DEBUG: add_param: tag=b27e1a1d33761e85846fc98f5f3a7e58.35a3
 9(21157) end of header reached, state=29
 9(21157) DEBUG: get_hdr_field: <To> [81];
uri=[sip:08117 at 203.160.97.232;user=phone]
 9(21157) DEBUG: to body [<sip:08117 at 203.160.97.232;user=phone>]
 9(21157) DEBUG: sl_filter_ACK : local ACK found -> dropping it! 
 9(21157) DEBUG:destroy_avp_list: destroing list (nil)
 9(21157) receive_msg: cleaning up
10(21158) SIP Request:
10(21158)  method:  <INVITE>
10(21158)  uri:     <sip:08117 at 203.160.97.232:5060>
10(21158)  version: <SIP/2.0>
10(21158) parse_headers: flags=1
10(21158) Found param type 232, <branch> = <z9hG4bkbd74baa8656e3b>; state=16
10(21158) end of header reached, state=5
10(21158) parse_headers: Via found, flags=1
10(21158) parse_headers: this is the first via
10(21158) After parse_msg...
10(21158) preparing to run routing scripts...
10(21158) DEBUG : is_maxfwd_present: searching for max_forwards header
10(21158) parse_headers: flags=128
10(21158) end of header reached, state=9
10(21158) DEBUG: get_hdr_field: <To> [39];
uri=[sip:08117 at 203.160.97.232;user=phone]
10(21158) DEBUG: to body [<sip:08117 at 203.160.97.232;user=phone>
]
10(21158) get_hdr_field: cseq <CSeq>: <2> <INVITE>
10(21158) DEBUG: is_maxfwd_present: value = 70 
10(21158) DEBUG: add_param: tag=35A33BE5CA4876B7CB
10(21158) end of header reached, state=29
10(21158) check_nonce(): comparing
[425850ba29ebdf74552c5946fe7de2bed452d4d6] and
[425850ba29ebdf74552c5946fe7de2bed452d4d6]
11(21159) udp_rcv_loop: probing packet received from 203.160.97.229 50195
10(21158) radius_authorize_sterman(): Success
10(21158) save_rpid(): rpid value is ''
10(21158) DEBUG: t_addifnew: msg id=1 , global msg id=0 , T on
entrance=0xffffffff
10(21158) parse_headers: flags=-1
10(21158) DEBUG: get_hdr_body : content_length=201
10(21158) found end of header
10(21158) parse_headers: flags=60
10(21158) t_lookup_request: start searching: hash=39311, isACK=0
10(21158) DEBUG: proceeding to pre-RFC3261 transaction matching
10(21158) DEBUG: t_lookup_request: no transaction found
10(21158) SER: new INVITE
10(21158) parse_headers: flags=-1
10(21158) check_via_address(203.160.97.236, 203.160.97.236, 0)
10(21158) WARNING:vqm_resize: resize(0) called
10(21158) DEBUG: reply sent out. buf=0x80db9c0: SIP/2.0 1...,
shmem=0xb5618a78: SIP/2.0 1
10(21158) DEBUG: t_reply: finished
10(21158) DEBUG: mk_proxy: doing DNS lookup...
10(21158) check_via_address(203.160.97.236, 203.160.97.236, 0)
10(21158) DEBUG: add_to_tail_of_timer[4]: 0xb5617328
10(21158) DEBUG: add_to_tail_of_timer[0]: 0xb561733c
10(21158) SER: new transaction fwd'ed
10(21158) DEBUG:destroy_avp_list: destroing list (nil)
10(21158) receive_msg: cleaning up
14(21162) SIP Reply  (status):
14(21162)  version: <SIP/2.0>
14(21162)  status:  <100>
14(21162)  reason:  <Trying>
14(21162) parse_headers: flags=1
14(21162) Found param type 232, <branch> = <z9hG4bKf899.35479332.0>; state=9
14(21162) parse_via: next_via
14(21162) Found param type 232, <branch> = <z9hG4bkbd74baa8656e3b>; state=16
14(21162) end of header reached, state=5
14(21162) parse_headers: Via found, flags=1
14(21162) parse_headers: this is the first via
14(21162) After parse_msg...
14(21162) forward_reply: found module tm, passing reply to it
14(21162) DEBUG: t_check: msg id=1 global id=0 T start=0xffffffff
14(21162) parse_headers: flags=17
14(21162) DEBUG: add_param: tag=E753CD6-1F94
14(21162) end of header reached, state=29
14(21162) DEBUG: get_hdr_field: <To> [56];
uri=[sip:08117 at 203.160.97.232;user=phone]
14(21162) DEBUG: to body [<sip:08117 at 203.160.97.232;user=phone>]
14(21162) get_hdr_field: cseq <CSeq>: <2> <INVITE>
14(21162) parse_headers: flags=4
14(21162) DEBUG: t_reply_matching: hash 39311 label 590967891 branch 0
14(21162) DEBUG: t_reply_matching: reply matched (T=0xb5617200)!
14(21162) DEBUG: t_check: msg id=1 global id=1 T end=0xb5617200
14(21162) DEBUG: t_on_reply: org. status uas=100, uac[0]=0 local=0 is_invite=1)
14(21162) ->>>>>>>>> T_code=100, new_code=100
14(21162) DEBUG: relay_reply: branch=0, save=0, relay=-1
14(21162) DEBUG: add_to_tail_of_timer[1]: 0xb561733c
14(21162) DEBUG:destroy_avp_list: destroing list (nil)
14(21162) receive_msg: cleaning up
18(21166) DEBUG: timer routine:4,tl=0xb5617328 next=(nil)
16(21164) SIP Reply  (status):
16(21164)  version: <SIP/2.0>
16(21164)  status:  <183>
16(21164)  reason:  <Session Progress>
16(21164) parse_headers: flags=1
16(21164) Found param type 232, <branch> = <z9hG4bKf899.35479332.0>; state=9
16(21164) parse_via: next_via
16(21164) Found param type 232, <branch> = <z9hG4bkbd74baa8656e3b>; state=16
16(21164) end of header reached, state=5
16(21164) parse_headers: Via found, flags=1
16(21164) parse_headers: this is the first via
16(21164) After parse_msg...
16(21164) forward_reply: found module tm, passing reply to it
16(21164) DEBUG: t_check: msg id=1 global id=0 T start=0xffffffff
16(21164) parse_headers: flags=17
16(21164) DEBUG: add_param: tag=E753CD6-1F94
16(21164) end of header reached, state=29
16(21164) DEBUG: get_hdr_field: <To> [56];
uri=[sip:08117 at 203.160.97.232;user=phone]
16(21164) DEBUG: to body [<sip:08117 at 203.160.97.232;user=phone>]
16(21164) get_hdr_field: cseq <CSeq>: <2> <INVITE>
16(21164) parse_headers: flags=4
16(21164) DEBUG: t_reply_matching: hash 39311 label 590967891 branch 0
16(21164) DEBUG: t_reply_matching: reply matched (T=0xb5617200)!
16(21164) DEBUG: t_check: msg id=1 global id=1 T end=0xb5617200
16(21164) DEBUG: t_on_reply: org. status uas=100, uac[0]=100 local=0
is_invite=1)
16(21164) ->>>>>>>>> T_code=100, new_code=183
16(21164) DEBUG: relay_reply: branch=0, save=0, relay=0
16(21164)  old size: 889, new size: 832
16(21164) build_res_from_sip_res: copied size: orig:92, new: 35, rest: 797 msg=
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 203.160.97.236:5060;branch=z9hG4bkbd74baa8656e3b
From: Zyxel01<sip:100 at 203.160.97.232;user=phone>;tag=35A33BE5CA4876B7CB
To: <sip:08117 at 203.160.97.232;user=phone>;tag=E753CD6-1F94
Date: Wed, 03 Mar 1993 19:22:44 GMT
Call-ID: 19943-DCB1-72FD-46D3-3FD11DC9E9C6 at 203.160.97.236
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 2 INVITE
Allow-Events: telephone-event
Contact: <sip:08117 at 203.160.97.233:5060>
Record-Route: <sip:08117 at 203.160.97.232;ftag=35A33BE5CA4876B7CB;lr=on>
Content-Disposition: session;handling=required
Content-Type: application/sdp
Content-Length: 208

v=0
o=CiscoSystemsSIP-GW-UserAgent 3705 1351 IN IP4 203.160.97.233
s=SIP Call
c=IN IP4 203.160.97.233
t=0 0
m=audio 17344 RTP/AVP 18
c=IN IP4 203.160.97.233
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no

16(21164) DEBUG: reply relayed. buf=0x80db138: SIP/2.0 1...,
shmem=0xb56191b8: SIP/2.0 1
16(21164) DEBUG: add_to_tail_of_timer[1]: 0xb561733c
16(21164) DEBUG:destroy_avp_list: destroing list (nil)
16(21164) receive_msg: cleaning up
 9(21157) SIP Reply  (status):
 9(21157)  version: <SIP/2.0>
 9(21157)  status:  <200>
 9(21157)  reason:  <OK>
 9(21157) parse_headers: flags=1
 9(21157) Found param type 232, <branch> = <z9hG4bKf899.35479332.0>; state=9
 9(21157) parse_via: next_via
 9(21157) Found param type 232, <branch> = <z9hG4bkbd74baa8656e3b>; state=16
 9(21157) end of header reached, state=5
 9(21157) parse_headers: Via found, flags=1
 9(21157) parse_headers: this is the first via
 9(21157) After parse_msg...
 9(21157) forward_reply: found module tm, passing reply to it
 9(21157) DEBUG: t_check: msg id=4 global id=0 T start=0xffffffff
 9(21157) parse_headers: flags=17
 9(21157) DEBUG: add_param: tag=E753CD6-1F94
 9(21157) end of header reached, state=29
 9(21157) DEBUG: get_hdr_field: <To> [56];
uri=[sip:08117 at 203.160.97.232;user=phone]
 9(21157) DEBUG: to body [<sip:08117 at 203.160.97.232;user=phone>]
 9(21157) get_hdr_field: cseq <CSeq>: <2> <INVITE>
 9(21157) parse_headers: flags=4
 9(21157) DEBUG: t_reply_matching: hash 39311 label 590967891 branch 0
 9(21157) DEBUG: t_reply_matching: reply matched (T=0xb5617200)!
 9(21157) DEBUG: t_check: msg id=4 global id=4 T end=0xb5617200
 9(21157) DEBUG: t_on_reply: org. status uas=183, uac[0]=183 local=0
is_invite=1)
 9(21157) ->>>>>>>>> T_code=183, new_code=200
 9(21157) DEBUG: relay_reply: branch=0, save=0, relay=0
 9(21157)  old size: 915, new size: 858
 9(21157) build_res_from_sip_res: copied size: orig:78, new: 21, rest: 837 msg=
SIP/2.0 200 OK
Via: SIP/2.0/UDP 203.160.97.236:5060;branch=z9hG4bkbd74baa8656e3b
From: Zyxel01<sip:100 at 203.160.97.232;user=phone>;tag=35A33BE5CA4876B7CB
To: <sip:08117 at 203.160.97.232;user=phone>;tag=E753CD6-1F94
Date: Wed, 03 Mar 1993 19:22:44 GMT
Call-ID: 19943-DCB1-72FD-46D3-3FD11DC9E9C6 at 203.160.97.236
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 2 INVITE
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER,
SUBSCRIBE, NOTIFY, INFO
Allow-Events: telephone-event
Contact: <sip:08117 at 203.160.97.233:5060>
Record-Route: <sip:08117 at 203.160.97.232;ftag=35A33BE5CA4876B7CB;lr=on>
Content-Type: application/sdp
Content-Length: 208

v=0
o=CiscoSystemsSIP-GW-UserAgent 3705 1351 IN IP4 203.160.97.233
s=SIP Call
c=IN IP4 203.160.97.233
t=0 0
m=audio 17344 RTP/AVP 18
c=IN IP4 203.160.97.233
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no

 9(21157) DEBUG: reply relayed. buf=0x80db4d0: SIP/2.0 2...,
shmem=0xb56191b8: SIP/2.0 2
 9(21157) DEBUG: cleanup_uacs: RETR/FR timers reset
 9(21157) DEBUG: add_to_tail_of_timer[2]: 0xb5617248
 9(21157) DEBUG:destroy_avp_list: destroing list (nil)
 9(21157) receive_msg: cleaning up
10(21158) SIP Request:
10(21158)  method:  <ACK>
10(21158)  uri:     <sip:08117 at 203.160.97.232>
10(21158)  version: <SIP/2.0>
10(21158) parse_headers: flags=1
10(21158) Found param type 232, <branch> = <z9hG4bkbd74baa8656e3b>; state=16
10(21158) end of header reached, state=5
10(21158) parse_headers: Via found, flags=1
10(21158) parse_headers: this is the first via
10(21158) After parse_msg...
10(21158) preparing to run routing scripts...
10(21158) DEBUG : sl_filter_ACK: to late to be a local ACK!
10(21158) DEBUG : is_maxfwd_present: searching for max_forwards header
10(21158) parse_headers: flags=128
10(21158) DEBUG: add_param: tag=E753CD6-1F94
10(21158) end of header reached, state=29
10(21158) DEBUG: get_hdr_field: <To> [56];
uri=[sip:08117 at 203.160.97.232;user=phone]
10(21158) DEBUG: to body [<sip:08117 at 203.160.97.232;user=phone>]
10(21158) get_hdr_field: cseq <CSeq>: <2> <ACK>
10(21158) DEBUG: get_hdr_body : content_length=0
10(21158) found end of header
10(21158) DEBUG: is_maxfwd_present: max_forwards header not found!
10(21158) DEBUG:destroy_avp_list: destroing list (nil)
10(21158) receive_msg: cleaning up
18(21166) DEBUG: timer routine:1,tl=0xb561733c next=(nil)
12(21160) SIP Reply  (status):
12(21160)  version: <SIP/2.0>
12(21160)  status:  <200>
12(21160)  reason:  <OK>
12(21160) parse_headers: flags=1
12(21160) Found param type 232, <branch> = <z9hG4bKf899.35479332.0>; state=9
12(21160) parse_via: next_via
12(21160) Found param type 232, <branch> = <z9hG4bkbd74baa8656e3b>; state=16
12(21160) end of header reached, state=5
12(21160) parse_headers: Via found, flags=1
12(21160) parse_headers: this is the first via
12(21160) After parse_msg...
12(21160) forward_reply: found module tm, passing reply to it
12(21160) DEBUG: t_check: msg id=1 global id=0 T start=0xffffffff
12(21160) parse_headers: flags=17
12(21160) DEBUG: add_param: tag=E753CD6-1F94
12(21160) end of header reached, state=29
12(21160) DEBUG: get_hdr_field: <To> [56];
uri=[sip:08117 at 203.160.97.232;user=phone]
12(21160) DEBUG: to body [<sip:08117 at 203.160.97.232;user=phone>]
12(21160) get_hdr_field: cseq <CSeq>: <2> <INVITE>
12(21160) parse_headers: flags=4
12(21160) DEBUG: t_reply_matching: hash 39311 label 590967891 branch 0
12(21160) DEBUG: t_reply_matching: reply matched (T=0xb5617200)!
12(21160) DEBUG: t_check: msg id=1 global id=1 T end=0xb5617200
12(21160) DEBUG: t_on_reply: org. status uas=200, uac[0]=200 local=0
is_invite=1)
12(21160) ->>>>>>>>> T_code=200, new_code=200
12(21160) DBG: t_should_relay: 200 INV after final sent
12(21160) DEBUG: relay_reply: branch=0, save=0, relay=0
12(21160)  old size: 915, new size: 858
12(21160) build_res_from_sip_res: copied size: orig:78, new: 21, rest: 837 msg=
SIP/2.0 200 OK
Via: SIP/2.0/UDP 203.160.97.236:5060;branch=z9hG4bkbd74baa8656e3b
From: Zyxel01<sip:100 at 203.160.97.232;user=phone>;tag=35A33BE5CA4876B7CB
To: <sip:08117 at 203.160.97.232;user=phone>;tag=E753CD6-1F94
Date: Wed, 03 Mar 1993 19:22:44 GMT
Call-ID: 19943-DCB1-72FD-46D3-3FD11DC9E9C6 at 203.160.97.236
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 2 INVITE
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER,
SUBSCRIBE, NOTIFY, INFO
Allow-Events: telephone-event
Contact: <sip:08117 at 203.160.97.233:5060>
Record-Route: <sip:08117 at 203.160.97.232;ftag=35A33BE5CA4876B7CB;lr=on>
Content-Type: application/sdp
Content-Length: 208

v=0
o=CiscoSystemsSIP-GW-UserAgent 3705 1351 IN IP4 203.160.97.233
s=SIP Call
c=IN IP4 203.160.97.233
t=0 0
m=audio 17344 RTP/AVP 18
c=IN IP4 203.160.97.233
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no

12(21160) DEBUG: reply relayed. buf=0x80db138: SIP/2.0 2...,
shmem=0xb56191b8: SIP/2.0 2
12(21160) DEBUG:destroy_avp_list: destroing list (nil)
12(21160) receive_msg: cleaning up
14(21162) SIP Reply  (status):
14(21162)  version: <SIP/2.0>
14(21162)  status:  <200>
14(21162)  reason:  <OK>
14(21162) parse_headers: flags=1
14(21162) Found param type 232, <branch> = <z9hG4bKf899.35479332.0>; state=9
14(21162) parse_via: next_via
14(21162) Found param type 232, <branch> = <z9hG4bkbd74baa8656e3b>; state=16
14(21162) end of header reached, state=5
14(21162) parse_headers: Via found, flags=1
14(21162) parse_headers: this is the first via
14(21162) After parse_msg...
14(21162) forward_reply: found module tm, passing reply to it
14(21162) DEBUG: t_check: msg id=2 global id=1 T start=0xffffffff
14(21162) parse_headers: flags=17
14(21162) DEBUG: add_param: tag=E753CD6-1F94
14(21162) end of header reached, state=29
14(21162) DEBUG: get_hdr_field: <To> [56];
uri=[sip:08117 at 203.160.97.232;user=phone]
14(21162) DEBUG: to body [<sip:08117 at 203.160.97.232;user=phone>]
14(21162) get_hdr_field: cseq <CSeq>: <2> <INVITE>
14(21162) parse_headers: flags=4
14(21162) DEBUG: t_reply_matching: hash 39311 label 590967891 branch 0
14(21162) DEBUG: t_reply_matching: reply matched (T=0xb5617200)!
14(21162) DEBUG: t_check: msg id=2 global id=2 T end=0xb5617200
14(21162) DEBUG: t_on_reply: org. status uas=200, uac[0]=200 local=0
is_invite=1)
14(21162) ->>>>>>>>> T_code=200, new_code=200
14(21162) DBG: t_should_relay: 200 INV after final sent
14(21162) DEBUG: relay_reply: branch=0, save=0, relay=0
14(21162)  old size: 915, new size: 858
14(21162) build_res_from_sip_res: copied size: orig:78, new: 21, rest: 837 msg=
SIP/2.0 200 OK
Via: SIP/2.0/UDP 203.160.97.236:5060;branch=z9hG4bkbd74baa8656e3b
From: Zyxel01<sip:100 at 203.160.97.232;user=phone>;tag=35A33BE5CA4876B7CB
To: <sip:08117 at 203.160.97.232;user=phone>;tag=E753CD6-1F94
Date: Wed, 03 Mar 1993 19:22:44 GMT
Call-ID: 19943-DCB1-72FD-46D3-3FD11DC9E9C6 at 203.160.97.236
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 2 INVITE
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER,
SUBSCRIBE, NOTIFY, INFO
Allow-Events: telephone-event
Contact: <sip:08117 at 203.160.97.233:5060>
Record-Route: <sip:08117 at 203.160.97.232;ftag=35A33BE5CA4876B7CB;lr=on>
Content-Type: application/sdp
Content-Length: 208

v=0
o=CiscoSystemsSIP-GW-UserAgent 3705 1351 IN IP4 203.160.97.233
s=SIP Call
c=IN IP4 203.160.97.233
t=0 0
m=audio 17344 RTP/AVP 18
c=IN IP4 203.160.97.233
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no

14(21162) DEBUG: reply relayed. buf=0x80db138: SIP/2.0 2...,
shmem=0xb56191b8: SIP/2.0 2
14(21162) DEBUG:destroy_avp_list: destroing list (nil)
14(21162) receive_msg: cleaning up
15(21163) SIP Reply  (status):
15(21163)  version: <SIP/2.0>
15(21163)  status:  <200>
15(21163)  reason:  <OK>
15(21163) parse_headers: flags=1
15(21163) Found param type 232, <branch> = <z9hG4bKf899.35479332.0>; state=9
15(21163) parse_via: next_via
15(21163) Found param type 232, <branch> = <z9hG4bkbd74baa8656e3b>; state=16
15(21163) end of header reached, state=5
15(21163) parse_headers: Via found, flags=1
15(21163) parse_headers: this is the first via
15(21163) After parse_msg...
15(21163) forward_reply: found module tm, passing reply to it
15(21163) DEBUG: t_check: msg id=2 global id=0 T start=0xffffffff
15(21163) parse_headers: flags=17
15(21163) DEBUG: add_param: tag=E753CD6-1F94
15(21163) end of header reached, state=29
15(21163) DEBUG: get_hdr_field: <To> [56];
uri=[sip:08117 at 203.160.97.232;user=phone]
15(21163) DEBUG: to body [<sip:08117 at 203.160.97.232;user=phone>]
15(21163) get_hdr_field: cseq <CSeq>: <2> <INVITE>
15(21163) parse_headers: flags=4
15(21163) DEBUG: t_reply_matching: hash 39311 label 590967891 branch 0
15(21163) DEBUG: t_reply_matching: reply matched (T=0xb5617200)!
15(21163) DEBUG: t_check: msg id=2 global id=2 T end=0xb5617200
15(21163) DEBUG: t_on_reply: org. status uas=200, uac[0]=200 local=0
is_invite=1)
15(21163) ->>>>>>>>> T_code=200, new_code=200
15(21163) DBG: t_should_relay: 200 INV after final sent
15(21163) DEBUG: relay_reply: branch=0, save=0, relay=0
15(21163)  old size: 915, new size: 858
15(21163) build_res_from_sip_res: copied size: orig:78, new: 21, rest: 837 msg=
SIP/2.0 200 OK
Via: SIP/2.0/UDP 203.160.97.236:5060;branch=z9hG4bkbd74baa8656e3b
From: Zyxel01<sip:100 at 203.160.97.232;user=phone>;tag=35A33BE5CA4876B7CB
To: <sip:08117 at 203.160.97.232;user=phone>;tag=E753CD6-1F94
Date: Wed, 03 Mar 1993 19:22:44 GMT
Call-ID: 19943-DCB1-72FD-46D3-3FD11DC9E9C6 at 203.160.97.236
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 2 INVITE
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER,
SUBSCRIBE, NOTIFY, INFO
Allow-Events: telephone-event
Contact: <sip:08117 at 203.160.97.233:5060>
Record-Route: <sip:08117 at 203.160.97.232;ftag=35A33BE5CA4876B7CB;lr=on>
Content-Type: application/sdp
Content-Length: 208

v=0
o=CiscoSystemsSIP-GW-UserAgent 3705 1351 IN IP4 203.160.97.233
s=SIP Call
c=IN IP4 203.160.97.233
t=0 0
m=audio 17344 RTP/AVP 18
c=IN IP4 203.160.97.233
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no

15(21163) DEBUG: reply relayed. buf=0x80db510: SIP/2.0 2...,
shmem=0xb56191b8: SIP/2.0 2
15(21163) DEBUG:destroy_avp_list: destroing list (nil)
15(21163) receive_msg: cleaning up
18(21166) DEBUG: timer routine:2,tl=0xb5617248 next=(nil)
18(21166) DEBUG: wait_handler : removing 0xb5617200 from table 
18(21166) DEBUG: delete transaction 0xb5617200
18(21166) DEBUG: wait_handler : done

More information about the sr-users mailing list