FreeRADIUS Version 2.1.4, for host i686-redhat-linux-gnu, built on Apr  7 2009 at 18:16:46
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/etc_group
including configuration file /etc/raddb/modules/checkval
including configuration file /etc/raddb/modules/wimax
including configuration file /etc/raddb/modules/files
including configuration file /etc/raddb/modules/mac2vlan
including configuration file /etc/raddb/modules/inner-eap
including configuration file /etc/raddb/modules/attr_rewrite
including configuration file /etc/raddb/modules/sqlcounter_expire_on_login
including configuration file /etc/raddb/modules/realm
including configuration file /etc/raddb/modules/linelog
including configuration file /etc/raddb/modules/echo
including configuration file /etc/raddb/modules/always
including configuration file /etc/raddb/modules/detail.log
including configuration file /etc/raddb/modules/mac2ip
including configuration file /etc/raddb/modules/sradutmp
including configuration file /etc/raddb/modules/chap
including configuration file /etc/raddb/modules/counter
including configuration file /etc/raddb/modules/mschap
including configuration file /etc/raddb/modules/lubestop
including configuration file /etc/raddb/modules/preprocess
including configuration file /etc/raddb/modules/pap
including configuration file /etc/raddb/modules/pam
including configuration file /etc/raddb/modules/passwd
including configuration file /etc/raddb/modules/policy
including configuration file /etc/raddb/modules/sql_log
including configuration file /etc/raddb/modules/acct_unique
including configuration file /etc/raddb/modules/smbpasswd
including configuration file /etc/raddb/modules/otp
including configuration file /etc/raddb/modules/ippool
including configuration file /etc/raddb/modules/detail
including configuration file /etc/raddb/modules/smsotp
including configuration file /etc/raddb/modules/unix
including configuration file /etc/raddb/modules/exec
including configuration file /etc/raddb/modules/expiration
including configuration file /etc/raddb/modules/digest
including configuration file /etc/raddb/modules/imaginenet
including configuration file /etc/raddb/modules/perl
including configuration file /etc/raddb/modules/attr_filter
including configuration file /etc/raddb/modules/logintime
including configuration file /etc/raddb/modules/radutmp
including configuration file /etc/raddb/modules/detail.example.com
including configuration file /etc/raddb/modules/expr
including configuration file /etc/raddb/policy.conf
including files in directory /etc/raddb/sites-enabled/
including configuration file /etc/raddb/sites-enabled/control-socket
including configuration file /etc/raddb/sites-enabled/imaginenet
including configuration file /etc/raddb/sites-enabled/default
group = radiusd
user = radiusd
including dictionary file /etc/raddb/dictionary
main {
	prefix = "/usr"
	localstatedir = "/var"
	logdir = "/var/log/radius"
	libdir = "/usr/lib/freeradius"
	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/sbin/checkrad"
	debug_level = 0
	proxy_requests = yes
 log {
	stripped_names = no
	auth = yes
	auth_badpass = yes
	auth_goodpass = no
 }
 security {
	max_attributes = 200
	reject_delay = 0
	status_server = yes
 }
}
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
	require_message_authenticator = no
	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
	irt = 2
	mrt = 16
	mrc = 5
	mrd = 30
 }
 home_server_pool my_auth_failover {
	type = fail-over
	home_server = localhost
 }
 realm example.com {
	auth_pool = my_auth_failover
 }
 realm LOCAL {
 }
 home_server auth_home1.imaginenet.local {
	ipaddr = 192.168.1.10
	port = 1812
	type = "auth"
	secret = "**********"
	response_window = 6
	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 = 300
	status_check_timeout = 4
	irt = 2
	mrt = 16
	mrc = 5
	mrd = 30
 }
 home_server auth_home2.imaginenet.local {
	ipaddr = 192.168.1.20
	port = 1812
	type = "auth"
	secret = "**********"
	response_window = 6
	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 = 300
	status_check_timeout = 4
	irt = 2
	mrt = 16
	mrc = 5
	mrd = 30
 }
 home_server_pool auth_pool.imaginenet {
	type = fail-over
	home_server = auth_home1.imaginenet.local
	home_server = auth_home2.imaginenet.local
 }
 home_server acct_home1.imaginenet.net {
	ipaddr = 192.168.1.10
	port = 1813
	type = "acct"
	secret = "**********"
	response_window = 6
	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 = 300
	status_check_timeout = 4
	irt = 2
	mrt = 16
	mrc = 5
	mrd = 30
 }
 home_server acct_detail.imaginenet {
	virtual_server = "acct_detail.imaginenet"
	port = 0
	response_window = 30
	max_outstanding = 65536
	zombie_period = 40
	status_check = "none"
	ping_interval = 30
	check_interval = 30
	num_answers_to_alive = 3
	num_pings_to_alive = 3
	revive_interval = 300
	status_check_timeout = 4
	irt = 2
	mrt = 16
	mrc = 5
	mrd = 30
 }
 home_server_pool acct_pool.imaginenet {
	type = fail-over
	virtual_server = ImagineNet
	home_server = acct_home1.imaginenet.net
	fallback = acct_detail.imaginenet
 }
 realm imaginenet.net {
	auth_pool = auth_pool.imaginenet
	acct_pool = acct_pool.imaginenet
	nostrip
 }
 realm linkup.us {
	auth_pool = auth_pool.imaginenet
	acct_pool = acct_pool.imaginenet
	nostrip
 }
 realm myfol.com {
	auth_pool = auth_pool.imaginenet
	acct_pool = acct_pool.imaginenet
	nostrip
 }
 realm bbcinternet.com {
	auth_pool = auth_pool.imaginenet
	acct_pool = acct_pool.imaginenet
	nostrip
 }
 realm acct_realm.imaginenet {
	acct_pool = acct_pool.imaginenet
 }
radiusd: #### Loading Clients ####
 client localhost {
	ipaddr = 127.0.0.1
	require_message_authenticator = no
	secret = "testing123"
	nastype = "other"
 }
 client as1.cle1.oh.imaginenet.net {
	ipaddr = 192.168.78.28
	require_message_authenticator = no
	secret = "**********"
 }
 client home1 {
	ipaddr = 192.168.1.10
	require_message_authenticator = no
	secret = "**********"
 }
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 acct_detail.imaginenet {
 modules {
 Module: Checking preacct {...} for more modules to load
 Module: Linked to module rlm_attr_filter
 Module: Instantiating attr_filter.imaginenet
  attr_filter attr_filter.imaginenet {
	attrsfile = "/etc/raddb/attrs.imaginenet"
	key = "%{User-Name}"
  }
 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 detail.imaginenet
  detail detail.imaginenet {
	detailfile = "/var/log/radius/radacct/ImagineNet/detail-%Y%m%d"
	header = "%t"
	detailperm = 384
	dirperm = 493
	locking = no
	log_packet_header = no
  }
 }
}
server ImagineNet {
 modules {
 Module: Checking accounting {...} for more modules to load
 Module: Checking post-proxy {...} for more modules to load
 }
}
 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: 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: 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 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: Instantiating attr_filter.accounting_response
  attr_filter attr_filter.accounting_response {
	attrsfile = "/etc/raddb/attrs.accounting_response"
	key = "%{User-Name}"
  }
 Module: Checking pre-proxy {...} for more modules to load
 Module: Checking post-auth {...} for more modules to load
 Module: Instantiating attr_filter.access_reject
  attr_filter attr_filter.access_reject {
	attrsfile = "/etc/raddb/attrs.access_reject"
	key = "%{User-Name}"
  }
 }
radiusd: #### Opening IP addresses and Ports ####
listen {
	type = "auth"
	ipaddr = 192.168.78.20
	port = 1812
}
listen {
	type = "acct"
	ipaddr = 192.168.78.20
	port = 1813
}
listen {
	type = "auth"
	ipaddr = 192.168.78.20
	port = 1645
}
listen {
	type = "acct"
	ipaddr = 192.168.78.20
	port = 1646
}
listen {
	type = "control"
 listen {
	socket = "/var/run/radiusd/radiusd.sock"
 }
}
listen {
	type = "detail"
  listen {
	filename = "/var/log/radius/radacct/ImagineNet/detail-*"
	load_factor = 10
	poll_interval = 1
  }
}
Listening on authentication address 192.168.78.20 port 1812
Listening on accounting address 192.168.78.20 port 1813
Listening on authentication address 192.168.78.20 port 1645
Listening on accounting address 192.168.78.20 port 1646
Listening on command file /var/run/radiusd/radiusd.sock
Listening on detail file /var/log/radius/radacct/ImagineNet/detail-* as server ImagineNet
Listening on proxy address 192.168.78.20 port 1814
Waking up in 0.3 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Waking up in 1.3 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Waking up in 1.4 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Waking up in 1.0 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Waking up in 1.0 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Waking up in 1.3 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Waking up in 1.0 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Waking up in 1.3 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Waking up in 1.3 seconds.



Waking up in 0.7 seconds.
rad_recv: Accounting-Request packet from host 192.168.70.210 port 1814, id=128, length=191
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 0
	Proxy-State = 0x3931
+- entering group preacct {...}
[preprocess] 	expand: %{User-Name} -> johndoe@linkup.us
[preprocess] 	expand: %{User-Name} -> johndoe@linkup.us
[preprocess] 	expand: %{User-Name} -> johndoe@linkup.us
[preprocess] 	expand: %{User-Name} -> johndoe@linkup.us
++[preprocess] returns ok
[acct_unique] Hashing 'NAS-Port = 31,Client-IP-Address = 192.168.70.210,NAS-IP-Address = 192.168.64.57,Acct-Session-Id = "0007EF12",User-Name = "johndoe@linkup.us"'
[acct_unique] Acct-Unique-Session-ID = "e35714fe059ff6c9".
++[acct_unique] returns ok
[suffix] Looking up realm "linkup.us" for User-Name = "johndoe@linkup.us"
[suffix] Found realm "linkup.us"
[suffix] Adding Realm = "linkup.us"
[suffix] Proxying request from user johndoe to realm linkup.us
[suffix] Preparing to proxy accounting request to realm "linkup.us" 
++[suffix] returns updated
++[files] returns noop
+- entering group accounting {...}
[detail] 	expand: /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d -> /var/log/radius/radacct/192.168.70.210/detail-20090409
[detail] /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d expands to /var/log/radius/radacct/192.168.70.210/detail-20090409
[detail] 	expand: %t -> Thu Apr  9 01:31:16 2009
++[detail] returns ok
[attr_filter.accounting_response] 	expand: %{User-Name} -> johndoe@linkup.us
 attr_filter: Matched entry DEFAULT at line 12
++[attr_filter.accounting_response] returns updated
 server ImagineNet {
 }
Sending Accounting-Request of id 250 to 192.168.78.115 port 1813
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 0
	Proxy-State = 0x3931
	Proxy-State = 0x313238
Proxying request 1 to home server 192.168.78.115 port 1813
Sending Accounting-Request of id 250 to 192.168.78.115 port 1813
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 0
	Proxy-State = 0x3931
	Proxy-State = 0x313238
Going to the next request
Waking up in 0.7 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Waking up in 0.2 seconds.
Waking up in 0.7 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Waking up in 1.3 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Waking up in 1.3 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Waking up in 0.4 seconds.
Cleaning up request 0 ID 142 with timestamp +497
Waking up in 0.7 seconds.
rad_recv: Accounting-Request packet from host 192.168.70.210 port 1814, id=129, length=191
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 5
	Proxy-State = 0x3932
