post-auth section entered twice?

21 May 2008

      Hello,

I'm trying to modify my radiusd.conf so that it will log Rejects and
Accepts in a simple log that shall look like this:

2008-05-21 15:18:51 REJECT invalid.user
2008-05-21 15:19:44 ACCEPT valid.user
2008-05-21 15:25:23 ACCEPT another.user
2008-05-21 15:31:33 REJECT bad.guy

To accomplish this, I've written a short shell script radius-log:

    #!/bin/sh
    echo "$(date "+%F %R:%S") $@" >> /var/log/freeradius/auth.log

and two exec's in the radiusd.conf:

    exec log_accept {
        output_pairs = none
        wait = yes
        program = "/usr/lib/iserv/radius_log ACCEPT %{User-Name}"
    }

    exec log_reject {
        output_pairs = none
        wait = yes
        program = "/usr/lib/iserv/radius_log REJECT %{User-Name}"
    }

Those are called in the post-auth section:

    post-auth {
        # rejected requests
        Post-Auth-Type REJECT {
            log_reject
        }

        # accepted requests
        log_accept
    }

But unfortunately, post-auth seems to be entered twice, and the log
looks like this:

    2008-05-21 15:18:51 REJECT radius.test
    2008-05-21 15:19:44 ACCEPT radius.test
    2008-05-21 15:19:44 ACCEPT radius.test
    2008-05-21 15:31:33 REJECT radius.test
    2008-05-21 15:31:34 REJECT radius.test
    2008-05-21 15:59:16 REJECT bla
    2008-05-21 15:59:16 REJECT bla
    2008-05-21 16:05:09 REJECT invalid.user
    2008-05-21 16:05:09 REJECT invalid.user
    2008-05-21 16:06:26 REJECT bad.guy
    2008-05-21 16:06:26 REJECT bad.guy
    2008-05-21 16:07:47 ACCEPT radius.test
    2008-05-21 16:07:47 ACCEPT radius.test

I thought about filtering the log through uniq before parsing it, but as
line 4 and 5 show, this is not guaranteed to work. Is there any way to
work around this so the log will only have one line per
accepted/rejected request?

The freeradius version used is the current Debian stable package,
1.1.3-3, extended by self-compiled EAP modules compiled from source.
freeradius is used to authenticate Windows WLAN clients against the
samba password file smbpasswd.

Martin v. Wittich

freeradius -X output of a session where one request was accepted, note
the two lines "Exec-Program: /usr/lib/iserv/radius_log ACCEPT radius.test":

Starting - reading configuration files ...
reread_config:  reading radiusd.conf
Config:   including file: /etc/freeradius/clients.conf
Config:   including file: /etc/freeradius/snmp.conf
 main: prefix = "/usr"
 main: localstatedir = "/var"
 main: logdir = "/var/log/freeradius"
 main: libdir = "/usr/lib/freeradius"
 main: radacctdir = "/var/log/freeradius/radacct"
 main: hostname_lookups = no
 main: max_request_time = 30
 main: cleanup_delay = 5
 main: max_requests = 1024
 main: delete_blocked_requests = 0
 main: port = 1812
 main: allow_core_dumps = no
 main: log_stripped_names = no
 main: log_file = "/var/log/freeradius/radius.log"
 main: log_auth = no
 main: log_auth_badpass = no
 main: log_auth_goodpass = no
 main: pidfile = "/var/run/freeradius/freeradius.pid"
 main: user = "root"
 main: group = "freerad"
 main: usercollide = no
 main: lower_user = "no"
 main: lower_pass = "no"
 main: nospace_user = "no"
 main: nospace_pass = "no"
 main: checkrad = "/usr/sbin/checkrad"
 main: proxy_requests = no
 security: max_attributes = 200
 security: reject_delay = 1
 security: status_server = yes
 main: debug_level = 0
read_config_files:  reading dictionary
read_config_files:  reading naslist
read_config_files:  reading clients
read_config_files:  reading realms
 listen: ipaddr = 192.168.90.13 IP address [192.168.90.13]
 listen: port = 0
 listen: type = "auth"
radiusd:  entering modules setup
Module: Library search path is /usr/lib/freeradius
Module: Loaded MS-CHAP
 mschap: use_mppe = yes
 mschap: require_encryption = no
 mschap: require_strong = no
 mschap: with_ntdomain_hack = no
 mschap: passwd = "(null)"
 mschap: ntlm_auth = "(null)"
Module: Instantiated mschap (mschap)
Module: Loaded eap
 eap: default_eap_type = "peap"
 eap: timer_expire = 60
 eap: ignore_unknown_eap_types = yes
 eap: cisco_accounting_username_bug = no
 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/freeradius/certs/server.pem"
 tls: certificate_file = "/etc/freeradius/certs/server.pem"
 tls: CA_file = "/etc/freeradius/certs/ca.pem"
 tls: private_key_password =
"RZOAaXWZvJ_zDwRLGMPrgn38T8g3tncZA06YjKAXMKmKCPbvAb2aN8xr1s7Lxz9y"
 tls: dh_file = "/etc/freeradius/certs/dh"
 tls: random_file = "/etc/freeradius/certs/random"
 tls: fragment_size = 1024
 tls: include_length = yes
 tls: check_crl = no
 tls: check_cert_cn = "(null)"
 tls: cipher_list = "DEFAULT"
 tls: check_cert_issuer = "(null)"
rlm_eap_tls: Loading the certificate file as a chain
rlm_eap: Loaded and initialized type tls
 peap: default_eap_type = "mschapv2"
 peap: copy_request_to_tunnel = no
 peap: use_tunneled_reply = no
 peap: proxy_tunneled_request_as_eap = yes
rlm_eap: Loaded and initialized type peap
 mschapv2: with_ntdomain_hack = no
rlm_eap: Loaded and initialized type mschapv2
Module: Instantiated eap (eap)
Module: Loaded preprocess
 preprocess: huntgroups = "/etc/freeradius/huntgroups"
 preprocess: hints = "/etc/freeradius/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
 preprocess: with_alvarion_vsa_hack = no
Module: Instantiated preprocess (preprocess)
Module: Loaded realm
 realm: format = "prefix"
 realm: delimiter = "/"
 realm: ignore_default = no
 realm: ignore_null = yes
Module: Instantiated realm (IPASS)
 realm: format = "suffix"
 realm: delimiter = "@"
 realm: ignore_default = no
 realm: ignore_null = yes
Module: Instantiated realm (suffix)
 realm: format = "prefix"
 realm: delimiter = "\"
 realm: ignore_default = no
 realm: ignore_null = yes
Module: Instantiated realm (ntdomain)
 realm: format = "suffix"
 realm: delimiter = "@"
 realm: ignore_default = no
 realm: ignore_null = no
Module: Instantiated realm (NULL)
Module: Loaded passwd
 passwd: filename = "/etc/samba/smbpasswd"
 passwd: format =
"*Stripped-User-Name::LM-Password:NT-Password:SMB-Account-CTRL-TEXT::"
 passwd: authtype = "(null)"
 passwd: delimiter = ":"
 passwd: ignorenislike = no
 passwd: ignoreempty = yes
 passwd: allowmultiplekeys = no
 passwd: hashsize = 0
