Starting - reading configuration files ...
reread_config:  reading radiusd.conf
Config:   including file: /usr/local/etc/raddb/proxy.conf
Config:   including file: /usr/local/etc/raddb/clients.conf
Config:   including file: /usr/local/etc/raddb/snmp.conf
Config:   including file: /usr/local/etc/raddb/eap.conf
Config:   including file: /usr/local/etc/raddb/sql.conf
 main: prefix = "/usr/local"
 main: localstatedir = "/var"
 main: logdir = "/var/log"
 main: libdir = "/usr/local/lib"
 main: radacctdir = "/var/log/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 = 0
 main: allow_core_dumps = no
 main: log_stripped_names = yes
 main: log_file = "/var/log/radius.log"
 main: log_auth = yes
 main: log_auth_badpass = yes
 main: log_auth_goodpass = no
 main: pidfile = "/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/sbin/checkrad"
 main: proxy_requests = no
 proxy: retry_delay = 5
 proxy: retry_count = 3
 proxy: synchronous = no
 proxy: default_fallback = yes
 proxy: dead_time = 120
 proxy: post_proxy_authorize = yes
 proxy: wake_all_if_all_dead = no
 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
Using deprecated naslist file.  Support for this will go away soon.
read_config_files:  reading clients
read_config_files:  reading realms
radiusd:  entering modules setup
Module: Library search path is /usr/local/lib
Module: Loaded PAP 
 pap: encryption_scheme = "crypt"
Module: Instantiated pap (pap) 
Module: Loaded CHAP 
Module: Instantiated chap (chap) 
Module: Loaded MS-CHAP 
 mschap: use_mppe = yes
 mschap: require_encryption = yes
 mschap: require_strong = yes
 mschap: with_ntdomain_hack = no
 mschap: passwd = "(null)"
 mschap: authtype = "MS-CHAP"
 mschap: ntlm_auth = "(null)"
Module: Instantiated mschap (mschap) 
Module: Loaded eap 
 eap: default_eap_type = "peap"
 eap: timer_expire = 600
 eap: ignore_unknown_eap_types = no
 eap: cisco_accounting_username_bug = no
rlm_eap: Loaded and initialized type md5
rlm_eap: Loaded and initialized type leap
 gtc: challenge = "Password: "
 gtc: auth_type = "PAP"
rlm_eap: Loaded and initialized type gtc
 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 = "/usr/local/etc/raddb/certs/cert-srv.pem"
 tls: certificate_file = "/usr/local/etc/raddb/certs/cert-srv.pem"
 tls: CA_file = "/usr/local/etc/raddb/certs/demoCA/cacert.pem"
 tls: private_key_password = "whatever"
 tls: dh_file = "/usr/local/etc/raddb/certs/dh"
 tls: random_file = "/usr/local/etc/raddb/certs/random"
 tls: fragment_size = 1024
 tls: include_length = yes
 tls: check_crl = no
 tls: check_cert_cn = "(null)"
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 = "/usr/local/etc/raddb/huntgroups"
 preprocess: hints = "/usr/local/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 LDAP 
 ldap: server = "131.188.3.53"
 ldap: port = 400
 ldap: net_timeout = 1
 ldap: timeout = 24
 ldap: timelimit = 23
 ldap: identity = "cn=florian,ou=allro,ou=AAAdsadm,o=Universitaet Erlangen-Nuernberg,c=DE"
 ldap: tls_mode = no
 ldap: start_tls = no
 ldap: tls_cacertfile = "(null)"
 ldap: tls_cacertdir = "(null)"
 ldap: tls_certfile = "(null)"
 ldap: tls_keyfile = "(null)"
 ldap: tls_randfile = "(null)"
 ldap: tls_require_cert = "allow"
 ldap: password = "XXX"
 ldap: basedn = "ou=AAAuser,o=Universitaet Erlangen-Nuernberg,c=DE"
 ldap: filter = "(Userid=%{Stripped-User-Name:-%{User-Name}})"
 ldap: base_filter = "(objectclass=radiusprofile)"
 ldap: default_profile = "(null)"
 ldap: profile_attribute = "(null)"
 ldap: password_header = "(null)"
 ldap: password_attribute = "User-Password"
 ldap: access_attr = "uid"
 ldap: groupname_attribute = "cn"
 ldap: groupmembership_filter = "(|(&(objectClass=GroupOfNames)(member=%{Ldap-UserDn}))(&(objectClass=GroupOfUniqueNames)(uniquemember=%{Ldap-UserDn})))"
 ldap: groupmembership_attribute = "(null)"
 ldap: dictionary_mapping = "/usr/local/etc/raddb/ldap.attrmap"
 ldap: ldap_debug = 0
 ldap: ldap_connections_number = 5
 ldap: compare_check_items = no
 ldap: access_attr_used_for_allow = yes
 ldap: do_xlat = yes