+- entering group preacct {...}
[preprocess] 	expand: %{User-Name} -> johndoe@linkup.us
[preprocess] 	expand: %{User-Name} -> johndoe@linkup.us
[preprocess] 	expand: %{User-Name} -> johndoe@linkup.us
[preprocess] 	expand: %{User-Name} -> johndoe@linkup.us
++[preprocess] returns ok
[acct_unique] Hashing 'NAS-Port = 31,Client-IP-Address = 192.168.70.210,NAS-IP-Address = 192.168.64.57,Acct-Session-Id = "0007EF12",User-Name = "johndoe@linkup.us"'
[acct_unique] Acct-Unique-Session-ID = "e35714fe059ff6c9".
++[acct_unique] returns ok
[suffix] Looking up realm "linkup.us" for User-Name = "johndoe@linkup.us"
[suffix] Found realm "linkup.us"
[suffix] Adding Realm = "linkup.us"
[suffix] Proxying request from user johndoe to realm linkup.us
[suffix] Preparing to proxy accounting request to realm "linkup.us" 
++[suffix] returns updated
++[files] returns noop
+- entering group accounting {...}
[detail] 	expand: /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d -> /var/log/radius/radacct/192.168.70.210/detail-20090409
[detail] /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d expands to /var/log/radius/radacct/192.168.70.210/detail-20090409
[detail] 	expand: %t -> Thu Apr  9 01:31:21 2009
++[detail] returns ok
[attr_filter.accounting_response] 	expand: %{User-Name} -> johndoe@linkup.us
 attr_filter: Matched entry DEFAULT at line 12
++[attr_filter.accounting_response] returns updated
 server ImagineNet {
 }
Sending Accounting-Request of id 207 to 192.168.78.115 port 1813
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 5
	Proxy-State = 0x3932
	Proxy-State = 0x313239
Proxying request 2 to home server 192.168.78.115 port 1813
Sending Accounting-Request of id 207 to 192.168.78.115 port 1813
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 5
	Proxy-State = 0x3932
	Proxy-State = 0x313239
Going to the next request
Waking up in 0.7 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Waking up in 0.2 seconds.
rad_recv: Accounting-Request packet from host 192.168.70.210 port 1814, id=128, length=191
Received conflicting packet from client Fidelity-Radius-1 port 1814 - ID: 128 due to unfinished request 1.  Giving up on old request.
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 5
	Proxy-State = 0x3931
+- entering group preacct {...}
[preprocess] 	expand: %{User-Name} -> johndoe@linkup.us
[preprocess] 	expand: %{User-Name} -> johndoe@linkup.us
[preprocess] 	expand: %{User-Name} -> johndoe@linkup.us
[preprocess] 	expand: %{User-Name} -> johndoe@linkup.us
++[preprocess] returns ok
[acct_unique] Hashing 'NAS-Port = 31,Client-IP-Address = 192.168.70.210,NAS-IP-Address = 192.168.64.57,Acct-Session-Id = "0007EF12",User-Name = "johndoe@linkup.us"'
[acct_unique] Acct-Unique-Session-ID = "e35714fe059ff6c9".
++[acct_unique] returns ok
[suffix] Looking up realm "linkup.us" for User-Name = "johndoe@linkup.us"
[suffix] Found realm "linkup.us"
[suffix] Adding Realm = "linkup.us"
[suffix] Proxying request from user johndoe to realm linkup.us
[suffix] Preparing to proxy accounting request to realm "linkup.us" 
++[suffix] returns updated
++[files] returns noop
+- entering group accounting {...}
[detail] 	expand: /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d -> /var/log/radius/radacct/192.168.70.210/detail-20090409
[detail] /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d expands to /var/log/radius/radacct/192.168.70.210/detail-20090409
[detail] 	expand: %t -> Thu Apr  9 01:31:22 2009
++[detail] returns ok
[attr_filter.accounting_response] 	expand: %{User-Name} -> johndoe@linkup.us
 attr_filter: Matched entry DEFAULT at line 12
++[attr_filter.accounting_response] returns updated
 server ImagineNet {
 }
Sending Accounting-Request of id 19 to 192.168.78.115 port 1813
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 5
	Proxy-State = 0x3931
	Proxy-State = 0x313238
Proxying request 3 to home server 192.168.78.115 port 1813
Sending Accounting-Request of id 19 to 192.168.78.115 port 1813
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 5
	Proxy-State = 0x3931
	Proxy-State = 0x313238
Going to the next request
Rejecting request 1 due to lack of any response from home server 192.168.78.115 port 1813
  Found Post-Proxy-Type 
 server ImagineNet {
+- entering group Fail {...}
[detail.imaginenet] 	expand: /var/log/radius/radacct/ImagineNet/detail-%Y%m%d -> /var/log/radius/radacct/ImagineNet/detail-20090409
[detail.imaginenet] /var/log/radius/radacct/ImagineNet/detail-%Y%m%d expands to /var/log/radius/radacct/ImagineNet/detail-20090409
[detail.imaginenet] 	expand: %t -> Thu Apr  9 01:31:16 2009
[detail.imaginenet] Freeradius-Proxied-To = 192.168.78.115
++[detail.imaginenet] returns ok
 }
Sending Accounting-Response of id 128 to 192.168.70.210 port 1814
	Proxy-State = 0x3931
Finished request 1.
Cleaning up request 1 ID 128 with timestamp +497
Going to the next request
PROXY: Marking home server 192.168.78.115 port 1813 as zombie (it looks like it is dead).
Sending Status-Server of id 8 to 192.168.78.115 port 1813
	Message-Authenticator := 0x00000000000000000000000000000000
	NAS-Identifier := "Status Check. Are you alive?"
Waking up in 0.9 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
detail_recv: Renaming /var/log/radius/radacct/ImagineNet/detail-20090409 -> /var/log/radius/radacct/ImagineNet/detail.work
detail_recv: Read packet from /var/log/radius/radacct/ImagineNet/detail.work
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 7
	Proxy-State = 0x3931
	Acct-Unique-Session-Id = "e35714fe059ff6c9"
	Realm = "linkup.us"
	Realm = "linkup.us"
	FreeRADIUS-Proxied-To = 192.168.78.115
	Packet-Original-Timestamp = "Apr  9 2009 01:31:16 EDT"
server ImagineNet {
+- entering group accounting {...}
++[control] returns noop
} # server ImagineNet
 server ImagineNet {
 }
Sending Accounting-Request of id 125 to 192.168.78.115 port 1813
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 7
	Proxy-State = 0x3931
	FreeRADIUS-Proxied-To = 192.168.78.115
	Proxy-State = 0x37363233
Proxying request 5 to home server 192.168.78.115 port 1813
Sending Accounting-Request of id 125 to 192.168.78.115 port 1813
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 7
	Proxy-State = 0x3931
	FreeRADIUS-Proxied-To = 192.168.78.115
	Proxy-State = 0x37363233
Going to the next request
Waking up in 0.9 seconds.
Waking up in 1.3 seconds.
Waking up in 0.7 seconds.
rad_recv: Accounting-Request packet from host 192.168.70.210 port 1814, id=130, length=191
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 10
	Proxy-State = 0x3933
+- entering group preacct {...}
[preprocess] 	expand: %{User-Name} -> johndoe@linkup.us
[preprocess] 	expand: %{User-Name} -> johndoe@linkup.us
[preprocess] 	expand: %{User-Name} -> johndoe@linkup.us
[preprocess] 	expand: %{User-Name} -> johndoe@linkup.us
++[preprocess] returns ok
[acct_unique] Hashing 'NAS-Port = 31,Client-IP-Address = 192.168.70.210,NAS-IP-Address = 192.168.64.57,Acct-Session-Id = "0007EF12",User-Name = "johndoe@linkup.us"'
[acct_unique] Acct-Unique-Session-ID = "e35714fe059ff6c9".
++[acct_unique] returns ok
[suffix] Looking up realm "linkup.us" for User-Name = "johndoe@linkup.us"
[suffix] Found realm "linkup.us"
[suffix] Adding Realm = "linkup.us"
[suffix] Proxying request from user johndoe to realm linkup.us
[suffix] Preparing to proxy accounting request to realm "linkup.us" 
++[suffix] returns updated
++[files] returns noop
+- entering group accounting {...}
[detail] 	expand: /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d -> /var/log/radius/radacct/192.168.70.210/detail-20090409
[detail] /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d expands to /var/log/radius/radacct/192.168.70.210/detail-20090409
[detail] 	expand: %t -> Thu Apr  9 01:31:26 2009
++[detail] returns ok
[attr_filter.accounting_response] 	expand: %{User-Name} -> johndoe@linkup.us
 attr_filter: Matched entry DEFAULT at line 12
++[attr_filter.accounting_response] returns updated
 server ImagineNet {
 }
Sending Accounting-Request of id 21 to 192.168.78.115 port 1813
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 10
	Proxy-State = 0x3933
	Proxy-State = 0x313330
Proxying request 6 to home server 192.168.78.115 port 1813
Sending Accounting-Request of id 21 to 192.168.78.115 port 1813
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 10
	Proxy-State = 0x3933
	Proxy-State = 0x313330
Going to the next request
Waking up in 0.6 seconds.
Waking up in 0.3 seconds.
rad_recv: Accounting-Request packet from host 192.168.70.210 port 1814, id=129, length=191
Received conflicting packet from client Fidelity-Radius-1 port 1814 - ID: 129 due to unfinished request 2.  Giving up on old request.
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 10
	Proxy-State = 0x3932
+- entering group preacct {...}
[preprocess] 	expand: %{User-Name} -> johndoe@linkup.us
[preprocess] 	expand: %{User-Name} -> johndoe@linkup.us
[preprocess] 	expand: %{User-Name} -> johndoe@linkup.us
[preprocess] 	expand: %{User-Name} -> johndoe@linkup.us
++[preprocess] returns ok
[acct_unique] Hashing 'NAS-Port = 31,Client-IP-Address = 192.168.70.210,NAS-IP-Address = 192.168.64.57,Acct-Session-Id = "0007EF12",User-Name = "johndoe@linkup.us"'
[acct_unique] Acct-Unique-Session-ID = "e35714fe059ff6c9".
++[acct_unique] returns ok
[suffix] Looking up realm "linkup.us" for User-Name = "johndoe@linkup.us"
[suffix] Found realm "linkup.us"
[suffix] Adding Realm = "linkup.us"
[suffix] Proxying request from user johndoe to realm linkup.us
[suffix] Preparing to proxy accounting request to realm "linkup.us" 
++[suffix] returns updated
++[files] returns noop
+- entering group accounting {...}
[detail] 	expand: /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d -> /var/log/radius/radacct/192.168.70.210/detail-20090409
[detail] /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d expands to /var/log/radius/radacct/192.168.70.210/detail-20090409
[detail] 	expand: %t -> Thu Apr  9 01:31:27 2009
++[detail] returns ok
[attr_filter.accounting_response] 	expand: %{User-Name} -> johndoe@linkup.us
 attr_filter: Matched entry DEFAULT at line 12
++[attr_filter.accounting_response] returns updated
 server ImagineNet {
 }
Sending Accounting-Request of id 238 to 192.168.78.115 port 1813
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 10
	Proxy-State = 0x3932
	Proxy-State = 0x313239
Proxying request 7 to home server 192.168.78.115 port 1813
Sending Accounting-Request of id 238 to 192.168.78.115 port 1813
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 10
	Proxy-State = 0x3932
	Proxy-State = 0x313239
Going to the next request
Rejecting request 2 due to lack of any response from home server 192.168.78.115 port 1813
  Found Post-Proxy-Type 
 server ImagineNet {
+- entering group Fail {...}
[detail.imaginenet] 	expand: /var/log/radius/radacct/ImagineNet/detail-%Y%m%d -> /var/log/radius/radacct/ImagineNet/detail-20090409
[detail.imaginenet] /var/log/radius/radacct/ImagineNet/detail-%Y%m%d expands to /var/log/radius/radacct/ImagineNet/detail-20090409
[detail.imaginenet] 	expand: %t -> Thu Apr  9 01:31:21 2009
[detail.imaginenet] Freeradius-Proxied-To = 192.168.78.115
++[detail.imaginenet] returns ok
 }
Sending Accounting-Response of id 129 to 192.168.70.210 port 1814
	Proxy-State = 0x3932