rlm_passwd: nfields: 7 keyfield 0(Stripped-User-Name) listable: no
Module: Instantiated passwd (etc_smbpasswd)
 passwd: filename = "/etc/group"
 passwd: format = "~Group-Name:::*,User-Name"
 passwd: authtype = "(null)"
 passwd: delimiter = ":"
 passwd: ignorenislike = yes
 passwd: ignoreempty = yes
 passwd: allowmultiplekeys = yes
 passwd: hashsize = 0
rlm_passwd: nfields: 4 keyfield 3(User-Name) listable: yes
Module: Instantiated passwd (etc_group)
Module: Loaded files
 files: usersfile = "/etc/freeradius/users"
 files: acctusersfile = "/etc/freeradius/acct_users"
 files: preproxy_usersfile = "/etc/freeradius/preproxy_users"
 files: compat = "no"
Module: Instantiated files (files)
Module: Loaded radutmp
 radutmp: filename = "/var/log/freeradius/radutmp"
 radutmp: username = "%{User-Name}"
 radutmp: case_sensitive = yes
 radutmp: check_with_nas = yes
 radutmp: perm = 384
 radutmp: callerid = yes
Module: Instantiated radutmp (radutmp)
Module: Loaded exec
 exec: wait = yes
 exec: program = "/usr/lib/iserv/radius_log REJECT %{User-Name}"
 exec: input_pairs = "request"
 exec: output_pairs = "none"
 exec: packet_type = "(null)"
Module: Instantiated exec (log_reject)
 exec: wait = yes
 exec: program = "/usr/lib/iserv/radius_log ACCEPT %{User-Name}"
 exec: input_pairs = "request"
 exec: output_pairs = "none"
 exec: packet_type = "(null)"
Module: Instantiated exec (log_accept)
Listening on authentication 192.168.90.13:1812
Ready to process requests.
rad_recv: Access-Request packet from host 192.168.90.99:3074, id=0,
length=135
	User-Name = "radius.test"
	NAS-IP-Address = 192.168.90.99
	Called-Station-Id = "0018f8adf209"
	Calling-Station-Id = "000e351db86b"
	NAS-Identifier = "0018f8adf209"
	NAS-Port = 14
	Framed-MTU = 1400
	NAS-Port-Type = Wireless-802.11
	EAP-Message = 0x02000010017261646975732e74657374
	Message-Authenticator = 0x503c8e7c3ce4585bf0aa78760ea6997f
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 0
  modcall[authorize]: module "preprocess" returns ok for request 0
  modcall[authorize]: module "mschap" returns noop for request 0
    rlm_realm: No '/' in User-Name = "radius.test", skipping NULL due to
config.
  modcall[authorize]: module "IPASS" returns noop for request 0
    rlm_realm: No '@' in User-Name = "radius.test", skipping NULL due to
config.
  modcall[authorize]: module "suffix" returns noop for request 0
    rlm_realm: No '\' in User-Name = "radius.test", skipping NULL due to
config.
  modcall[authorize]: module "ntdomain" returns noop for request 0
    rlm_realm: No '@' in User-Name = "radius.test", looking up realm NULL
    rlm_realm: Found realm "NULL"
    rlm_realm: Adding Stripped-User-Name = "radius.test"
    rlm_realm: Proxying request from user radius.test to realm NULL
    rlm_realm: Adding Realm = "NULL"
    rlm_realm: Authentication realm is LOCAL.
  modcall[authorize]: module "NULL" returns noop for request 0
  rlm_eap: EAP packet type response id 0 length 16
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 0
try to find in file
rlm_passwd: Added LM-Password: '01FC5A6BE7BC6929AAD3B435B51404EE' to
config_items
rlm_passwd: Added NT-Password: '0CB6948805F797BF2A82807973B89537' to
config_items
rlm_passwd: Added SMB-Account-CTRL-TEXT: '[U          ]' to config_items
try to find in file
  modcall[authorize]: module "etc_smbpasswd" returns ok for request 0
try to find in file
  modcall[authorize]: module "etc_group" returns notfound for request 0
  modcall[authorize]: module "files" returns notfound for request 0
modcall: leaving group authorize (returns updated) for request 0
  rad_check_password:  Found Auth-Type EAP
auth: type "EAP"
  Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 0
  rlm_eap: EAP Identity
  rlm_eap: processing type tls
  rlm_eap_tls: Initiate
  rlm_eap_tls: Start returned 1
  modcall[authenticate]: module "eap" returns handled for request 0
modcall: leaving group authenticate (returns handled) for request 0
Sending Access-Challenge of id 0 to 192.168.90.99 port 3074
	EAP-Message = 0x010100061920
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x91491508003cb277427a954d2097d76f
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 192.168.90.99:3074, id=0,
length=217
	User-Name = "radius.test"
	NAS-IP-Address = 192.168.90.99
	Called-Station-Id = "0018f8adf209"
	Calling-Station-Id = "000e351db86b"
	NAS-Identifier = "0018f8adf209"
	NAS-Port = 14
	Framed-MTU = 1400
	State = 0x91491508003cb277427a954d2097d76f
	NAS-Port-Type = Wireless-802.11
	EAP-Message =
0x0201005019800000004616030100410100003d030148342178def6efb1232d2bff6278bb1d1da5903981b502821af1dbcf0f55439d00001600040005000a000900640062000300060013001200630100
	Message-Authenticator = 0xc7a38a5340a37dbd72dc31047d1ec10a
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 1
  modcall[authorize]: module "preprocess" returns ok for request 1
  modcall[authorize]: module "mschap" returns noop for request 1
    rlm_realm: No '/' in User-Name = "radius.test", skipping NULL due to
config.
  modcall[authorize]: module "IPASS" returns noop for request 1
    rlm_realm: No '@' in User-Name = "radius.test", skipping NULL due to
config.
  modcall[authorize]: module "suffix" returns noop for request 1
    rlm_realm: No '\' in User-Name = "radius.test", skipping NULL due to
config.
  modcall[authorize]: module "ntdomain" returns noop for request 1
    rlm_realm: No '@' in User-Name = "radius.test", looking up realm NULL
    rlm_realm: Found realm "NULL"
    rlm_realm: Adding Stripped-User-Name = "radius.test"
    rlm_realm: Proxying request from user radius.test to realm NULL
    rlm_realm: Adding Realm = "NULL"
    rlm_realm: Authentication realm is LOCAL.
  modcall[authorize]: module "NULL" returns noop for request 1
  rlm_eap: EAP packet type response id 1 length 80
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 1
try to find in file
rlm_passwd: Added LM-Password: '01FC5A6BE7BC6929AAD3B435B51404EE' to
config_items
rlm_passwd: Added NT-Password: '0CB6948805F797BF2A82807973B89537' to
config_items
rlm_passwd: Added SMB-Account-CTRL-TEXT: '[U          ]' to config_items
try to find in file
  modcall[authorize]: module "etc_smbpasswd" returns ok for request 1
try to find in file
  modcall[authorize]: module "etc_group" returns notfound for request 1
  modcall[authorize]: module "files" returns notfound for request 1
modcall: leaving group authorize (returns updated) for request 1
  rad_check_password:  Found Auth-Type EAP
auth: type "EAP"
  Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 1
  rlm_eap: Request found, released from the list
  rlm_eap: EAP/peap
  rlm_eap: processing type peap
  rlm_eap_peap: Authenticate
  rlm_eap_tls: processing TLS