rlm_ldap: Registering ldap_groupcmp for Ldap-Group
rlm_ldap: Registering ldap_xlat with xlat_name ldap
rlm_ldap: reading ldap<->radius mappings from file /usr/local/etc/raddb/ldap.attrmap
rlm_ldap: LDAP fauUserid mapped to RADIUS User-Password
conns: 0x817a680
Module: Instantiated ldap (ldap) 
Module: Loaded realm 
 realm: format = "suffix"
 realm: delimiter = "@"
 realm: ignore_default = no
 realm: ignore_null = no
Module: Instantiated realm (suffix) 
Module: Loaded files 
 files: usersfile = "/usr/local/etc/raddb/users"
 files: acctusersfile = "/usr/local/etc/raddb/acct_users"
 files: preproxy_usersfile = "/usr/local/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"
Module: Instantiated acct_unique (acct_unique) 
Module: Loaded detail 
 detail: detailfile = "/var/log/radacct/%{Client-IP-Address}/detail-%Y%m%d"
 detail: detailperm = 384
 detail: dirperm = 493
 detail: locking = no
Module: Instantiated detail (detail) 
Module: Loaded System 
 unix: cache = no
 unix: passwd = "(null)"
 unix: shadow = "(null)"
 unix: group = "(null)"
 unix: radwtmp = "/var/log/radwtmp"
 unix: usegroup = no
 unix: cache_reload = 600
Module: Instantiated unix (unix) 
Module: Loaded radutmp 
 radutmp: filename = "/var/log/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 authentication *:1812
Listening on accounting *:1813
Ready to process requests.
rad_recv: Access-Request packet from host 131.188.4.191:20001, id=58, length=143
	NAS-Port-Id = "2049/2"
	Calling-Station-Id = "00-90-4B-8F-B7-3B"
	Called-Station-Id = "00-0B-0E-03-00-42:FAU-SEC"
	Service-Type = Framed-User
	EAP-Message = 0x0201000c01756e727a313438
	User-Name = "unrz148"
	NAS-Identifier = "Trapeze"
	NAS-Port-Type = Wireless-802.11
	NAS-IP-Address = 131.188.4.191
	Message-Authenticator = 0xc06d9475f4e1734074fa5f7a47c242c8
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 0
  modcall[authorize]: module "preprocess" returns ok for request 0
rlm_ldap: - authorize
rlm_ldap: performing user authorization for unrz148
radius_xlat:  '(Userid=unrz148)'
radius_xlat:  'ou=AAAuser,o=Universitaet Erlangen-Nuernberg,c=DE'
rlm_ldap: ldap_get_conn: Checking Id: 0
rlm_ldap: ldap_get_conn: Got Id: 0
rlm_ldap: attempting LDAP reconnection
rlm_ldap: (re)connect to 131.188.3.53:400, authentication 0
rlm_ldap: bind as cn=florian,ou=allro,ou=AAAdsadm,o=Universitaet Erlangen-Nuernberg,c=DE/xxxx to 131.188.3.53:400
rlm_ldap: waiting for bind result ...
rlm_ldap: Bind was successful
rlm_ldap: performing search in ou=AAAuser,o=Universitaet Erlangen-Nuernberg,c=DE, with filter (Userid=unrz148)
rlm_ldap: checking if remote access for unrz148 is allowed by uid
rlm_ldap: looking for check items in directory...
rlm_ldap: Adding fauUserid as User-Password, value unrz148 & op=21
rlm_ldap: looking for reply items in directory...
rlm_ldap: user unrz148 authorized to use remote access
rlm_ldap: ldap_release_conn: Release Id: 0
  modcall[authorize]: module "ldap" returns ok for request 0
  rlm_eap: EAP packet type response id 1 length 12
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 0
    rlm_realm: No '@' in User-Name = "unrz148", looking up realm NULL
    rlm_realm: No such realm "NULL"
  modcall[authorize]: module "suffix" returns noop for request 0
  modcall[authorize]: module "files" returns notfound for request 0