Finished request 2.
Cleaning up request 2 ID 129 with timestamp +502
Going to the next request
Waking up in 0.7 seconds.
Waking up in 0.2 seconds.
Rejecting request 3 due to lack of any response from home server 192.168.78.115 port 1813
  Found Post-Proxy-Type 
 server ImagineNet {
+- entering group Fail {...}
[detail.imaginenet] 	expand: /var/log/radius/radacct/ImagineNet/detail-%Y%m%d -> /var/log/radius/radacct/ImagineNet/detail-20090409
[detail.imaginenet] /var/log/radius/radacct/ImagineNet/detail-%Y%m%d expands to /var/log/radius/radacct/ImagineNet/detail-20090409
[detail.imaginenet] 	expand: %t -> Thu Apr  9 01:31:22 2009
[detail.imaginenet] Freeradius-Proxied-To = 192.168.78.115
++[detail.imaginenet] returns ok
 }
Sending Accounting-Response of id 128 to 192.168.70.210 port 1814
	Proxy-State = 0x3931
Finished request 3.
Cleaning up request 3 ID 128 with timestamp +503
Going to the next request
Waking up in 0.9 seconds.
Rejecting request 5 due to lack of any response from home server 192.168.78.115 port 1813
  Found Post-Proxy-Type 
 server ImagineNet {
+- entering group Fail {...}
[detail.imaginenet] Suppressing writes to detail file as the request was just read from a detail file.
++[detail.imaginenet] returns noop
 }
server ImagineNet {
} # server ImagineNet
Finished request 5.
Cleaning up request 5 ID 7623 with timestamp +504
Going to the next request
Waking up in 0.2 seconds.
Delaying next detail event for 1.0 seconds.
Waking up in 0.2 seconds.
detail_recv: Read packet from /var/log/radius/radacct/ImagineNet/detail.work
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 13
	Proxy-State = 0x3931
	Acct-Unique-Session-Id = "e35714fe059ff6c9"
	Realm = "linkup.us"
	Realm = "linkup.us"
	FreeRADIUS-Proxied-To = 192.168.78.115
	Packet-Original-Timestamp = "Apr  9 2009 01:31:16 EDT"
server ImagineNet {
+- entering group accounting {...}
++[control] returns noop
} # server ImagineNet
 server ImagineNet {
 }
Sending Accounting-Request of id 0 to 192.168.78.115 port 1813
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 13
	Proxy-State = 0x3931
	FreeRADIUS-Proxied-To = 192.168.78.115
	Proxy-State = 0x3333343232
Proxying request 8 to home server 192.168.78.115 port 1813
Sending Accounting-Request of id 0 to 192.168.78.115 port 1813
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 13
	Proxy-State = 0x3931
	FreeRADIUS-Proxied-To = 192.168.78.115
	Proxy-State = 0x3333343232
Going to the next request
Waking up in 0.7 seconds.
Waking up in 0.2 seconds.
Waking up in 0.2 seconds.
Waking up in 0.8 seconds.
rad_recv: Accounting-Request packet from host 192.168.70.210 port 1814, id=131, length=191
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 15
	Proxy-State = 0x3934
+- entering group preacct {...}
[preprocess] 	expand: %{User-Name} -> johndoe@linkup.us
[preprocess] 	expand: %{User-Name} -> johndoe@linkup.us
[preprocess] 	expand: %{User-Name} -> johndoe@linkup.us
[preprocess] 	expand: %{User-Name} -> johndoe@linkup.us
++[preprocess] returns ok
[acct_unique] Hashing 'NAS-Port = 31,Client-IP-Address = 192.168.70.210,NAS-IP-Address = 192.168.64.57,Acct-Session-Id = "0007EF12",User-Name = "johndoe@linkup.us"'
[acct_unique] Acct-Unique-Session-ID = "e35714fe059ff6c9".
++[acct_unique] returns ok
[suffix] Looking up realm "linkup.us" for User-Name = "johndoe@linkup.us"
[suffix] Found realm "linkup.us"
[suffix] Adding Realm = "linkup.us"
[suffix] Proxying request from user johndoe to realm linkup.us
[suffix] Preparing to proxy accounting request to realm "linkup.us" 
++[suffix] returns updated
++[files] returns noop
+- entering group accounting {...}
[detail] 	expand: /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d -> /var/log/radius/radacct/192.168.70.210/detail-20090409
[detail] /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d expands to /var/log/radius/radacct/192.168.70.210/detail-20090409
[detail] 	expand: %t -> Thu Apr  9 01:31:31 2009
++[detail] returns ok
[attr_filter.accounting_response] 	expand: %{User-Name} -> johndoe@linkup.us
 attr_filter: Matched entry DEFAULT at line 12
++[attr_filter.accounting_response] returns updated
 server ImagineNet {
 }
Sending Accounting-Request of id 239 to 192.168.78.115 port 1813
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 15
	Proxy-State = 0x3934
	Proxy-State = 0x313331
Proxying request 9 to home server 192.168.78.115 port 1813
Sending Accounting-Request of id 239 to 192.168.78.115 port 1813
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 15
	Proxy-State = 0x3934
	Proxy-State = 0x313331
Going to the next request
Waking up in 0.3 seconds.
Waking up in 0.5 seconds.
rad_recv: Accounting-Request packet from host 192.168.70.210 port 1814, id=130, length=191
Received conflicting packet from client Fidelity-Radius-1 port 1814 - ID: 130 due to unfinished request 6.  Giving up on old request.
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 15
	Proxy-State = 0x3933
+- entering group preacct {...}
[preprocess] 	expand: %{User-Name} -> johndoe@linkup.us
[preprocess] 	expand: %{User-Name} -> johndoe@linkup.us
[preprocess] 	expand: %{User-Name} -> johndoe@linkup.us
[preprocess] 	expand: %{User-Name} -> johndoe@linkup.us
++[preprocess] returns ok
[acct_unique] Hashing 'NAS-Port = 31,Client-IP-Address = 192.168.70.210,NAS-IP-Address = 192.168.64.57,Acct-Session-Id = "0007EF12",User-Name = "johndoe@linkup.us"'
[acct_unique] Acct-Unique-Session-ID = "e35714fe059ff6c9".
++[acct_unique] returns ok
[suffix] Looking up realm "linkup.us" for User-Name = "johndoe@linkup.us"
[suffix] Found realm "linkup.us"
[suffix] Adding Realm = "linkup.us"
[suffix] Proxying request from user johndoe to realm linkup.us
[suffix] Preparing to proxy accounting request to realm "linkup.us" 
++[suffix] returns updated
++[files] returns noop
+- entering group accounting {...}
[detail] 	expand: /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d -> /var/log/radius/radacct/192.168.70.210/detail-20090409
[detail] /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d expands to /var/log/radius/radacct/192.168.70.210/detail-20090409
[detail] 	expand: %t -> Thu Apr  9 01:31:32 2009
++[detail] returns ok
[attr_filter.accounting_response] 	expand: %{User-Name} -> johndoe@linkup.us
 attr_filter: Matched entry DEFAULT at line 12
++[attr_filter.accounting_response] returns updated
 server ImagineNet {
 }
Sending Accounting-Request of id 1 to 192.168.78.115 port 1813
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 15
	Proxy-State = 0x3933
	Proxy-State = 0x313330
Proxying request 10 to home server 192.168.78.115 port 1813
Sending Accounting-Request of id 1 to 192.168.78.115 port 1813
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 15
	Proxy-State = 0x3933
	Proxy-State = 0x313330
Going to the next request
Rejecting request 6 due to lack of any response from home server 192.168.78.115 port 1813
  Found Post-Proxy-Type 
 server ImagineNet {
+- entering group Fail {...}
[detail.imaginenet] 	expand: /var/log/radius/radacct/ImagineNet/detail-%Y%m%d -> /var/log/radius/radacct/ImagineNet/detail-20090409
[detail.imaginenet] /var/log/radius/radacct/ImagineNet/detail-%Y%m%d expands to /var/log/radius/radacct/ImagineNet/detail-20090409
[detail.imaginenet] 	expand: %t -> Thu Apr  9 01:31:26 2009
[detail.imaginenet] Freeradius-Proxied-To = 192.168.78.115
++[detail.imaginenet] returns ok
 }
Sending Accounting-Response of id 130 to 192.168.70.210 port 1814
	Proxy-State = 0x3933
Finished request 6.
Cleaning up request 6 ID 130 with timestamp +507
Going to the next request
Waking up in 0.7 seconds.
Waking up in 0.2 seconds.
Rejecting request 7 due to lack of any response from home server 192.168.78.115 port 1813
  Found Post-Proxy-Type 
 server ImagineNet {
+- entering group Fail {...}
[detail.imaginenet] 	expand: /var/log/radius/radacct/ImagineNet/detail-%Y%m%d -> /var/log/radius/radacct/ImagineNet/detail-20090409
[detail.imaginenet] /var/log/radius/radacct/ImagineNet/detail-%Y%m%d expands to /var/log/radius/radacct/ImagineNet/detail-20090409
[detail.imaginenet] 	expand: %t -> Thu Apr  9 01:31:27 2009
[detail.imaginenet] Freeradius-Proxied-To = 192.168.78.115
++[detail.imaginenet] returns ok
 }
Sending Accounting-Response of id 129 to 192.168.70.210 port 1814
	Proxy-State = 0x3932
Finished request 7.
Cleaning up request 7 ID 129 with timestamp +508
Going to the next request
Waking up in 0.2 seconds.
Waking up in 0.6 seconds.
Waking up in 0.6 seconds.
Waking up in 0.4 seconds.
Waking up in 0.3 seconds.
Rejecting request 8 due to lack of any response from home server 192.168.78.115 port 1813
  Found Post-Proxy-Type 
 server ImagineNet {
+- entering group Fail {...}
[detail.imaginenet] Suppressing writes to detail file as the request was just read from a detail file.
++[detail.imaginenet] returns noop
 }
server ImagineNet {
} # server ImagineNet
Finished request 8.
Cleaning up request 8 ID 33422 with timestamp +510
Going to the next request
Waking up in 0.4 seconds.
Delaying next detail event for 1.0 seconds.
Waking up in 0.4 seconds.
detail_recv: Read packet from /var/log/radius/radacct/ImagineNet/detail.work
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 20
	Proxy-State = 0x3931
	Acct-Unique-Session-Id = "e35714fe059ff6c9"
	Realm = "linkup.us"
	Realm = "linkup.us"
	FreeRADIUS-Proxied-To = 192.168.78.115
	Packet-Original-Timestamp = "Apr  9 2009 01:31:16 EDT"
server ImagineNet {
+- entering group accounting {...}
++[control] returns noop
} # server ImagineNet
 server ImagineNet {
 }
Sending Accounting-Request of id 11 to 192.168.78.115 port 1813
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 20
	Proxy-State = 0x3931
	FreeRADIUS-Proxied-To = 192.168.78.115
	Proxy-State = 0x3530383238
Proxying request 11 to home server 192.168.78.115 port 1813
Sending Accounting-Request of id 11 to 192.168.78.115 port 1813
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 20
	Proxy-State = 0x3931
	FreeRADIUS-Proxied-To = 192.168.78.115
	Proxy-State = 0x3530383238
Going to the next request
Waking up in 0.4 seconds.
Waking up in 0.4 seconds.
Waking up in 0.2 seconds.
Rejecting request 9 due to lack of any response from home server 192.168.78.115 port 1813
  Found Post-Proxy-Type 
 server ImagineNet {
+- entering group Fail {...}
[detail.imaginenet] 	expand: /var/log/radius/radacct/ImagineNet/detail-%Y%m%d -> /var/log/radius/radacct/ImagineNet/detail-20090409
[detail.imaginenet] /var/log/radius/radacct/ImagineNet/detail-%Y%m%d expands to /var/log/radius/radacct/ImagineNet/detail-20090409
[detail.imaginenet] 	expand: %t -> Thu Apr  9 01:31:31 2009
[detail.imaginenet] Freeradius-Proxied-To = 192.168.78.115
++[detail.imaginenet] returns ok
 }
