FreeRADIUS Version 2.1.8, for host amd64-portbld-freebsd7.2, built on May  3 2010 at 13:08:56
Copyright (C) 1999-2009 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 /usr/local/etc/raddb/radiusd.conf
including configuration file /usr/local/etc/raddb/proxy.conf
including configuration file /usr/local/etc/raddb/clients.conf
including files in directory /usr/local/etc/raddb/modules/
including configuration file /usr/local/etc/raddb/modules/wimax
including configuration file /usr/local/etc/raddb/modules/always
including configuration file /usr/local/etc/raddb/modules/attr_filter
including configuration file /usr/local/etc/raddb/modules/attr_rewrite
including configuration file /usr/local/etc/raddb/modules/chap
including configuration file /usr/local/etc/raddb/modules/checkval
including configuration file /usr/local/etc/raddb/modules/counter
including configuration file /usr/local/etc/raddb/modules/detail
including configuration file /usr/local/etc/raddb/modules/detail.example.com
including configuration file /usr/local/etc/raddb/modules/detail.log
including configuration file /usr/local/etc/raddb/modules/digest
including configuration file /usr/local/etc/raddb/modules/echo
including configuration file /usr/local/etc/raddb/modules/etc_group
including configuration file /usr/local/etc/raddb/modules/exec
including configuration file /usr/local/etc/raddb/modules/expiration
including configuration file /usr/local/etc/raddb/modules/expr
including configuration file /usr/local/etc/raddb/modules/files
including configuration file /usr/local/etc/raddb/modules/inner-eap
including configuration file /usr/local/etc/raddb/modules/ippool
including configuration file /usr/local/etc/raddb/modules/krb5
including configuration file /usr/local/etc/raddb/modules/ldap
including configuration file /usr/local/etc/raddb/modules/linelog
including configuration file /usr/local/etc/raddb/modules/logintime
including configuration file /usr/local/etc/raddb/modules/mac2ip
including configuration file /usr/local/etc/raddb/modules/mac2vlan
including configuration file /usr/local/etc/raddb/modules/mschap
including configuration file /usr/local/etc/raddb/modules/otp
including configuration file /usr/local/etc/raddb/modules/pam
including configuration file /usr/local/etc/raddb/modules/pap
including configuration file /usr/local/etc/raddb/modules/passwd
including configuration file /usr/local/etc/raddb/modules/perl
including configuration file /usr/local/etc/raddb/modules/policy
including configuration file /usr/local/etc/raddb/modules/preprocess
including configuration file /usr/local/etc/raddb/modules/radutmp
including configuration file /usr/local/etc/raddb/modules/realm
including configuration file /usr/local/etc/raddb/modules/smbpasswd
including configuration file /usr/local/etc/raddb/modules/smsotp
including configuration file /usr/local/etc/raddb/modules/sql_log
including configuration file /usr/local/etc/raddb/modules/sqlcounter_expire_on_login
including configuration file /usr/local/etc/raddb/modules/sradutmp
including configuration file /usr/local/etc/raddb/modules/unix
including configuration file /usr/local/etc/raddb/modules/acct_unique
including configuration file /usr/local/etc/raddb/eap.conf
including configuration file /usr/local/etc/raddb/policy.conf
including files in directory /usr/local/etc/raddb/sites-enabled/
including configuration file /usr/local/etc/raddb/sites-enabled/default
main {
	user = "freeradius"
	group = "freeradius"
	allow_core_dumps = no
}
including dictionary file /usr/local/etc/raddb/dictionary
main {
	prefix = "/usr/local"
	localstatedir = "/var"
	logdir = "/var/log/radius"
	libdir = "/usr/local/lib/freeradius-2.1.8"
	radacctdir = "/var/log/radius/radacct"
	hostname_lookups = no
	max_request_time = 30
	cleanup_delay = 5
	max_requests = 1024
	pidfile = "/var/run/radiusd/radiusd.pid"
	checkrad = "/usr/local/sbin/checkrad"
	debug_level = 0
	proxy_requests = yes
 log {
	stripped_names = no
	auth = no
	auth_badpass = no
	auth_goodpass = no
 }
 security {
	max_attributes = 200
	reject_delay = 1
	status_server = yes
 }
}
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 {
 }
