-----BEGIN PGP SIGNED MESSAGE-----
Hash: SHA1
I use FreeRadius Version 0.7.1 on Linux (RehHat 7.2) to authenticate
some user's logon on Cisco's routers.
For this, i use account of Linux (/etc/password, /etc/shadow and
/etc/group) and i define users in /opt/freeradius/etc/raddb/users like
this :
# File : /opt/freeradius/etc/raddb/users
# testok :
#---------------------------------------------------------
testok Auth-Type := System
~ Service-Type = Login-User,
~ Reply-Message = " Hello Testok !"
# testko :
#---------------------------------------------------------
testko Auth-Type := System
~ Service-Type = Login-User,
~ Reply-Message = " Hello Testko !"
I create two users for my test (with the same password):
useradd -u 602 -g 600 -d /home/testok -s /bin/rbash -m testok
useradd -u 601 -g 600 -d /home/testko -s /bin/rbash -m -c foocomment testko
In /etc/passwd :
testok:x:602:600::/home/testok:/bin/rbash
testko:x:601:600:foocomment:/home/testko:/bin/rbash
There is a bug when the Unix's user have a comment in /etc/password.
I couldn't connect to a Cisco with 'testko'.
The Radiuslog see belong :
Wed Oct 16 14:21:03 2002 : Info: Starting - reading configuration files ...
Wed Oct 16 14:21:03 2002 : Debug: reread_config: reading radiusd.conf
Wed Oct 16 14:21:03 2002 : Debug: Config: including file:
/opt/freeradius/etc/raddb/proxy.conf
Wed Oct 16 14:21:03 2002 : Debug: Config: including file:
/opt/freeradius/etc/raddb/clients.conf
Wed Oct 16 14:21:03 2002 : Debug: Config: including file:
/opt/freeradius/etc/raddb/snmp.conf
Wed Oct 16 14:21:03 2002 : Debug: Config: including file:
/opt/freeradius/etc/raddb/sql.conf
Wed Oct 16 14:21:03 2002 : Debug: main: prefix = "/opt/freeradius"
Wed Oct 16 14:21:03 2002 : Debug: main: localstatedir =
"/opt/freeradius/var"
Wed Oct 16 14:21:03 2002 : Debug: main: logdir =
"/opt/freeradius/var/log/radius"
Wed Oct 16 14:21:03 2002 : Debug: main: libdir = "/opt/freeradius/lib"
Wed Oct 16 14:21:03 2002 : Debug: main: radacctdir =
"/opt/freeradius/var/log/radius/radacct"
Wed Oct 16 14:21:03 2002 : Debug: main: hostname_lookups = no
Wed Oct 16 14:21:03 2002 : Debug: read_config_files: reading dictionary
Wed Oct 16 14:21:03 2002 : Debug: read_config_files: reading clients
Wed Oct 16 14:21:03 2002 : Debug: read_config_files: reading realms
Wed Oct 16 14:21:03 2002 : Debug: read_config_files: reading naslist
Wed Oct 16 14:21:03 2002 : Debug: main: max_request_time = 30
Wed Oct 16 14:21:03 2002 : Debug: main: cleanup_delay = 5
Wed Oct 16 14:21:03 2002 : Debug: main: max_requests = 1024
Wed Oct 16 14:21:03 2002 : Debug: main: delete_blocked_requests = 0
Wed Oct 16 14:21:03 2002 : Debug: main: port = 1812
Wed Oct 16 14:21:03 2002 : Debug: main: allow_core_dumps = no
Wed Oct 16 14:21:03 2002 : Debug: main: log_stripped_names = no
Wed Oct 16 14:21:03 2002 : Debug: main: log_auth = yes
Wed Oct 16 14:21:03 2002 : Debug: main: log_auth_badpass = yes
Wed Oct 16 14:21:03 2002 : Debug: main: log_auth_goodpass = yes
Wed Oct 16 14:21:03 2002 : Debug: main: pidfile =
"/opt/freeradius/var/run/radiusd/radiusd.pid"
Wed Oct 16 14:21:03 2002 : Debug: main: bind_address = 10.154.99.65 IP
address [10.154.99.65]
Wed Oct 16 14:21:03 2002 : Debug: main: user = "radiusd"
Wed Oct 16 14:21:03 2002 : Debug: main: group = "radiusd"
Wed Oct 16 14:21:03 2002 : Debug: main: usercollide = yes
Wed Oct 16 14:21:03 2002 : Debug: main: lower_user = "no"
Wed Oct 16 14:21:03 2002 : Debug: main: lower_pass = "no"
Wed Oct 16 14:21:03 2002 : Debug: main: nospace_user = "no"
Wed Oct 16 14:21:03 2002 : Debug: main: nospace_pass = "no"
Wed Oct 16 14:21:03 2002 : Debug: main: proxy_requests = yes
Wed Oct 16 14:21:03 2002 : Debug: proxy: retry_delay = 5
Wed Oct 16 14:21:03 2002 : Debug: proxy: retry_count = 3
Wed Oct 16 14:21:03 2002 : Debug: proxy: synchronous = no
Wed Oct 16 14:21:03 2002 : Debug: proxy: default_fallback = yes
Wed Oct 16 14:21:03 2002 : Debug: proxy: dead_time = 120
Wed Oct 16 14:21:03 2002 : Debug: security: max_attributes = 200
Wed Oct 16 14:21:03 2002 : Debug: security: reject_delay = 1
Wed Oct 16 14:21:03 2002 : Debug: main: debug_level = 0
Wed Oct 16 14:21:03 2002 : Debug: read_config_files: entering modules setup
Wed Oct 16 14:21:03 2002 : Debug: Module: Library search path is
/opt/freeradius/lib
Wed Oct 16 14:21:03 2002 : Debug: Module: Loaded System
Wed Oct 16 14:21:03 2002 : Debug: unix: cache = yes
Wed Oct 16 14:21:03 2002 : Debug: unix: passwd = "/etc/passwd"
Wed Oct 16 14:21:03 2002 : Debug: unix: shadow = "/etc/shadow"
Wed Oct 16 14:21:03 2002 : Debug: unix: group = "/etc/group"
Wed Oct 16 14:21:03 2002 : Debug: unix: radwtmp =
"/opt/freeradius/var/log/radius/radwtmp"
Wed Oct 16 14:21:03 2002 : Debug: unix: usegroup = no
Wed Oct 16 14:21:03 2002 : Debug: unix: cache_reload = 600
Wed Oct 16 14:21:03 2002 : Info: HASH: Reinitializing hash structures
and lists for caching...
Wed Oct 16 14:21:03 2002 : Debug: HASH: user testok found in
hashtable bucket 47422
Wed Oct 16 14:21:03 2002 : Debug: HASH: user testko found in
hashtable bucket 15798
Wed Oct 16 14:21:03 2002 : Info: HASH: Stored 41 entries from /etc/passwd
Wed Oct 16 14:21:03 2002 : Info: HASH: Stored 45 entries from /etc/group
Wed Oct 16 14:21:03 2002 : Debug: Module: Instantiated unix (unix)
Wed Oct 16 14:21:03 2002 : Debug: Module: Loaded preprocess
Wed Oct 16 14:21:03 2002 : Debug: preprocess: huntgroups =
"/opt/freeradius/etc/raddb/huntgroups"
Wed Oct 16 14:21:03 2002 : Debug: preprocess: hints =
"/opt/freeradius/etc/raddb/hints"
Wed Oct 16 14:21:03 2002 : Debug: preprocess: with_ascend_hack = no
Wed Oct 16 14:21:03 2002 : Debug: preprocess: ascend_channels_per_line = 23
Wed Oct 16 14:21:03 2002 : Debug: preprocess: with_ntdomain_hack = no
Wed Oct 16 14:21:03 2002 : Debug: preprocess:
with_specialix_jetstream_hack = no
Wed Oct 16 14:21:03 2002 : Debug: preprocess: with_cisco_vsa_hack = no
Wed Oct 16 14:21:03 2002 : Debug: Module: Instantiated preprocess
(preprocess)
Wed Oct 16 14:21:03 2002 : Debug: Module: Loaded realm
Wed Oct 16 14:21:03 2002 : Debug: realm: format = "suffix"
Wed Oct 16 14:21:03 2002 : Debug: realm: delimiter = "@"
Wed Oct 16 14:21:03 2002 : Debug: Module: Instantiated realm (suffix)
Wed Oct 16 14:21:03 2002 : Debug: Module: Loaded files
Wed Oct 16 14:21:03 2002 : Debug: files: usersfile =
"/opt/freeradius/etc/raddb/users"
Wed Oct 16 14:21:03 2002 : Debug: files: acctusersfile =
"/opt/freeradius/etc/raddb/acct_users"
Wed Oct 16 14:21:03 2002 : Debug: files: compat = "no"
Wed Oct 16 14:21:03 2002 : Debug: auth_type_fixup: Auth-Type [1000]
Wed Oct 16 14:21:03 2002 : Debug: auth_type_fixup: Crypt-Password [1006]
Wed Oct 16 14:21:03 2002 : Debug: auth_type_fixup: Auth-Type [1000]
Wed Oct 16 14:21:03 2002 : Debug: auth_type_fixup: User-Password [2]
Wed Oct 16 14:21:03 2002 : Debug: auth_type_fixup: Auth-Type [1000]
Wed Oct 16 14:21:03 2002 : Debug: auth_type_fixup: User-Password [2]
Wed Oct 16 14:21:03 2002 : Debug: auth_type_fixup: Auth-Type [1000]
Wed Oct 16 14:21:03 2002 : Debug: auth_type_fixup: User-Password [2]
Wed Oct 16 14:21:03 2002 : Debug: auth_type_fixup: Auth-Type [1000]
Wed Oct 16 14:21:03 2002 : Debug: auth_type_fixup: Crypt-Password [1006]
Wed Oct 16 14:21:03 2002 : Debug: auth_type_fixup: Auth-Type [1000]
Wed Oct 16 14:21:03 2002 : Debug: auth_type_fixup: Crypt-Password [1006]
Wed Oct 16 14:21:03 2002 : Debug: auth_type_fixup: Auth-Type [1000]
Wed Oct 16 14:21:03 2002 : Debug: auth_type_fixup: User-Password [2]
Wed Oct 16 14:21:03 2002 : Debug: auth_type_fixup: Auth-Type [1000]
Wed Oct 16 14:21:03 2002 : Debug: auth_type_fixup: Crypt-Password [1006]
Wed Oct 16 14:21:03 2002 : Debug: auth_type_fixup: Auth-Type [1000]
Wed Oct 16 14:21:03 2002 : Debug: auth_type_fixup: Service-Type [6]
Wed Oct 16 14:21:03 2002 : Debug: auth_type_fixup: Crypt-Password [1006]
Wed Oct 16 14:21:03 2002 : Debug: Module: Instantiated files (files)
Wed Oct 16 14:21:03 2002 : Debug: Module: Loaded detail
Wed Oct 16 14:21:03 2002 : Debug: detail: detailfile =
"/opt/freeradius/var/log/radius/radacct/%{Client-IP-Address}/detail"
Wed Oct 16 14:21:03 2002 : Debug: detail: detailperm = 384
Wed Oct 16 14:21:03 2002 : Debug: detail: dirperm = 493
Wed Oct 16 14:21:03 2002 : Debug: detail: locking = no
Wed Oct 16 14:21:03 2002 : Debug: Module: Instantiated detail (detail)
Wed Oct 16 14:21:03 2002 : Debug: Module: Loaded radutmp
Wed Oct 16 14:21:03 2002 : Debug: radutmp: filename =
"/opt/freeradius/var/log/radius/radutmp"
Wed Oct 16 14:21:03 2002 : Debug: radutmp: username = "%{User-Name}"
Wed Oct 16 14:21:03 2002 : Debug: radutmp: perm = 384
Wed Oct 16 14:21:03 2002 : Debug: radutmp: callerid = yes
Wed Oct 16 14:21:03 2002 : Debug: Module: Instantiated radutmp (radutmp)
Wed Oct 16 14:21:03 2002 : Info: Listening on IP address 10.154.99.65,
ports 1812/udp and 1813/udp, with proxy on 1814/udp.
Wed Oct 16 14:21:03 2002 : Info: Ready to process requests.
rad_recv: Accounting-Request packet from host 10.154.99.244:1646, id=64,
length=101
NAS-IP-Address = 10.154.99.244
NAS-Port = 67
NAS-Port-Type = Virtual
User-Name = "np"
Calling-Station-Id = "10.154.99.1"
Acct-Status-Type = Stop
Acct-Authentic = RADIUS
Service-Type = NAS-Prompt-User
Acct-Session-Id = "00000072"
Acct-Terminate-Cause = User-Request
Acct-Session-Time = 516
Acct-Delay-Time = 10
Wed Oct 16 14:21:08 2002 : Debug: modcall: entering group preacct
Wed Oct 16 14:21:08 2002 : Debug: modcall[preacct]: module
"preprocess" returns noop
Wed Oct 16 14:21:08 2002 : Debug: rlm_realm: Looking up realm NULL
for User-Name = "np"
Wed Oct 16 14:21:08 2002 : Debug: rlm_realm: No such realm NULL
Wed Oct 16 14:21:08 2002 : Debug: modcall[preacct]: module "suffix"
returns noop
Wed Oct 16 14:21:08 2002 : Debug: modcall[preacct]: module "files"
returns noop
Wed Oct 16 14:21:08 2002 : Debug: modcall: group preacct returns noop
Wed Oct 16 14:21:08 2002 : Debug: modcall: entering group accounting
Wed Oct 16 14:21:08 2002 : Debug: radius_xlat:
'/opt/freeradius/var/log/radius/radacct/10.154.99.244/detail'
Wed Oct 16 14:21:08 2002 : Debug: rlm_detail:
/opt/freeradius/var/log/radius/radacct/%{Client-IP-Address}/detail
expands to /opt/freeradius/var/log/radius/radacct/10.154.99.244/detail
Wed Oct 16 14:21:08 2002 : Debug: modcall[accounting]: module "detail"
returns ok
Wed Oct 16 14:21:08 2002 : Debug: modcall[accounting]: module "unix"
returns ok
Wed Oct 16 14:21:08 2002 : Debug: radius_xlat: 'np'
Wed Oct 16 14:21:08 2002 : Debug: modcall[accounting]: module
"radutmp" returns ok
Wed Oct 16 14:21:08 2002 : Debug: modcall: group accounting returns ok
Sending Accounting-Response of id 64 to 10.154.99.244:1646
Wed Oct 16 14:21:08 2002 : Debug: Finished request 0
Wed Oct 16 14:21:08 2002 : Debug: Going to the next request
Wed Oct 16 14:21:08 2002 : Debug: --- Walking the entire request list ---
Wed Oct 16 14:21:08 2002 : Debug: Cleaning up request 0 ID 64 with
timestamp 3dad59b4
Wed Oct 16 14:21:08 2002 : Debug: Nothing to do. Sleeping until we see
a request.
rad_recv: Access-Request packet from host 10.154.99.244:1645, id=65,
length=77
NAS-IP-Address = 10.154.99.244
NAS-Port = 66
NAS-Port-Type = Virtual
User-Name = "testok"
Calling-Station-Id = "10.154.99.1"
User-Password = "(\266~\202u\372\273w\000\274;pB\026\024q"
Wed Oct 16 14:21:10 2002 : Debug: modcall: entering group authorize
Wed Oct 16 14:21:10 2002 : Debug: modcall[authorize]: module
"preprocess" returns ok
Wed Oct 16 14:21:10 2002 : Debug: rlm_realm: Looking up realm NULL
for User-Name = "testok"
Wed Oct 16 14:21:10 2002 : Debug: rlm_realm: No such realm NULL
Wed Oct 16 14:21:10 2002 : Debug: modcall[authorize]: module "suffix"
returns noop
Wed Oct 16 14:21:10 2002 : Debug: users: Checking testok at 75
Wed Oct 16 14:21:10 2002 : Debug: rad_check_password: Found Auth-Type
System
Wed Oct 16 14:21:10 2002 : Debug: auth: type "System"
Wed Oct 16 14:21:10 2002 : Debug: modcall: entering group authenticate
Wed Oct 16 14:21:10 2002 : Debug: HASH: user testok found in
hashtable bucket 47422
Wed Oct 16 14:21:10 2002 : Debug: modcall[authenticate]: module "unix"
returns ok
Wed Oct 16 14:21:10 2002 : Debug: modcall: group authenticate returns ok
Wed Oct 16 14:21:10 2002 : Debug: users: Matched testok at 75
Wed Oct 16 14:21:10 2002 : Debug: modcall[authorize]: module "files"
returns ok
Wed Oct 16 14:21:10 2002 : Debug: modcall: group authorize returns ok
Wed Oct 16 14:21:10 2002 : Debug: rad_check_password: Found Auth-Type
System
Wed Oct 16 14:21:10 2002 : Debug: auth: type "System"
Wed Oct 16 14:21:10 2002 : Debug: modcall: entering group authenticate
Wed Oct 16 14:21:10 2002 : Debug: HASH: user testok found in
hashtable bucket 47422
Wed Oct 16 14:21:10 2002 : Debug: modcall[authenticate]: module "unix"
returns ok
Wed Oct 16 14:21:10 2002 : Debug: modcall: group authenticate returns ok
Wed Oct 16 14:21:10 2002 : Debug: radius_xlat: ' Hello Testok !'
Wed Oct 16 14:21:10 2002 : Auth: Login OK: [testok/test] (from client
r-test port 66 cli 10.154.99.1)
Sending Access-Accept of id 65 to 10.154.99.244:1645
Service-Type = Login-User
Reply-Message = " Hello Testok !"
Wed Oct 16 14:21:10 2002 : Debug: Finished request 1
Wed Oct 16 14:21:10 2002 : Debug: Going to the next request
Wed Oct 16 14:21:10 2002 : Debug: --- Walking the entire request list ---
Wed Oct 16 14:21:10 2002 : Debug: Waking up in 6 seconds...
rad_recv: Accounting-Request packet from host 10.154.99.244:1646, id=66,
length=93
NAS-IP-Address = 10.154.99.244
NAS-Port = 66
NAS-Port-Type = Virtual
User-Name = "testok"
Calling-Station-Id = "10.154.99.1"
Acct-Status-Type = Start
Acct-Authentic = RADIUS
Service-Type = NAS-Prompt-User
Acct-Session-Id = "00000076"
Acct-Delay-Time = 0
Wed Oct 16 14:21:10 2002 : Debug: modcall: entering group preacct
Wed Oct 16 14:21:10 2002 : Debug: modcall[preacct]: module
"preprocess" returns noop
Wed Oct 16 14:21:10 2002 : Debug: rlm_realm: Looking up realm NULL
for User-Name = "testok"
Wed Oct 16 14:21:10 2002 : Debug: rlm_realm: No such realm NULL
Wed Oct 16 14:21:10 2002 : Debug: modcall[preacct]: module "suffix"
returns noop
Wed Oct 16 14:21:10 2002 : Debug: modcall[preacct]: module "files"
returns noop
Wed Oct 16 14:21:10 2002 : Debug: modcall: group preacct returns noop
Wed Oct 16 14:21:10 2002 : Debug: modcall: entering group accounting
Wed Oct 16 14:21:10 2002 : Debug: radius_xlat:
'/opt/freeradius/var/log/radius/radacct/10.154.99.244/detail'
Wed Oct 16 14:21:10 2002 : Debug: rlm_detail:
/opt/freeradius/var/log/radius/radacct/%{Client-IP-Address}/detail
expands to /opt/freeradius/var/log/radius/radacct/10.154.99.244/detail
Wed Oct 16 14:21:10 2002 : Debug: modcall[accounting]: module "detail"
returns ok
Wed Oct 16 14:21:10 2002 : Debug: modcall[accounting]: module "unix"
returns ok
Wed Oct 16 14:21:10 2002 : Debug: radius_xlat: 'testok'
Wed Oct 16 14:21:10 2002 : Debug: modcall[accounting]: module
"radutmp" returns ok
Wed Oct 16 14:21:10 2002 : Debug: modcall: group accounting returns ok
Sending Accounting-Response of id 66 to 10.154.99.244:1646
Wed Oct 16 14:21:10 2002 : Debug: Finished request 2
Wed Oct 16 14:21:10 2002 : Debug: Going to the next request
Wed Oct 16 14:21:10 2002 : Debug: Cleaning up request 2 ID 66 with
timestamp 3dad59b6
Wed Oct 16 14:21:10 2002 : Debug: rl_next: returning NULL
Wed Oct 16 14:21:10 2002 : Debug: Waking up in 6 seconds...
Wed Oct 16 14:21:16 2002 : Debug: --- Walking the entire request list ---
Wed Oct 16 14:21:16 2002 : Debug: Cleaning up request 1 ID 65 with
timestamp 3dad59b6
Wed Oct 16 14:21:16 2002 : Debug: Nothing to do. Sleeping until we see
a request.
rad_recv: Access-Request packet from host 10.154.99.244:1645, id=67,
length=78
NAS-IP-Address = 10.154.99.244
NAS-Port = 67
NAS-Port-Type = Virtual
User-Name = "testko"
Calling-Station-Id = "10.154.99.65"
User-Password = "\206\272\241\302\346\237\200\237\361>\217c:\224\361\022"
Wed Oct 16 14:21:18 2002 : Debug: modcall: entering group authorize
Wed Oct 16 14:21:18 2002 : Debug: modcall[authorize]: module
"preprocess" returns ok
Wed Oct 16 14:21:18 2002 : Debug: rlm_realm: Looking up realm NULL
for User-Name = "testko"
Wed Oct 16 14:21:18 2002 : Debug: rlm_realm: No such realm NULL
Wed Oct 16 14:21:18 2002 : Debug: modcall[authorize]: module "suffix"
returns noop
Wed Oct 16 14:21:18 2002 : Debug: users: Checking testko at 81
Wed Oct 16 14:21:18 2002 : Debug: rad_check_password: Found Auth-Type
System
Wed Oct 16 14:21:18 2002 : Debug: auth: type "System"
Wed Oct 16 14:21:18 2002 : Debug: modcall: entering group authenticate
Wed Oct 16 14:21:18 2002 : Debug: HASH: user testko found in
hashtable bucket 15798
Wed Oct 16 14:21:18 2002 : Debug: modcall[authenticate]: module "unix"
returns ok
Wed Oct 16 14:21:18 2002 : Debug: modcall: group authenticate returns ok
Wed Oct 16 14:21:18 2002 : Debug: users: Matched testko at 81
Wed Oct 16 14:21:18 2002 : Debug: modcall[authorize]: module "files"
returns ok
Wed Oct 16 14:21:18 2002 : Debug: modcall: group authorize returns ok
Wed Oct 16 14:21:18 2002 : Debug: rad_check_password: Found Auth-Type
System
Wed Oct 16 14:21:18 2002 : Debug: auth: type "System"
Wed Oct 16 14:21:18 2002 : Debug: modcall: entering group authenticate
Wed Oct 16 14:21:18 2002 : Debug: HASH: user testko found in
hashtable bucket 15798
Wed Oct 16 14:21:18 2002 : Debug: modcall[authenticate]: module "unix"
returns ok
Wed Oct 16 14:21:18 2002 : Debug: modcall: group authenticate returns ok
Wed Oct 16 14:21:18 2002 : Debug: radius_xlat: ' Hello Testko !'
Wed Oct 16 14:21:18 2002 : Auth: Login OK: [testko/test] (from client
r-test port 67 cli 10.154.99.65)
Sending Access-Accept of id 67 to 10.154.99.244:1645
Class = 0x
Service-Type = Login-User
Reply-Message = " Hello Testko !"
Class = 0x
Wed Oct 16 14:21:18 2002 : Debug: Finished request 3
Wed Oct 16 14:21:18 2002 : Debug: Going to the next request
Wed Oct 16 14:21:18 2002 : Debug: --- Walking the entire request list ---
Wed Oct 16 14:21:18 2002 : Debug: Waking up in 6 seconds...
rad_recv: Access-Request packet from host 10.154.99.244:1645, id=67,
length=78
Wed Oct 16 14:21:23 2002 : Debug: Sending duplicate reply to client
r-test:1645 - ID: 67
Re-sending Access-Accept of id 67 to 10.154.99.244:1645
Wed Oct 16 14:21:23 2002 : Debug: --- Walking the entire request list ---
Wed Oct 16 14:21:23 2002 : Debug: Waking up in 1 seconds...
Wed Oct 16 14:21:24 2002 : Debug: --- Walking the entire request list ---
Wed Oct 16 14:21:24 2002 : Debug: Cleaning up request 3 ID 67 with
timestamp 3dad59be
Wed Oct 16 14:21:24 2002 : Debug: Nothing to do. Sleeping until we see
a request.
The Cisco Log (AAA Authentication debugging is on,
Radius protocol debugging is on) :
r-test#sh log
Syslog logging: enabled (0 messages dropped, 0 flushes, 0 overruns)
~ Console logging: level debugging, 8588 messages logged
~ Monitor logging: level debugging, 727 messages logged
~ Buffer logging: level debugging, 438 messages logged
~ Trap logging: level informational, 26 message lines logged
Log Buffer (16000 bytes):
1w2d: AAA: parse name=tty67 idb type=-1 tty=-1
1w2d: AAA: name=tty67 flags=0x11 type=5 shelf=0 slot=0 adapter=0 port=67
channel=0
1w2d: AAA/MEMORY: create_user (0x612EB374) user='' ruser='' port='tty67'
rem_addr='10.154.99.65' authen_type=ASCII service=LOGIN priv=1
1w2d: AAA/AUTHEN/START (765214283): port='tty67' list='' action=LOGIN
service=LOGIN
1w2d: AAA/AUTHEN/START (765214283): using "default" list
1w2d: AAA/AUTHEN/START (765214283): Method=radius (radius)
1w2d: AAA/AUTHEN (765214283): status = GETUSER
1w2d: AAA/AUTHEN/CONT (765214283): continue_login (user='(undef)')
1w2d: AAA/AUTHEN (765214283): status = GETUSER
1w2d: AAA/AUTHEN (765214283): Method=radius (radius)
1w2d: AAA/AUTHEN (765214283): status = GETPASS
1w2d: AAA/AUTHEN/CONT (765214283): continue_login (user='testok')
1w2d: AAA/AUTHEN (765214283): status = GETPASS
1w2d: AAA/AUTHEN (765214283): Method=radius (radius)
1w2d: RADIUS: ustruct sharecount=1
1w2d: RADIUS: Initial Transmit tty67 id 79 10.154.99.65:1812,
Access-Request, len 78
1w2d: Attribute 4 6 0A9A63F4
1w2d: Attribute 5 6 00000043
1w2d: Attribute 61 6 00000005
1w2d: Attribute 1 8 74657374
1w2d: Attribute 31 14 31302E31
1w2d: Attribute 2 18 31D68FCD
1w2d: RADIUS: Received from id 79 10.154.99.65:1812, Access-Accept, len 43
1w2d: Attribute 6 6 00000001
1w2d: Attribute 18 17 2048656C
1w2d: RADIUS: saved authorization data for user 612EB374 at 60E400C4
1w2d: AAA/AUTHEN (765214283): status = PASS
1w2d: RADIUS: ustruct sharecount=3
1w2d: RADIUS: Initial Transmit tty67 id 80 10.154.99.65:1813,
Accounting-Request, len 94
1w2d: Attribute 4 6 0A9A63F4
1w2d: Attribute 5 6 00000043
1w2d: Attribute 61 6 00000005
1w2d: Attribute 1 8 74657374
1w2d: Attribute 31 14 31302E31
1w2d: Attribute 40 6 00000001
1w2d: Attribute 45 6 00000001
1w2d: Attribute 6 6 00000007
1w2d: Attribute 44 10 30303030
1w2d: Attribute 41 6 00000000
1w2d: RADIUS: Received from id 80 10.154.99.65:1813,
Accounting-response, len 20
1w2d: AAA: parse name=tty68 idb type=-1 tty=-1
1w2d: AAA: name=tty68 flags=0x11 type=5 shelf=0 slot=0 adapter=0 port=68
channel=0
1w2d: AAA/MEMORY: create_user (0x612EC224) user='' ruser='' port='tty68'
rem_addr='10.154.99.1' authen_type=ASCII service=LOGIN priv=1
1w2d: AAA/AUTHEN/START (3498201480): port='tty68' list='' action=LOGIN
service=LOGIN
1w2d: AAA/AUTHEN/START (3498201480): using "default" list
1w2d: AAA/AUTHEN/START (3498201480): Method=radius (radius)
1w2d: AAA/AUTHEN (3498201480): status = GETUSER
1w2d: AAA/AUTHEN/CONT (3498201480): continue_login (user='(undef)')
1w2d: AAA/AUTHEN (3498201480): status = GETUSER
1w2d: AAA/AUTHEN (3498201480): Method=radius (radius)
1w2d: AAA/AUTHEN (3498201480): status = GETPASS
1w2d: AAA/AUTHEN/CONT (3498201480): continue_login (user='testko')
1w2d: AAA/AUTHEN (3498201480): status = GETPASS
1w2d: AAA/AUTHEN (3498201480): Method=radius (radius)
1w2d: RADIUS: ustruct sharecount=1
1w2d: RADIUS: Initial Transmit tty68 id 81 10.154.99.65:1812,
Access-Request, len 77
1w2d: Attribute 4 6 0A9A63F4
1w2d: Attribute 5 6 00000044
1w2d: Attribute 61 6 00000005
1w2d: Attribute 1 8 74657374
1w2d: Attribute 31 13 31302E31
1w2d: Attribute 2 18 822337A6
1w2d: RADIUS: Received from 10.154.99.65 - un-sane packet
1w2d: RADIUS: Retransmit id 81
1w2d: RADIUS: Received from 10.154.99.65 - un-sane packet
1w2d: RADIUS: Retransmit id 81
1w2d: RADIUS: Received from 10.154.99.65 - un-sane packet
1w2d: RADIUS: Retransmit id 81
1w2d: RADIUS: Received from 10.154.99.65 - un-sane packet
1w2d: RADIUS: Marking server 10.154.99.65:1812,1813 dead
1w2d: RADIUS: Tried all servers.
1w2d: RADIUS: No valid server found. Trying any viable server
1w2d: RADIUS: Tried all servers.
1w2d: RADIUS: No response for id 81
1w2d: RADIUS: No response from server
1w2d: AAA/AUTHEN (3498201480): status = ERROR
1w2d: AAA/AUTHEN/START (2152198380): port='tty68' list='' action=LOGIN
service=LOGIN
1w2d: AAA/AUTHEN/START (2152198380): Restart
1w2d: AAA/AUTHEN/START (2152198380): Method=LOCAL
1w2d: AAA/AUTHEN (2152198380): User not found, end of method list
1w2d: AAA/AUTHEN (2152198380): status = FAIL
1w2d: AAA/MEMORY: free_user (0x612EC224) user='testko' ruser=''
port='tty68' rem_addr='10.154.99.1' authen_type=ASCII service=LOGIN priv=1
1w2d: AAA: parse name=tty68 idb type=-1 tty=-1
1w2d: AAA: name=tty68 flags=0x11 type=5 shelf=0 slot=0 adapter=0 port=68
channel=0
1w2d: AAA/MEMORY: create_user (0x612EB4D0) user='' ruser='' port='tty68'
rem_addr='10.154.99.1' authen_type=ASCII service=LOGIN priv=1
1w2d: AAA/AUTHEN/START (3473982617): port='tty68' list='' action=LOGIN
service=LOGIN
1w2d: AAA/AUTHEN/START (3473982617): using "default" list
1w2d: AAA/AUTHEN/START (3473982617): Method=radius (radius)
1w2d: AAA/AUTHEN (3473982617): status = GETUSER
Regards.
- --
* Jean-Paul Chapalain - Reseaux et Systemes Distribues *
* Groupement Informatique Credit Mutuel *
* Tel : +33 298002873 Fax : +33 298284005 *
* mailto : [EMAIL PROTECTED] *
-----BEGIN PGP SIGNATURE-----
Version: GnuPG v1.2.0 (GNU/Linux)
Comment: Using GnuPG with Mozilla - http://enigmail.mozdev.org
iD8DBQE9rWH9KUg1Mnwh2vYRAvQNAJ9dMzkJjwJ9h3iTaOjOVZ71EwmHugCcCpQ1
K2488uUvFPsjKYjzALeI284=
=kSpA
-----END PGP SIGNATURE-----
-
List info/subscribe/unsubscribe? See http://www.freeradius.org/list/users.html