rlm_eap_tls:  Length Included
  eaptls_verify returned 11
    (other): before/accept initialization
    TLS_accept: before/accept initialization
  rlm_eap_tls: <<< TLS 1.0 Handshake [length 0041], ClientHello
    TLS_accept: SSLv3 read client hello A
  rlm_eap_tls: >>> TLS 1.0 Handshake [length 004a], ServerHello
    TLS_accept: SSLv3 write server hello A
  rlm_eap_tls: >>> TLS 1.0 Handshake [length 07a0], Certificate
    TLS_accept: SSLv3 write certificate A
  rlm_eap_tls: >>> TLS 1.0 Handshake [length 0004], ServerHelloDone
    TLS_accept: SSLv3 write server done A
    TLS_accept: SSLv3 flush data
    TLS_accept: Need to read more data: SSLv3 read client certificate A
In SSL Handshake Phase
In SSL Accept mode
  eaptls_process returned 13
  rlm_eap_peap: EAPTLS_HANDLED
  modcall[authenticate]: module "eap" returns handled for request 1
modcall: leaving group authenticate (returns handled) for request 1
Sending Access-Challenge of id 0 to 192.168.90.99 port 3074
	EAP-Message =
0x0102040a19c0000007fd160301004a0200004603014834216faa18dddd2f612972cfb884461bf6fbd3fb14fa5ac044c809c491a3d5208d4560b3a6142e1e8481466b8deece972ec724ea6b3dc3bba17b03fa7fcea90200040016030107a00b00079c0007990003623082035e30820246a003020102020101300d06092a864886f70d0101040500306c310b3009060355040613026465310a3008060355040813012e310a3008060355040713012e310a3008060355040a13012e3121301f06092a864886f70d0109011612726f6f7440646576322e69736572762e6575311630140603550403130d646576322e69736572762e6575301e170d30383034
	EAP-Message =
0x31303134323231325a170d3039303431303134323231325a3060310b3009060355040613026465310a3008060355040813012e310a3008060355040a13012e311630140603550403130d646576322e69736572762e65753121301f06092a864886f70d0109011612726f6f7440646576322e69736572762e657530820122300d06092a864886f70d01010105000382010f003082010a0282010100dad25dca4d1f9f5c204e658b4563f45473286026aa0c3fbb1a54512f4d3565f92ef4864bc40b62844896bb61d29049902fb74ef70b4c3e112bd95f49cf4a4f51be93d51288612c5bb1f4637f14977ac99865cbcf1f8f913276c07e2f0659db7c5ce6
	EAP-Message =
0xca5e4736a07cd1cdbcff45595ba3185c274fb59ed9d4649ca6be1ba5bdbef9ecc1fef98631bd416bb82580bfb8bf686fac168bafd2e0e4bdd081e730df79b57f0366fdb0b83add13844fec0faecd5c5f5911db45fdb1fc2e93fc6a5b5c6acfe5cec72cad5a1bfee550ef0ec840c0da48cac8167ddfb580ad31160296272c9ddfb6860b81f101febfe23f7f26f5bb856e91918650ecb5ed30a7b521e9c3410203010001a317301530130603551d25040c300a06082b06010505070301300d06092a864886f70d01010405000382010100bc4c19797581b1f7a5afaba1130946f5b362ad089907200fe70ac3c93c20a8366d2830f0a01088eedd3cf5601d
	EAP-Message =
0x1fa6557a780a20e8b34d43e78fd9a6e806a55b9899ef8a4ac0365f6413addbcfcf8ba221397230d8bec54455f1c7ce3ae5f48f5eb6b9456d9d8e65f33903e16b8d61ea3ded6f62129fa914164e0212a0f591c7f8ed7543493d2463706b3c526d149967489a3d049dad7a8f9a728822e96e713bca4aecda9b7878c2a1ef6cb9174e2879b425149bc8ca30f789fb92eac94ca0a492b438a0bf17c86842bf77ccbbe9881c936199ec99bc4e2ac4b1c703029d68cb19f7bd3c0f34f77a4952b0131817753e2fc0d494915412095e2d96793b363a5e0004313082042d30820315a003020102020900ab1f7a4cf1f05de8300d06092a864886f70d0101050500
	EAP-Message = 0x306c310b3009060355040613026465310a3008060355
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0xaca6bb8087c1c2c3c5221ec30ceb2c16
Finished request 1
Going to the next request
Waking up in 6 seconds...
rad_recv: Access-Request packet from host 192.168.90.99:3074, id=0,
length=143
	User-Name = "radius.test"
	NAS-IP-Address = 192.168.90.99
	Called-Station-Id = "0018f8adf209"
	Calling-Station-Id = "000e351db86b"
	NAS-Identifier = "0018f8adf209"
	NAS-Port = 14
	Framed-MTU = 1400
	State = 0xaca6bb8087c1c2c3c5221ec30ceb2c16
	NAS-Port-Type = Wireless-802.11
	EAP-Message = 0x020200061900
	Message-Authenticator = 0xc2767ad82cd2b732b265bf70b277e023
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 2
  modcall[authorize]: module "preprocess" returns ok for request 2
  modcall[authorize]: module "mschap" returns noop for request 2
    rlm_realm: No '/' in User-Name = "radius.test", skipping NULL due to
config.
  modcall[authorize]: module "IPASS" returns noop for request 2
    rlm_realm: No '@' in User-Name = "radius.test", skipping NULL due to
config.
  modcall[authorize]: module "suffix" returns noop for request 2
    rlm_realm: No '\' in User-Name = "radius.test", skipping NULL due to
config.
  modcall[authorize]: module "ntdomain" returns noop for request 2
    rlm_realm: No '@' in User-Name = "radius.test", looking up realm NULL
    rlm_realm: Found realm "NULL"
    rlm_realm: Adding Stripped-User-Name = "radius.test"
    rlm_realm: Proxying request from user radius.test to realm NULL
    rlm_realm: Adding Realm = "NULL"
    rlm_realm: Authentication realm is LOCAL.
  modcall[authorize]: module "NULL" returns noop for request 2
  rlm_eap: EAP packet type response id 2 length 6
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 2
try to find in file
rlm_passwd: Added LM-Password: '01FC5A6BE7BC6929AAD3B435B51404EE' to
config_items
rlm_passwd: Added NT-Password: '0CB6948805F797BF2A82807973B89537' to
config_items
rlm_passwd: Added SMB-Account-CTRL-TEXT: '[U          ]' to config_items
try to find in file
  modcall[authorize]: module "etc_smbpasswd" returns ok for request 2
try to find in file
  modcall[authorize]: module "etc_group" returns notfound for request 2
  modcall[authorize]: module "files" returns notfound for request 2
modcall: leaving group authorize (returns updated) for request 2
  rad_check_password:  Found Auth-Type EAP
auth: type "EAP"
  Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 2
  rlm_eap: Request found, released from the list
  rlm_eap: EAP/peap
  rlm_eap: processing type peap
  rlm_eap_peap: Authenticate
  rlm_eap_tls: processing TLS
rlm_eap_tls: Received EAP-TLS ACK message
  rlm_eap_tls: ack handshake fragment handler
  eaptls_verify returned 1
  eaptls_process returned 13
  rlm_eap_peap: EAPTLS_HANDLED
  modcall[authenticate]: module "eap" returns handled for request 2
modcall: leaving group authenticate (returns handled) for request 2
Sending Access-Challenge of id 0 to 192.168.90.99 port 3074
	EAP-Message =