radiusd: #### Loading Clients ####
 client 10.11.8.17 {
	require_message_authenticator = no
	secret = "dkjf8972"
	shortname = "vpn_shadow"
 }
 client 10.11.19.1 {
	require_message_authenticator = no
	secret = "kl;as;djkf"
	shortname = "main"
 }
 client localhost {
	ipaddr = 127.0.0.1
	require_message_authenticator = no
	secret = "testing123"
	nastype = "other"
 }
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 {
 modules {
 Module: Checking authenticate {...} for more modules to load
 Module: Linked to module rlm_pap
 Module: Instantiating pap
  pap {
	encryption_scheme = "auto"
	auto_header = no
  }
 Module: Linked to module rlm_chap
 Module: Instantiating chap
 Module: Linked to module rlm_mschap
 Module: Instantiating mschap
  mschap {
	use_mppe = yes
	require_encryption = no
	require_strong = no
	with_ntdomain_hack = no
  }
 Module: Linked to module rlm_perl
 Module: Instantiating kes
  perl kes {
	module = "/usr/local/etc/raddb/kes.pl"
	func_authorize = "authorize"
	func_authenticate = "authenticate"
	func_accounting = "accounting"
	func_preacct = "preacct"
	func_checksimul = "checksimul"
	func_detach = "detach"
	func_xlat = "xlat"
	func_pre_proxy = "pre_proxy"
	func_post_proxy = "post_proxy"
	func_post_auth = "post_auth"
	func_recv_coa = "recv_coa"
	func_send_coa = "send_coa"
  }
 Module: Linked to module rlm_unix
 Module: Instantiating unix
  unix {
	radwtmp = "/var/log/radius/radwtmp"
  }
 Module: Linked to module rlm_eap
 Module: Instantiating eap
  eap {
	default_eap_type = "md5"
	timer_expire = 60
	ignore_unknown_eap_types = no
	cisco_accounting_username_bug = no
	max_sessions = 2048
  }
 Module: Linked to sub-module rlm_eap_md5
 Module: Instantiating eap-md5
 Module: Linked to sub-module rlm_eap_leap
 Module: Instantiating eap-leap
 Module: Linked to sub-module rlm_eap_gtc
 Module: Instantiating eap-gtc
   gtc {
	challenge = "Password: "
	auth_type = "PAP"
   }
 Module: Linked to sub-module rlm_eap_tls
 Module: Instantiating eap-tls
   tls {
	rsa_key_exchange = no
	dh_key_exchange = yes
	rsa_key_length = 512
	dh_key_length = 512
	verify_depth = 0
	pem_file_type = yes
	private_key_file = "/usr/local/etc/raddb/certs/server.pem"
	certificate_file = "/usr/local/etc/raddb/certs/server.pem"
	CA_file = "/usr/local/etc/raddb/certs/ca.pem"
	private_key_password = "whatever"
	dh_file = "/usr/local/etc/raddb/certs/dh"
	random_file = "/usr/local/etc/raddb/certs/random"
	fragment_size = 1024
	include_length = yes
	check_crl = no
	cipher_list = "DEFAULT"
	make_cert_command = "/usr/local/etc/raddb/certs/bootstrap"
    cache {
	enable = no
	lifetime = 24
	max_entries = 255
    }
   }
 Module: Linked to sub-module rlm_eap_ttls
 Module: Instantiating eap-ttls
   ttls {
	default_eap_type = "md5"
	copy_request_to_tunnel = no
	use_tunneled_reply = no
	virtual_server = "inner-tunnel"
	include_length = yes
   }
 Module: Linked to sub-module rlm_eap_peap
 Module: Instantiating eap-peap
   peap {
	default_eap_type = "mschapv2"
	copy_request_to_tunnel = no
	use_tunneled_reply = no
	proxy_tunneled_request_as_eap = yes
	virtual_server = "inner-tunnel"
   }
 Module: Linked to sub-module rlm_eap_mschapv2
 Module: Instantiating eap-mschapv2
   mschapv2 {
	with_ntdomain_hack = no
   }
 Module: Checking authorize {...} for more modules to load
 Module: Linked to module rlm_preprocess
 Module: Instantiating preprocess
  preprocess {
	huntgroups = "/usr/local/etc/raddb/huntgroups"
	hints = "/usr/local/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_acct_unique
 Module: Instantiating acct_unique
  acct_unique {
	key = "User-Name, Acct-Session-Id, NAS-IP-Address, Calling-Station-Id, NAS-Port"
  }
 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 = "/usr/local/etc/raddb/users"
	acctusersfile = "/usr/local/etc/raddb/acct_users"
	preproxy_usersfile = "/usr/local/etc/raddb/preproxy_users"
	compat = "no"
  }
 Module: Checking preacct {...} for more modules to load
 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: Linked to module rlm_radutmp
 Module: Instantiating radutmp
  radutmp {
	filename = "/var/log/radius/radutmp"
	username = "%{User-Name}"
	case_sensitive = yes
	check_with_nas = yes
	perm = 384
	callerid = yes
  }
 Module: Linked to module rlm_attr_filter
 Module: Instantiating attr_filter.accounting_response
  attr_filter attr_filter.accounting_response {
	attrsfile = "/usr/local/etc/raddb/attrs.accounting_response"
	key = "%{User-Name}"
  }
 Module: Checking session {...} for more modules to load
 Module: Checking post-proxy {...} for more modules to load
 Module: Checking post-auth {...} for more modules to load
 Module: Instantiating attr_filter.access_reject
  attr_filter attr_filter.access_reject {
	attrsfile = "/usr/local/etc/raddb/attrs.access_reject"
	key = "%{User-Name}"
  }
 } # modules
} # server
 thread pool {
	start_servers = 5
	max_servers = 64
	min_spare_servers = 15
	max_spare_servers = 40
	max_requests_per_server = 600
	cleanup_delay = 5
	max_queue_size = 65536
 }
