Hi ..i'm trying to send INVITE message between alice and bob.
and i want to call control using b2bua_radius.py (sippy http://b2bua.org/wiki/B2BUADocumentation) i.e i want call will
disconnect after 30 second and radius will generate Start/Stop
.
i did all the setup and also i don't want to redius server
Authentication/Authorization because
the my sip proxy i.e open-ims already did
Authentication/Authorization  sing Md5-digest
so i want bypass. so i'm using following command
i'm not able to set -s flag for static routing and what will be value for this like

b2bua_radius.py ... -s '200110508667@b2bua.org;cli=16046288900;rid=-1;expires=30;np_expires=5;ash=Name%3AValue' .
or where radius get for this routing for dynamic routing like this
h323-ivr-in = 'Routing:200110508667@b2bua.org;cli=16046288900;rid=-1;expires=30;np_expires=5;ash=Name%3AValue'



[root@ngpchn sippy]# python b2bua_radius.py -fDU -l 172.18.3.52 -p 5065
-a 172.18.3.52 -k 3 -A 2 -m 30

i'm not pretty much sure about flag -s and how i will.

after successfully running all these component i found
when I INVITE for alice to bob
i found SIP/2.0 500 Internal Server Error (2)
then call able to connect but call not able to disconnect after 30
second e.i my requirement
another problem is when i hang up call after 30(manually ) only one
side call is dissconnet
and another side is continue .. i'm not able rectify the problem.

can any help me regarding resolve this problem also what i doing
roungh specially senting with
-s.

i'm attaching log file also....
waiting for positive responce..


Thanks
~Suresh


[root@ngpchn sippy]# python b2bua_radius.py -fD -l 172.18.3.52 -p 5065
-a 172.18.3.52 -k 3 -A 2 -m 30
14 Jan 16:03:13/GLOBAL/b2bua: RECEIVED message from 172.18.3.52:6060:
INVITE sip:bob@info-spectrum.com SIP/2.0
Record-Route: <sip:mo@scscf.info-spectrum.
com:6060;lr>
Route: <sip:172.18.3.52:5065;lr>,
<sip:iscmark@scscf.info-spectrum.com:6060;lr;s=1;h=0;d=0;a=7369703a616c69636540696e666f2d737065637472756d2e636f6d>
Record-Route: <sip:mo@pcscf.info-spectrum.com:4060;lr>
Via: SIP/2.0/UDP 172.18.3.52:6060;branch=z9hG4bKa35e.8904bbf3.0
Via: SIP/2.0/UDP 172.18.3.52:4060;branch=z9hG4bKa35e.28aed705.0
Via: SIP/2.0/UDP 172.18.1.197:1976;rport=1976;branch=z9hG4bK7376
From: <sip:alice@info-spectrum.com>;tag=11382
To: <sip:bob@info-spectrum.com>
Call-ID: 1231924661763@172.18.1.197
CSeq: 901 INVITE
Max-Forwards: 15
Allow: INVITE,ACK,CANCEL,BYE,MESSAGE,OPTIONS,UPDATE,NOTIFY,PRACK
Contact: <sip:alice@172.18.1.197:1976;transport=udp>;expires=1000
User-Agent: Mercuro IMS Client Beta (4.0.1011.0)
Supported: sdp-anat
Content-Type: application/sdp
P-Access-Network-Info: 3GPP-UTRAN-TDD;utran-cell-id-3gpp=00000000
Privacy: none
Content-Length: 231
P-Asserted-Identity: <sip:alice@info-spectrum.com>
P-Charging-Vector:
icid-value="P-CSCFabcd496dbf6900000026";icid-generated-at=172.18.3.52;orig-ioi="info-spectrum.com"
Timestamp: 1231929193

v=0
o=- 3344 3344 IN IP4 172.18.1.197
s=Mercuro IMS Client Session
t=0 0
m=audio 31562 RTP/AVP 0 8 101
c=IN IP4 172.18.1.197
a=rtpmap:0 PCMU/8000/1
a=rtpmap:8 PCMA/8000/1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

14 Jan 16:03:13/GLOBAL/b2bua: SENDING message to 172.18.3.52:6060:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 172.18.3.52:6060;branch=z9hG4bKa35e.8904bbf3.0
Via: SIP/2.0/UDP 172.18.3.52:4060;branch=z9hG4bKa35e.28aed705.0
Via: SIP/2.0/UDP 172.18.1.197:1976;rport=1976;branch=z9hG4bK7376
Record-Route: <sip:mo@scscf.info-spectrum.com:6060;lr>
Record-Route: <sip:mo@pcscf.info-spectrum.com:4060;lr>
From: <sip:alice@info-spectrum.com>;tag=11382
To: <sip:bob@info-spectrum.com>
Call-ID: 1231924661763@172.18.1.197
CSeq: 901 INVITE
Server: Sippy B2BUA (RADIUS)


14 Jan 16:03:13/1231924661763@172.18.1.197/b2bua: sending AAA request:
User-Name                        = '172.18.3.52'
Password                         = 'cisco'
Calling-Station-Id               = 'alice'
Called-Station-Id                = 'bob'
h323-conf-id                     = 'B8377C92 DF6642BB 25A7C3A3 D4E529CE'
call-id                          = '1231924661763@172.18.1.197'
h323-remote-address              = '172.18.3.52'
h323-session-protocol            = 'sipv2'

14 Jan 16:03:13/1231924661763@172.18.1.197/b2bua: AAA request accepted
(delay is 0.030), processing response:

14 Jan 16:03:13/GLOBAL/b2bua: SENDING message to 172.18.3.52:6060:
SIP/2.0 500 Internal Server Error (2)
Via: SIP/2.0/UDP 172.18.3.52:6060;branch=z9hG4bKa35e.8904bbf3.0
Via: SIP/2.0/UDP 172.18.3.52:4060;branch=z9hG4bKa35e.28aed705.0
Via: SIP/2.0/UDP 172.18.1.197:1976;rport=1976;branch=z9hG4bK7376
Record-Route: <sip:mo@scscf.info-spectrum.com:6060;lr>
Record-Route: <sip:mo@pcscf.info-spectrum.com:4060;lr>
From: <sip:alice@info-spectrum.com>;tag=11382
To: <sip:bob@info-spectrum.com>;tag=d63760e38a4879ada2cf7f5c137369ac
Call-ID: 1231924661763@172.18.1.197
CSeq: 901 INVITE
Server: Sippy B2BUA (RADIUS)


14 Jan 16:03:13/1231924661763@172.18.1.197/b2bua: sending Acct Stop (Answer):
h323-call-origin                 = 'answer'
h323-call-type                   = 'VoIP'
h323-session-protocol            = 'sipv2'
h323-setup-time                  = '10:33:13.000 GMT Wed Jan 14 2009'
User-Name                        = '172.18.3.52'
Calling-Station-Id               = 'alice'
Called-Station-Id                = 'bob'
h323-conf-id                     = 'B8377C92 DF6642BB 25A7C3A3 D4E529CE'
call-id                          = '1231924661763@172.18.1.197'
Acct-Session-Id                  = '1231924661763@172.18.1.197'
h323-remote-address              = '172.18.3.52'
h323-disconnect-time             = '10:33:13.000 GMT Wed Jan 14 2009'
h323-connect-time                = '10:33:13.000 GMT Wed Jan 14 2009'
Acct-Session-Time                = '0'
h323-disconnect-cause            = '29'
Acct-Status-Type                 = 'Stop'

14 Jan 16:03:13/GLOBAL/b2bua: RECEIVED message from 172.18.3.52:6060:
ACK sip:bob@info-spectrum.com SIP/2.0
Via: SIP/2.0/UDP 172.18.3.52:6060;branch=z9hG4bKa35e.8904bbf3.0
From: <sip:alice@info-spectrum.com>;tag=11382
Call-ID: 1231924661763@172.18.1.197
To: <sip:bob@info-spectrum.com>;tag=d63760e38a4879ada2cf7f5c137369ac
CSeq: 901 ACK
Route: <sip:orig@scscf.info-spectrum.com:6060;lr>
User-Agent: InfoIMS(1.0.0-dev1 InfoIMS (i386/linux))
Content-Length: 0


14 Jan 16:03:13/GLOBAL/b2bua: RECEIVED message from 172.18.3.52:6060:
INVITE sip:bob@info-spectrum.com SIP/2.0
Record-Route: <sip:mt@scscf.info-spectrum.com:6060;lr>
Route: <sip:172.18.3.52:5065;lr>,
<sip:iscmark@scscf.info-spectrum.com:6060;lr;s=1;h=0;d=1;a=7369703a626f6240696e666f2d737065637472756d2e636f6d>
Record-Route: <sip:mo@scscf.info-spectrum.com:6060;lr>
Record-Route: <sip:mo@pcscf.info-spectrum.com:4060;lr>
Via: SIP/2.0/UDP 172.18.3.52:6060;branch=z9hG4bKa35e.9904bbf3.0
Via: SIP/2.0/UDP 172.18.3.52:6060;branch=z9hG4bKa35e.8904bbf3.1
Via: SIP/2.0/UDP 172.18.3.52:4060;branch=z9hG4bKa35e.28aed705.0
Via: SIP/2.0/UDP 172.18.1.197:1976;rport=1976;branch=z9hG4bK7376
From: <sip:alice@info-spectrum.com>;tag=11382
To: <sip:bob@info-spectrum.com>
Call-ID: 1231924661763@172.18.1.197
CSeq: 901 INVITE
Max-Forwards: 14
Allow: INVITE,ACK,CANCEL,BYE,MESSAGE,OPTIONS,UPDATE,NOTIFY,PRACK
Contact: <sip:alice@172.18.1.197:1976;transport=udp>;expires=1000
User-Agent: Mercuro IMS Client Beta (4.0.1011.0)
Supported: sdp-anat
Content-Type: application/sdp
P-Access-Network-Info: 3GPP-UTRAN-TDD;utran-cell-id-3gpp=00000000
Privacy: none
Content-Length: 231
P-Asserted-Identity: <sip:alice@info-spectrum.com>
P-Charging-Vector:
icid-value="P-CSCFabcd496dbf6900000026";icid-generated-at=172.18.3.52;orig-ioi="info-spectrum.com"
Timestamp: 1231929193

v=0
o=- 3344 3344 IN IP4 172.18.1.197
s=Mercuro IMS Client Session
t=0 0
m=audio 31562 RTP/AVP 0 8 101
c=IN IP4 172.18.1.197
a=rtpmap:0 PCMU/8000/1
a=rtpmap:8 PCMA/8000/1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

14 Jan 16:03:13/GLOBAL/b2bua: SENDING message to 172.18.3.52:6060:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 172.18.3.52:6060;branch=z9hG4bKa35e.9904bbf3.0
Via: SIP/2.0/UDP 172.18.3.52:6060;branch=z9hG4bKa35e.8904bbf3.1
Via: SIP/2.0/UDP 172.18.3.52:4060;branch=z9hG4bKa35e.28aed705.0
Via: SIP/2.0/UDP 172.18.1.197:1976;rport=1976;branch=z9hG4bK7376
Record-Route: <sip:mt@scscf.info-spectrum.com:6060;lr>
Record-Route: <sip:mo@scscf.info-spectrum.com:6060;lr>
Record-Route: <sip:mo@pcscf.info-spectrum.com:4060;lr>
From: <sip:alice@info-spectrum.com>;tag=11382
To: <sip:bob@info-spectrum.com>
Call-ID: 1231924661763@172.18.1.197
CSeq: 901 INVITE
Server: Sippy B2BUA (RADIUS)


14 Jan 16:03:13/1231924661763@172.18.1.197/b2bua: sending AAA request:
User-Name                        = '172.18.3.52'
Password                         = 'cisco'
Calling-Station-Id               = 'alice'
Called-Station-Id                = 'bob'
h323-conf-id                     = '7EAC4F20 651D23F0 75C1FFA6 E6EE4BFA'
call-id                          = '1231924661763@172.18.1.197'
h323-remote-address              = '172.18.3.52'
h323-session-protocol            = 'sipv2'

14 Jan 16:03:13/1231924661763@172.18.1.197/b2bua: AAA request accepted
(delay is 0.025), processing response:

14 Jan 16:03:13/GLOBAL/b2bua: SENDING message to 172.18.3.52:6060:
SIP/2.0 500 Internal Server Error (2)
Via: SIP/2.0/UDP 172.18.3.52:6060;branch=z9hG4bKa35e.9904bbf3.0
Via: SIP/2.0/UDP 172.18.3.52:6060;branch=z9hG4bKa35e.8904bbf3.1
Via: SIP/2.0/UDP 172.18.3.52:4060;branch=z9hG4bKa35e.28aed705.0
Via: SIP/2.0/UDP 172.18.1.197:1976;rport=1976;branch=z9hG4bK7376
Record-Route: <sip:mt@scscf.info-spectrum.com:6060;lr>
Record-Route: <sip:mo@scscf.info-spectrum.com:6060;lr>
Record-Route: <sip:mo@pcscf.info-spectrum.com:4060;lr>
From: <sip:alice@info-spectrum.com>;tag=11382
To: <sip:bob@info-spectrum.com>;tag=4d778ee83fef7504d306e1345f51b4dd
Call-ID: 1231924661763@172.18.1.197
CSeq: 901 INVITE
Server: Sippy B2BUA (RADIUS)


14 Jan 16:03:13/1231924661763@172.18.1.197/b2bua: sending Acct Stop (Answer):
h323-call-origin                 = 'answer'
h323-call-type                   = 'VoIP'
h323-session-protocol            = 'sipv2'
h323-setup-time                  = '10:33:13.000 GMT Wed Jan 14 2009'
User-Name                        = '172.18.3.52'
Calling-Station-Id               = 'alice'
Called-Station-Id                = 'bob'
h323-conf-id                     = '7EAC4F20 651D23F0 75C1FFA6 E6EE4BFA'
call-id                          = '1231924661763@172.18.1.197'
Acct-Session-Id                  = '1231924661763@172.18.1.197'
h323-remote-address              = '172.18.3.52'
h323-disconnect-time             = '10:33:13.000 GMT Wed Jan 14 2009'
h323-connect-time                = '10:33:13.000 GMT Wed Jan 14 2009'
Acct-Session-Time                = '0'
h323-disconnect-cause            = '29'
Acct-Status-Type                 = 'Stop'

14 Jan 16:03:13/GLOBAL/b2bua: RECEIVED message from 172.18.3.52:6060:
ACK sip:bob@info-spectrum.com SIP/2.0
Via: SIP/2.0/UDP 172.18.3.52:6060;branch=z9hG4bKa35e.9904bbf3.0
From: <sip:alice@info-spectrum.com>;tag=11382
Call-ID: 1231924661763@172.18.1.197
To: <sip:bob@info-spectrum.com>;tag=4d778ee83fef7504d306e1345f51b4dd
CSeq: 901 ACK
User-Agent: InfoIMS(1.0.0-dev1 InfoIMS (i386/linux))
Content-Length: 0


14 Jan 16:03:13/1231924661763@172.18.1.197/b2bua: Acct/answer request
accepted (delay is 0.083)

14 Jan 16:03:13/1231924661763@172.18.1.197/b2bua: Acct/answer request
accepted (delay is 0.071)

GC is invoked, 0 calls in map
0 client, 0 server transactions in memory



--------------------------------------------------------------------------------------




[root@ngpchn raddb]# radiusd -X
FreeRADIUS Version 2.1.3, for host i686-pc-linux-gnu, built on Jan 13
2009 at 11:56:22
Copyright (C) 1999-2008 The FreeRADIUS server project and contributors.
There is NO warranty; not even for MERCHANTABILITY or FITNESS FOR A
PARTICULAR PURPOSE.
You may redistribute copies of FreeRADIUS under the terms of the
GNU General Public License v2.
Starting - reading configuration files ...
including configuration file /etc/raddb/radiusd.conf
including configuration file /etc/raddb/proxy.conf
including configuration file /etc/raddb/clients.conf
including files in directory /etc/raddb/modules/
including configuration file /etc/raddb/modules/mac2vlan
including configuration file /etc/raddb/modules/counter
including configuration file /etc/raddb/modules/passwd
including configuration file /etc/raddb/modules/mschap
including configuration file /etc/raddb/modules/ldap
including configuration file /etc/raddb/modules/wimax
including configuration file /etc/raddb/modules/sradutmp
including configuration file /etc/raddb/modules/etc_group
including configuration file /etc/raddb/modules/unix
including configuration file /etc/raddb/modules/radutmp
including configuration file /etc/raddb/modules/digest
including configuration file /etc/raddb/modules/exec
including configuration file /etc/raddb/modules/policy
including configuration file /etc/raddb/modules/detail.example.com
including configuration file /etc/raddb/modules/detail.log
including configuration file /etc/raddb/modules/perl
including configuration file /etc/raddb/modules/mac2ip
including configuration file /etc/raddb/modules/ippool
including configuration file /etc/raddb/modules/inner-eap
including configuration file /etc/raddb/modules/acct_unique
including configuration file /etc/raddb/modules/sqlcounter_expire_on_login
including configuration file /etc/raddb/modules/pap
including configuration file /etc/raddb/modules/smbpasswd
including configuration file /etc/raddb/modules/expr
including configuration file /etc/raddb/modules/linelog
including configuration file /etc/raddb/modules/expiration
including configuration file /etc/raddb/modules/files
including configuration file /etc/raddb/modules/attr_rewrite
including configuration file /etc/raddb/modules/logintime
including configuration file /etc/raddb/modules/echo
including configuration file /etc/raddb/modules/always
including configuration file /etc/raddb/modules/chap
including configuration file /etc/raddb/modules/realm
including configuration file /etc/raddb/modules/preprocess
including configuration file /etc/raddb/modules/pam
including configuration file /etc/raddb/modules/krb5
including configuration file /etc/raddb/modules/detail
including configuration file /etc/raddb/modules/checkval
including configuration file /etc/raddb/modules/attr_filter
including configuration file /etc/raddb/modules/sql_log
including configuration file /etc/raddb/eap.conf
including configuration file /etc/raddb/sql.conf
including configuration file /etc/raddb/sql/mysql/dialup.conf
including configuration file /etc/raddb/sql/mysql/counter.conf
including configuration file /etc/raddb/policy.conf
including files in directory /etc/raddb/sites-enabled/
including configuration file /etc/raddb/sites-enabled/default
including configuration file /etc/raddb/sites-enabled/inner-tunnel
including dictionary file /etc/raddb/dictionary
main {
       prefix = "/usr/local"
       localstatedir = "/var"
       logdir = "/var/log/radius"
       libdir = "/usr/local/lib"
       radacctdir = "/var/log/radius/radacct"
       hostname_lookups = no
       max_request_time = 30
       cleanup_delay = 5
       max_requests = 1024
       allow_core_dumps = no
       pidfile = "/var/run/radiusd/radiusd.pid"
       checkrad = "/usr/local/sbin/checkrad"
       debug_level = 0
       proxy_requests = yes
 log {
       stripped_names = no
       auth = no
       auth_badpass = no
       auth_goodpass = no
 }
 security {
       max_attributes = 200
       reject_delay = 1
       status_server = yes
 }
}
 client 172.18.3.52 {
       ipaddr = 172.18.3.52
       require_message_authenticator = no
       secret = "testing123"
       shortname = "local"
       nastype = "portslave"
       login = "!root"
       password = "nopassword"
 }
 client 172.18.1.197 {
       ipaddr = 172.18.1.197
       require_message_authenticator = no
       secret = "testing123"
       shortname = "NTRadPing"
       nastype = "portslave"
       login = "!root"
       password = "nopassword"
 }
radiusd: #### Loading Realms and Home Servers ####
 proxy server {
       retry_delay = 5
       retry_count = 3
       default_fallback = no
       dead_time = 120
       wake_all_if_all_dead = no
 }
 home_server localhost {
       ipaddr = 127.0.0.1
       port = 1812
       type = "auth"
       secret = "testing123"
       response_window = 20
       max_outstanding = 65536
       zombie_period = 40
       status_check = "status-server"
       ping_interval = 30
       check_interval = 30
       num_answers_to_alive = 3
       num_pings_to_alive = 3
       revive_interval = 120
       status_check_timeout = 4
 }
 home_server_pool my_auth_failover {
       type = fail-over
       home_server = localhost
 }
 realm example.com {
       auth_pool = my_auth_failover
 }
 realm info-spectrum.com {
       nostrip
       authhost = LOCAL
       accthost = LOCAL
 }
 realm LOCAL {
 }
radiusd: #### Instantiating modules ####
 instantiate {
 Module: Linked to module rlm_exec
 Module: Instantiating exec
 exec {
       wait = no
       input_pairs = "request"
       shell_escape = yes
 }
 Module: Linked to module rlm_expr
 Module: Instantiating expr
 Module: Linked to module rlm_expiration
 Module: Instantiating expiration
 expiration {
       reply-message = "Password Has Expired  "
 }
 Module: Linked to module rlm_logintime
 Module: Instantiating logintime
 logintime {
       reply-message = "You are calling outside your allowed timespan  "
       minimum-timeout = 60
 }
 }
radiusd: #### Loading Virtual Servers ####
server inner-tunnel {
 modules {
 Module: Checking authenticate {...} for more modules to load
 Module: Linked to module rlm_pap
 Module: Instantiating pap
 pap {
       encryption_scheme = "auto"
       auto_header = no
 }
 Module: Linked to module rlm_chap
 Module: Instantiating chap
 Module: Linked to module rlm_mschap
 Module: Instantiating mschap
 mschap {
       use_mppe = yes
       require_encryption = no
       require_strong = no
       with_ntdomain_hack = no
 }
 Module: Linked to module rlm_unix
 Module: Instantiating unix
 unix {
       radwtmp = "/var/log/radius/radwtmp"
 }
 Module: Linked to module rlm_eap
 Module: Instantiating eap
 eap {
       default_eap_type = "md5"
       timer_expire = 60
       ignore_unknown_eap_types = no
       cisco_accounting_username_bug = no
       max_sessions = 2048
 }
 Module: Linked to sub-module rlm_eap_md5
 Module: Instantiating eap-md5
 Module: Linked to sub-module rlm_eap_leap
 Module: Instantiating eap-leap
 Module: Linked to sub-module rlm_eap_gtc
 Module: Instantiating eap-gtc
  gtc {
       challenge = "Password: "
       auth_type = "PAP"
  }
 Module: Linked to sub-module rlm_eap_tls
 Module: Instantiating eap-tls
  tls {
       rsa_key_exchange = no
       dh_key_exchange = yes
       rsa_key_length = 512
       dh_key_length = 512
       verify_depth = 0
       pem_file_type = yes
       private_key_file = "/etc/raddb/certs/server.pem"
       certificate_file = "/etc/raddb/certs/server.pem"
       CA_file = "/etc/raddb/certs/ca.pem"
       private_key_password = "whatever"
       dh_file = "/etc/raddb/certs/dh"
       random_file = "/etc/raddb/certs/random"
       fragment_size = 1024
       include_length = yes
       check_crl = no
       cipher_list = "DEFAULT"
       make_cert_command = "/etc/raddb/certs/bootstrap"
   cache {
       enable = no
       lifetime = 24
       max_entries = 255
   }
  }
 Module: Linked to sub-module rlm_eap_ttls
 Module: Instantiating eap-ttls
  ttls {
       default_eap_type = "md5"
       copy_request_to_tunnel = no
       use_tunneled_reply = no
       virtual_server = "inner-tunnel"
  }
 Module: Linked to sub-module rlm_eap_peap
 Module: Instantiating eap-peap
  peap {
       default_eap_type = "mschapv2"
       copy_request_to_tunnel = no
       use_tunneled_reply = no
       proxy_tunneled_request_as_eap = yes
       virtual_server = "inner-tunnel"
  }
 Module: Linked to sub-module rlm_eap_mschapv2
 Module: Instantiating eap-mschapv2
  mschapv2 {
       with_ntdomain_hack = no
  }
 Module: Checking authorize {...} for more modules to load
 Module: Linked to module rlm_realm
 Module: Instantiating suffix
 realm suffix {
       format = "suffix"
       delimiter = "@"
       ignore_default = no
       ignore_null = no
 }
 Module: Linked to module rlm_files
 Module: Instantiating files
 files {
       usersfile = "/etc/raddb/users"
       acctusersfile = "/etc/raddb/acct_users"
       preproxy_usersfile = "/etc/raddb/preproxy_users"
       compat = "no"
 }
 Module: Checking session {...} for more modules to load
 Module: Linked to module rlm_radutmp
 Module: Instantiating radutmp
 radutmp {
       filename = "/var/log/radius/radutmp"
       username = "%{User-Name}"
       case_sensitive = yes
       check_with_nas = yes
       perm = 384
       callerid = yes
 }
 Module: Checking post-proxy {...} for more modules to load
 Module: Checking post-auth {...} for more modules to load
 Module: Linked to module rlm_attr_filter
 Module: Instantiating attr_filter.access_reject
 attr_filter attr_filter.access_reject {
       attrsfile = "/etc/raddb/attrs.access_reject"
       key = "%{User-Name}"
 }
 }
}
 modules {
 Module: Checking authenticate {...} for more modules to load
 Module: Checking authorize {...} for more modules to load
 Module: Linked to module rlm_preprocess
 Module: Instantiating preprocess
 preprocess {
       huntgroups = "/etc/raddb/huntgroups"
       hints = "/etc/raddb/hints"
       with_ascend_hack = no
       ascend_channels_per_line = 23
       with_ntdomain_hack = no
       with_specialix_jetstream_hack = no
       with_cisco_vsa_hack = no
       with_alvarion_vsa_hack = no
 }
 Module: Checking preacct {...} for more modules to load
 Module: Linked to module rlm_acct_unique
 Module: Instantiating acct_unique
 acct_unique {
       key = "User-Name, Acct-Session-Id, NAS-IP-Address,
Client-IP-Address, NAS-Port"
 }
 Module: Checking accounting {...} for more modules to load
 Module: Linked to module rlm_detail
 Module: Instantiating detail
 detail {
       detailfile =
"/var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d"
       header = "%t"
       detailperm = 384
       dirperm = 493
       locking = no
       log_packet_header = no
 }
 Module: Instantiating attr_filter.accounting_response
 attr_filter attr_filter.accounting_response {
       attrsfile = "/etc/raddb/attrs.accounting_response"
       key = "%{User-Name}"
 }
 Module: Checking session {...} for more modules to load
 Module: Checking post-proxy {...} for more modules to load
 Module: Checking post-auth {...} for more modules to load
 }
radiusd: #### Opening IP addresses and Ports ####
listen {
       type = "auth"
       ipaddr = 172.18.3.52
       port = 1645
}
listen {
       type = "acct"
       ipaddr = 172.18.3.52
       port = 1646
}
Listening on authentication address 172.18.3.52 port 1645
Listening on accounting address 172.18.3.52 port 1646
Listening on proxy address 172.18.3.52 port 1647
Ready to process requests.
rad_recv: Access-Request packet from host 172.18.3.52 port 32957,
id=237, length=247
       User-Name = "172.18.3.52"
       User-Password = "cisco"
       Calling-Station-Id = "alice"
       Called-Station-Id = "bob"
       h323-conf-id = "h323-conf-id=B8377C92 DF6642BB 25A7C3A3 D4E529CE"
       Cisco-AVPair = "call-id=1231924661763@172.18.1.197"
       h323-remote-address = "h323-remote-address=172.18.3.52"
       Cisco-AVPair = "h323-session-protocol=sipv2"
       NAS-Port = 5060
       NAS-IP-Address = 172.18.3.52
+- entering group authorize {...}
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "172.18.3.52", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] No EAP-Message, not doing EAP
++[eap] returns noop
++[unix] returns notfound
[files] users: Matched entry 172.18.3.52 at line 92
++[files] returns ok
++[expiration] returns noop
++[logintime] returns noop
++[pap] returns updated
Found Auth-Type = PAP
+- entering group PAP {...}
[pap] login attempt with password "cisco"
[pap] Using clear text password "cisco"
[pap] User authenticated successfully
++[pap] returns ok
+- entering group post-auth {...}
++[exec] returns noop
Sending Access-Accept of id 237 to 172.18.3.52 port 32957
Finished request 0.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Accounting-Request packet from host 172.18.3.52 port 32958,
id=238, length=540
       h323-call-origin = "h323-call-origin=answer"
       h323-call-type = "h323-call-type=VoIP"
       Cisco-AVPair = "h323-session-protocol=sipv2"
       h323-setup-time = "h323-setup-time=10:33:13.000 GMT Wed Jan 14 2009"
       User-Name = "172.18.3.52"
       Calling-Station-Id = "alice"
       Called-Station-Id = "bob"
       h323-conf-id = "h323-conf-id=B8377C92 DF6642BB 25A7C3A3 D4E529CE"
       Cisco-AVPair = "call-id=1231924661763@172.18.1.197"
       Acct-Session-Id = "1231924661763@172.18.1.197"
       h323-remote-address = "h323-remote-address=172.18.3.52"
       h323-disconnect-time = "h323-disconnect-time=10:33:13.000 GMT