Sending Accounting-Response of id 131 to 192.168.70.210 port 1814
	Proxy-State = 0x3934
Finished request 9.
Cleaning up request 9 ID 131 with timestamp +512
Going to the next request
Waking up in 0.1 seconds.
rad_recv: Accounting-Request packet from host 192.168.70.210 port 1814, id=131, length=191
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 20
	Proxy-State = 0x3934
+- entering group preacct {...}
[preprocess] 	expand: %{User-Name} -> johndoe@linkup.us
[preprocess] 	expand: %{User-Name} -> johndoe@linkup.us
[preprocess] 	expand: %{User-Name} -> johndoe@linkup.us
[preprocess] 	expand: %{User-Name} -> johndoe@linkup.us
++[preprocess] returns ok
[acct_unique] Hashing 'NAS-Port = 31,Client-IP-Address = 192.168.70.210,NAS-IP-Address = 192.168.64.57,Acct-Session-Id = "0007EF12",User-Name = "johndoe@linkup.us"'
[acct_unique] Acct-Unique-Session-ID = "e35714fe059ff6c9".
++[acct_unique] returns ok
[suffix] Looking up realm "linkup.us" for User-Name = "johndoe@linkup.us"
[suffix] Found realm "linkup.us"
[suffix] Adding Realm = "linkup.us"
[suffix] Proxying request from user johndoe to realm linkup.us
[suffix] Preparing to proxy accounting request to realm "linkup.us" 
++[suffix] returns updated
++[files] returns noop
+- entering group accounting {...}
[detail] 	expand: /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d -> /var/log/radius/radacct/192.168.70.210/detail-20090409
[detail] /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d expands to /var/log/radius/radacct/192.168.70.210/detail-20090409
[detail] 	expand: %t -> Thu Apr  9 01:31:37 2009
++[detail] returns ok
[attr_filter.accounting_response] 	expand: %{User-Name} -> johndoe@linkup.us
 attr_filter: Matched entry DEFAULT at line 12
++[attr_filter.accounting_response] returns updated
 server ImagineNet {
 }
Sending Accounting-Request of id 12 to 192.168.78.115 port 1813
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 20
	Proxy-State = 0x3934
	Proxy-State = 0x313331
Proxying request 12 to home server 192.168.78.115 port 1813
Sending Accounting-Request of id 12 to 192.168.78.115 port 1813
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 20
	Proxy-State = 0x3934
	Proxy-State = 0x313331
Going to the next request
Waking up in 0.1 seconds.
Waking up in 0.2 seconds.
Waking up in 0.6 seconds.
Rejecting request 10 due to lack of any response from home server 192.168.78.115 port 1813
  Found Post-Proxy-Type 
 server ImagineNet {
+- entering group Fail {...}
[detail.imaginenet] 	expand: /var/log/radius/radacct/ImagineNet/detail-%Y%m%d -> /var/log/radius/radacct/ImagineNet/detail-20090409
[detail.imaginenet] /var/log/radius/radacct/ImagineNet/detail-%Y%m%d expands to /var/log/radius/radacct/ImagineNet/detail-20090409
[detail.imaginenet] 	expand: %t -> Thu Apr  9 01:31:32 2009
[detail.imaginenet] Freeradius-Proxied-To = 192.168.78.115
++[detail.imaginenet] returns ok
 }
Sending Accounting-Response of id 130 to 192.168.70.210 port 1814
	Proxy-State = 0x3933
Finished request 10.
Cleaning up request 10 ID 130 with timestamp +513
Going to the next request
Waking up in 0.5 seconds.
Waking up in 1.1 seconds.
Waking up in 0.1 seconds.
Waking up in 0.8 seconds.
Waking up in 0.3 seconds.
Waking up in 0.1 seconds.
Waking up in 0.3 seconds.
Rejecting request 11 due to lack of any response from home server 192.168.78.115 port 1813
  Found Post-Proxy-Type 
 server ImagineNet {
+- entering group Fail {...}
[detail.imaginenet] Suppressing writes to detail file as the request was just read from a detail file.
++[detail.imaginenet] returns noop
 }
server ImagineNet {
} # server ImagineNet
Finished request 11.
Cleaning up request 11 ID 50828 with timestamp +517
Going to the next request
Waking up in 0.2 seconds.
Delaying next detail event for 1.0 seconds.
Waking up in 0.2 seconds.
detail_recv: Read packet from /var/log/radius/radacct/ImagineNet/detail.work
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 26
	Proxy-State = 0x3931
	Acct-Unique-Session-Id = "e35714fe059ff6c9"
	Realm = "linkup.us"
	Realm = "linkup.us"
	FreeRADIUS-Proxied-To = 192.168.78.115
	Packet-Original-Timestamp = "Apr  9 2009 01:31:16 EDT"
server ImagineNet {
+- entering group accounting {...}
++[control] returns noop
} # server ImagineNet
 server ImagineNet {
 }
Sending Accounting-Request of id 192 to 192.168.78.115 port 1813
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 26
	Proxy-State = 0x3931
	FreeRADIUS-Proxied-To = 192.168.78.115
	Proxy-State = 0x3631333634
Proxying request 13 to home server 192.168.78.115 port 1813
Sending Accounting-Request of id 192 to 192.168.78.115 port 1813
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 26
	Proxy-State = 0x3931
	FreeRADIUS-Proxied-To = 192.168.78.115
	Proxy-State = 0x3631333634
Going to the next request
Waking up in 0.5 seconds.
Waking up in 0.1 seconds.
Waking up in 0.2 seconds.
Rejecting request 12 due to lack of any response from home server 192.168.78.115 port 1813
  Found Post-Proxy-Type 
 server ImagineNet {
+- entering group Fail {...}
[detail.imaginenet] 	expand: /var/log/radius/radacct/ImagineNet/detail-%Y%m%d -> /var/log/radius/radacct/ImagineNet/detail-20090409
[detail.imaginenet] /var/log/radius/radacct/ImagineNet/detail-%Y%m%d expands to /var/log/radius/radacct/ImagineNet/detail-20090409
[detail.imaginenet] 	expand: %t -> Thu Apr  9 01:31:37 2009
[detail.imaginenet] Freeradius-Proxied-To = 192.168.78.115
++[detail.imaginenet] returns ok
 }
Sending Accounting-Response of id 131 to 192.168.70.210 port 1814
	Proxy-State = 0x3934
Finished request 12.
Cleaning up request 12 ID 131 with timestamp +518
Going to the next request
Waking up in 0.4 seconds.
rad_recv: Accounting-Request packet from host 192.168.78.28 port 1646, id=5, length=379
	Acct-Session-Id = "0000A0D3"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.195.151
	User-Name = "mrnobody"
	Acct-Authentic = RADIUS
	Cisco-AVPair = "connect-progress=LAN Ses Up"
	Cisco-AVPair = "nas-tx-speed=38666"
	Cisco-AVPair = "nas-rx-speed=24000"
	Acct-Session-Time = 4716
	Acct-Input-Octets = 1207845
	Acct-Output-Octets = 4225924
	Acct-Input-Packets = 6480
	Acct-Output-Packets = 5601
	Acct-Terminate-Cause = User-Request
	Cisco-AVPair = "disc-cause-ext=PPP Receive Term"
	Acct-Status-Type = Stop
	Calling-Station-Id = "2168899392"
	Called-Station-Id = "2165320001"
	NAS-Port-Type = Async
	Cisco-NAS-Port = "Async25"
	NAS-Port = 25
	Connect-Info = "38666/24000 V90/V44/LAPM (38666/19200)"
	Cisco-AVPair = "v92-info=V.92 MOH/No QC Requested/0/0"
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.78.28
	Acct-Delay-Time = 10
+- entering group preacct {...}
[preprocess] 	expand: %{User-Name} -> mrnobody
[preprocess] 	expand: %{Client-IP-Address} -> 192.168.78.28
[preprocess]   hints: Matched DEFAULT at 81
[preprocess] 	expand: %{User-Name}@imaginenet.net -> mrnobody@imaginenet.net
++[preprocess] returns ok
[acct_unique] Hashing 'NAS-Port = 25,Client-IP-Address = 192.168.78.28,NAS-IP-Address = 192.168.78.28,Acct-Session-Id = "0000A0D3",User-Name := "mrnobody@imaginenet.net"'
[acct_unique] Acct-Unique-Session-ID = "3804aa2367226613".
++[acct_unique] returns ok
[suffix] Looking up realm "imaginenet.net" for User-Name = "mrnobody@imaginenet.net"
[suffix] Found realm "imaginenet.net"
[suffix] Adding Realm = "imaginenet.net"
[suffix] Proxying request from user mrnobody to realm imaginenet.net
[suffix] Preparing to proxy accounting request to realm "imaginenet.net" 
++[suffix] returns updated
++[files] returns noop
+- entering group accounting {...}
[detail] 	expand: /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d -> /var/log/radius/radacct/192.168.78.28/detail-20090409
[detail] /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d expands to /var/log/radius/radacct/192.168.78.28/detail-20090409
[detail] 	expand: %t -> Thu Apr  9 01:31:44 2009
++[detail] returns ok
[attr_filter.accounting_response] 	expand: %{User-Name} -> mrnobody@imaginenet.net
 attr_filter: Matched entry DEFAULT at line 12
++[attr_filter.accounting_response] returns updated
 server ImagineNet {
 }
Sending Accounting-Request of id 46 to 192.168.78.115 port 1813
	Acct-Session-Id = "0000A0D3"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.195.151
	User-Name := "mrnobody@imaginenet.net"
	Acct-Authentic = RADIUS
	Cisco-AVPair = "connect-progress=LAN Ses Up"
	Cisco-AVPair = "nas-tx-speed=38666"
	Cisco-AVPair = "nas-rx-speed=24000"
	Acct-Session-Time = 4716
	Acct-Input-Octets = 1207845
	Acct-Output-Octets = 4225924
	Acct-Input-Packets = 6480
	Acct-Output-Packets = 5601
	Acct-Terminate-Cause = User-Request
	Cisco-AVPair = "disc-cause-ext=PPP Receive Term"
	Acct-Status-Type = Stop
	Calling-Station-Id = "2168899392"
	Called-Station-Id = "2165320001"
	NAS-Port-Type = Async
	Cisco-NAS-Port = "Async25"
	NAS-Port = 25
	Connect-Info = "38666/24000 V90/V44/LAPM (38666/19200)"
	Cisco-AVPair = "v92-info=V.92 MOH/No QC Requested/0/0"
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.78.28
	Acct-Delay-Time = 10
	Proxy-State = 0x35
Proxying request 14 to home server 192.168.78.115 port 1813
Sending Accounting-Request of id 46 to 192.168.78.115 port 1813
	Acct-Session-Id = "0000A0D3"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.195.151
	User-Name := "mrnobody@imaginenet.net"
	Acct-Authentic = RADIUS
	Cisco-AVPair = "connect-progress=LAN Ses Up"
	Cisco-AVPair = "nas-tx-speed=38666"
	Cisco-AVPair = "nas-rx-speed=24000"
	Acct-Session-Time = 4716
	Acct-Input-Octets = 1207845
	Acct-Output-Octets = 4225924
	Acct-Input-Packets = 6480
	Acct-Output-Packets = 5601
	Acct-Terminate-Cause = User-Request
	Cisco-AVPair = "disc-cause-ext=PPP Receive Term"
	Acct-Status-Type = Stop
	Calling-Station-Id = "2168899392"
	Called-Station-Id = "2165320001"
	NAS-Port-Type = Async
	Cisco-NAS-Port = "Async25"
	NAS-Port = 25
	Connect-Info = "38666/24000 V90/V44/LAPM (38666/19200)"
	Cisco-AVPair = "v92-info=V.92 MOH/No QC Requested/0/0"
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.78.28
	Acct-Delay-Time = 10
	Proxy-State = 0x35