modcall: 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: group authenticate returns handled for request 0
Sending Access-Challenge of id 58 to 131.188.4.191:20001
	EAP-Message = 0x010200061920
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x270ffa52f6cd54af512613f5dc5a0163
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 131.188.4.191:20001, id=58, length=143
Sending duplicate reply to client airbrush:20001 - ID: 58
Re-sending Access-Challenge of id 58 to 131.188.4.191:20001
--- Walking the entire request list ---
Cleaning up request 0 ID 58 with timestamp 42ae9a7b
Nothing to do.  Sleeping until we see a request.
rad_recv: Access-Request packet from host 131.188.4.191:20001, id=59, length=261
	NAS-Port-Id = "2049/2"
	Calling-Station-Id = "00-90-4B-8F-B7-3B"
	Called-Station-Id = "00-0B-0E-03-00-42:FAU-SEC"
	Service-Type = Framed-User
	User-Name = "unrz148"
	State = 0x270ffa52f6cd54af512613f5dc5a0163
	EAP-Message = [payload removed: 226 characters]
	NAS-Identifier = "Trapeze"
	NAS-Port-Type = Wireless-802.11
	NAS-IP-Address = 131.188.4.191
	Message-Authenticator = 0x9bf7576f94ad6fbb49ea90935b20a0cd
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 1
  modcall[authorize]: module "preprocess" returns ok for request 1
rlm_ldap: - authorize
rlm_ldap: performing user authorization for unrz148
radius_xlat:  '(Userid=unrz148)'
radius_xlat:  'ou=AAAuser,o=Universitaet Erlangen-Nuernberg,c=DE'
rlm_ldap: ldap_get_conn: Checking Id: 0
rlm_ldap: ldap_get_conn: Got Id: 0
rlm_ldap: performing search in ou=AAAuser,o=Universitaet Erlangen-Nuernberg,c=DE, with filter (Userid=unrz148)
rlm_ldap: checking if remote access for unrz148 is allowed by uid
rlm_ldap: looking for check items in directory...
rlm_ldap: Adding fauUserid as User-Password, value unrz148 & op=21
rlm_ldap: looking for reply items in directory...
rlm_ldap: user unrz148 authorized to use remote access
rlm_ldap: ldap_release_conn: Release Id: 0
  modcall[authorize]: module "ldap" returns ok for request 1
  rlm_eap: EAP packet type response id 2 length 112
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 1
    rlm_realm: No '@' in User-Name = "unrz148", looking up realm NULL
    rlm_realm: No such realm "NULL"
  modcall[authorize]: module "suffix" returns noop for request 1
  modcall[authorize]: module "files" returns notfound for request 1
modcall: 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 0061], 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 0694], 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:error in 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: group authenticate returns handled for request 1
Sending Access-Challenge of id 59 to 131.188.4.191:20001
	EAP-Message = [payload removed: 508 characters]
	EAP-Message = [payload removed: 508 characters]
	EAP-Message = [payload removed: 508 characters]
	EAP-Message = [payload removed: 508 characters]
	EAP-Message = 0x652e636f6d301e170d3034303132353133323630375a
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x2adab79082d449df9b7da77f4bcd00f9
Finished request 1
Going to the next request
--- Walking the entire request list ---
Waking up in 6 seconds...
rad_recv: Access-Request packet from host 131.188.4.191:20001, id=59, length=261
Sending duplicate reply to client airbrush:20001 - ID: 59
Re-sending Access-Challenge of id 59 to 131.188.4.191:20001
--- Walking the entire request list ---
Cleaning up request 1 ID 59 with timestamp 42ae9a81
Nothing to do.  Sleeping until we see a request.
rad_recv: Access-Request packet from host 131.188.4.191:20001, id=60, length=155
	NAS-Port-Id = "2049/2"
	Calling-Station-Id = "00-90-4B-8F-B7-3B"
	Called-Station-Id = "00-0B-0E-03-00-42:FAU-SEC"
	Service-Type = Framed-User
	User-Name = "unrz148"
	State = 0x2adab79082d449df9b7da77f4bcd00f9
	EAP-Message = 0x020300061900
	NAS-Identifier = "Trapeze"
	NAS-Port-Type = Wireless-802.11
	NAS-IP-Address = 131.188.4.191
	Message-Authenticator = 0x83e5596497bddbf7ee210c4e7d4d509b
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 2
  modcall[authorize]: module "preprocess" returns ok for request 2
rlm_ldap: - authorize
rlm_ldap: performing user authorization for unrz148
radius_xlat:  '(Userid=unrz148)'
radius_xlat:  'ou=AAAuser,o=Universitaet Erlangen-Nuernberg,c=DE'
rlm_ldap: ldap_get_conn: Checking Id: 0
rlm_ldap: ldap_get_conn: Got Id: 0
rlm_ldap: performing search in ou=AAAuser,o=Universitaet Erlangen-Nuernberg,c=DE, with filter (Userid=unrz148)
rlm_ldap: checking if remote access for unrz148 is allowed by uid
rlm_ldap: looking for check items in directory...
rlm_ldap: Adding fauUserid as User-Password, value unrz148 & op=21
rlm_ldap: looking for reply items in directory...
rlm_ldap: user unrz148 authorized to use remote access
rlm_ldap: ldap_release_conn: Release Id: 0
  modcall[authorize]: module "ldap" returns ok for request 2
  rlm_eap: EAP packet type response id 3 length 6
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 2
    rlm_realm: No '@' in User-Name = "unrz148", looking up realm NULL
    rlm_realm: No such realm "NULL"
  modcall[authorize]: module "suffix" returns noop for request 2
  modcall[authorize]: module "files" returns notfound for request 2