Wed Jan 14 2009"
       h323-connect-time = "h323-connect-time=10:33:13.000 GMT Wed Jan 14 2009"
       Acct-Session-Time = 0
       h323-disconnect-cause = "h323-disconnect-cause=29"
       Acct-Status-Type = Stop
       NAS-Port = 5060
       Acct-Delay-Time = 0
       NAS-IP-Address = 172.18.3.52
+- entering group preacct {...}
++[preprocess] returns ok
[acct_unique] Hashing 'NAS-Port = 5060,Client-IP-Address =
172.18.3.52,NAS-IP-Address = 172.18.3.52,Acct-Session-Id =
"1231924661763@172.18.1.197",User-Name = "172.18.3.52"'
[acct_unique] Acct-Unique-Session-ID = "547b4ecb37f1696c".
++[acct_unique] returns ok
[suffix] No '@' in User-Name = "172.18.3.52", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
++[files] returns noop
+- entering group accounting {...}
[detail]        expand:
/var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d ->
/var/log/radius/radacct/172.18.3.52/detail-20090114
[detail] /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d
expands to /var/log/radius/radacct/172.18.3.52/detail-20090114
[detail]        expand: %t -> Wed Jan 14 16:03:13 2009
++[detail] returns ok
++[unix] returns ok
[radutmp]       expand: /var/log/radius/radutmp -> /var/log/radius/radutmp
[radutmp]       expand: %{User-Name} -> 172.18.3.52
rlm_radutmp: Logout for NAS local port 5060, but no Login record
++[radutmp] returns ok
[attr_filter.accounting_response]       expand: %{User-Name} -> 172.18.3.52
 attr_filter: Matched entry DEFAULT at line 12