Going to the next request
Waking up in 0.2 seconds.
Waking up in 0.2 seconds.
Waking up in 0.4 seconds.
Waking up in 0.1 seconds.
Waking up in 0.5 seconds.
Waking up in 0.2 seconds.
Waking up in 0.5 seconds.
Waking up in 0.2 seconds.
Waking up in 0.3 seconds.
Waking up in 0.4 seconds.
Waking up in 0.4 seconds.
Waking up in 0.1 seconds.
Waking up in 0.2 seconds.
Waking up in 0.2 seconds.
Rejecting request 13 due to lack of any response from home server 192.168.78.115 port 1813
  Found Post-Proxy-Type 
 server ImagineNet {
+- entering group Fail {...}
[detail.imaginenet] Suppressing writes to detail file as the request was just read from a detail file.
++[detail.imaginenet] returns noop
 }
server ImagineNet {
} # server ImagineNet
Finished request 13.
Cleaning up request 13 ID 61364 with timestamp +523
Going to the next request
Waking up in 0.2 seconds.
Delaying next detail event for 1.0 seconds.
Waking up in 0.2 seconds.
detail_recv: Read packet from /var/log/radius/radacct/ImagineNet/detail.work
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 32
	Proxy-State = 0x3931
	Acct-Unique-Session-Id = "e35714fe059ff6c9"
	Realm = "linkup.us"
	Realm = "linkup.us"
	FreeRADIUS-Proxied-To = 192.168.78.115
	Packet-Original-Timestamp = "Apr  9 2009 01:31:16 EDT"
server ImagineNet {
+- entering group accounting {...}
++[control] returns noop
} # server ImagineNet
 server ImagineNet {
 }
Sending Accounting-Request of id 146 to 192.168.78.115 port 1813
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 32
	Proxy-State = 0x3931
	FreeRADIUS-Proxied-To = 192.168.78.115
	Proxy-State = 0x3530343838
Proxying request 15 to home server 192.168.78.115 port 1813
Sending Accounting-Request of id 146 to 192.168.78.115 port 1813
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 32
	Proxy-State = 0x3931
	FreeRADIUS-Proxied-To = 192.168.78.115
	Proxy-State = 0x3530343838
Going to the next request
Waking up in 0.1 seconds.
rad_recv: Accounting-Request packet from host 192.168.78.28 port 1646, id=6, length=379
	Acct-Session-Id = "0000A0D3"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.195.151
	User-Name = "mrnobody"
	Acct-Authentic = RADIUS
	Cisco-AVPair = "connect-progress=LAN Ses Up"
	Cisco-AVPair = "nas-tx-speed=38666"
	Cisco-AVPair = "nas-rx-speed=24000"
	Acct-Session-Time = 4716
	Acct-Input-Octets = 1207845
	Acct-Output-Octets = 4225924
	Acct-Input-Packets = 6480
	Acct-Output-Packets = 5601
	Acct-Terminate-Cause = User-Request
	Cisco-AVPair = "disc-cause-ext=PPP Receive Term"
	Acct-Status-Type = Stop
	Calling-Station-Id = "2168899392"
	Called-Station-Id = "2165320001"
	NAS-Port-Type = Async
	Cisco-NAS-Port = "Async25"
	NAS-Port = 25
	Connect-Info = "38666/24000 V90/V44/LAPM (38666/19200)"
	Cisco-AVPair = "v92-info=V.92 MOH/No QC Requested/0/0"
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.78.28
	Acct-Delay-Time = 15
+- entering group preacct {...}
[preprocess] 	expand: %{User-Name} -> mrnobody
[preprocess] 	expand: %{Client-IP-Address} -> 192.168.78.28
[preprocess]   hints: Matched DEFAULT at 81
[preprocess] 	expand: %{User-Name}@imaginenet.net -> mrnobody@imaginenet.net
++[preprocess] returns ok
[acct_unique] Hashing 'NAS-Port = 25,Client-IP-Address = 192.168.78.28,NAS-IP-Address = 192.168.78.28,Acct-Session-Id = "0000A0D3",User-Name := "mrnobody@imaginenet.net"'
[acct_unique] Acct-Unique-Session-ID = "3804aa2367226613".
++[acct_unique] returns ok
[suffix] Looking up realm "imaginenet.net" for User-Name = "mrnobody@imaginenet.net"
[suffix] Found realm "imaginenet.net"
[suffix] Adding Realm = "imaginenet.net"
[suffix] Proxying request from user mrnobody to realm imaginenet.net
[suffix] Preparing to proxy accounting request to realm "imaginenet.net" 
++[suffix] returns updated
++[files] returns noop
+- entering group accounting {...}
[detail] 	expand: /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d -> /var/log/radius/radacct/192.168.78.28/detail-20090409
[detail] /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d expands to /var/log/radius/radacct/192.168.78.28/detail-20090409
[detail] 	expand: %t -> Thu Apr  9 01:31:49 2009
++[detail] returns ok
[attr_filter.accounting_response] 	expand: %{User-Name} -> mrnobody@imaginenet.net
 attr_filter: Matched entry DEFAULT at line 12
++[attr_filter.accounting_response] returns updated
 server ImagineNet {
 }
Sending Accounting-Request of id 53 to 192.168.78.115 port 1813
	Acct-Session-Id = "0000A0D3"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.195.151
	User-Name := "mrnobody@imaginenet.net"
	Acct-Authentic = RADIUS
	Cisco-AVPair = "connect-progress=LAN Ses Up"
	Cisco-AVPair = "nas-tx-speed=38666"
	Cisco-AVPair = "nas-rx-speed=24000"
	Acct-Session-Time = 4716
	Acct-Input-Octets = 1207845
	Acct-Output-Octets = 4225924
	Acct-Input-Packets = 6480
	Acct-Output-Packets = 5601
	Acct-Terminate-Cause = User-Request
	Cisco-AVPair = "disc-cause-ext=PPP Receive Term"
	Acct-Status-Type = Stop
	Calling-Station-Id = "2168899392"
	Called-Station-Id = "2165320001"
	NAS-Port-Type = Async
	Cisco-NAS-Port = "Async25"
	NAS-Port = 25
	Connect-Info = "38666/24000 V90/V44/LAPM (38666/19200)"
	Cisco-AVPair = "v92-info=V.92 MOH/No QC Requested/0/0"
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.78.28
	Acct-Delay-Time = 15
	Proxy-State = 0x36
Proxying request 16 to home server 192.168.78.115 port 1813
Sending Accounting-Request of id 53 to 192.168.78.115 port 1813
	Acct-Session-Id = "0000A0D3"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.195.151
	User-Name := "mrnobody@imaginenet.net"
	Acct-Authentic = RADIUS
	Cisco-AVPair = "connect-progress=LAN Ses Up"
	Cisco-AVPair = "nas-tx-speed=38666"
	Cisco-AVPair = "nas-rx-speed=24000"
	Acct-Session-Time = 4716
	Acct-Input-Octets = 1207845
	Acct-Output-Octets = 4225924
	Acct-Input-Packets = 6480
	Acct-Output-Packets = 5601
	Acct-Terminate-Cause = User-Request
	Cisco-AVPair = "disc-cause-ext=PPP Receive Term"
	Acct-Status-Type = Stop
	Calling-Station-Id = "2168899392"
	Called-Station-Id = "2165320001"
	NAS-Port-Type = Async
	Cisco-NAS-Port = "Async25"
	NAS-Port = 25
	Connect-Info = "38666/24000 V90/V44/LAPM (38666/19200)"
	Cisco-AVPair = "v92-info=V.92 MOH/No QC Requested/0/0"
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.78.28
	Acct-Delay-Time = 15
	Proxy-State = 0x36
Going to the next request
Waking up in 0.4 seconds.
Waking up in 0.2 seconds.
Waking up in 0.2 seconds.
Waking up in 0.4 seconds.
Rejecting request 14 due to lack of any response from home server 192.168.78.115 port 1813
  Found Post-Proxy-Type 
 server ImagineNet {
+- entering group Fail {...}
[detail.imaginenet] 	expand: /var/log/radius/radacct/ImagineNet/detail-%Y%m%d -> /var/log/radius/radacct/ImagineNet/detail-20090409
[detail.imaginenet] /var/log/radius/radacct/ImagineNet/detail-%Y%m%d expands to /var/log/radius/radacct/ImagineNet/detail-20090409
[detail.imaginenet] 	expand: %t -> Thu Apr  9 01:31:44 2009
[detail.imaginenet] Freeradius-Proxied-To = 192.168.78.115
++[detail.imaginenet] returns ok
 }
Sending Accounting-Response of id 5 to 192.168.78.28 port 1646
Finished request 14.
Cleaning up request 14 ID 5 with timestamp +525
Going to the next request
Waking up in 0.1 seconds.
Waking up in 0.1 seconds.
Waking up in 0.2 seconds.
Waking up in 0.6 seconds.
Waking up in 0.2 seconds.
Waking up in 0.2 seconds.
Sending Status-Server of id 212 to 192.168.78.115 port 1813
	Message-Authenticator := 0x00000000000000000000000000000000
	NAS-Identifier := "Status Check. Are you alive?"
Waking up in 0.5 seconds.
Waking up in 0.1 seconds.
Waking up in 0.1 seconds.
Waking up in 0.4 seconds.
Waking up in 0.3 seconds.
Waking up in 0.4 seconds.
Waking up in 0.1 seconds.
Waking up in 0.3 seconds.
Rejecting request 15 due to lack of any response from home server 192.168.78.115 port 1813
  Found Post-Proxy-Type 
 server ImagineNet {
+- entering group Fail {...}
[detail.imaginenet] Suppressing writes to detail file as the request was just read from a detail file.
++[detail.imaginenet] returns noop
 }
server ImagineNet {
} # server ImagineNet
Finished request 15.
Cleaning up request 15 ID 50488 with timestamp +529
Going to the next request
Delaying next detail event for 1.0 seconds.
detail_recv: Read packet from /var/log/radius/radacct/ImagineNet/detail.work
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 38
	Proxy-State = 0x3931
	Acct-Unique-Session-Id = "e35714fe059ff6c9"
	Realm = "linkup.us"
	Realm = "linkup.us"
	FreeRADIUS-Proxied-To = 192.168.78.115
	Packet-Original-Timestamp = "Apr  9 2009 01:31:16 EDT"
server ImagineNet {
+- entering group accounting {...}
++[control] returns noop
} # server ImagineNet
 server ImagineNet {
 }
Sending Accounting-Request of id 173 to 192.168.78.115 port 1813
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 38
	Proxy-State = 0x3931
	FreeRADIUS-Proxied-To = 192.168.78.115
	Proxy-State = 0x3438313232
Proxying request 18 to home server 192.168.78.115 port 1813
Sending Accounting-Request of id 173 to 192.168.78.115 port 1813
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 38
	Proxy-State = 0x3931
	FreeRADIUS-Proxied-To = 192.168.78.115
	Proxy-State = 0x3438313232
Going to the next request
Waking up in 0.3 seconds.
Rejecting request 16 due to lack of any response from home server 192.168.78.115 port 1813
  Found Post-Proxy-Type 
 server ImagineNet {
+- entering group Fail {...}
[detail.imaginenet] 	expand: /var/log/radius/radacct/ImagineNet/detail-%Y%m%d -> /var/log/radius/radacct/ImagineNet/detail-20090409
[detail.imaginenet] /var/log/radius/radacct/ImagineNet/detail-%Y%m%d expands to /var/log/radius/radacct/ImagineNet/detail-20090409
[detail.imaginenet] 	expand: %t -> Thu Apr  9 01:31:49 2009
[detail.imaginenet] Freeradius-Proxied-To = 192.168.78.115
++[detail.imaginenet] returns ok
 }