0x010304031900040813012e310a3008060355040713012e310a3008060355040a13012e3121301f06092a864886f70d0109011612726f6f7440646576322e69736572762e6575311630140603550403130d646576322e69736572762e6575301e170d3038303431303134323231325a170d3038303531303134323231325a306c310b3009060355040613026465310a3008060355040813012e310a3008060355040713012e310a3008060355040a13012e3121301f06092a864886f70d0109011612726f6f7440646576322e69736572762e6575311630140603550403130d646576322e69736572762e657530820122300d06092a864886f70d010101
	EAP-Message =
0x05000382010f003082010a0282010100e309c58b5da1007bd597f4500daf63671e1bf326eb20e659f4b42a0b93183e74e9a85b23b17211781dcc86d86193efe2dd6a4bc74225e1e6547ae32af02a377be57f0e51fff3924d9af0839dc3f997b2dd01bb4112536364c45e33ec225fcee553f3f9347a1486789048edc80747bc0c6ffb6006bb428e097cc2da21f052e50afcabb2102d0840fc8b8516b64c3f842ccfdd65da7f204d70dfe9752fc762965c57507b92ad5d4d286a810551e9ceb8de83c702b63e39cecee50626df3bab5474ed8132322dd504ba6c08cafd430e7b989a03332d1eb17265bfbcbaf7aea59aae6a7af98dbd3dc8510a567ae396
	EAP-Message =
0x3d56b7bfce518dc8e78e79fd56b181826f7ffd0203010001a381d13081ce301d0603551d0e041604143cc663d140f71e715fb58b7bfb4ba32268dff0d730819e0603551d2304819630819380143cc663d140f71e715fb58b7bfb4ba32268dff0d7a170a46e306c310b3009060355040613026465310a3008060355040813012e310a3008060355040713012e310a3008060355040a13012e3121301f06092a864886f70d0109011612726f6f7440646576322e69736572762e6575311630140603550403130d646576322e69736572762e6575820900ab1f7a4cf1f05de8300c0603551d13040530030101ff300d06092a864886f70d01010505000382
	EAP-Message =
0x0101003f09fcb47c0ba870a55464bd83e658c4b9c82d0a78da445630b099ae426cd5ce4b1b642ebe2cb23e060a48b3e6b032958477dc13e0d26b90250c2f08308905a1b0eccb48165c489269f958ecc9704c3d9c177e2dd6ee5690fa4b61e05ce9162e72d4159960d8a779184d9b4b088fd34037987ad5c0a9dc1d0c914fc5fad9f013cc5f0a5cc416159e5da53f9e26dc7e03f9a4e4fd7fd93536ba94a0b8be6497f1d54ca9f971e3f367f0d38b8b14fe5d1b3d232df9e8da30670390e52bc644bf6f1a752b11b6e0a611e2476e784ed6feeee6c8965c073192327e1d548214a35c50ccd07d4abffab79e42efe9d42442e272e5765020436e06b916df
	EAP-Message = 0x519efd0a44b416030100040e000000
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x3ca16efbbe60a8423fabc2a4a6a98324
Finished request 2
Going to the next request
Waking up in 6 seconds...
rad_recv: Access-Request packet from host 192.168.90.99:3074, id=0,
length=459
	User-Name = "radius.test"
	NAS-IP-Address = 192.168.90.99
	Called-Station-Id = "0018f8adf209"
	Calling-Station-Id = "000e351db86b"
	NAS-Identifier = "0018f8adf209"
	NAS-Port = 14
	Framed-MTU = 1400
	State = 0x3ca16efbbe60a8423fabc2a4a6a98324
	NAS-Port-Type = Wireless-802.11
	EAP-Message =
0x0203014019800000013616030101061000010201006c4aaebab568b3628e1071ee3dce5639e4e5cdca6877a0687137f15aaab01c91d4c9aa010b6614840fd7e6bdd5d5092777b5b3513f4d209694f2a1b939fc18d82bb4dbce461e3012a1d0c18ff6f96cbe74e020692355b56b2d36b5c613754d74f78fc45eda8d3f467d21b6c7b8ccd59254cd17662228ae843a118789e17ed6a65f3c8d2e98d65949173a9f2b547add10421b16454c8de9654dec666d1679a508325831da520e7dbc2b145bda768b043bbd8ea5b5ca16e7d08cf1e34e5cb0bb6ad89432c30eef9ae7df5eebb4589039e35f930ec4b0e66651def8b27bb8ee83496ba3184db59450ac
	EAP-Message =
0x27cf47bb3fd109a0ee0ae37a390d717fef827823e3ab913b1403010001011603010020681cfe49043ffb9693c7055a4c43399c542411fa5728c2e56df71e69be5b1dbd
	Message-Authenticator = 0xe0f1772b9ffde601e852d1e5e7a2c125
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 3
  modcall[authorize]: module "preprocess" returns ok for request 3
  modcall[authorize]: module "mschap" returns noop for request 3
    rlm_realm: No '/' in User-Name = "radius.test", skipping NULL due to
config.
  modcall[authorize]: module "IPASS" returns noop for request 3
    rlm_realm: No '@' in User-Name = "radius.test", skipping NULL due to
config.
  modcall[authorize]: module "suffix" returns noop for request 3
    rlm_realm: No '\' in User-Name = "radius.test", skipping NULL due to
config.
  modcall[authorize]: module "ntdomain" returns noop for request 3
    rlm_realm: No '@' in User-Name = "radius.test", looking up realm NULL
    rlm_realm: Found realm "NULL"
    rlm_realm: Adding Stripped-User-Name = "radius.test"
    rlm_realm: Proxying request from user radius.test to realm NULL
    rlm_realm: Adding Realm = "NULL"
    rlm_realm: Authentication realm is LOCAL.
  modcall[authorize]: module "NULL" returns noop for request 3
  rlm_eap: EAP packet type response id 3 length 253
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 3
try to find in file
rlm_passwd: Added LM-Password: '01FC5A6BE7BC6929AAD3B435B51404EE' to
config_items
rlm_passwd: Added NT-Password: '0CB6948805F797BF2A82807973B89537' to
config_items
rlm_passwd: Added SMB-Account-CTRL-TEXT: '[U          ]' to config_items
try to find in file
  modcall[authorize]: module "etc_smbpasswd" returns ok for request 3
try to find in file
  modcall[authorize]: module "etc_group" returns notfound for request 3
  modcall[authorize]: module "files" returns notfound for request 3
modcall: leaving group authorize (returns updated) for request 3
  rad_check_password:  Found Auth-Type EAP
auth: type "EAP"
  Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 3
  rlm_eap: Request found, released from the list
  rlm_eap: EAP/peap
  rlm_eap: processing type peap
  rlm_eap_peap: Authenticate
  rlm_eap_tls: processing TLS
rlm_eap_tls:  Length Included
  eaptls_verify returned 11
  rlm_eap_tls: <<< TLS 1.0 Handshake [length 0106], ClientKeyExchange
    TLS_accept: SSLv3 read client key exchange A
  rlm_eap_tls: <<< TLS 1.0 ChangeCipherSpec [length 0001]
  rlm_eap_tls: <<< TLS 1.0 Handshake [length 0010], Finished
    TLS_accept: SSLv3 read finished A
  rlm_eap_tls: >>> TLS 1.0 ChangeCipherSpec [length 0001]
    TLS_accept: SSLv3 write change cipher spec A
  rlm_eap_tls: >>> TLS 1.0 Handshake [length 0010], Finished
    TLS_accept: SSLv3 write finished A
    TLS_accept: SSLv3 flush data
    (other): SSL negotiation finished successfully