Thread spawned new child 1. Total threads in pool: 1
Thread spawned new child 2. Total threads in pool: 2
Thread spawned new child 3. Total threads in pool: 3
Thread spawned new child 4. Total threads in pool: 4
Thread spawned new child 5. Total threads in pool: 5
Thread pool initialized
radiusd: #### Opening IP addresses and Ports ####
listen {
	type = "auth"
	ipaddr = *
	port = 0
}
listen {
	type = "acct"
	ipaddr = *
	port = 0
}
Listening on authentication address * port 1812
Listening on accounting address * port 1813
Listening on proxy address * port 1814
Ready to process requests.
Thread 1 waiting to be assigned a request
Thread 2 waiting to be assigned a request
Thread 3 waiting to be assigned a request
Thread 4 waiting to be assigned a request
Thread 5 waiting to be assigned a request
Threads: total/active/spare threads = 5/0/5
Threads: Spawning 10 spares
Thread spawned new child 6. Total threads in pool: 6
Thread spawned new child 7. Total threads in pool: 7
Thread spawned new child 8. Total threads in pool: 8
Thread spawned new child 9. Total threads in pool: 9
Thread spawned new child 10. Total threads in pool: 10
Thread spawned new child 11. Total threads in pool: 11
Thread spawned new child 12. Total threads in pool: 12
Thread spawned new child 13. Total threads in pool: 13
Thread spawned new child 14. Total threads in pool: 14
Thread spawned new child 15. Total threads in pool: 15
Waking up in 0.9 seconds.
Thread 6 waiting to be assigned a request
Thread 6 got semaphore
Thread 6 handling request 0, (1 handled so far)
+- entering group preacct {...}
++[preprocess] returns ok
[acct_unique] Hashing 'NAS-Port = 4,Calling-Station-Id = "10.10.16.214 / 00:13:77:31:4b:36 / rl3",NAS-IP-Address = 10.0.16.1,Acct-Session-Id = "3133076-L2",User-Name = "kamilla"'
[acct_unique] Acct-Unique-Session-ID = "f5d73deacb15c601".
++[acct_unique] returns ok
[suffix] No '@' in User-Name = "kamilla", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
++[files] returns noop
+- entering group accounting {...}
[detail] 	expand: /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d -> /var/log/radius/radacct/10.11.19.1/detail-20100506
[detail] /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d expands to /var/log/radius/radacct/10.11.19.1/detail-20100506
[detail] 	expand: %t -> Thu May  6 12:03:35 2010
++[detail] returns ok
Thread 7 waiting to be assigned a request
Thread 8 waiting to be assigned a request
Thread 9 waiting to be assigned a request
Thread 10 waiting to be assigned a request
Thread 11 waiting to be assigned a request
Thread 12 waiting to be assigned a request
Thread 13 waiting to be assigned a request
Thread 14 waiting to be assigned a request
Thread 15 waiting to be assigned a request
Threads: total/active/spare threads = 15/1/14
Threads: Spawning 1 spares
Thread spawned new child 16. Total threads in pool: 16
Waking up in 0.9 seconds.
rlm_perl: Start new thread
Thread 16 waiting to be assigned a request
Thread 16 got semaphore
Thread 16 handling request 1, (1 handled so far)
+- entering group preacct {...}
++[preprocess] returns ok
[acct_unique] Hashing 'NAS-Port = 3,Calling-Station-Id = "10.10.16.18 / 00:22:15:71:ce:3e / rl3",NAS-IP-Address = 10.0.16.1,Acct-Session-Id = "3134300-L1",User-Name = "konkov"'
[acct_unique] Acct-Unique-Session-ID = "86a54fb35c72014a".
++[acct_unique] returns ok
[suffix] No '@' in User-Name = "konkov", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
++[files] returns noop
+- entering group accounting {...}
[detail] 	expand: /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d -> /var/log/radius/radacct/10.11.19.1/detail-20100506
[detail] /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d expands to /var/log/radius/radacct/10.11.19.1/detail-20100506
[detail] 	expand: %t -> Thu May  6 12:03:35 2010
++[detail] returns ok
rlm_perl: Start new thread
Threads: total/active/spare threads = 16/2/14
Threads: Spawning 1 spares
Thread spawned new child 17. Total threads in pool: 17
Waking up in 0.8 seconds.
Threads: total/active/spare threads = 17/2/15
Waking up in 0.8 seconds.
Waking up in 0.8 seconds.
Waking up in 0.8 seconds.
Waking up in 0.8 seconds.
Thread 17 waiting to be assigned a request
Thread 17 got semaphore
Thread 17 handling request 2, (1 handled so far)
+- entering group preacct {...}
++[preprocess] returns ok
[acct_unique] Hashing 'NAS-Port = 7,Calling-Station-Id = "10.10.16.231 / 00:30:4f:08:2f:dc / rl3",NAS-IP-Address = 10.0.16.1,Acct-Session-Id = "3134683-L5",User-Name = "egic"'
[acct_unique] Acct-Unique-Session-ID = "9d101c124f442455".
++[acct_unique] returns ok
[suffix] No '@' in User-Name = "egic", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
++[files] returns noop
+- entering group accounting {...}
[detail] 	expand: /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d -> /var/log/radius/radacct/10.11.19.1/detail-20100506
[detail] /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d expands to /var/log/radius/radacct/10.11.19.1/detail-20100506
[detail] 	expand: %t -> Thu May  6 12:03:35 2010
++[detail] returns ok
rlm_perl: Start new thread
Thread 7 got semaphore
Thread 7 handling request 3, (1 handled so far)
+- entering group preacct {...}
++[preprocess] returns ok
[acct_unique] Hashing 'NAS-Port = 10,Calling-Station-Id = "10.10.16.111 / 00:24:8c:0e:07:be / rl3",NAS-IP-Address = 10.0.16.1,Acct-Session-Id = "3136142-L8",User-Name = "viktoriya"'
[acct_unique] Acct-Unique-Session-ID = "8c8a51980e3cc2ba".
++[acct_unique] returns ok
[suffix] No '@' in User-Name = "viktoriya", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
++[files] returns noop
+- entering group accounting {...}
[detail] 	expand: /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d -> /var/log/radius/radacct/10.11.19.1/detail-20100506
[detail] /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d expands to /var/log/radius/radacct/10.11.19.1/detail-20100506
[detail] 	expand: %t -> Thu May  6 12:03:35 2010
++[detail] returns ok
rlm_perl: Start new thread
Thread 8 got semaphore
Thread 8 handling request 4, (1 handled so far)
+- entering group preacct {...}
++[preprocess] returns ok
[acct_unique] Hashing 'NAS-Port = 6,Calling-Station-Id = "10.10.16.110 / 00:02:44:8c:c3:84 / rl3",NAS-IP-Address = 10.0.16.1,Acct-Session-Id = "3134023-L4",User-Name = "yura"'
[acct_unique] Acct-Unique-Session-ID = "619c47417471b76f".
++[acct_unique] returns ok
[suffix] No '@' in User-Name = "yura", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
++[files] returns noop
+- entering group accounting {...}
[detail] 	expand: /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d -> /var/log/radius/radacct/10.11.19.1/detail-20100506
[detail] /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d expands to /var/log/radius/radacct/10.11.19.1/detail-20100506
[detail] 	expand: %t -> Thu May  6 12:03:35 2010
++[detail] returns ok
rlm_perl: Start new thread
Thread 1 got semaphore
Thread 1 handling request 5, (1 handled so far)
+- entering group preacct {...}
++[preprocess] returns ok
[acct_unique] Hashing 'NAS-Port = 5,Calling-Station-Id = "10.11.16.239 /  / ",NAS-IP-Address = 10.0.16.1,Acct-Session-Id = "3133361-L3",User-Name = "eugen"'
[acct_unique] Acct-Unique-Session-ID = "d89e7aa626d0f06c".
++[acct_unique] returns ok
[suffix] No '@' in User-Name = "eugen", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
++[files] returns noop
+- entering group accounting {...}
[detail] 	expand: /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d -> /var/log/radius/radacct/10.11.19.1/detail-20100506
[detail] /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d expands to /var/log/radius/radacct/10.11.19.1/detail-20100506
[detail] 	expand: %t -> Thu May  6 12:03:35 2010
++[detail] returns ok
rlm_perl: Start new thread
Thread 2 got semaphore
Thread 2 handling request 6, (1 handled so far)
+- entering group preacct {...}
++[preprocess] returns ok
[acct_unique] Hashing 'NAS-Port = 9,Calling-Station-Id = "10.10.16.234 / 00:04:61:11:11:11 / rl3",NAS-IP-Address = 10.0.16.1,Acct-Session-Id = "3135905-L7",User-Name = "alexander"'
[acct_unique] Acct-Unique-Session-ID = "9ea861ba5844f552".
++[acct_unique] returns ok
[suffix] No '@' in User-Name = "alexander", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
++[files] returns noop
+- entering group accounting {...}
[detail] 	expand: /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d -> /var/log/radius/radacct/10.11.19.1/detail-20100506
[detail] /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d expands to /var/log/radius/radacct/10.11.19.1/detail-20100506
[detail] 	expand: %t -> Thu May  6 12:03:35 2010
++[detail] returns ok
rlm_perl: Start new thread
rlm_perl: NEW CONNECTION: DBI::db=HASH(0x8058439f0)
rlm_perl: DBI::db=HASH(0x8058439f0): Thread started
rlm_perl: DBI::db=HASH(0x8058439f0): start accounting
rlm_perl: DBI::db=HASH(0x8058439f0): update online status for 'konkov'
rlm_perl: NEW CONNECTION: DBI::db=HASH(0x805a949f0)
rlm_perl: DBI::db=HASH(0x805a949f0): Thread started
rlm_perl: DBI::db=HASH(0x805a949f0): start accounting
rlm_perl: DBI::db=HASH(0x805a949f0): update online status for 'egic'
rlm_perl: NEW CONNECTION: DBI::db=HASH(0x805cd99f0)
rlm_perl: DBI::db=HASH(0x805cd99f0): Thread started
rlm_perl: DBI::db=HASH(0x805cd99f0): start accounting
rlm_perl: DBI::db=HASH(0x805cd99f0): update online status for 'viktoriya'
rlm_perl: NEW CONNECTION: DBI::db=HASH(0x8063c79f0)
rlm_perl: DBI::db=HASH(0x8063c79f0): Thread started
rlm_perl: DBI::db=HASH(0x8063c79f0): start accounting
rlm_perl: DBI::db=HASH(0x8063c79f0): update online status for 'alexander'
rlm_perl: NEW CONNECTION: DBI::db=HASH(0x803bf29f0)
rlm_perl: DBI::db=HASH(0x803bf29f0): Thread started
rlm_perl: DBI::db=HASH(0x803bf29f0): start accounting
rlm_perl: DBI::db=HASH(0x803bf29f0): update online status for 'kamilla'
rlm_perl: NEW CONNECTION: DBI::db=HASH(0x8061769f0)
rlm_perl: DBI::db=HASH(0x8061769f0): Thread started
rlm_perl: DBI::db=HASH(0x8061769f0): start accounting
rlm_perl: DBI::db=HASH(0x8061769f0): update online status for 'eugen'
rlm_perl: NEW CONNECTION: DBI::db=HASH(0x805ef09f0)
rlm_perl: DBI::db=HASH(0x805ef09f0): Thread started
rlm_perl: DBI::db=HASH(0x805ef09f0): start accounting
rlm_perl: DBI::db=HASH(0x805ef09f0): update online status for 'yura'
rlm_perl: DBI::db=HASH(0x803bf29f0): get price
rlm_perl: DBI::db=HASH(0x8058439f0): get price
rlm_perl: DBI::db=HASH(0x805cd99f0): get price
rlm_perl: DBI::db=HASH(0x805a949f0): get price
rlm_perl: DBI::db=HASH(0x8061769f0): get price
rlm_perl: DBI::db=HASH(0x805ef09f0): get price
rlm_perl: DBI::db=HASH(0x8063c79f0): get price
rlm_perl: DBI::db=HASH(0x803bf29f0): Bill user kamilla(86154090) => : $VAR1 = undef; 
rlm_perl: DBI::db=HASH(0x805cd99f0): Bill user viktoriya(1278402) => : $VAR1 = undef; 
rlm_perl: DBI::db=HASH(0x8058439f0): Bill user konkov(14087) => : $VAR1 = undef; 
rlm_perl: DBI::db=HASH(0x805a949f0): Bill user egic(39085292) => : $VAR1 = undef; 
rlm_perl: DBI::db=HASH(0x8061769f0): Bill user eugen(381495407) => : $VAR1 = undef; 
rlm_perl: DBI::db=HASH(0x805ef09f0): Bill user yura(3089870) => : $VAR1 = undef; 
rlm_perl: DBI::db=HASH(0x803bf29f0): Start LogOff
rlm_perl: DBI::db=HASH(0x805cd99f0): Start LogOff
rlm_perl: DBI::db=HASH(0x8063c79f0): Bill user alexander(93063781) => : $VAR1 = undef; 
Waking up in 0.1 seconds.
rlm_perl: DBI::db=HASH(0x8058439f0): Start LogOff
rlm_perl: DBI::db=HASH(0x805a949f0): Start LogOff
rlm_perl: DBI::db=HASH(0x8061769f0): Start LogOff
rlm_perl: DBI::db=HASH(0x803bf29f0): Finish LogOff
rlm_perl: DBI::db=HASH(0x803bf29f0): 11111111111111
rlm_perl: DBI::db=HASH(0x805cd99f0): Finish LogOff
rlm_perl: DBI::db=HASH(0x805cd99f0): 11111111111111
rlm_perl: DBI::db=HASH(0x805ef09f0): Start LogOff
rlm_perl: DBI::db=HASH(0x8058439f0): Finish LogOff
rlm_perl: DBI::db=HASH(0x8058439f0): 11111111111111
rlm_perl: DBI::db=HASH(0x8063c79f0): Start LogOff
rlm_perl: DBI::db=HASH(0x805a949f0): Finish LogOff
rlm_perl: DBI::db=HASH(0x805a949f0): 11111111111111
rlm_perl: DBI::db=HASH(0x8061769f0): Finish LogOff
rlm_perl: DBI::db=HASH(0x8061769f0): 11111111111111
Waking up in 1.3 seconds.
rlm_perl: DBI::db=HASH(0x803bf29f0): 22222222222222
rlm_perl: DBI::db=HASH(0x803bf29f0): 3333333333333333
rlm_perl: DBI::db=HASH(0x803bf29f0): finish accounting
rlm_perl: Added pair NAS-Port-Type = Virtual
rlm_perl: Added pair Acct-Unique-Session-Id = f5d73deacb15c601
rlm_perl: Added pair Tunnel-Type = PPTP
rlm_perl: Added pair Tunnel-Client-Endpoint = 10.10.16.214
rlm_perl: Added pair Acct-Status-Type = Stop
rlm_perl: Added pair Acct-Output-Packets = 64365
rlm_perl: Added pair NAS-IP-Address = 10.0.16.1
rlm_perl: Added pair Acct-Output-Octets = 86154090
rlm_perl: Added pair Tunnel-Server-Endpoint = 10.10.16.1
rlm_perl: Added pair Acct-Terminate-Cause = Admin-Reboot
rlm_perl: Added pair Tunnel-Server-Auth-Id = kes.net.ua
rlm_perl: Added pair Acct-Session-Time = 3557
rlm_perl: Added pair Tunnel-Medium-Type = IPv4
rlm_perl: Added pair User-Name = kamilla
rlm_perl: Added pair NAS-Identifier = kes.net.ua
rlm_perl: Added pair Acct-Input-Packets = 37544
rlm_perl: Added pair Framed-IP-Address = 192.168.15.6
rlm_perl: Added pair Acct-Input-Octets = 1874026
rlm_perl: Added pair Acct-Session-Id = 3133076-L2
rlm_perl: Added pair Acct-Output-Gigawords = 0
rlm_perl: Added pair Service-Type = Framed-User
rlm_perl: Added pair mpd-iface = ng3
rlm_perl: Added pair Acct-Link-Count = 1
rlm_perl: Added pair Acct-Authentic = RADIUS
rlm_perl: Added pair mpd-bundle = B2
rlm_perl: Added pair NAS-Port-Id = rl3
rlm_perl: Added pair mpd-iface-index = 13
rlm_perl: Added pair Calling-Station-Id = 10.10.16.214 / 00:13:77:31:4b:36 / rl3
rlm_perl: Added pair Framed-Protocol = PPP
rlm_perl: Added pair Acct-Input-Gigawords = 0
rlm_perl: Added pair Acct-Multi-Session-Id = 3133076-B2
rlm_perl: Added pair mpd-link = L2
rlm_perl: Added pair NAS-Port = 4
++[kes] returns ok
++[unix] returns ok
[radutmp] 	expand: /var/log/radius/radutmp -> /var/log/radius/radutmp
[radutmp] 	expand: %{User-Name} -> kamilla
++[radutmp] returns ok
[attr_filter.accounting_response] 	expand: %{User-Name} -> kamilla
 attr_filter: Matched entry DEFAULT at line 12