Sending Accounting-Response of id 6 to 192.168.78.28 port 1646
Finished request 16.
Cleaning up request 16 ID 6 with timestamp +530
Going to the next request
Waking up in 0.1 seconds.
Waking up in 0.1 seconds.
Waking up in 0.1 seconds.
Waking up in 0.1 seconds.
Waking up in 0.4 seconds.
Waking up in 0.1 seconds.
Waking up in 0.1 seconds.
Waking up in 0.1 seconds.
Waking up in 0.2 seconds.
Waking up in 0.3 seconds.
Waking up in 0.5 seconds.
Waking up in 0.1 seconds.
Waking up in 0.1 seconds.
Waking up in 0.3 seconds.
Waking up in 0.5 seconds.
Waking up in 0.3 seconds.
Waking up in 0.2 seconds.
Waking up in 0.4 seconds.
Waking up in 0.2 seconds.
Waking up in 0.1 seconds.
Rejecting request 18 due to lack of any response from home server 192.168.78.115 port 1813
  Found Post-Proxy-Type 
 server ImagineNet {
+- entering group Fail {...}
[detail.imaginenet] Suppressing writes to detail file as the request was just read from a detail file.
++[detail.imaginenet] returns noop
 }
server ImagineNet {
} # server ImagineNet
Finished request 18.
Cleaning up request 18 ID 48122 with timestamp +535
Going to the next request
Waking up in 0.3 seconds.
Delaying next detail event for 1.0 seconds.
Waking up in 0.3 seconds.
detail_recv: Read packet from /var/log/radius/radacct/ImagineNet/detail.work
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 45
	Proxy-State = 0x3931
	Acct-Unique-Session-Id = "e35714fe059ff6c9"
	Realm = "linkup.us"
	Realm = "linkup.us"
	FreeRADIUS-Proxied-To = 192.168.78.115
	Packet-Original-Timestamp = "Apr  9 2009 01:31:16 EDT"
server ImagineNet {
+- entering group accounting {...}
++[control] returns noop
} # server ImagineNet
 server ImagineNet {
 }
Sending Accounting-Request of id 16 to 192.168.78.115 port 1813
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 45
	Proxy-State = 0x3931
	FreeRADIUS-Proxied-To = 192.168.78.115
	Proxy-State = 0x3631313337
Proxying request 19 to home server 192.168.78.115 port 1813
Sending Accounting-Request of id 16 to 192.168.78.115 port 1813
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 45
	Proxy-State = 0x3931
	FreeRADIUS-Proxied-To = 192.168.78.115
	Proxy-State = 0x3631313337
Going to the next request
Waking up in 0.3 seconds.
Waking up in 0.1 seconds.
Waking up in 0.1 seconds.
Waking up in 0.2 seconds.
Waking up in 0.3 seconds.
Waking up in 0.3 seconds.
Waking up in 0.2 seconds.
Waking up in 0.6 seconds.
Waking up in 0.1 seconds.
rad_recv: Accounting-Request packet from host 192.168.78.28 port 1646, id=9, length=379
	Acct-Session-Id = "0000A0D3"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.195.151
	User-Name = "mrnobody"
	Acct-Authentic = RADIUS
	Cisco-AVPair = "connect-progress=LAN Ses Up"
	Cisco-AVPair = "nas-tx-speed=38666"
	Cisco-AVPair = "nas-rx-speed=24000"
	Acct-Session-Time = 4716
	Acct-Input-Octets = 1207845
	Acct-Output-Octets = 4225924
	Acct-Input-Packets = 6480
	Acct-Output-Packets = 5601
	Acct-Terminate-Cause = User-Request
	Cisco-AVPair = "disc-cause-ext=PPP Receive Term"
	Acct-Status-Type = Stop
	Calling-Station-Id = "2168899392"
	Called-Station-Id = "2165320001"
	NAS-Port-Type = Async
	Cisco-NAS-Port = "Async25"
	NAS-Port = 25
	Connect-Info = "38666/24000 V90/V44/LAPM (38666/19200)"
	Cisco-AVPair = "v92-info=V.92 MOH/No QC Requested/0/0"
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.78.28
	Acct-Delay-Time = 30
+- entering group preacct {...}
[preprocess] 	expand: %{User-Name} -> mrnobody
[preprocess] 	expand: %{Client-IP-Address} -> 192.168.78.28
[preprocess]   hints: Matched DEFAULT at 81
[preprocess] 	expand: %{User-Name}@imaginenet.net -> mrnobody@imaginenet.net
++[preprocess] returns ok
[acct_unique] Hashing 'NAS-Port = 25,Client-IP-Address = 192.168.78.28,NAS-IP-Address = 192.168.78.28,Acct-Session-Id = "0000A0D3",User-Name := "mrnobody@imaginenet.net"'
[acct_unique] Acct-Unique-Session-ID = "3804aa2367226613".
++[acct_unique] returns ok
[suffix] Looking up realm "imaginenet.net" for User-Name = "mrnobody@imaginenet.net"
[suffix] Found realm "imaginenet.net"
[suffix] Adding Realm = "imaginenet.net"
[suffix] Proxying request from user mrnobody to realm imaginenet.net
[suffix] Preparing to proxy accounting request to realm "imaginenet.net" 
++[suffix] returns updated
++[files] returns noop
+- entering group accounting {...}
[detail] 	expand: /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d -> /var/log/radius/radacct/192.168.78.28/detail-20090409
[detail] /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d expands to /var/log/radius/radacct/192.168.78.28/detail-20090409
[detail] 	expand: %t -> Thu Apr  9 01:32:04 2009
++[detail] returns ok
[attr_filter.accounting_response] 	expand: %{User-Name} -> mrnobody@imaginenet.net
 attr_filter: Matched entry DEFAULT at line 12
++[attr_filter.accounting_response] returns updated
 server ImagineNet {
 }
Sending Accounting-Request of id 173 to 192.168.78.115 port 1813
	Acct-Session-Id = "0000A0D3"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.195.151
	User-Name := "mrnobody@imaginenet.net"
	Acct-Authentic = RADIUS
	Cisco-AVPair = "connect-progress=LAN Ses Up"
	Cisco-AVPair = "nas-tx-speed=38666"
	Cisco-AVPair = "nas-rx-speed=24000"
	Acct-Session-Time = 4716
	Acct-Input-Octets = 1207845
	Acct-Output-Octets = 4225924
	Acct-Input-Packets = 6480
	Acct-Output-Packets = 5601
	Acct-Terminate-Cause = User-Request
	Cisco-AVPair = "disc-cause-ext=PPP Receive Term"
	Acct-Status-Type = Stop
	Calling-Station-Id = "2168899392"
	Called-Station-Id = "2165320001"
	NAS-Port-Type = Async
	Cisco-NAS-Port = "Async25"
	NAS-Port = 25
	Connect-Info = "38666/24000 V90/V44/LAPM (38666/19200)"
	Cisco-AVPair = "v92-info=V.92 MOH/No QC Requested/0/0"
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.78.28
	Acct-Delay-Time = 30
	Proxy-State = 0x39
Proxying request 20 to home server 192.168.78.115 port 1813
Sending Accounting-Request of id 173 to 192.168.78.115 port 1813
	Acct-Session-Id = "0000A0D3"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.195.151
	User-Name := "mrnobody@imaginenet.net"
	Acct-Authentic = RADIUS
	Cisco-AVPair = "connect-progress=LAN Ses Up"
	Cisco-AVPair = "nas-tx-speed=38666"
	Cisco-AVPair = "nas-rx-speed=24000"
	Acct-Session-Time = 4716
	Acct-Input-Octets = 1207845
	Acct-Output-Octets = 4225924
	Acct-Input-Packets = 6480
	Acct-Output-Packets = 5601
	Acct-Terminate-Cause = User-Request
	Cisco-AVPair = "disc-cause-ext=PPP Receive Term"
	Acct-Status-Type = Stop
	Calling-Station-Id = "2168899392"
	Called-Station-Id = "2165320001"
	NAS-Port-Type = Async
	Cisco-NAS-Port = "Async25"
	NAS-Port = 25
	Connect-Info = "38666/24000 V90/V44/LAPM (38666/19200)"
	Cisco-AVPair = "v92-info=V.92 MOH/No QC Requested/0/0"
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.78.28
	Acct-Delay-Time = 30
	Proxy-State = 0x39
Going to the next request
Waking up in 0.1 seconds.
Waking up in 0.2 seconds.
Waking up in 0.4 seconds.
Waking up in 0.2 seconds.
Waking up in 0.1 seconds.
Waking up in 0.2 seconds.
Waking up in 0.3 seconds.
Waking up in 0.2 seconds.
Waking up in 0.2 seconds.
Waking up in 0.1 seconds.
Waking up in 0.4 seconds.
Rejecting request 19 due to lack of any response from home server 192.168.78.115 port 1813
PROXY: Marking home server 192.168.78.115 port 1813 as dead.
  Found Post-Proxy-Type 
 server ImagineNet {
+- entering group Fail {...}
[detail.imaginenet] Suppressing writes to detail file as the request was just read from a detail file.
++[detail.imaginenet] returns noop
 }
server ImagineNet {
} # server ImagineNet
Finished request 19.
Cleaning up request 19 ID 61137 with timestamp +542
Going to the next request
detail_recv: Read packet from /var/log/radius/radacct/ImagineNet/detail.work
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 51
	Proxy-State = 0x3931
	Acct-Unique-Session-Id = "e35714fe059ff6c9"
	Realm = "linkup.us"
	Realm = "linkup.us"
	FreeRADIUS-Proxied-To = 192.168.78.115
	Packet-Original-Timestamp = "Apr  9 2009 01:31:16 EDT"
server ImagineNet {
+- entering group accounting {...}
++[control] returns noop
} # server ImagineNet
 server ImagineNet {
 }
>>> Sending proxied request internally to virtual server.
server acct_detail.imaginenet {
+- entering group preacct {...}
[attr_filter.imaginenet] 	expand: %{User-Name} -> johndoe@linkup.us
 attr_filter: Matched entry DEFAULT at line 1
++[attr_filter.imaginenet] returns updated
+- entering group accounting {...}
[detail] Suppressing writes to detail file as the request was just read from a detail file.
++[detail] returns noop
[detail.imaginenet] Suppressing writes to detail file as the request was just read from a detail file.
++[detail.imaginenet] returns noop
} # server acct_detail.imaginenet
Going to the next request
<<< Received proxied response from internal virtual server.
 server ImagineNet {
 }
Finished request 21.
Cleaning up request 21 ID 6021 with timestamp +548
Going to the next request
Waking up in 0.1 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
detail_recv: Renaming /var/log/radius/radacct/ImagineNet/detail-20090409 -> /var/log/radius/radacct/ImagineNet/detail.work
detail_recv: Read packet from /var/log/radius/radacct/ImagineNet/detail.work
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 51
	Proxy-State = 0x3932
	Acct-Unique-Session-Id = "e35714fe059ff6c9"
	Realm = "linkup.us"
	Realm = "linkup.us"
	FreeRADIUS-Proxied-To = 192.168.78.115
	Packet-Original-Timestamp = "Apr  9 2009 01:31:21 EDT"
server ImagineNet {
+- entering group accounting {...}
++[control] returns noop
} # server ImagineNet
 server ImagineNet {
 }
>>> Sending proxied request internally to virtual server.
server acct_detail.imaginenet {
+- entering group preacct {...}
[attr_filter.imaginenet] 	expand: %{User-Name} -> johndoe@linkup.us
 attr_filter: Matched entry DEFAULT at line 1
++[attr_filter.imaginenet] returns updated
+- entering group accounting {...}
[detail] Suppressing writes to detail file as the request was just read from a detail file.
++[detail] returns noop
[detail.imaginenet] Suppressing writes to detail file as the request was just read from a detail file.
++[detail.imaginenet] returns noop
} # server acct_detail.imaginenet
Going to the next request
<<< Received proxied response from internal virtual server.
 server ImagineNet {
 }
