Waking up in 5 seconds...
rad_recv: Access-Request packet from host 192.168.0.250:1895, id=249, length=162
        User-Name = "kurama"
        Cisco-AVPair = "ssid=Pukey"
        NAS-IP-Address = 192.168.0.250
        Called-Station-Id = "004096285ceb"
        Calling-Station-Id = "00095b679ccf"
        NAS-Identifier = "AP340-285ceb"
        NAS-Port = 37
        Framed-MTU = 1400
        State = 0x2cb55ba661c0fd982b57c25bb6f65f7f
        NAS-Port-Type = Wireless-802.11
        Service-Type = Authenticate-Only
        EAP-Message = 0x02d300060d00
        Message-Authenticator = 0x771be5932e592aae6cbbe80f9f42c397
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 10
  modcall[authorize]: module "preprocess" returns ok for request 10
rlm_ldap: - authorize
rlm_ldap: performing user authorization for kurama
radius_xlat:  '(uid=kurama)'
radius_xlat:  'dc=pukey'
rlm_ldap: ldap_get_conn: Checking Id: 0
rlm_ldap: ldap_get_conn: Got Id: 0
rlm_ldap: performing search in dc=pukey, with filter (uid=kurama)
rlm_ldap: object not found or got ambiguous search result
rlm_ldap: search failed
rlm_ldap: ldap_release_conn: Release Id: 0
  modcall[authorize]: module "ldap" returns notfound for request 10
  modcall[authorize]: module "chap" returns noop for request 10
  modcall[authorize]: module "mschap" returns noop for request 10
    rlm_realm: No '@' in User-Name = "kurama", looking up realm NULL
    rlm_realm: Found realm "NULL"
    rlm_realm: Adding Stripped-User-Name = "kurama"
    rlm_realm: Proxying request from user kurama to realm NULL
    rlm_realm: Adding Realm = "NULL"
    rlm_realm: Authentication realm is LOCAL.
  modcall[authorize]: module "suffix" returns noop for request 10
  rlm_eap: EAP packet type response id 211 length 6
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 10
  modcall[authorize]: module "files" returns notfound for request 10
modcall: leaving group authorize (returns updated) for request 10
  rad_check_password:  Found Auth-Type EAP
auth: type "EAP"
  Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 10
  rlm_eap: Request found, released from the list
  rlm_eap: EAP/tls
  rlm_eap: processing type tls
  rlm_eap_tls: Authenticate
  rlm_eap_tls: processing TLS
rlm_eap_tls: Received EAP-TLS ACK message
  rlm_eap_tls: ack handshake is finished
  eaptls_verify returned 3
  eaptls_process returned 3
  rlm_eap: Freeing handler
  modcall[authenticate]: module "eap" returns ok for request 10
modcall: leaving group authenticate (returns ok) for request 10
  Processing the post-auth section of radiusd.conf
modcall: entering group post-auth for request 10
rlm_sql (sql): Processing sql_postauth
radius_xlat:  'kurama'
rlm_sql (sql): sql_set_user escaped user --> 'kurama'
radius_xlat:  'INSERT into radpostauth (id, user, pass, reply, date) values ('', 'kurama', 'Chap-Password', 'Access-Accept', NOW())'
rlm_sql (sql) in sql_postauth: query is INSERT into radpostauth (id, user, pass, reply, date) values ('', 'kurama', 'Chap-Password', 'Access-Accept', NOW())
rlm_sql (sql): Reserving sql socket id: 3
rlm_sql (sql): Released sql socket id: 3
  modcall[post-auth]: module "sql" returns ok for request 10
modcall: leaving group post-auth (returns ok) for request 10
Sending Access-Accept of id 249 to 192.168.0.250 port 1895
        MS-MPPE-Recv-Key = 0x3d9ee874dd4124c5823902339371f6d23c1f96f2c59e586feeb5f00972ff6fa6
        MS-MPPE-Send-Key = 0xbff74788a7d53ccbebcb54c90fd2d337f41ce91f2c865dd8bdd7296b60e0cf4c
        EAP-Message = 0x03d30004
        Message-Authenticator = 0x00000000000000000000000000000000
        User-Name = "kurama"
Finished request 10
Going to the next request
--- Walking the entire request list ---
Waking up in 4 seconds...
--- Walking the entire request list ---
Cleaning up request 0 ID 239 with timestamp 45d39568
Cleaning up request 1 ID 240 with timestamp 45d39568
Waking up in 1 seconds...