modcall: 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: group authenticate returns handled for request 2
Sending Access-Challenge of id 60 to 131.188.4.191:20001
	EAP-Message = [payload removed: 508 characters]
	EAP-Message = [payload removed: 508 characters]
	EAP-Message = [payload removed: 508 characters]
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0xdba2ec94e26cdc9029662c91d5de7c75
Finished request 2
Going to the next request
--- Walking the entire request list ---
Waking up in 6 seconds...
rad_recv: Access-Request packet from host 131.188.4.191:20001, id=60, length=155
Sending duplicate reply to client airbrush:20001 - ID: 60
Re-sending Access-Challenge of id 60 to 131.188.4.191:20001
--- Walking the entire request list ---
Cleaning up request 2 ID 60 with timestamp 42ae9a87
Nothing to do.  Sleeping until we see a request.
rad_recv: Access-Request packet from host 131.188.4.191:20001, id=61, length=341
	NAS-Port-Id = "2049/2"
	Calling-Station-Id = "00-90-4B-8F-B7-3B"
	Called-Station-Id = "00-0B-0E-03-00-42:FAU-SEC"
	Service-Type = Framed-User
	User-Name = "unrz148"
	State = 0xdba2ec94e26cdc9029662c91d5de7c75
	EAP-Message = [payload removed: 386 characters]
	NAS-Identifier = "Trapeze"
	NAS-Port-Type = Wireless-802.11
	NAS-IP-Address = 131.188.4.191
	Message-Authenticator = 0xa10d965b399e7ed9781f6af3b5b69486
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 3
  modcall[authorize]: module "preprocess" returns ok for request 3
rlm_ldap: - authorize
rlm_ldap: performing user authorization for unrz148
radius_xlat:  '(Userid=unrz148)'
radius_xlat:  'ou=AAAuser,o=Universitaet Erlangen-Nuernberg,c=DE'
rlm_ldap: ldap_get_conn: Checking Id: 0
rlm_ldap: ldap_get_conn: Got Id: 0
rlm_ldap: performing search in ou=AAAuser,o=Universitaet Erlangen-Nuernberg,c=DE, with filter (Userid=unrz148)
rlm_ldap: checking if remote access for unrz148 is allowed by uid
rlm_ldap: looking for check items in directory...
rlm_ldap: Adding fauUserid as User-Password, value unrz148 & op=21
rlm_ldap: looking for reply items in directory...
rlm_ldap: user unrz148 authorized to use remote access
rlm_ldap: ldap_release_conn: Release Id: 0
  modcall[authorize]: module "ldap" returns ok for request 3
  rlm_eap: EAP packet type response id 4 length 192
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 3
    rlm_realm: No '@' in User-Name = "unrz148", looking up realm NULL
    rlm_realm: No such realm "NULL"
  modcall[authorize]: module "suffix" returns noop for request 3
  modcall[authorize]: module "files" returns notfound for request 3
modcall: 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 0086], 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: group authenticate returns handled for request 3
Sending Access-Challenge of id 61 to 131.188.4.191:20001
	EAP-Message = 0x0105003119001403010001011603010020737ff100536fae4f010e5a5dc6d837e397c7f85ffc5dfdd4672a97191ce55a62
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x9d81b76d29d5bb96464d985ea5a72a71
Finished request 3
Going to the next request
--- Walking the entire request list ---
Waking up in 6 seconds...
rad_recv: Access-Request packet from host 131.188.4.191:20001, id=61, length=341
Sending duplicate reply to client airbrush:20001 - ID: 61
Re-sending Access-Challenge of id 61 to 131.188.4.191:20001
--- Walking the entire request list ---
Cleaning up request 3 ID 61 with timestamp 42ae9a8d
Nothing to do.  Sleeping until we see a request.
rad_recv: Access-Request packet from host 131.188.4.191:20001, id=62, length=155
	NAS-Port-Id = "2049/2"
	Calling-Station-Id = "00-90-4B-8F-B7-3B"
	Called-Station-Id = "00-0B-0E-03-00-42:FAU-SEC"
	Service-Type = Framed-User
	User-Name = "unrz148"
	State = 0x9d81b76d29d5bb96464d985ea5a72a71
	EAP-Message = 0x020500061900
	NAS-Identifier = "Trapeze"
	NAS-Port-Type = Wireless-802.11
	NAS-IP-Address = 131.188.4.191
	Message-Authenticator = 0xa79c981dce7c71f29bc87fdf2d8d3e80
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 4
  modcall[authorize]: module "preprocess" returns ok for request 4