Finished request 22.
Cleaning up request 22 ID 13363 with timestamp +548
Going to the next request
Waking up in 0.1 seconds.
detail_recv: Read packet from /var/log/radius/radacct/ImagineNet/detail.work
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 50
	Proxy-State = 0x3931
	Acct-Unique-Session-Id = "e35714fe059ff6c9"
	Realm = "linkup.us"
	Realm = "linkup.us"
	FreeRADIUS-Proxied-To = 192.168.78.115
	Packet-Original-Timestamp = "Apr  9 2009 01:31:22 EDT"
server ImagineNet {
+- entering group accounting {...}
++[control] returns noop
} # server ImagineNet
 server ImagineNet {
 }
>>> Sending proxied request internally to virtual server.
server acct_detail.imaginenet {
+- entering group preacct {...}
[attr_filter.imaginenet] 	expand: %{User-Name} -> johndoe@linkup.us
 attr_filter: Matched entry DEFAULT at line 1
++[attr_filter.imaginenet] returns updated
+- entering group accounting {...}
[detail] Suppressing writes to detail file as the request was just read from a detail file.
++[detail] returns noop
[detail.imaginenet] Suppressing writes to detail file as the request was just read from a detail file.
++[detail.imaginenet] returns noop
} # server acct_detail.imaginenet
Going to the next request
<<< Received proxied response from internal virtual server.
 server ImagineNet {
 }
Finished request 23.
Cleaning up request 23 ID 51182 with timestamp +548
Going to the next request
Waking up in 0.1 seconds.
detail_recv: Read packet from /var/log/radius/radacct/ImagineNet/detail.work
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 51
	Proxy-State = 0x3933
	Acct-Unique-Session-Id = "e35714fe059ff6c9"
	Realm = "linkup.us"
	Realm = "linkup.us"
	FreeRADIUS-Proxied-To = 192.168.78.115
	Packet-Original-Timestamp = "Apr  9 2009 01:31:26 EDT"
server ImagineNet {
+- entering group accounting {...}
++[control] returns noop
} # server ImagineNet
 server ImagineNet {
 }
>>> Sending proxied request internally to virtual server.
server acct_detail.imaginenet {
+- entering group preacct {...}
[attr_filter.imaginenet] 	expand: %{User-Name} -> johndoe@linkup.us
 attr_filter: Matched entry DEFAULT at line 1
++[attr_filter.imaginenet] returns updated
+- entering group accounting {...}
[detail] Suppressing writes to detail file as the request was just read from a detail file.
++[detail] returns noop
[detail.imaginenet] Suppressing writes to detail file as the request was just read from a detail file.
++[detail.imaginenet] returns noop
} # server acct_detail.imaginenet
Going to the next request
<<< Received proxied response from internal virtual server.
 server ImagineNet {
 }
Finished request 24.
Cleaning up request 24 ID 46119 with timestamp +548
Going to the next request
Waking up in 0.1 seconds.
detail_recv: Read packet from /var/log/radius/radacct/ImagineNet/detail.work
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 50
	Proxy-State = 0x3932
	Acct-Unique-Session-Id = "e35714fe059ff6c9"
	Realm = "linkup.us"
	Realm = "linkup.us"
	FreeRADIUS-Proxied-To = 192.168.78.115
	Packet-Original-Timestamp = "Apr  9 2009 01:31:27 EDT"
server ImagineNet {
+- entering group accounting {...}
++[control] returns noop
} # server ImagineNet
 server ImagineNet {
 }
>>> Sending proxied request internally to virtual server.
server acct_detail.imaginenet {
+- entering group preacct {...}
[attr_filter.imaginenet] 	expand: %{User-Name} -> johndoe@linkup.us
 attr_filter: Matched entry DEFAULT at line 1
++[attr_filter.imaginenet] returns updated
+- entering group accounting {...}
[detail] Suppressing writes to detail file as the request was just read from a detail file.
++[detail] returns noop
[detail.imaginenet] Suppressing writes to detail file as the request was just read from a detail file.
++[detail.imaginenet] returns noop
} # server acct_detail.imaginenet
Going to the next request
<<< Received proxied response from internal virtual server.
 server ImagineNet {
 }
Finished request 25.
Cleaning up request 25 ID 32165 with timestamp +548
Going to the next request
detail_recv: Read packet from /var/log/radius/radacct/ImagineNet/detail.work
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 51
	Proxy-State = 0x3934
	Acct-Unique-Session-Id = "e35714fe059ff6c9"
	Realm = "linkup.us"
	Realm = "linkup.us"
	FreeRADIUS-Proxied-To = 192.168.78.115
	Packet-Original-Timestamp = "Apr  9 2009 01:31:31 EDT"
server ImagineNet {
+- entering group accounting {...}
++[control] returns noop
} # server ImagineNet
 server ImagineNet {
 }
>>> Sending proxied request internally to virtual server.
server acct_detail.imaginenet {
+- entering group preacct {...}
[attr_filter.imaginenet] 	expand: %{User-Name} -> johndoe@linkup.us
 attr_filter: Matched entry DEFAULT at line 1
++[attr_filter.imaginenet] returns updated
+- entering group accounting {...}
[detail] Suppressing writes to detail file as the request was just read from a detail file.
++[detail] returns noop
[detail.imaginenet] Suppressing writes to detail file as the request was just read from a detail file.
++[detail.imaginenet] returns noop
} # server acct_detail.imaginenet
Going to the next request
<<< Received proxied response from internal virtual server.
 server ImagineNet {
 }
Finished request 26.
Cleaning up request 26 ID 5779 with timestamp +548
Going to the next request
detail_recv: Read packet from /var/log/radius/radacct/ImagineNet/detail.work
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 50
	Proxy-State = 0x3933
	Acct-Unique-Session-Id = "e35714fe059ff6c9"
	Realm = "linkup.us"
	Realm = "linkup.us"
	FreeRADIUS-Proxied-To = 192.168.78.115
	Packet-Original-Timestamp = "Apr  9 2009 01:31:32 EDT"
server ImagineNet {
+- entering group accounting {...}
++[control] returns noop
} # server ImagineNet
 server ImagineNet {
 }
>>> Sending proxied request internally to virtual server.
server acct_detail.imaginenet {
+- entering group preacct {...}
[attr_filter.imaginenet] 	expand: %{User-Name} -> johndoe@linkup.us
 attr_filter: Matched entry DEFAULT at line 1
++[attr_filter.imaginenet] returns updated
+- entering group accounting {...}
[detail] Suppressing writes to detail file as the request was just read from a detail file.
++[detail] returns noop
[detail.imaginenet] Suppressing writes to detail file as the request was just read from a detail file.
++[detail.imaginenet] returns noop
} # server acct_detail.imaginenet
Going to the next request
<<< Received proxied response from internal virtual server.
 server ImagineNet {
 }
Finished request 27.
Cleaning up request 27 ID 13253 with timestamp +548
Going to the next request
detail_recv: Read packet from /var/log/radius/radacct/ImagineNet/detail.work
	Acct-Session-Id = "0007EF12"
	Tunnel-Server-Endpoint:0 = "10.18.225.5"
	Tunnel-Client-Endpoint:0 = "10.18.225.6"
	Tunnel-Assignment-Id:0 = "225"
	Tunnel-Type:0 = L2TP
	Acct-Tunnel-Connection = "6643470"
	Tunnel-Client-Auth-Id:0 = "clev6225rr.oh.AADS"
	Tunnel-Server-Auth-Id:0 = "XXXXXXXX"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.209.47
	User-Name = "johndoe@linkup.us"
	Acct-Authentic = RADIUS
	Acct-Status-Type = Start
	NAS-Port-Type = Virtual
	NAS-Port = 31
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.64.57
	Acct-Delay-Time = 50
	Proxy-State = 0x3934
	Acct-Unique-Session-Id = "e35714fe059ff6c9"
	Realm = "linkup.us"
	Realm = "linkup.us"
	FreeRADIUS-Proxied-To = 192.168.78.115
	Packet-Original-Timestamp = "Apr  9 2009 01:31:37 EDT"
server ImagineNet {
+- entering group accounting {...}
++[control] returns noop
} # server ImagineNet
 server ImagineNet {
 }
>>> Sending proxied request internally to virtual server.
server acct_detail.imaginenet {
+- entering group preacct {...}
[attr_filter.imaginenet] 	expand: %{User-Name} -> johndoe@linkup.us
 attr_filter: Matched entry DEFAULT at line 1
++[attr_filter.imaginenet] returns updated
+- entering group accounting {...}
[detail] Suppressing writes to detail file as the request was just read from a detail file.
++[detail] returns noop
[detail.imaginenet] Suppressing writes to detail file as the request was just read from a detail file.
++[detail.imaginenet] returns noop
} # server acct_detail.imaginenet
Going to the next request
<<< Received proxied response from internal virtual server.
 server ImagineNet {
 }
Finished request 28.
Cleaning up request 28 ID 29688 with timestamp +548
Going to the next request
detail_recv: Read packet from /var/log/radius/radacct/ImagineNet/detail.work
	Acct-Session-Id = "0000A0D3"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.195.151
	Acct-Authentic = RADIUS
	Cisco-AVPair = "connect-progress=LAN Ses Up"
	Cisco-AVPair = "nas-tx-speed=38666"
	Cisco-AVPair = "nas-rx-speed=24000"
	Acct-Session-Time = 4716
	Acct-Input-Octets = 1207845
	Acct-Output-Octets = 4225924
	Acct-Input-Packets = 6480
	Acct-Output-Packets = 5601
	Acct-Terminate-Cause = User-Request
	Cisco-AVPair = "disc-cause-ext=PPP Receive Term"
	Acct-Status-Type = Stop
	Calling-Station-Id = "2168899392"
	Called-Station-Id = "2165320001"
	NAS-Port-Type = Async
	Cisco-NAS-Port = "Async25"
	NAS-Port = 25
	Connect-Info = "38666/24000 V90/V44/LAPM (38666/19200)"
	Cisco-AVPair = "v92-info=V.92 MOH/No QC Requested/0/0"
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.78.28
	Acct-Delay-Time = 33
	Acct-Unique-Session-Id = "3804aa2367226613"
	Realm = "imaginenet.net"
	Realm = "imaginenet.net"
	FreeRADIUS-Proxied-To = 192.168.78.115
	Packet-Original-Timestamp = "Apr  9 2009 01:31:44 EDT"
server ImagineNet {
+- entering group accounting {...}
++[control] returns noop
} # server ImagineNet
 server ImagineNet {
 }
>>> Sending proxied request internally to virtual server.
server acct_detail.imaginenet {
+- entering group preacct {...}
[attr_filter.imaginenet] 	expand: %{User-Name} -> 
++[attr_filter.imaginenet] returns noop
+- entering group accounting {...}
[detail] Suppressing writes to detail file as the request was just read from a detail file.
++[detail] returns noop
[detail.imaginenet] Suppressing writes to detail file as the request was just read from a detail file.
++[detail.imaginenet] returns noop
} # server acct_detail.imaginenet
Going to the next request
<<< Received proxied response from internal virtual server.
 server ImagineNet {
 }
Finished request 29.
Cleaning up request 29 ID 11377 with timestamp +548
Going to the next request
detail_recv: Read packet from /var/log/radius/radacct/ImagineNet/detail.work
	Acct-Session-Id = "0000A0D3"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.195.151
	Acct-Authentic = RADIUS
	Cisco-AVPair = "connect-progress=LAN Ses Up"
	Cisco-AVPair = "nas-tx-speed=38666"
	Cisco-AVPair = "nas-rx-speed=24000"
	Acct-Session-Time = 4716
	Acct-Input-Octets = 1207845
	Acct-Output-Octets = 4225924
	Acct-Input-Packets = 6480
	Acct-Output-Packets = 5601
	Acct-Terminate-Cause = User-Request
	Cisco-AVPair = "disc-cause-ext=PPP Receive Term"
	Acct-Status-Type = Stop
	Calling-Station-Id = "2168899392"
	Called-Station-Id = "2165320001"
	NAS-Port-Type = Async
	Cisco-NAS-Port = "Async25"
	NAS-Port = 25
	Connect-Info = "38666/24000 V90/V44/LAPM (38666/19200)"
	Cisco-AVPair = "v92-info=V.92 MOH/No QC Requested/0/0"
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.78.28
	Acct-Delay-Time = 33
	Acct-Unique-Session-Id = "3804aa2367226613"
	Realm = "imaginenet.net"
	Realm = "imaginenet.net"
	FreeRADIUS-Proxied-To = 192.168.78.115
	Packet-Original-Timestamp = "Apr  9 2009 01:31:49 EDT"