++[attr_filter.accounting_response] returns updated
Finished request 0.
Going to the next request
Thread 6 waiting to be assigned a request
rlm_perl: DBI::db=HASH(0x805cd99f0): 22222222222222
rlm_perl: DBI::db=HASH(0x805cd99f0): 3333333333333333
rlm_perl: DBI::db=HASH(0x805cd99f0): finish accounting
rlm_perl: Added pair NAS-Port-Type = Virtual
rlm_perl: Added pair Acct-Unique-Session-Id = 8c8a51980e3cc2ba
rlm_perl: Added pair Tunnel-Type = PPTP
rlm_perl: Added pair Tunnel-Client-Endpoint = 10.10.16.111
rlm_perl: Added pair Acct-Status-Type = Stop
rlm_perl: Added pair Acct-Output-Packets = 1411
rlm_perl: Added pair NAS-IP-Address = 10.0.16.1
rlm_perl: Added pair Acct-Output-Octets = 1278402
rlm_perl: Added pair Tunnel-Server-Endpoint = 10.0.16.1
rlm_perl: Added pair Acct-Terminate-Cause = Admin-Reboot
rlm_perl: Added pair Tunnel-Server-Auth-Id = kes.net.ua
rlm_perl: Added pair Acct-Session-Time = 491
rlm_perl: Added pair Tunnel-Medium-Type = IPv4
rlm_perl: Added pair User-Name = viktoriya
rlm_perl: Added pair NAS-Identifier = kes.net.ua
rlm_perl: Added pair Acct-Input-Packets = 1600
rlm_perl: Added pair Framed-IP-Address = 192.168.2.12
rlm_perl: Added pair Acct-Input-Octets = 165672
rlm_perl: Added pair Acct-Session-Id = 3136142-L8
rlm_perl: Added pair Acct-Output-Gigawords = 0
rlm_perl: Added pair Service-Type = Framed-User
rlm_perl: Added pair mpd-iface = ng9
rlm_perl: Added pair Acct-Link-Count = 1
rlm_perl: Added pair Acct-Authentic = RADIUS
rlm_perl: Added pair mpd-bundle = B8
rlm_perl: Added pair NAS-Port-Id = rl3
rlm_perl: Added pair mpd-iface-index = 19
rlm_perl: Added pair Calling-Station-Id = 10.10.16.111 / 00:24:8c:0e:07:be / rl3
rlm_perl: Added pair Framed-Protocol = PPP
rlm_perl: Added pair Acct-Input-Gigawords = 0
rlm_perl: Added pair Acct-Multi-Session-Id = 3136143-B8
rlm_perl: Added pair mpd-link = L8
rlm_perl: Added pair NAS-Port = 10
++[kes] returns ok
++[unix] returns ok
[radutmp] 	expand: /var/log/radius/radutmp -> /var/log/radius/radutmp
[radutmp] 	expand: %{User-Name} -> viktoriya
++[radutmp] returns ok
[attr_filter.accounting_response] 	expand: %{User-Name} -> viktoriya
 attr_filter: Matched entry DEFAULT at line 12