rlm_ldap: - authorize
rlm_ldap: performing user authorization for unrz148
radius_xlat:  '(Userid=unrz148)'
radius_xlat:  'ou=AAAuser,o=Universitaet Erlangen-Nuernberg,c=DE'
rlm_ldap: ldap_get_conn: Checking Id: 0
rlm_ldap: ldap_get_conn: Got Id: 0
rlm_ldap: performing search in ou=AAAuser,o=Universitaet Erlangen-Nuernberg,c=DE, with filter (Userid=unrz148)
rlm_ldap: checking if remote access for unrz148 is allowed by uid
rlm_ldap: looking for check items in directory...
rlm_ldap: Adding fauUserid as User-Password, value unrz148 & op=21
rlm_ldap: looking for reply items in directory...
rlm_ldap: user unrz148 authorized to use remote access
rlm_ldap: ldap_release_conn: Release Id: 0
  modcall[authorize]: module "ldap" returns ok for request 4
  rlm_eap: EAP packet type response id 5 length 6
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 4
    rlm_realm: No '@' in User-Name = "unrz148", looking up realm NULL
    rlm_realm: No such realm "NULL"
  modcall[authorize]: module "suffix" returns noop for request 4
  modcall[authorize]: module "files" returns notfound for request 4
modcall: 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: group authenticate returns handled for request 4
Sending Access-Challenge of id 62 to 131.188.4.191:20001
	EAP-Message = 0x010600201900170301001566e4b4b405bc2c12b176e3557a29c3c03815f4ddd2
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x5550b1e72047a6159121b463f0f2760a
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 131.188.4.191:20001, id=62, length=155
Sending duplicate reply to client airbrush:20001 - ID: 62
Re-sending Access-Challenge of id 62 to 131.188.4.191:20001
--- Walking the entire request list ---
Cleaning up request 4 ID 62 with timestamp 42ae9a93
Nothing to do.  Sleeping until we see a request.
rad_recv: Access-Request packet from host 131.188.4.191:20001, id=63, length=184
	NAS-Port-Id = "2049/2"
	Calling-Station-Id = "00-90-4B-8F-B7-3B"
	Called-Station-Id = "00-0B-0E-03-00-42:FAU-SEC"
	Service-Type = Framed-User
	User-Name = "unrz148"
	State = 0x5550b1e72047a6159121b463f0f2760a
	EAP-Message = 0x020600231900170301001888367f88b8b7708556ab4886d9c94dde43f6bafe2532d994
	NAS-Identifier = "Trapeze"
	NAS-Port-Type = Wireless-802.11
	NAS-IP-Address = 131.188.4.191
	Message-Authenticator = 0x8ea347ff548c28722c8172e46158a763
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 5
  modcall[authorize]: module "preprocess" returns ok for request 5
rlm_ldap: - authorize
rlm_ldap: performing user authorization for unrz148
radius_xlat:  '(Userid=unrz148)'
radius_xlat:  'ou=AAAuser,o=Universitaet Erlangen-Nuernberg,c=DE'
rlm_ldap: ldap_get_conn: Checking Id: 0
rlm_ldap: ldap_get_conn: Got Id: 0
rlm_ldap: performing search in ou=AAAuser,o=Universitaet Erlangen-Nuernberg,c=DE, with filter (Userid=unrz148)
rlm_ldap: checking if remote access for unrz148 is allowed by uid
rlm_ldap: looking for check items in directory...
rlm_ldap: Adding fauUserid as User-Password, value unrz148 & op=21
rlm_ldap: looking for reply items in directory...
rlm_ldap: user unrz148 authorized to use remote access
rlm_ldap: ldap_release_conn: Release Id: 0
  modcall[authorize]: module "ldap" returns ok for request 5
  rlm_eap: EAP packet type response id 6 length 35
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 5
    rlm_realm: No '@' in User-Name = "unrz148", looking up realm NULL
    rlm_realm: No such realm "NULL"
  modcall[authorize]: module "suffix" returns noop for request 5
  modcall[authorize]: module "files" returns notfound for request 5