SSL Connection Established
  eaptls_process returned 13
  rlm_eap_peap: EAPTLS_HANDLED
  modcall[authenticate]: module "eap" returns handled for request 3
modcall: leaving group authenticate (returns handled) for request 3
Sending Access-Challenge of id 0 to 192.168.90.99 port 3074
	EAP-Message =
0x0104003119001403010001011603010020afc5ff95ec041dabd57928f051a048f56612d9ead2860ddadf63b7eb011aa310
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x40fbd71a1891f37fc89cf8b5f11375e4
Finished request 3
Going to the next request
Waking up in 6 seconds...
rad_recv: Access-Request packet from host 192.168.90.99:3074, id=0,
length=143
	User-Name = "radius.test"
	NAS-IP-Address = 192.168.90.99
	Called-Station-Id = "0018f8adf209"
	Calling-Station-Id = "000e351db86b"
	NAS-Identifier = "0018f8adf209"
	NAS-Port = 14
	Framed-MTU = 1400
	State = 0x40fbd71a1891f37fc89cf8b5f11375e4
	NAS-Port-Type = Wireless-802.11
	EAP-Message = 0x020400061900
	Message-Authenticator = 0x89e38e6ee85c5a8ec34803cfdd00313d
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 4
  modcall[authorize]: module "preprocess" returns ok for request 4
  modcall[authorize]: module "mschap" returns noop for request 4
    rlm_realm: No '/' in User-Name = "radius.test", skipping NULL due to
config.
  modcall[authorize]: module "IPASS" returns noop for request 4
    rlm_realm: No '@' in User-Name = "radius.test", skipping NULL due to
config.
  modcall[authorize]: module "suffix" returns noop for request 4
    rlm_realm: No '\' in User-Name = "radius.test", skipping NULL due to
config.
  modcall[authorize]: module "ntdomain" returns noop for request 4
    rlm_realm: No '@' in User-Name = "radius.test", looking up realm NULL
    rlm_realm: Found realm "NULL"
    rlm_realm: Adding Stripped-User-Name = "radius.test"
    rlm_realm: Proxying request from user radius.test to realm NULL
    rlm_realm: Adding Realm = "NULL"
    rlm_realm: Authentication realm is LOCAL.
  modcall[authorize]: module "NULL" returns noop for request 4
  rlm_eap: EAP packet type response id 4 length 6
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 4
try to find in file
rlm_passwd: Added LM-Password: '01FC5A6BE7BC6929AAD3B435B51404EE' to
config_items
rlm_passwd: Added NT-Password: '0CB6948805F797BF2A82807973B89537' to
config_items
rlm_passwd: Added SMB-Account-CTRL-TEXT: '[U          ]' to config_items
try to find in file
  modcall[authorize]: module "etc_smbpasswd" returns ok for request 4
try to find in file
  modcall[authorize]: module "etc_group" returns notfound for request 4
  modcall[authorize]: module "files" returns notfound for request 4
modcall: leaving group authorize (returns updated) for request 4
  rad_check_password:  Found Auth-Type EAP
auth: type "EAP"
  Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 4
  rlm_eap: Request found, released from the list
  rlm_eap: EAP/peap
  rlm_eap: processing type peap
  rlm_eap_peap: 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_peap: EAPTLS_SUCCESS
  modcall[authenticate]: module "eap" returns handled for request 4
modcall: leaving group authenticate (returns handled) for request 4
Sending Access-Challenge of id 0 to 192.168.90.99 port 3074
	EAP-Message =
0x0105002019001703010015c8c468e0cda9739dceb5f453f3dfcb56ae322f6dcd
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0xb8321779987cf45090d58a84297fed1b
Finished request 4
Going to the next request
--- Walking the entire request list ---
Waking up in 6 seconds...
rad_recv: Access-Request packet from host 192.168.90.99:3074, id=0,
length=176
	User-Name = "radius.test"
	NAS-IP-Address = 192.168.90.99
	Called-Station-Id = "0018f8adf209"
	Calling-Station-Id = "000e351db86b"
	NAS-Identifier = "0018f8adf209"
	NAS-Port = 14
	Framed-MTU = 1400
	State = 0xb8321779987cf45090d58a84297fed1b
	NAS-Port-Type = Wireless-802.11
	EAP-Message =
0x020500271900170301001c6d32866d9f77272f05ca4086d14341e1013e80df8fe6810217d58c9e
	Message-Authenticator = 0x1450903706f5678f95c3f6a913039f00
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 5
  modcall[authorize]: module "preprocess" returns ok for request 5
  modcall[authorize]: module "mschap" returns noop for request 5
    rlm_realm: No '/' in User-Name = "radius.test", skipping NULL due to
config.
  modcall[authorize]: module "IPASS" returns noop for request 5
    rlm_realm: No '@' in User-Name = "radius.test", skipping NULL due to
config.
  modcall[authorize]: module "suffix" returns noop for request 5
    rlm_realm: No '\' in User-Name = "radius.test", skipping NULL due to
config.
  modcall[authorize]: module "ntdomain" returns noop for request 5
    rlm_realm: No '@' in User-Name = "radius.test", looking up realm NULL
    rlm_realm: Found realm "NULL"
    rlm_realm: Adding Stripped-User-Name = "radius.test"
    rlm_realm: Proxying request from user radius.test to realm NULL
    rlm_realm: Adding Realm = "NULL"
    rlm_realm: Authentication realm is LOCAL.
  modcall[authorize]: module "NULL" returns noop for request 5
  rlm_eap: EAP packet type response id 5 length 39
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 5
try to find in file
rlm_passwd: Added LM-Password: '01FC5A6BE7BC6929AAD3B435B51404EE' to
config_items
rlm_passwd: Added NT-Password: '0CB6948805F797BF2A82807973B89537' to
config_items
rlm_passwd: Added SMB-Account-CTRL-TEXT: '[U          ]' to config_items
try to find in file
  modcall[authorize]: module "etc_smbpasswd" returns ok for request 5
try to find in file
  modcall[authorize]: module "etc_group" returns notfound for request 5
  modcall[authorize]: module "files" returns notfound for request 5
modcall: leaving group authorize (returns updated) for request 5
  rad_check_password:  Found Auth-Type EAP
auth: type "EAP"
  Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 5
  rlm_eap: Request found, released from the list
  rlm_eap: EAP/peap
  rlm_eap: processing type peap
  rlm_eap_peap: Authenticate
  rlm_eap_tls: processing TLS
  eaptls_verify returned 7
  rlm_eap_tls: Done initial handshake
  eaptls_process returned 7
  rlm_eap_peap: EAPTLS_OK
  rlm_eap_peap: Session established.  Decoding tunneled attributes.
  rlm_eap_peap: Identity - radius.test
  rlm_eap_peap: Tunneled data is valid.
  PEAP: Got tunneled EAP-Message
	EAP-Message = 0x02050010017261646975732e74657374
  PEAP: Got tunneled identity of radius.test
  PEAP: Setting default EAP type for tunneled EAP session.
  PEAP: Setting User-Name to radius.test
  PEAP: Sending tunneled request
	EAP-Message = 0x02050010017261646975732e74657374
	FreeRADIUS-Proxied-To = 127.0.0.1
	User-Name = "radius.test"
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 5
  modcall[authorize]: module "preprocess" returns ok for request 5
  modcall[authorize]: module "mschap" returns noop for request 5
    rlm_realm: No '/' in User-Name = "radius.test", skipping NULL due to