++[attr_filter.accounting_response] returns updated
Sending Accounting-Response of id 238 to 172.18.3.52 port 32958
Finished request 1.
Cleaning up request 1 ID 238 with timestamp +54
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Access-Request packet from host 172.18.3.52 port 32959,
id=239, length=247
       User-Name = "172.18.3.52"
       User-Password = "cisco"
       Calling-Station-Id = "alice"
       Called-Station-Id = "bob"
       h323-conf-id = "h323-conf-id=7EAC4F20 651D23F0 75C1FFA6 E6EE4BFA"
       Cisco-AVPair = "call-id=1231924661763@172.18.1.197"
       h323-remote-address = "h323-remote-address=172.18.3.52"
       Cisco-AVPair = "h323-session-protocol=sipv2"
       NAS-Port = 5060
       NAS-IP-Address = 172.18.3.52
+- entering group authorize {...}
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
[suffix] No '@' in User-Name = "172.18.3.52", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] No EAP-Message, not doing EAP
++[eap] returns noop
++[unix] returns notfound
[files] users: Matched entry 172.18.3.52 at line 92
++[files] returns ok
++[expiration] returns noop
++[logintime] returns noop
++[pap] returns updated
Found Auth-Type = PAP
+- entering group PAP {...}
[pap] login attempt with password "cisco"
[pap] Using clear text password "cisco"
[pap] User authenticated successfully
++[pap] returns ok
+- entering group post-auth {...}
++[exec] returns noop
Sending Access-Accept of id 239 to 172.18.3.52 port 32959
Finished request 2.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Accounting-Request packet from host 172.18.3.52 port 32960,
id=240, length=540
       h323-call-origin = "h323-call-origin=answer"
       h323-call-type = "h323-call-type=VoIP"
       Cisco-AVPair = "h323-session-protocol=sipv2"
       h323-setup-time = "h323-setup-time=10:33:13.000 GMT Wed Jan 14 2009"
       User-Name = "172.18.3.52"
       Calling-Station-Id = "alice"
       Called-Station-Id = "bob"
       h323-conf-id = "h323-conf-id=7EAC4F20 651D23F0 75C1FFA6 E6EE4BFA"
       Cisco-AVPair = "call-id=1231924661763@172.18.1.197"
       Acct-Session-Id = "1231924661763@172.18.1.197"
       h323-remote-address = "h323-remote-address=172.18.3.52"
       h323-disconnect-time = "h323-disconnect-time=10:33:13.000 GMT