modcall: 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 - unrz148
  rlm_eap_peap: Tunneled data is valid.
  PEAP: Got tunneled identity of unrz148
  PEAP: Setting default EAP type for tunneled EAP session.
  PEAP: Setting User-Name to unrz148
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 5
  modcall[authorize]: module "preprocess" returns ok for request 5
rlm_ldap: - authorize
rlm_ldap: performing user authorization for unrz148
radius_xlat:  '(Userid=unrz148)'
radius_xlat:  'ou=AAAuser,o=Universitaet Erlangen-Nuernberg,c=DE'
rlm_ldap: ldap_get_conn: Checking Id: 0
rlm_ldap: ldap_get_conn: Got Id: 0
rlm_ldap: performing search in ou=AAAuser,o=Universitaet Erlangen-Nuernberg,c=DE, with filter (Userid=unrz148)
rlm_ldap: checking if remote access for unrz148 is allowed by uid
rlm_ldap: looking for check items in directory...
rlm_ldap: Adding fauUserid as User-Password, value unrz148 & op=21
rlm_ldap: looking for reply items in directory...
rlm_ldap: user unrz148 authorized to use remote access
rlm_ldap: ldap_release_conn: Release Id: 0
  modcall[authorize]: module "ldap" returns ok for request 5
  rlm_eap: EAP packet type response id 6 length 12
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 5
    rlm_realm: No '@' in User-Name = "unrz148", looking up realm NULL
    rlm_realm: No such realm "NULL"
  modcall[authorize]: module "suffix" returns noop for request 5
  modcall[authorize]: module "files" returns notfound for request 5
modcall: 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: group authenticate returns handled for request 5
  PEAP: Got tunneled Access-Challenge
  modcall[authenticate]: module "eap" returns handled for request 5
modcall: group authenticate returns handled for request 5
Sending Access-Challenge of id 63 to 131.188.4.191:20001
	EAP-Message = 0x010700381900170301002d7c2c8e60506d6f294dcb35bd3b21b7b49bf411c538c25f6371e7879f4e0b98753a967c6119158063715e62041b
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x608c84a6c7bdebe161a378bf2712bbdb
Finished request 5
Going to the next request
--- Walking the entire request list ---
Waking up in 6 seconds...
rad_recv: Access-Request packet from host 131.188.4.191:20001, id=63, length=184
Sending duplicate reply to client airbrush:20001 - ID: 63
Re-sending Access-Challenge of id 63 to 131.188.4.191:20001
--- Walking the entire request list ---
Cleaning up request 5 ID 63 with timestamp 42ae9a99
Nothing to do.  Sleeping until we see a request.
rad_recv: Access-Request packet from host 131.188.4.191:20001, id=63, length=184
	NAS-Port-Id = "2049/2"
	Calling-Station-Id = "00-90-4B-8F-B7-3B"
	Called-Station-Id = "00-0B-0E-03-00-42:FAU-SEC"
	Service-Type = Framed-User
	User-Name = "unrz148"
	State = 0x5550b1e72047a6159121b463f0f2760a
	EAP-Message = 0x020600231900170301001888367f88b8b7708556ab4886d9c94dde43f6bafe2532d994
	NAS-Identifier = "Trapeze"
	NAS-Port-Type = Wireless-802.11
	NAS-IP-Address = 131.188.4.191
	Message-Authenticator = 0x8ea347ff548c28722c8172e46158a763
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 6
  modcall[authorize]: module "preprocess" returns ok for request 6
rlm_ldap: - authorize
rlm_ldap: performing user authorization for unrz148
radius_xlat:  '(Userid=unrz148)'
radius_xlat:  'ou=AAAuser,o=Universitaet Erlangen-Nuernberg,c=DE'
rlm_ldap: ldap_get_conn: Checking Id: 0
rlm_ldap: ldap_get_conn: Got Id: 0
rlm_ldap: performing search in ou=AAAuser,o=Universitaet Erlangen-Nuernberg,c=DE, with filter (Userid=unrz148)
rlm_ldap: checking if remote access for unrz148 is allowed by uid
rlm_ldap: looking for check items in directory...
rlm_ldap: Adding fauUserid as User-Password, value unrz148 & op=21
rlm_ldap: looking for reply items in directory...
rlm_ldap: user unrz148 authorized to use remote access
rlm_ldap: ldap_release_conn: Release Id: 0
  modcall[authorize]: module "ldap" returns ok for request 6
  rlm_eap: EAP packet type response id 6 length 35
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 6
    rlm_realm: No '@' in User-Name = "unrz148", looking up realm NULL
    rlm_realm: No such realm "NULL"
  modcall[authorize]: module "suffix" returns noop for request 6
  modcall[authorize]: module "files" returns notfound for request 6