config.
  modcall[authorize]: module "IPASS" returns noop for request 5
    rlm_realm: No '@' in User-Name = "radius.test", skipping NULL due to
config.
  modcall[authorize]: module "suffix" returns noop for request 5
    rlm_realm: No '\' in User-Name = "radius.test", skipping NULL due to
config.
  modcall[authorize]: module "ntdomain" returns noop for request 5
    rlm_realm: No '@' in User-Name = "radius.test", looking up realm NULL
    rlm_realm: Found realm "NULL"
    rlm_realm: Adding Stripped-User-Name = "radius.test"
    rlm_realm: Proxying request from user radius.test to realm NULL
    rlm_realm: Adding Realm = "NULL"
    rlm_realm: Authentication realm is LOCAL.
  modcall[authorize]: module "NULL" returns noop for request 5
  rlm_eap: EAP packet type response id 5 length 16
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 5
try to find in file
rlm_passwd: Added LM-Password: '01FC5A6BE7BC6929AAD3B435B51404EE' to
config_items
rlm_passwd: Added NT-Password: '0CB6948805F797BF2A82807973B89537' to
config_items
rlm_passwd: Added SMB-Account-CTRL-TEXT: '[U          ]' to config_items
try to find in file
  modcall[authorize]: module "etc_smbpasswd" returns ok for request 5
try to find in file
  modcall[authorize]: module "etc_group" returns notfound for request 5
  modcall[authorize]: module "files" returns notfound for request 5
modcall: leaving group authorize (returns updated) for request 5
  rad_check_password:  Found Auth-Type EAP
auth: type "EAP"
  Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 5
  rlm_eap: EAP Identity
  rlm_eap: processing type mschapv2
rlm_eap_mschapv2: Issuing Challenge
  modcall[authenticate]: module "eap" returns handled for request 5
modcall: leaving group authenticate (returns handled) for request 5
  PEAP: Got tunneled reply RADIUS code 11
	EAP-Message =
0x010600251a01060020106f5ecf9995eca5dcf7830dac44edf57c7261646975732e74657374
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0xaf219783135a43979003d381f735eda7
  PEAP: Processing from tunneled session code 0x8177458 11
	EAP-Message =
0x010600251a01060020106f5ecf9995eca5dcf7830dac44edf57c7261646975732e74657374
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0xaf219783135a43979003d381f735eda7
  PEAP: Got tunneled Access-Challenge
  modcall[authenticate]: module "eap" returns handled for request 5
modcall: leaving group authenticate (returns handled) for request 5
Sending Access-Challenge of id 0 to 192.168.90.99 port 3074
	EAP-Message =
0x0106003c19001703010031f3762ae88cdc9a9c7d6b00ee50921fc1e19fa9b36a8a0b7f99a362f41bac2e3f2fde6b999c374ffe0c4ceae6f9d1ea7129
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x2b65ba1a68bab25a22274b3370d7054b
Finished request 5
Going to the next request
Waking up in 6 seconds...
rad_recv: Access-Request packet from host 192.168.90.99:3074, id=0,
length=230
	User-Name = "radius.test"
	NAS-IP-Address = 192.168.90.99
	Called-Station-Id = "0018f8adf209"
	Calling-Station-Id = "000e351db86b"
	NAS-Identifier = "0018f8adf209"
	NAS-Port = 14
	Framed-MTU = 1400
	State = 0x2b65ba1a68bab25a22274b3370d7054b
	NAS-Port-Type = Wireless-802.11
	EAP-Message =
0x0206005d19001703010052020db50d2ce3a87a83778c8a0a54944cdfa8296b000d9478846c1f77f76d9d913c90e5b217a0170e15f8654f9362a205109b7036546dc6568ce8ad11f7bae6fdfda01b7c2a67e1a00012516ac3e41b68fda1
	Message-Authenticator = 0x3cbd9f44777bcb4193b39110cf81a3bb
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 6
  modcall[authorize]: module "preprocess" returns ok for request 6
  modcall[authorize]: module "mschap" returns noop for request 6
    rlm_realm: No '/' in User-Name = "radius.test", skipping NULL due to
config.
  modcall[authorize]: module "IPASS" returns noop for request 6
    rlm_realm: No '@' in User-Name = "radius.test", skipping NULL due to
config.
  modcall[authorize]: module "suffix" returns noop for request 6
    rlm_realm: No '\' in User-Name = "radius.test", skipping NULL due to
config.
  modcall[authorize]: module "ntdomain" returns noop for request 6
    rlm_realm: No '@' in User-Name = "radius.test", looking up realm NULL
    rlm_realm: Found realm "NULL"
    rlm_realm: Adding Stripped-User-Name = "radius.test"
    rlm_realm: Proxying request from user radius.test to realm NULL
    rlm_realm: Adding Realm = "NULL"
    rlm_realm: Authentication realm is LOCAL.
  modcall[authorize]: module "NULL" returns noop for request 6
  rlm_eap: EAP packet type response id 6 length 93
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 6
try to find in file
rlm_passwd: Added LM-Password: '01FC5A6BE7BC6929AAD3B435B51404EE' to
config_items
rlm_passwd: Added NT-Password: '0CB6948805F797BF2A82807973B89537' to
config_items
rlm_passwd: Added SMB-Account-CTRL-TEXT: '[U          ]' to config_items
try to find in file
  modcall[authorize]: module "etc_smbpasswd" returns ok for request 6
try to find in file
  modcall[authorize]: module "etc_group" returns notfound for request 6
  modcall[authorize]: module "files" returns notfound for request 6
modcall: leaving group authorize (returns updated) for request 6
  rad_check_password:  Found Auth-Type EAP
auth: type "EAP"
  Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 6
  rlm_eap: Request found, released from the list
  rlm_eap: EAP/peap
  rlm_eap: processing type peap
  rlm_eap_peap: Authenticate
  rlm_eap_tls: processing TLS
  eaptls_verify returned 7
  rlm_eap_tls: Done initial handshake
  eaptls_process returned 7
  rlm_eap_peap: EAPTLS_OK
  rlm_eap_peap: Session established.  Decoding tunneled attributes.
  rlm_eap_peap: EAP type mschapv2
  rlm_eap_peap: Tunneled data is valid.
  PEAP: Got tunneled EAP-Message
	EAP-Message =
0x020600461a0206004131dcccc6474aeb98ae69bb63874a9447840000000000000000e8cc61af0b3f6d60f67763cdc0913e9f3b911907298aa424007261646975732e74657374
  PEAP: Setting User-Name to radius.test
  PEAP: Adding old state with af 21
  PEAP: Sending tunneled request
	EAP-Message =
0x020600461a0206004131dcccc6474aeb98ae69bb63874a9447840000000000000000e8cc61af0b3f6d60f67763cdc0913e9f3b911907298aa424007261646975732e74657374
	FreeRADIUS-Proxied-To = 127.0.0.1
	User-Name = "radius.test"
	State = 0xaf219783135a43979003d381f735eda7
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 6
  modcall[authorize]: module "preprocess" returns ok for request 6
  modcall[authorize]: module "mschap" returns noop for request 6
    rlm_realm: No '/' in User-Name = "radius.test", skipping NULL due to
config.
  modcall[authorize]: module "IPASS" returns noop for request 6
    rlm_realm: No '@' in User-Name = "radius.test", skipping NULL due to