server ImagineNet {
+- entering group accounting {...}
++[control] returns noop
} # server ImagineNet
 server ImagineNet {
 }
>>> Sending proxied request internally to virtual server.
server acct_detail.imaginenet {
+- entering group preacct {...}
[attr_filter.imaginenet] 	expand: %{User-Name} -> 
++[attr_filter.imaginenet] returns noop
+- entering group accounting {...}
[detail] Suppressing writes to detail file as the request was just read from a detail file.
++[detail] returns noop
[detail.imaginenet] Suppressing writes to detail file as the request was just read from a detail file.
++[detail.imaginenet] returns noop
} # server acct_detail.imaginenet
Going to the next request
<<< Received proxied response from internal virtual server.
 server ImagineNet {
 }
Finished request 30.
Cleaning up request 30 ID 42377 with timestamp +548
Going to the next request
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Waking up in 0.2 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Waking up in 0.2 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Waking up in 0.2 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Waking up in 0.1 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Waking up in 0.1 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Waking up in 0.1 seconds.
rad_recv: Accounting-Request packet from host 192.168.78.28 port 1646, id=10, length=379
	Acct-Session-Id = "0000A0D3"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.195.151
	User-Name = "mrnobody"
	Acct-Authentic = RADIUS
	Cisco-AVPair = "connect-progress=LAN Ses Up"
	Cisco-AVPair = "nas-tx-speed=38666"
	Cisco-AVPair = "nas-rx-speed=24000"
	Acct-Session-Time = 4716
	Acct-Input-Octets = 1207845
	Acct-Output-Octets = 4225924
	Acct-Input-Packets = 6480
	Acct-Output-Packets = 5601
	Acct-Terminate-Cause = User-Request
	Cisco-AVPair = "disc-cause-ext=PPP Receive Term"
	Acct-Status-Type = Stop
	Calling-Station-Id = "2168899392"
	Called-Station-Id = "2165320001"
	NAS-Port-Type = Async
	Cisco-NAS-Port = "Async25"
	NAS-Port = 25
	Connect-Info = "38666/24000 V90/V44/LAPM (38666/19200)"
	Cisco-AVPair = "v92-info=V.92 MOH/No QC Requested/0/0"
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.78.28
	Acct-Delay-Time = 35
+- entering group preacct {...}
[preprocess] 	expand: %{User-Name} -> mrnobody
[preprocess] 	expand: %{Client-IP-Address} -> 192.168.78.28
[preprocess]   hints: Matched DEFAULT at 81
[preprocess] 	expand: %{User-Name}@imaginenet.net -> mrnobody@imaginenet.net
++[preprocess] returns ok
[acct_unique] Hashing 'NAS-Port = 25,Client-IP-Address = 192.168.78.28,NAS-IP-Address = 192.168.78.28,Acct-Session-Id = "0000A0D3",User-Name := "mrnobody@imaginenet.net"'
[acct_unique] Acct-Unique-Session-ID = "3804aa2367226613".
++[acct_unique] returns ok
[suffix] Looking up realm "imaginenet.net" for User-Name = "mrnobody@imaginenet.net"
[suffix] Found realm "imaginenet.net"
[suffix] Adding Realm = "imaginenet.net"
[suffix] Proxying request from user mrnobody to realm imaginenet.net
[suffix] Preparing to proxy accounting request to realm "imaginenet.net" 
++[suffix] returns updated
++[files] returns noop
+- entering group accounting {...}
[detail] 	expand: /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d -> /var/log/radius/radacct/192.168.78.28/detail-20090409
[detail] /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d expands to /var/log/radius/radacct/192.168.78.28/detail-20090409
[detail] 	expand: %t -> Thu Apr  9 01:32:09 2009
++[detail] returns ok
[attr_filter.accounting_response] 	expand: %{User-Name} -> mrnobody@imaginenet.net
 attr_filter: Matched entry DEFAULT at line 12
++[attr_filter.accounting_response] returns updated
 server ImagineNet {
 }
>>> Sending proxied request internally to virtual server.
server acct_detail.imaginenet {
+- entering group preacct {...}
[attr_filter.imaginenet] 	expand: %{User-Name} -> mrnobody@imaginenet.net
 attr_filter: Matched entry DEFAULT at line 1
++[attr_filter.imaginenet] returns updated
+- entering group accounting {...}
[detail] 	expand: /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d -> /var/log/radius/radacct/192.168.78.28/detail-20090409
[detail] /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d expands to /var/log/radius/radacct/192.168.78.28/detail-20090409
[detail] 	expand: %t -> Thu Apr  9 01:32:09 2009
++[detail] returns ok
[detail.imaginenet] 	expand: /var/log/radius/radacct/ImagineNet/detail-%Y%m%d -> /var/log/radius/radacct/ImagineNet/detail-20090409
[detail.imaginenet] /var/log/radius/radacct/ImagineNet/detail-%Y%m%d expands to /var/log/radius/radacct/ImagineNet/detail-20090409
[detail.imaginenet] 	expand: %t -> Thu Apr  9 01:32:09 2009
++[detail.imaginenet] returns ok
} # server acct_detail.imaginenet
Going to the next request
<<< Received proxied response from internal virtual server.
 server ImagineNet {
 }
Sending Accounting-Response of id 10 to 192.168.78.28 port 1646
Finished request 31.
Cleaning up request 31 ID 10 with timestamp +550
Going to the next request
Waking up in 0.1 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
detail_recv: Renaming /var/log/radius/radacct/ImagineNet/detail-20090409 -> /var/log/radius/radacct/ImagineNet/detail.work
detail_recv: Read packet from /var/log/radius/radacct/ImagineNet/detail.work
	Acct-Session-Id = "0000A0D3"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.195.151
	Acct-Session-Time = 4716
	Acct-Input-Octets = 1207845
	Acct-Output-Octets = 4225924
	Acct-Input-Packets = 6480
	Acct-Output-Packets = 5601
	Acct-Terminate-Cause = User-Request
	Acct-Status-Type = Stop
	Calling-Station-Id = "2168899392"
	Called-Station-Id = "2165320001"
	NAS-Port = 25
	Connect-Info = "38666/24000 V90/V44/LAPM (38666/19200)"
	NAS-IP-Address = 192.168.78.28
	Proxy-State = 0x3130
	Packet-Original-Timestamp = "Apr  9 2009 01:32:09 EDT"
	Acct-Delay-Time = 0
server ImagineNet {
+- entering group accounting {...}
++[control] returns noop
} # server ImagineNet
 server ImagineNet {
 }
>>> Sending proxied request internally to virtual server.
server acct_detail.imaginenet {
+- entering group preacct {...}
[attr_filter.imaginenet] 	expand: %{User-Name} -> 
++[attr_filter.imaginenet] returns noop
+- entering group accounting {...}
[detail] Suppressing writes to detail file as the request was just read from a detail file.
++[detail] returns noop
[detail.imaginenet] Suppressing writes to detail file as the request was just read from a detail file.
++[detail.imaginenet] returns noop
} # server acct_detail.imaginenet
Going to the next request
<<< Received proxied response from internal virtual server.
 server ImagineNet {
 }
Finished request 32.
Cleaning up request 32 ID 26652 with timestamp +550
Going to the next request
Waking up in 0.4 seconds.
Waking up in 0.4 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Waking up in 0.1 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Waking up in 0.1 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Rejecting request 20 due to lack of any response from home server 192.168.78.115 port 1813
  Found Post-Proxy-Type 
 server ImagineNet {
+- entering group Fail {...}
[detail.imaginenet] 	expand: /var/log/radius/radacct/ImagineNet/detail-%Y%m%d -> /var/log/radius/radacct/ImagineNet/detail-20090409
[detail.imaginenet] /var/log/radius/radacct/ImagineNet/detail-%Y%m%d expands to /var/log/radius/radacct/ImagineNet/detail-20090409
[detail.imaginenet] 	expand: %t -> Thu Apr  9 01:32:04 2009
[detail.imaginenet] Freeradius-Proxied-To = 192.168.78.115
++[detail.imaginenet] returns ok
 }
Sending Accounting-Response of id 9 to 192.168.78.28 port 1646
Finished request 20.
Cleaning up request 20 ID 9 with timestamp +545
Going to the next request
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
detail_recv: Renaming /var/log/radius/radacct/ImagineNet/detail-20090409 -> /var/log/radius/radacct/ImagineNet/detail.work
detail_recv: Read packet from /var/log/radius/radacct/ImagineNet/detail.work
	Acct-Session-Id = "0000A0D3"
	Framed-Protocol = PPP
	Framed-IP-Address = 192.168.195.151
	Acct-Authentic = RADIUS
	Cisco-AVPair = "connect-progress=LAN Ses Up"
	Cisco-AVPair = "nas-tx-speed=38666"
	Cisco-AVPair = "nas-rx-speed=24000"
	Acct-Session-Time = 4716
	Acct-Input-Octets = 1207845
	Acct-Output-Octets = 4225924
	Acct-Input-Packets = 6480
	Acct-Output-Packets = 5601
	Acct-Terminate-Cause = User-Request
	Cisco-AVPair = "disc-cause-ext=PPP Receive Term"
	Acct-Status-Type = Stop
	Calling-Station-Id = "2168899392"
	Called-Station-Id = "2165320001"
	NAS-Port-Type = Async
	Cisco-NAS-Port = "Async25"
	NAS-Port = 25
	Connect-Info = "38666/24000 V90/V44/LAPM (38666/19200)"
	Cisco-AVPair = "v92-info=V.92 MOH/No QC Requested/0/0"
	Service-Type = Framed-User
	NAS-IP-Address = 192.168.78.28
	Acct-Delay-Time = 36
	Acct-Unique-Session-Id = "3804aa2367226613"
	Realm = "imaginenet.net"
	Realm = "imaginenet.net"
	FreeRADIUS-Proxied-To = 192.168.78.115
	Packet-Original-Timestamp = "Apr  9 2009 01:32:04 EDT"
server ImagineNet {
+- entering group accounting {...}
++[control] returns noop
} # server ImagineNet
 server ImagineNet {
 }
>>> Sending proxied request internally to virtual server.
server acct_detail.imaginenet {
+- entering group preacct {...}
[attr_filter.imaginenet] 	expand: %{User-Name} -> 
++[attr_filter.imaginenet] returns noop
+- entering group accounting {...}
[detail] Suppressing writes to detail file as the request was just read from a detail file.
++[detail] returns noop
[detail.imaginenet] Suppressing writes to detail file as the request was just read from a detail file.
++[detail.imaginenet] returns noop
} # server acct_detail.imaginenet
Going to the next request
<<< Received proxied response from internal virtual server.
 server ImagineNet {
 }
Finished request 33.
Cleaning up request 33 ID 54810 with timestamp +551
Going to the next request
Waking up in 0.1 seconds.
Waking up in 0.1 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Waking up in 0.1 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Waking up in 0.4 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Waking up in 0.1 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Waking up in 0.1 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Waking up in 0.4 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Waking up in 0.2 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Waking up in 0.1 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Waking up in 0.1 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Waking up in 0.1 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Waking up in 0.2 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Waking up in 0.1 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Waking up in 0.2 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Waking up in 0.3 seconds.
Polling for detail file /var/log/radius/radacct/ImagineNet/detail-*
Waking up in 0.1 seconds.