modcall: 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 not found in the list
rlm_eap: Either EAP-request timed out OR EAP-response to an unknown EAP-request
  rlm_eap: Failed in handler
  modcall[authenticate]: module "eap" returns invalid for request 6
modcall: group authenticate returns invalid for request 6
auth: Failed to validate the user.
Login incorrect: [unrz148/<no User-Password attribute>] (from client airbrush port 0 cli 00-90-4B-8F-B7-3B)
Delaying request 6 for 1 seconds
Finished request 6
Going to the next request
--- Walking the entire request list ---
Waking up in 1 seconds...
rad_recv: Access-Request packet from host 131.188.4.191:20001, id=64, length=238
	NAS-Port-Id = "2049/2"
	Calling-Station-Id = "00-90-4B-8F-B7-3B"
	Called-Station-Id = "00-0B-0E-03-00-42:FAU-SEC"
	Service-Type = Framed-User
	User-Name = "unrz148"
	State = 0x608c84a6c7bdebe161a378bf2712bbdb
	EAP-Message = 0x020700591900170301004e7e90b7c54598cc8f991d8641e6b01aaaeb889df18ef70a87a66f19687d685b759e0e27c406a9ff52a71ee0421866391250b4dcf6c017ca748ed4dced7211db8e09b2e395a308a4cc166730ad3de6
	NAS-Identifier = "Trapeze"
	NAS-Port-Type = Wireless-802.11
	NAS-IP-Address = 131.188.4.191
	Message-Authenticator = 0xc220272d9a037f0d9b77726394ccc47c
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 7
  modcall[authorize]: module "preprocess" returns ok for request 7
rlm_ldap: - authorize
rlm_ldap: performing user authorization for unrz148
radius_xlat:  '(Userid=unrz148)'
radius_xlat:  'ou=AAAuser,o=Universitaet Erlangen-Nuernberg,c=DE'
rlm_ldap: ldap_get_conn: Checking Id: 0
rlm_ldap: ldap_get_conn: Got Id: 0
rlm_ldap: performing search in ou=AAAuser,o=Universitaet Erlangen-Nuernberg,c=DE, with filter (Userid=unrz148)
rlm_ldap: checking if remote access for unrz148 is allowed by uid
rlm_ldap: looking for check items in directory...
rlm_ldap: Adding fauUserid as User-Password, value unrz148 & op=21
rlm_ldap: looking for reply items in directory...
rlm_ldap: user unrz148 authorized to use remote access
rlm_ldap: ldap_release_conn: Release Id: 0
  modcall[authorize]: module "ldap" returns ok for request 7
  rlm_eap: EAP packet type response id 7 length 89
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 7
    rlm_realm: No '@' in User-Name = "unrz148", looking up realm NULL
    rlm_realm: No such realm "NULL"
  modcall[authorize]: module "suffix" returns noop for request 7
  modcall[authorize]: module "files" returns notfound for request 7
modcall: 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: Setting User-Name to unrz148
  PEAP: Adding old state with 4b e4
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 7
  modcall[authorize]: module "preprocess" returns ok for request 7
rlm_ldap: - authorize
rlm_ldap: performing user authorization for unrz148
radius_xlat:  '(Userid=unrz148)'
radius_xlat:  'ou=AAAuser,o=Universitaet Erlangen-Nuernberg,c=DE'
rlm_ldap: ldap_get_conn: Checking Id: 0
rlm_ldap: ldap_get_conn: Got Id: 0
rlm_ldap: performing search in ou=AAAuser,o=Universitaet Erlangen-Nuernberg,c=DE, with filter (Userid=unrz148)
rlm_ldap: checking if remote access for unrz148 is allowed by uid
rlm_ldap: looking for check items in directory...
rlm_ldap: Adding fauUserid as User-Password, value unrz148 & op=21
rlm_ldap: looking for reply items in directory...
rlm_ldap: user unrz148 authorized to use remote access
rlm_ldap: ldap_release_conn: Release Id: 0
  modcall[authorize]: module "ldap" returns ok for request 7
  rlm_eap: EAP packet type response id 7 length 66
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 7
    rlm_realm: No '@' in User-Name = "unrz148", looking up realm NULL
    rlm_realm: No such realm "NULL"
  modcall[authorize]: module "suffix" returns noop for request 7
  modcall[authorize]: module "files" returns notfound for request 7
modcall: 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
  Processing the authenticate section of radiusd.conf
modcall: entering group Auth-Type for request 7
  rlm_mschap: Told to do MS-CHAPv2 for unrz148 with NT-Password
rlm_mschap: adding MS-CHAPv2 MPPE keys
  modcall[authenticate]: module "mschap" returns ok for request 7