++[attr_filter.accounting_response] returns updated
Finished request 3.
Going to the next request
Thread 7 waiting to be assigned a request
rlm_perl: DBI::db=HASH(0x805ef09f0): Finish LogOff
rlm_perl: DBI::db=HASH(0x805ef09f0): 11111111111111
Sending duplicate reply to client main port 50560 - ID: 47
Waking up in 1.3 seconds.
Sending duplicate reply to client main port 56885 - ID: 108
Waking up in 1.3 seconds.
Sending duplicate reply to client main port 50560 - ID: 47
Waking up in 1.3 seconds.
Sending duplicate reply to client main port 56885 - ID: 108
Waking up in 1.3 seconds.
rlm_perl: DBI::db=HASH(0x8058439f0): 22222222222222
rlm_perl: DBI::db=HASH(0x8058439f0): 3333333333333333
rlm_perl: DBI::db=HASH(0x8058439f0): finish accounting
rlm_perl: Added pair NAS-Port-Type = Virtual
rlm_perl: Added pair Acct-Unique-Session-Id = 86a54fb35c72014a
rlm_perl: Added pair Tunnel-Type = PPTP
rlm_perl: Added pair Tunnel-Client-Endpoint = 10.10.16.18
rlm_perl: Added pair Acct-Status-Type = Stop
rlm_perl: Added pair Acct-Output-Packets = 115
rlm_perl: Added pair NAS-IP-Address = 10.0.16.1
rlm_perl: Added pair Acct-Output-Octets = 14087
rlm_perl: Added pair Tunnel-Server-Endpoint = 10.0.16.1
rlm_perl: Added pair Acct-Terminate-Cause = Admin-Reboot
rlm_perl: Added pair Tunnel-Server-Auth-Id = kes.net.ua
rlm_perl: Added pair Acct-Session-Time = 2333
rlm_perl: Added pair Tunnel-Medium-Type = IPv4
rlm_perl: Added pair User-Name = konkov
rlm_perl: Added pair NAS-Identifier = kes.net.ua
rlm_perl: Added pair Acct-Input-Packets = 142
rlm_perl: Added pair Framed-IP-Address = 192.168.9.5
rlm_perl: Added pair Acct-Input-Octets = 13725
rlm_perl: Added pair Acct-Session-Id = 3134300-L1
rlm_perl: Added pair Acct-Output-Gigawords = 0
rlm_perl: Added pair Service-Type = Framed-User
rlm_perl: Added pair mpd-iface = ng2
rlm_perl: Added pair Acct-Link-Count = 1
rlm_perl: Added pair Acct-Authentic = RADIUS
rlm_perl: Added pair mpd-bundle = B1
rlm_perl: Added pair NAS-Port-Id = rl3
rlm_perl: Added pair mpd-iface-index = 12
rlm_perl: Added pair Calling-Station-Id = 10.10.16.18 / 00:22:15:71:ce:3e / rl3
rlm_perl: Added pair Framed-Protocol = PPP
rlm_perl: Added pair Acct-Input-Gigawords = 0
rlm_perl: Added pair Acct-Multi-Session-Id = 3134311-B1
rlm_perl: Added pair mpd-link = L1
rlm_perl: Added pair NAS-Port = 3
++[kes] returns ok
++[unix] returns ok
[radutmp] 	expand: /var/log/radius/radutmp -> /var/log/radius/radutmp
[radutmp] 	expand: %{User-Name} -> konkov
++[radutmp] returns ok
[attr_filter.accounting_response] 	expand: %{User-Name} -> konkov
 attr_filter: Matched entry DEFAULT at line 12