Wed Jan 14 2009"
       h323-connect-time = "h323-connect-time=10:33:13.000 GMT Wed Jan 14 2009"
       Acct-Session-Time = 0
       h323-disconnect-cause = "h323-disconnect-cause=29"
       Acct-Status-Type = Stop
       NAS-Port = 5060
       Acct-Delay-Time = 0
       NAS-IP-Address = 172.18.3.52
+- entering group preacct {...}
++[preprocess] returns ok
[acct_unique] Hashing 'NAS-Port = 5060,Client-IP-Address =
172.18.3.52,NAS-IP-Address = 172.18.3.52,Acct-Session-Id =
"1231924661763@172.18.1.197",User-Name = "172.18.3.52"'
[acct_unique] Acct-Unique-Session-ID = "547b4ecb37f1696c".
++[acct_unique] returns ok
[suffix] No '@' in User-Name = "172.18.3.52", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
++[files] returns noop
+- entering group accounting {...}
[detail]        expand:
/var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d ->
/var/log/radius/radacct/172.18.3.52/detail-20090114
[detail] /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d
expands to /var/log/radius/radacct/172.18.3.52/detail-20090114
[detail]        expand: %t -> Wed Jan 14 16:03:13 2009
++[detail] returns ok
++[unix] returns ok
[radutmp]       expand: /var/log/radius/radutmp -> /var/log/radius/radutmp
[radutmp]       expand: %{User-Name} -> 172.18.3.52
rlm_radutmp: Logout for NAS local port 5060, but no Login record
++[radutmp] returns ok
[attr_filter.accounting_response]       expand: %{User-Name} -> 172.18.3.52
 attr_filter: Matched entry DEFAULT at line 12
++[attr_filter.accounting_response] returns updated
Sending Accounting-Response of id 240 to 172.18.3.52 port 32960
Finished request 3.
Cleaning up request 3 ID 240 with timestamp +54
Going to the next request
Waking up in 4.8 seconds.
Cleaning up request 0 ID 237 with timestamp +54
Cleaning up request 2 ID 239 with timestamp +54
Ready to process requests.