Starting - reading configuration files ... reread_config: reading radiusd.conf Config: including file: /usr/local/radius/etc/raddb/proxy.conf Config: including file: /usr/local/radius/etc/raddb/clients.conf Config: including file: /usr/local/radius/etc/raddb/snmp.conf Config: including file: /usr/local/radius/etc/raddb/sql.conf main: prefix = "/usr/local/radius" main: localstatedir = "/usr/local/radius/var" main: logdir = "/usr/local/radius/var/log/radius" main: libdir = "/usr/local/radius/lib" main: radacctdir = "/usr/local/radius/var/log/radius/radacct" main: datadir = "/usr/local/radius/share" main: dictdir = "/usr/local/radius/share/freeradius" main: hostname_lookups = no main: max_request_time = 30 main: cleanup_delay = 5 main: max_requests = 1024 main: delete_blocked_requests = 0 main: port = 0 main: allow_core_dumps = no main: log_stripped_names = no main: log_file = "/usr/local/radius/var/log/radius/radius.log" main: log_auth = no main: log_auth_badpass = no main: log_auth_goodpass = no main: pidfile = "/usr/local/radius/var/run/radiusd/radiusd.pid" main: user = "(null)" main: group = "(null)" main: usercollide = no main: lower_user = "no" main: lower_pass = "no" main: nospace_user = "no" main: nospace_pass = "no" main: checkrad = "/usr/local/radius/sbin/checkrad" main: proxy_requests = yes proxy: retry_delay = 5 proxy: retry_count = 3 proxy: synchronous = no proxy: default_fallback = yes proxy: dead_time = 120 proxy: post_proxy_authorize = yes security: max_attributes = 200 security: reject_delay = 1 security: status_server = no main: debug_level = 0 read_config_files: reading dictionary read_config_files: reading naslist read_config_files: reading clients read_config_files: reading realms radiusd: entering modules setup Module: Library search path is /usr/local/radius/lib Module: Loaded expr Module: Instantiated expr (expr) Module: Loaded System unix: cache = no unix: passwd = "(null)" unix: shadow = "(null)" unix: group = "(null)" unix: radwtmp = "/usr/local/radius/var/log/radius/radwtmp" unix: usegroup = no unix: cache_reload = 600 Module: Instantiated unix (unix) Module: Loaded eap eap: default_eap_type = "tls" eap: timer_expire = 60 tls: rsa_key_exchange = no tls: dh_key_exchange = yes tls: rsa_key_length = 512 tls: dh_key_length = 512 tls: verify_depth = 0 tls: CA_path = "(null)" tls: pem_file_type = yes tls: private_key_file = "/etc/1x/r/cert-svr.pem" tls: certificate_file = "/etc/1x/r/cert-svr.pem" tls: CA_file = "/etc/1x/r/root.pem" tls: private_key_password = "whatever" tls: dh_file = "/etc/1x/r/DH" tls: random_file = "/etc/1x/r/random" tls: fragment_size = 1024 tls: include_length = yes rlm_eap_tls: conf N ctx stored rlm_eap: Loaded and initialized the type tls Module: Instantiated eap (eap) Module: Loaded preprocess preprocess: huntgroups = "/usr/local/radius/etc/raddb/huntgroups" preprocess: hints = "/usr/local/radius/etc/raddb/hints" preprocess: with_ascend_hack = no preprocess: ascend_channels_per_line = 23 preprocess: with_ntdomain_hack = no preprocess: with_specialix_jetstream_hack = no preprocess: with_cisco_vsa_hack = no Module: Instantiated preprocess (preprocess) Module: Loaded realm realm: format = "suffix" realm: delimiter = "@" Module: Instantiated realm (suffix) Module: Loaded files files: usersfile = "/usr/local/radius/etc/raddb/users" files: acctusersfile = "/usr/local/radius/etc/raddb/acct_users" files: preproxy_usersfile = "/usr/local/radius/etc/raddb/preproxy_users" files: compat = "no" Module: Instantiated files (files) Module: Loaded Acct-Unique-Session-Id acct_unique: key = "User-Name, Acct-Session-Id, NAS-IP-Address, Client-IP-Address, NAS-Port-Id" Module: Instantiated acct_unique (acct_unique) Module: Loaded detail detail: detailfile = "/usr/local/radius/var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d" detail: detailperm = 384 detail: dirperm = 493 detail: locking = no Module: Instantiated detail (detail) Module: Loaded radutmp radutmp: filename = "/usr/local/radius/var/log/radius/radutmp" radutmp: username = "%{User-Name}" radutmp: case_sensitive = yes radutmp: check_with_nas = yes radutmp: perm = 384 radutmp: callerid = yes Module: Instantiated radutmp (radutmp) Listening on IP address *, ports 1812/udp and 1813/udp, with proxy on 1814/udp. Ready to process requests. rad_recv: Access-Request packet from host 128.198.61.130:1330, id=25, length=149 User-Name = "adam-ctl" Cisco-AVPair = "ssid=winrace" NAS-IP-Address = 128.198.61.130 Called-Station-Id = "000b464e8fd6" Calling-Station-Id = "000ab78b5d16" NAS-Identifier = "AP1200" NAS-Port = 37 Framed-MTU = 1400 NAS-Port-Type = Wireless-802.11 Service-Type = Login-User EAP-Message = "\002\033\000\r\001adam-ctl" Message-Authenticator = 0x826bcd4f68dadaeb55083fb20f4f716e modcall: entering group authorize modcall[authorize]: module "preprocess" returns ok modcall[authorize]: module "eap" returns updated rlm_realm: No '@' in User-Name = "adam-ctl", looking up realm NULL rlm_realm: No such realm NULL modcall[authorize]: module "suffix" returns noop users: Matched adam-ctl at 97 modcall[authorize]: module "files" returns ok modcall: group authorize returns updated rad_check_password: Found Auth-Type EAP auth: type "EAP" modcall: entering group authenticate rlm_eap: processing type tls modcall[authenticate]: module "eap" returns ok modcall: group authenticate returns ok Sending Access-Challenge of id 25 to 128.198.61.130:1330 EAP-Message = "\001\034\000\006\r " Message-Authenticator = 0x00000000000000000000000000000000 State = 0xadc9c56d48bb9c137289c4cb964abce881aba53e856d1854ad838c4c1d95d5ff47eb3107 Finished request 0 Going to the next request --- Walking the entire request list --- Waking up in 6 seconds... rad_recv: Access-Request packet from host 128.198.61.130:1331, id=26, length=272 User-Name = "adam-ctl" Cisco-AVPair = "ssid=winrace" NAS-IP-Address = 128.198.61.130 Called-Station-Id = "000b464e8fd6" Calling-Station-Id = "000ab78b5d16" NAS-Identifier = "AP1200" NAS-Port = 37 Framed-MTU = 1400 State = 0xadc9c56d48bb9c137289c4cb964abce881aba53e856d1854ad838c4c1d95d5ff47eb3107 NAS-Port-Type = Wireless-802.11 Service-Type = Login-User EAP-Message = "\002\034\000b\r\200\000\000\000X\026\003\001\000S\001\000\000O\003\001>\245\244\365\035\263H\334\355I\244NUlMX\216i\311@\341\242Dc\372G\335\224B\201\223\304\000\000(\000\026\000\023\000\n\000f\000\005\000\004\000e\000d\000c\000b\000a\000`\000\025\000\022\000\t\000\024\000\021\000\010\000\006\000\003\001" Message-Authenticator = 0x5468475be151cc7d077c7dc815461873 modcall: entering group authorize modcall[authorize]: module "preprocess" returns ok modcall[authorize]: module "eap" returns updated rlm_realm: No '@' in User-Name = "adam-ctl", looking up realm NULL rlm_realm: No such realm NULL modcall[authorize]: module "suffix" returns noop users: Matched adam-ctl at 97 modcall[authorize]: module "files" returns ok modcall: group authorize returns updated rad_check_password: Found Auth-Type EAP auth: type "EAP" modcall: entering group authenticate rlm_eap: Request found, released from the list rlm_eap: EAP_TYPE - tls rlm_eap: processing type tls rlm_eap_tls: Length Included undefined: before/accept initialization TLS_accept: before/accept initialization <<< TLS 1.0 Handshake [length 0053], ClientHello TLS_accept: SSLv3 read client hello A >>> TLS 1.0 Handshake [length 004a], ServerHello TLS_accept: SSLv3 write server hello A >>> TLS 1.0 Handshake [length 063c], Certificate TLS_accept: SSLv3 write certificate A >>> TLS 1.0 Handshake [length 00a0], CertificateRequest TLS_accept: SSLv3 write certificate request A TLS_accept: SSLv3 flush data TLS_accept:error in SSLv3 read client certificate A rlm_eap_tls: SSL_read Error Error code is ..... 2 SSL Error ..... 2 modcall[authenticate]: module "eap" returns ok modcall: group authenticate returns ok Sending Access-Challenge of id 26 to 128.198.61.130:1331 EAP-Message = "\001\035\004\n\r\300\000\000\0075\026\003\001\000J\002\000\000F\003\001>\245\253\204\371\2726z8\266D\021\326\207\305\220c\345\312\001\366\256\324c\205.\362\014\236\334\347]\314\021v\236\3333\267E\332\211G\017\202 User-Name = adam-ctl --> BUF-Name = UCCS Wireless Project --> subject = /C=US/ST=Colorado/O=UCCS/OU=Computer Science/CN=UCCS Wireless Project/emailAddress=nbulusu@cs.uccs.edu --> issuer = /C=US/ST=Colorado/O=UCCS/OU=Computer Science/CN=UCCS Wireless Project/emailAddress=nbulusu@cs.uccs.edu --> verify return:1 chain-depth=0, error=0 --> User-Name = adam-ctl --> BUF-Name = adam-ctl --> subject = /C=US/ST=Colorado/O=UCCS/OU=Computer Science/CN=adam-ctl/emailAddress=nbulusu@cs.uccs.edu --> issuer = /C=US/ST=Colorado/O=UCCS/OU=Computer Science/CN=UCCS Wireless Project/emailAddress=nbulusu@cs.uccs.edu --> verify return:1 TLS_accept: SSLv3 read client certificate A <<< TLS 1.0 Handshake [length 0086], ClientKeyExchange TLS_accept: SSLv3 read client key exchange A <<< TLS 1.0 Handshake [length 0086], CertificateVerify TLS_accept: SSLv3 read certificate verify A <<< TLS 1.0 ChangeCipherSpec [length 0001] <<< TLS 1.0 Handshake [length 0010], Finished TLS_accept: SSLv3 read finished A >>> TLS 1.0 ChangeCipherSpec [length 0001] TLS_accept: SSLv3 write change cipher spec A >>> TLS 1.0 Handshake [length 0010], Finished TLS_accept: SSLv3 write finished A TLS_accept: SSLv3 flush data undefined: SSL negotiation finished successfully rlm_eap_tls: SSL_read Error Error code is ..... 2 SSL Error ..... 2 modcall[authenticate]: module "eap" returns ok modcall: group authenticate returns ok Sending Access-Challenge of id 29 to 128.198.61.130:1334 EAP-Message = "\001 \000=\r\200\000\000\0003\024\003\001\000\001\001\026\003\001\000(\266\031\324\210\002\256\023\334\303\302\252\316\027l\3453\325\372\367H\206C\372?\016Q+\253Z\247 \022\373\255\021\254\357?>\226" Message-Authenticator = 0x00000000000000000000000000000000 State = 0xf09cc0daf9966accedec989ba54abbb684aba53e7395e6337f5f3e9dd9b0c17b384e8a42 Finished request 4 Going to the next request Waking up in 3 seconds... rad_recv: Access-Request packet from host 128.198.61.130:1335, id=30, length=180 User-Name = "adam-ctl" Cisco-AVPair = "ssid=winrace" NAS-IP-Address = 128.198.61.130 Called-Station-Id = "000b464e8fd6" Calling-Station-Id = "000ab78b5d16" NAS-Identifier = "AP1200" NAS-Port = 37 Framed-MTU = 1400 State = 0xf09cc0daf9966accedec989ba54abbb684aba53e7395e6337f5f3e9dd9b0c17b384e8a42 NAS-Port-Type = Wireless-802.11 Service-Type = Login-User EAP-Message = "\002 \000\006\r" Message-Authenticator = 0x075378d20f9e748bc4da4c7ee83742c1 modcall: entering group authorize modcall[authorize]: module "preprocess" returns ok modcall[authorize]: module "eap" returns updated rlm_realm: No '@' in User-Name = "adam-ctl", looking up realm NULL rlm_realm: No such realm NULL modcall[authorize]: module "suffix" returns noop users: Matched adam-ctl at 97 modcall[authorize]: module "files" returns ok modcall: group authorize returns updated rad_check_password: Found Auth-Type EAP auth: type "EAP" modcall: entering group authenticate rlm_eap: Request found, released from the list rlm_eap: EAP_TYPE - tls rlm_eap: processing type tls rlm_eap_tls: Received EAP-TLS ACK message modcall[authenticate]: module "eap" returns ok modcall: group authenticate returns ok Sending Access-Accept of id 30 to 128.198.61.130:1335 MS-MPPE-Recv-Key = 0xe5bee8642542b967b40f1e8812032be981dba44e976ad1f32b7cc096a5d4f8556843f2fa18e01ef9517e6178168039ccfa7d MS-MPPE-Send-Key = 0xe5bdeda57dcd273afec40d95460354eee6db46f43353be39a9604045d909176fc82007b6fd34a2a1a2f8e8defb6ebfa609ae EAP-Message = "\003 \000\004" Message-Authenticator = 0x00000000000000000000000000000000 Finished request 5 Going to the next request --- Walking the entire request list --- Waking up in 2 seconds... --- Walking the entire request list --- Cleaning up request 0 ID 25 with timestamp 3ea5ab81 Waking up in 3 seconds... --- Walking the entire request list --- Cleaning up request 1 ID 26 with timestamp 3ea5ab84 Cleaning up request 2 ID 27 with timestamp 3ea5ab84 Cleaning up request 3 ID 28 with timestamp 3ea5ab84 Cleaning up request 4 ID 29 with timestamp 3ea5ab84 Waking up in 1 seconds... --- Walking the entire request list --- Cleaning up request 5 ID 30 with timestamp 3ea5ab85 Nothing to do. Sleeping until we see a request.