modcall: group Auth-Type returns ok for request 7
MSCHAP Success 
  modcall[authenticate]: module "eap" returns handled for request 7
modcall: group authenticate returns handled for request 7
  PEAP: Got tunneled Access-Challenge
  modcall[authenticate]: module "eap" returns handled for request 7
modcall: group authenticate returns handled for request 7
Sending Access-Challenge of id 64 to 131.188.4.191:20001
	EAP-Message = 0x0108004a1900170301003f218f117d77a76da70c19bb284b51eb603360dc21f3a00f2c7dbc1d0c22da1235981479012b052b73ad68eaf1537881a61a34d52d046862f4b6de4cd5016510
	Message-Authenticator = 0x00000000000000000000000000000000
	State = 0x7f60a4cbd632e692be15a7144b117590
Finished request 7
Going to the next request
--- Walking the entire request list ---
Sending Access-Reject of id 63 to 131.188.4.191:20001
Cleaning up request 6 ID 63 with timestamp 42ae9aa5
Waking up in 6 seconds...
rad_recv: Access-Request packet from host 131.188.4.191:20001, id=64, length=238
Sending duplicate reply to client airbrush:20001 - ID: 64
Re-sending Access-Challenge of id 64 to 131.188.4.191:20001
--- Walking the entire request list ---
Cleaning up request 7 ID 64 with timestamp 42ae9aab
Nothing to do.  Sleeping until we see a request.
rad_recv: Access-Request packet from host 131.188.4.191:20001, id=64, length=238
	NAS-Port-Id = "2049/2"
	Calling-Station-Id = "00-90-4B-8F-B7-3B"
	Called-Station-Id = "00-0B-0E-03-00-42:FAU-SEC"
	Service-Type = Framed-User
	User-Name = "unrz148"
	State = 0x608c84a6c7bdebe161a378bf2712bbdb
	EAP-Message = 0x020700591900170301004e7e90b7c54598cc8f991d8641e6b01aaaeb889df18ef70a87a66f19687d685b759e0e27c406a9ff52a71ee0421866391250b4dcf6c017ca748ed4dced7211db8e09b2e395a308a4cc166730ad3de6
	NAS-Identifier = "Trapeze"
	NAS-Port-Type = Wireless-802.11
	NAS-IP-Address = 131.188.4.191
	Message-Authenticator = 0xc220272d9a037f0d9b77726394ccc47c
  Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 8
  modcall[authorize]: module "preprocess" returns ok for request 8
rlm_ldap: - authorize
rlm_ldap: performing user authorization for unrz148
radius_xlat:  '(Userid=unrz148)'
radius_xlat:  'ou=AAAuser,o=Universitaet Erlangen-Nuernberg,c=DE'
rlm_ldap: ldap_get_conn: Checking Id: 0
rlm_ldap: ldap_get_conn: Got Id: 0
rlm_ldap: performing search in ou=AAAuser,o=Universitaet Erlangen-Nuernberg,c=DE, with filter (Userid=unrz148)
rlm_ldap: checking if remote access for unrz148 is allowed by uid
rlm_ldap: looking for check items in directory...
rlm_ldap: Adding fauUserid as User-Password, value unrz148 & op=21
rlm_ldap: looking for reply items in directory...
rlm_ldap: user unrz148 authorized to use remote access
rlm_ldap: ldap_release_conn: Release Id: 0
  modcall[authorize]: module "ldap" returns ok for request 8
  rlm_eap: EAP packet type response id 7 length 89
  rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
  modcall[authorize]: module "eap" returns updated for request 8
    rlm_realm: No '@' in User-Name = "unrz148", looking up realm NULL
    rlm_realm: No such realm "NULL"
  modcall[authorize]: module "suffix" returns noop for request 8
  modcall[authorize]: module "files" returns notfound for request 8
modcall: 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 not found in the list
rlm_eap: Either EAP-request timed out OR EAP-response to an unknown EAP-request
  rlm_eap: Failed in handler
  modcall[authenticate]: module "eap" returns invalid for request 8
modcall: group authenticate returns invalid for request 8
auth: Failed to validate the user.
Login incorrect: [unrz148/<no User-Password attribute>] (from client airbrush port 0 cli 00-90-4B-8F-B7-3B)
Delaying request 8 for 1 seconds
Finished request 8
Going to the next request
--- Walking the entire request list ---
Waking up in 1 seconds...
--- Walking the entire request list ---
Sending Access-Reject of id 64 to 131.188.4.191:20001
Cleaning up request 8 ID 64 with timestamp 42ae9ab7
Nothing to do.  Sleeping until we see a request.