config.
  modcall[authorize]: module "suffix" returns noop for request 6
    rlm_realm: No '\' in User-Name = "radius.test", skipping NULL due to
config.
  modcall[authorize]: module "ntdomain" returns noop for request 6
    rlm_realm: No '@' in User-Name = "radius.test", looking up realm NULL
    rlm_realm: Found realm "NULL"
    rlm_realm: Adding Stripped-User-Name = "radius.test"
    rlm_realm: Proxying request from user radius.test to realm NULL
    rlm_realm: Adding Realm = "NULL"
    rlm_realm: Authentication realm is LOCAL.
  modcall[authorize]: module "NULL" returns noop for request 6
  rlm_eap: EAP packet type response id 6 length 70
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 6
try to find in file
rlm_passwd: Added LM-Password: '01FC5A6BE7BC6929AAD3B435B51404EE' to
config_items
rlm_passwd: Added NT-Password: '0CB6948805F797BF2A82807973B89537' to
config_items
rlm_passwd: Added SMB-Account-CTRL-TEXT: '[U          ]' to config_items
try to find in file
  modcall[authorize]: module "etc_smbpasswd" returns ok for request 6
try to find in file
  modcall[authorize]: module "etc_group" returns notfound for request 6
  modcall[authorize]: module "files" returns notfound for request 6
modcall: leaving group authorize (returns updated) for request 6
  rad_check_password:  Found Auth-Type EAP
auth: type "EAP"
  Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 6
  rlm_eap: Request found, released from the list
  rlm_eap: EAP/mschapv2
  rlm_eap: processing type mschapv2
  Processing the authenticate section of radiusd.conf
modcall: entering group MS-CHAP for request 6
  rlm_mschap: Found LM-Password
  rlm_mschap: Found NT-Password
  rlm_mschap: Told to do MS-CHAPv2 for radius.test with NT-Password
rlm_mschap: adding MS-CHAPv2 MPPE keys
  modcall[authenticate]: module "mschap" returns ok for request 6
modcall: leaving group MS-CHAP (returns ok) for request 6
MSCHAP Success
  modcall[authenticate]: module "eap" returns handled for request 6
modcall: leaving group authenticate (returns handled) for request 6
  PEAP: Got tunneled reply RADIUS code 11
	MS-CHAP2-Success =
0x06533d41424244314434333831363232414439393438383833333530423934313344433035413843324241
	EAP-Message =
0x010700331a0306002e533d41424244314434333831363232414439393438383833333530423934313344433035413843324241
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0xfc028fd69bc7f8f8e458b02aa56318c0
  PEAP: Processing from tunneled session code 0x8153b00 11
	MS-CHAP2-Success =
0x06533d41424244314434333831363232414439393438383833333530423934313344433035413843324241
	EAP-Message =
0x010700331a0306002e533d41424244314434333831363232414439393438383833333530423934313344433035413843324241
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0xfc028fd69bc7f8f8e458b02aa56318c0
  PEAP: Got tunneled Access-Challenge
  modcall[authenticate]: module "eap" returns handled for request 6
modcall: leaving group authenticate (returns handled) for request 6
Sending Access-Challenge of id 0 to 192.168.90.99 port 3074
	EAP-Message =
0x0107004a1900170301003f4cfb2c7590495fe0b63f51667a81688c1a47a7ea500fbdaa7d0ea4816a5f177ea7fdbcba8f125e7a3f3f32be7ef4c213d6b737c255ea7a41832ade358a800f
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x0d9baccbd7c88f417837920cd8da7736
Finished request 6
Going to the next request
Waking up in 6 seconds...
rad_recv: Access-Request packet from host 192.168.90.99:3074, id=0,
length=166
	User-Name = "radius.test"
	NAS-IP-Address = 192.168.90.99
	Called-Station-Id = "0018f8adf209"
	Calling-Station-Id = "000e351db86b"
	NAS-Identifier = "0018f8adf209"
	NAS-Port = 14
	Framed-MTU = 1400
	State = 0x0d9baccbd7c88f417837920cd8da7736
	NAS-Port-Type = Wireless-802.11
	EAP-Message = 0x0207001d1900170301001265ee22ebd957f0b698f64b1645f50be02753
	Message-Authenticator = 0x9f2bb54bc99aef6bda5f662f6d15cf9f
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 7
  modcall[authorize]: module "preprocess" returns ok for request 7
  modcall[authorize]: module "mschap" returns noop for request 7
    rlm_realm: No '/' in User-Name = "radius.test", skipping NULL due to
config.
  modcall[authorize]: module "IPASS" returns noop for request 7
    rlm_realm: No '@' in User-Name = "radius.test", skipping NULL due to
config.
  modcall[authorize]: module "suffix" returns noop for request 7
    rlm_realm: No '\' in User-Name = "radius.test", skipping NULL due to
config.
  modcall[authorize]: module "ntdomain" returns noop for request 7
    rlm_realm: No '@' in User-Name = "radius.test", looking up realm NULL
    rlm_realm: Found realm "NULL"
    rlm_realm: Adding Stripped-User-Name = "radius.test"
    rlm_realm: Proxying request from user radius.test to realm NULL
    rlm_realm: Adding Realm = "NULL"
    rlm_realm: Authentication realm is LOCAL.
  modcall[authorize]: module "NULL" returns noop for request 7
  rlm_eap: EAP packet type response id 7 length 29
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 7
try to find in file
rlm_passwd: Added LM-Password: '01FC5A6BE7BC6929AAD3B435B51404EE' to
config_items
rlm_passwd: Added NT-Password: '0CB6948805F797BF2A82807973B89537' to
config_items
rlm_passwd: Added SMB-Account-CTRL-TEXT: '[U          ]' to config_items
try to find in file
  modcall[authorize]: module "etc_smbpasswd" returns ok for request 7
try to find in file
  modcall[authorize]: module "etc_group" returns notfound for request 7
  modcall[authorize]: module "files" returns notfound for request 7
modcall: leaving group authorize (returns updated) for request 7
  rad_check_password:  Found Auth-Type EAP
auth: type "EAP"
  Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 7
  rlm_eap: Request found, released from the list
  rlm_eap: EAP/peap
  rlm_eap: processing type peap
  rlm_eap_peap: Authenticate
  rlm_eap_tls: processing TLS
  eaptls_verify returned 7
  rlm_eap_tls: Done initial handshake
  eaptls_process returned 7
  rlm_eap_peap: EAPTLS_OK
  rlm_eap_peap: Session established.  Decoding tunneled attributes.
  rlm_eap_peap: EAP type mschapv2
  rlm_eap_peap: Tunneled data is valid.
  PEAP: Got tunneled EAP-Message
	EAP-Message = 0x020700061a03
  PEAP: Setting User-Name to radius.test
  PEAP: Adding old state with fc 02
  PEAP: Sending tunneled request
	EAP-Message = 0x020700061a03
	FreeRADIUS-Proxied-To = 127.0.0.1
	User-Name = "radius.test"
	State = 0xfc028fd69bc7f8f8e458b02aa56318c0
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 7
  modcall[authorize]: module "preprocess" returns ok for request 7
  modcall[authorize]: module "mschap" returns noop for request 7
    rlm_realm: No '/' in User-Name = "radius.test", skipping NULL due to
config.
  modcall[authorize]: module "IPASS" returns noop for request 7
    rlm_realm: No '@' in User-Name = "radius.test", skipping NULL due to