++[attr_filter.accounting_response] returns updated
Finished request 1.
Going to the next request
Thread 16 waiting to be assigned a request
rlm_perl: DBI::db=HASH(0x8063c79f0): Finish LogOff
rlm_perl: DBI::db=HASH(0x8063c79f0): 11111111111111
rlm_perl: DBI::db=HASH(0x805a949f0): 22222222222222
rlm_perl: DBI::db=HASH(0x805a949f0): 3333333333333333
rlm_perl: DBI::db=HASH(0x805a949f0): finish accounting
rlm_perl: Added pair NAS-Port-Type = Virtual
rlm_perl: Added pair Acct-Unique-Session-Id = 9d101c124f442455
rlm_perl: Added pair Tunnel-Type = PPTP
rlm_perl: Added pair Tunnel-Client-Endpoint = 10.10.16.231
rlm_perl: Added pair Acct-Status-Type = Stop
rlm_perl: Added pair Acct-Output-Packets = 33866
rlm_perl: Added pair NAS-IP-Address = 10.0.16.1
rlm_perl: Added pair Acct-Output-Octets = 11797263
rlm_perl: Added pair Tunnel-Server-Endpoint = 10.0.16.1
rlm_perl: Added pair Acct-Terminate-Cause = Admin-Reboot
rlm_perl: Added pair Tunnel-Server-Auth-Id = kes.net.ua
rlm_perl: Added pair Acct-Session-Time = 1950
rlm_perl: Added pair Tunnel-Medium-Type = IPv4
rlm_perl: Added pair User-Name = egic
rlm_perl: Added pair NAS-Identifier = kes.net.ua
rlm_perl: Added pair Acct-Input-Packets = 50522
rlm_perl: Added pair Framed-IP-Address = 192.168.1.9
rlm_perl: Added pair Acct-Input-Octets = 39085292
rlm_perl: Added pair Acct-Session-Id = 3134683-L5
rlm_perl: Added pair Acct-Output-Gigawords = 0
rlm_perl: Added pair Service-Type = Framed-User
rlm_perl: Added pair mpd-iface = ng6
rlm_perl: Added pair Acct-Link-Count = 1
rlm_perl: Added pair Acct-Authentic = RADIUS
rlm_perl: Added pair mpd-bundle = B5
rlm_perl: Added pair NAS-Port-Id = rl3
rlm_perl: Added pair mpd-iface-index = 16
rlm_perl: Added pair Calling-Station-Id = 10.10.16.231 / 00:30:4f:08:2f:dc / rl3
rlm_perl: Added pair Framed-Protocol = PPP
rlm_perl: Added pair Acct-Input-Gigawords = 0
rlm_perl: Added pair Acct-Multi-Session-Id = 3134683-B5
rlm_perl: Added pair mpd-link = L5
rlm_perl: Added pair NAS-Port = 7
++[kes] returns ok
++[unix] returns ok
[radutmp] 	expand: /var/log/radius/radutmp -> /var/log/radius/radutmp
[radutmp] 	expand: %{User-Name} -> egic
++[radutmp] returns ok
[attr_filter.accounting_response] 	expand: %{User-Name} -> egic
 attr_filter: Matched entry DEFAULT at line 12
++[attr_filter.accounting_response] returns updated
Finished request 2.
Going to the next request
Thread 17 waiting to be assigned a request
Sending duplicate reply to client main port 63940 - ID: 30
Waking up in 1.2 seconds.
Sending duplicate reply to client main port 50996 - ID: 228
Waking up in 1.2 seconds.