config.
  modcall[authorize]: module "suffix" returns noop for request 7
    rlm_realm: No '\' in User-Name = "radius.test", skipping NULL due to
config.
  modcall[authorize]: module "ntdomain" returns noop for request 7
    rlm_realm: No '@' in User-Name = "radius.test", looking up realm NULL
    rlm_realm: Found realm "NULL"
    rlm_realm: Adding Stripped-User-Name = "radius.test"
    rlm_realm: Proxying request from user radius.test to realm NULL
    rlm_realm: Adding Realm = "NULL"
    rlm_realm: Authentication realm is LOCAL.
  modcall[authorize]: module "NULL" returns noop for request 7
  rlm_eap: EAP packet type response id 7 length 6
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 7
try to find in file
rlm_passwd: Added LM-Password: '01FC5A6BE7BC6929AAD3B435B51404EE' to
config_items
rlm_passwd: Added NT-Password: '0CB6948805F797BF2A82807973B89537' to
config_items
rlm_passwd: Added SMB-Account-CTRL-TEXT: '[U          ]' to config_items
try to find in file
  modcall[authorize]: module "etc_smbpasswd" returns ok for request 7
try to find in file
  modcall[authorize]: module "etc_group" returns notfound for request 7
  modcall[authorize]: module "files" returns notfound for request 7
modcall: leaving group authorize (returns updated) for request 7
  rad_check_password:  Found Auth-Type EAP
auth: type "EAP"
  Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 7
  rlm_eap: Request found, released from the list
  rlm_eap: EAP/mschapv2
  rlm_eap: processing type mschapv2
  rlm_eap: Freeing handler
  modcall[authenticate]: module "eap" returns ok for request 7
modcall: leaving group authenticate (returns ok) for request 7
  Processing the post-auth section of radiusd.conf
modcall: entering group post-auth for request 7
radius_xlat:  '/usr/lib/iserv/radius_log ACCEPT radius.test'
Exec-Program: /usr/lib/iserv/radius_log ACCEPT radius.test
Exec-Program output:
Exec-Program: returned: 0
  modcall[post-auth]: module "log_accept" returns ok for request 7
modcall: leaving group post-auth (returns ok) for request 7
  PEAP: Got tunneled reply RADIUS code 2
	EAP-Message = 0x03070004
	Message-Authenticator = 0x00000000000000000000000000000000
	User-Name = "radius.test"
  PEAP: Processing from tunneled session code 0x8177418 2
	EAP-Message = 0x03070004
	Message-Authenticator = 0x00000000000000000000000000000000
	User-Name = "radius.test"
  PEAP: Tunneled authentication was successful.
  rlm_eap_peap: SUCCESS
  modcall[authenticate]: module "eap" returns handled for request 7
modcall: leaving group authenticate (returns handled) for request 7
Sending Access-Challenge of id 0 to 192.168.90.99 port 3074
	EAP-Message =
0x010800261900170301001b0ae1277a8dedb36bf8fa165654e128f46211a9dcaf2e81fa13f540
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x9acdc78327e4cad45fadfe002171570e
Finished request 7
Going to the next request
Waking up in 6 seconds...
rad_recv: Access-Request packet from host 192.168.90.99:3074, id=0,
length=175
	User-Name = "radius.test"
	NAS-IP-Address = 192.168.90.99
	Called-Station-Id = "0018f8adf209"
	Calling-Station-Id = "000e351db86b"
	NAS-Identifier = "0018f8adf209"
	NAS-Port = 14
	Framed-MTU = 1400
	State = 0x9acdc78327e4cad45fadfe002171570e
	NAS-Port-Type = Wireless-802.11
	EAP-Message =
0x020800261900170301001bb9555e83c543a8d937051e34c4c534642a098890e77ca712ecf9da
	Message-Authenticator = 0xb76555f2125e8eb614045e9531545afd
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 8
  modcall[authorize]: module "preprocess" returns ok for request 8
  modcall[authorize]: module "mschap" returns noop for request 8
    rlm_realm: No '/' in User-Name = "radius.test", skipping NULL due to
config.
  modcall[authorize]: module "IPASS" returns noop for request 8
    rlm_realm: No '@' in User-Name = "radius.test", skipping NULL due to
config.
  modcall[authorize]: module "suffix" returns noop for request 8
    rlm_realm: No '\' in User-Name = "radius.test", skipping NULL due to
config.
  modcall[authorize]: module "ntdomain" returns noop for request 8
    rlm_realm: No '@' in User-Name = "radius.test", looking up realm NULL
    rlm_realm: Found realm "NULL"
    rlm_realm: Adding Stripped-User-Name = "radius.test"
    rlm_realm: Proxying request from user radius.test to realm NULL
    rlm_realm: Adding Realm = "NULL"
    rlm_realm: Authentication realm is LOCAL.
  modcall[authorize]: module "NULL" returns noop for request 8
  rlm_eap: EAP packet type response id 8 length 38
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 8
try to find in file
rlm_passwd: Added LM-Password: '01FC5A6BE7BC6929AAD3B435B51404EE' to
config_items
rlm_passwd: Added NT-Password: '0CB6948805F797BF2A82807973B89537' to
config_items
rlm_passwd: Added SMB-Account-CTRL-TEXT: '[U          ]' to config_items
try to find in file
  modcall[authorize]: module "etc_smbpasswd" returns ok for request 8
try to find in file
  modcall[authorize]: module "etc_group" returns notfound for request 8
  modcall[authorize]: module "files" returns notfound for request 8
modcall: leaving group authorize (returns updated) for request 8
  rad_check_password:  Found Auth-Type EAP
auth: type "EAP"
  Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 8
  rlm_eap: Request found, released from the list
  rlm_eap: EAP/peap
  rlm_eap: processing type peap
  rlm_eap_peap: Authenticate
  rlm_eap_tls: processing TLS
  eaptls_verify returned 7
  rlm_eap_tls: Done initial handshake
  eaptls_process returned 7
  rlm_eap_peap: EAPTLS_OK
  rlm_eap_peap: Session established.  Decoding tunneled attributes.
  rlm_eap_peap: Received EAP-TLV response.
  rlm_eap_peap: Tunneled data is valid.
  rlm_eap_peap: Success
  rlm_eap: Freeing handler
  modcall[authenticate]: module "eap" returns ok for request 8
modcall: leaving group authenticate (returns ok) for request 8
  Processing the post-auth section of radiusd.conf
modcall: entering group post-auth for request 8
radius_xlat:  '/usr/lib/iserv/radius_log ACCEPT radius.test'
Exec-Program: /usr/lib/iserv/radius_log ACCEPT radius.test
Exec-Program output:
Exec-Program: returned: 0
  modcall[post-auth]: module "log_accept" returns ok for request 8
modcall: leaving group post-auth (returns ok) for request 8
Sending Access-Accept of id 0 to 192.168.90.99 port 3074
	MS-MPPE-Recv-Key =
0x78dc21b97545abd7c2ada43aa9ec6d177c9f4f4ca7bbe148b58e4341a64f0964
	MS-MPPE-Send-Key =
0xc5e108121867476527cdda209f357f9bbdef022f54ed47ac83a204b331818375
	EAP-Message = 0x03080004
	Message-Authenticator = 0x00000000000000000000000000000000
	User-Name = "radius.test"
Finished request 8
Going to the next request
Waking up in 6 seconds...
--- Walking the entire request list ---
Cleaning up request 8 ID 0 with timestamp 48342170
Nothing to do.  Sleeping until we see a request.