Thank you Chris Parker for your prior assistance.
Authentication on my RADIUS server was successful but the Telnet
prompt to my target Unix box still does not appear on the user's
PC, with user setup;
>usertest Auth-Type := Local, User-Password == "testunix"
> Service-Type = Login-User,
> Login-Service = Telnet,
> Login-TCP-Port = Telnet,
> Login-IP-Host = 192.168.212.43
Then, I used Hyperterminal to dial into my service provider's
RADIUS client and manually entered the required Login and
Password and got the following in my Hyperterminal window;
Welcome to RADIUS Client Service
login: usertest
password: ********
Please hold ...
Authenticated.
But the Telnet prompt for the target Unix box 192.168.212.43
SunOS 5.6
login:
does not appear after 'Authenticated'. What may be wrong
considering the debug info below which includes starting the
RADIUS server and (******>>>>>) the attempt to connect using
Hyperterminal;
[DEBUG Begin]
freeradius-0.9.3]# radiusd -Xxxy
Tue Jan 13 11:05:06 2004 : Info: Starting - reading
configuration files ...
Tue Jan 13 11:05:06 2004 : Debug: reread_config: reading
radiusd.conf
Tue Jan 13 11:05:06 2004 : Debug: Config: including file:
/usr/local/etc/raddb/proxy.conf
Tue Jan 13 11:05:06 2004 : Debug: Config: including file:
/usr/local/etc/raddb/clients.conf
Tue Jan 13 11:05:06 2004 : Debug: Config: including file:
/usr/local/etc/raddb/snmp.conf
Tue Jan 13 11:05:06 2004 : Debug: Config: including file:
/usr/local/etc/raddb/sql.conf
Tue Jan 13 11:05:06 2004 : Debug: main: prefix = "/usr/local"
Tue Jan 13 11:05:06 2004 : Debug: main: localstatedir =
"/usr/local/var"
Tue Jan 13 11:05:06 2004 : Debug: main: logdir =
"/usr/local/var/log/radius"
Tue Jan 13 11:05:06 2004 : Debug: main: libdir =
"/usr/local/lib"
Tue Jan 13 11:05:06 2004 : Debug: main: radacctdir =
"/usr/local/var/log/radius/radacct"
Tue Jan 13 11:05:06 2004 : Debug: main: hostname_lookups = no
Tue Jan 13 11:05:06 2004 : Debug: main: max_request_time = 30
Tue Jan 13 11:05:06 2004 : Debug: main: cleanup_delay = 5
Tue Jan 13 11:05:06 2004 : Debug: main: max_requests = 1024
Tue Jan 13 11:05:06 2004 : Debug: main: delete_blocked_requests
= 0
Tue Jan 13 11:05:06 2004 : Debug: main: port = 1645
Tue Jan 13 11:05:06 2004 : Debug: main: allow_core_dumps = no
Tue Jan 13 11:05:06 2004 : Debug: main: log_stripped_names = no
Tue Jan 13 11:05:06 2004 : Debug: main: log_file =
"/usr/local/var/log/radius/radius.log"
Tue Jan 13 11:05:06 2004 : Debug: main: log_auth = no
Tue Jan 13 11:05:06 2004 : Debug: main: log_auth_badpass = no
Tue Jan 13 11:05:06 2004 : Debug: main: log_auth_goodpass = no
Tue Jan 13 11:05:06 2004 : Debug: main: pidfile =
"/usr/local/var/run/radiusd/radiusd.pid"
Tue Jan 13 11:05:06 2004 : Debug: main: user = "(null)"
Tue Jan 13 11:05:06 2004 : Debug: main: group = "(null)"
Tue Jan 13 11:05:06 2004 : Debug: main: usercollide = no
Tue Jan 13 11:05:06 2004 : Debug: main: lower_user = "no"
Tue Jan 13 11:05:06 2004 : Debug: main: lower_pass = "no"
Tue Jan 13 11:05:06 2004 : Debug: main: nospace_user = "no"
Tue Jan 13 11:05:06 2004 : Debug: main: nospace_pass = "no"
Tue Jan 13 11:05:06 2004 : Debug: main: checkrad =
"/usr/local/sbin/checkrad"
Tue Jan 13 11:05:06 2004 : Debug: main: proxy_requests = yes
Tue Jan 13 11:05:06 2004 : Debug: proxy: retry_delay = 5
Tue Jan 13 11:05:06 2004 : Debug: proxy: retry_count = 3
Tue Jan 13 11:05:06 2004 : Debug: proxy: synchronous = no
Tue Jan 13 11:05:06 2004 : Debug: proxy: default_fallback = yes
Tue Jan 13 11:05:06 2004 : Debug: proxy: dead_time = 120
Tue Jan 13 11:05:06 2004 : Debug: proxy: post_proxy_authorize =
yes
Tue Jan 13 11:05:06 2004 : Debug: proxy: wake_all_if_all_dead =
no
Tue Jan 13 11:05:06 2004 : Debug: security: max_attributes =
200
Tue Jan 13 11:05:06 2004 : Debug: security: reject_delay = 1
Tue Jan 13 11:05:06 2004 : Debug: security: status_server = no
Tue Jan 13 11:05:06 2004 : Debug: main: debug_level = 0
Tue Jan 13 11:05:06 2004 : Debug: read_config_files: reading
dictionary
Tue Jan 13 11:05:06 2004 : Debug: read_config_files: reading
naslist
Tue Jan 13 11:05:06 2004 : Info: Using deprecated naslist file.
Support for this will go away soon.
Tue Jan 13 11:05:06 2004 : Debug: read_config_files: reading
clients
Tue Jan 13 11:05:06 2004 : Info: Using deprecated clients file.
Support for this will go away soon.
Tue Jan 13 11:05:06 2004 : Debug: read_config_files: reading
realms
Tue Jan 13 11:05:06 2004 : Info: Using deprecated realms file.
Support for this will go away soon.
Tue Jan 13 11:05:06 2004 : Debug: radiusd: entering modules
setup
Tue Jan 13 11:05:07 2004 : Debug: Module: Library search path is
/usr/local/lib
Tue Jan 13 11:05:07 2004 : Debug: Module: Loaded expr
Tue Jan 13 11:05:07 2004 : Debug: Module: Instantiated expr
(expr)
Tue Jan 13 11:05:07 2004 : Debug: Module: Loaded PAP
Tue Jan 13 11:05:07 2004 : Debug: pap: encryption_scheme =
"md5"
Tue Jan 13 11:05:07 2004 : Debug: Module: Instantiated pap (pap)
Tue Jan 13 11:05:07 2004 : Debug: Module: Loaded CHAP
Tue Jan 13 11:05:07 2004 : Debug: Module: Instantiated chap
(chap)
Tue Jan 13 11:05:07 2004 : Debug: Module: Loaded MS-CHAP
Tue Jan 13 11:05:07 2004 : Debug: mschap: use_mppe = yes
Tue Jan 13 11:05:07 2004 : Debug: mschap: require_encryption =
no
Tue Jan 13 11:05:07 2004 : Debug: mschap: require_strong = no
Tue Jan 13 11:05:07 2004 : Debug: mschap: passwd = "(null)"
Tue Jan 13 11:05:07 2004 : Debug: mschap: authtype = "MS-CHAP"
Tue Jan 13 11:05:07 2004 : Debug: Module: Instantiated mschap
(mschap)
Tue Jan 13 11:05:07 2004 : Debug: Module: Loaded System
Tue Jan 13 11:05:07 2004 : Debug: unix: cache = no
Tue Jan 13 11:05:07 2004 : Debug: unix: passwd = "(null)"
Tue Jan 13 11:05:07 2004 : Debug: unix: shadow = "(null)"
Tue Jan 13 11:05:07 2004 : Debug: unix: group = "(null)"
Tue Jan 13 11:05:07 2004 : Debug: unix: radwtmp =
"/usr/local/var/log/radius/radwtmp"
Tue Jan 13 11:05:07 2004 : Debug: unix: usegroup = no
Tue Jan 13 11:05:07 2004 : Debug: unix: cache_reload = 600
Tue Jan 13 11:05:07 2004 : Debug: Module: Instantiated unix
(unix)
Tue Jan 13 11:05:07 2004 : Debug: Module: Loaded eap
Tue Jan 13 11:05:07 2004 : Debug: eap: default_eap_type = "md5"
Tue Jan 13 11:05:07 2004 : Debug: eap: timer_expire = 60
Tue Jan 13 11:05:07 2004 : Debug: rlm_eap: Loaded and
initialized the type md5
Tue Jan 13 11:05:07 2004 : Debug: rlm_eap: Loaded and
initialized the type leap
Tue Jan 13 11:05:07 2004 : Debug: Module: Instantiated eap (eap)
Tue Jan 13 11:05:07 2004 : Debug: Module: Loaded preprocess
Tue Jan 13 11:05:07 2004 : Debug: preprocess: huntgroups =
"/usr/local/etc/raddb/huntgroups"
Tue Jan 13 11:05:07 2004 : Debug: preprocess: hints =
"/usr/local/etc/raddb/hints"
Tue Jan 13 11:05:07 2004 : Debug: preprocess: with_ascend_hack
= no
Tue Jan 13 11:05:07 2004 : Debug: preprocess:
ascend_channels_per_line = 23
Tue Jan 13 11:05:07 2004 : Debug: preprocess:
with_ntdomain_hack = no
Tue Jan 13 11:05:07 2004 : Debug: preprocess:
with_specialix_jetstream_hack = no
Tue Jan 13 11:05:07 2004 : Debug: preprocess:
with_cisco_vsa_hack = no
Tue Jan 13 11:05:08 2004 : Debug: Module: Instantiated
preprocess (preprocess)
Tue Jan 13 11:05:08 2004 : Debug: Module: Loaded detail
Tue Jan 13 11:05:08 2004 : Debug: detail: detailfile =
"/usr/local/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d"
Tue Jan 13 11:05:08 2004 : Debug: detail: detailperm = 384
Tue Jan 13 11:05:08 2004 : Debug: detail: dirperm = 493
Tue Jan 13 11:05:08 2004 : Debug: detail: locking = no
Tue Jan 13 11:05:08 2004 : Debug: Module: Instantiated detail
(auth_log)
Tue Jan 13 11:05:08 2004 : Debug: Module: Loaded realm
Tue Jan 13 11:05:08 2004 : Debug: realm: format = "suffix"
Tue Jan 13 11:05:08 2004 : Debug: realm: delimiter = "@"
Tue Jan 13 11:05:08 2004 : Debug: Module: Instantiated realm
(suffix)
Tue Jan 13 11:05:08 2004 : Debug: Module: Loaded files
Tue Jan 13 11:05:08 2004 : Debug: files: usersfile =
"/usr/local/etc/raddb/users"
Tue Jan 13 11:05:08 2004 : Debug: files: acctusersfile =
"/usr/local/etc/raddb/acct_users"
Tue Jan 13 11:05:08 2004 : Debug: files: preproxy_usersfile =
"/usr/local/etc/raddb/preproxy_users"
Tue Jan 13 11:05:08 2004 : Debug: files: compat = "no"
Tue Jan 13 11:05:08 2004 : Debug: Module: Instantiated files
(files)
Tue Jan 13 11:05:08 2004 : Debug: Module: Loaded
Acct-Unique-Session-Id
Tue Jan 13 11:05:08 2004 : Debug: acct_unique: key =
"User-Name, Acct-Session-Id, NAS-IP-Address, Client-IP-Address,
NAS-Port-Id"
Tue Jan 13 11:05:08 2004 : Debug: Module: Instantiated
acct_unique (acct_unique)
Tue Jan 13 11:05:08 2004 : Debug: detail: detailfile =
"/usr/local/var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d"
Tue Jan 13 11:05:08 2004 : Debug: detail: detailperm = 384
Tue Jan 13 11:05:08 2004 : Debug: detail: dirperm = 493
Tue Jan 13 11:05:08 2004 : Debug: detail: locking = no
Tue Jan 13 11:05:08 2004 : Debug: Module: Instantiated detail
(detail)
Tue Jan 13 11:05:08 2004 : Debug: Module: Loaded radutmp
Tue Jan 13 11:05:08 2004 : Debug: radutmp: filename =
"/usr/local/var/log/radius/radutmp"
Tue Jan 13 11:05:08 2004 : Debug: radutmp: username =
"%{User-Name}"
Tue Jan 13 11:05:08 2004 : Debug: radutmp: case_sensitive = yes
Tue Jan 13 11:05:08 2004 : Debug: radutmp: check_with_nas = yes
Tue Jan 13 11:05:08 2004 : Debug: radutmp: perm = 384
Tue Jan 13 11:05:08 2004 : Debug: radutmp: callerid = yes
Tue Jan 13 11:05:08 2004 : Debug: Module: Instantiated radutmp
(radutmp)
Tue Jan 13 11:05:08 2004 : Info: Listening on IP address *,
ports 1645/udp and 1646/udp, with proxy on 1647/udp.
Tue Jan 13 11:05:08 2004 : Info: Ready to process requests.
*******>>>>>>
rad_recv: Access-Request packet from host 86.201.123.45:45454,
id=230, length=89
NAS-IP-Address = 194.212.130.2
Called-Station-Id = "8037867177"
Calling-Station-Id = "8037127336"
NAS-Port = 21222122
Service-Type = NAS-Prompt-User
User-Name = "usertest"
User-Password = "testunix"
Tue Jan 13 13:18:51 2004 : Debug: modcall: entering group
authorize for request
0
Tue Jan 13 13:18:51 2004 : Debug: modsingle[authorize]:
calling preprocess (rlm_preprocess) for request 0
Tue Jan 13 13:18:51 2004 : Debug: modsingle[authorize]:
returned from preprocess (rlm_preprocess) for request 0
Tue Jan 13 13:18:51 2004 : Debug: modcall[authorize]: module
"preprocess" returns ok for request 0
Tue Jan 13 13:18:51 2004 : Debug: modsingle[authorize]:
calling auth_log (rlm_detail) for request 0
Tue Jan 13 13:18:52 2004 : Debug: radius_xlat:
'/usr/local/var/log/radius/radacct/86.201.123.45/auth-detail-20040113'
Tue Jan 13 13:18:52 2004 : Debug: rlm_detail:
/usr/local/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d
expands to
/usr/local/var/log/radius/radacct/86.201.123.45/auth-detail-20040113
Tue Jan 13 13:18:52 2004 : Debug: modsingle[authorize]:
returned from auth_log (rlm_detail) for request 0
Tue Jan 13 13:18:52 2004 : Debug: modcall[authorize]: module
"auth_log" returns ok for request 0
Tue Jan 13 13:18:52 2004 : Debug: modsingle[authorize]:
calling chap (rlm_chap) for request 0
Tue Jan 13 13:18:52 2004 : Debug: modsingle[authorize]:
returned from chap (rlm_chap) for request 0
Tue Jan 13 13:18:52 2004 : Debug: modcall[authorize]: module
"chap" returns noop for request 0
Tue Jan 13 13:18:52 2004 : Debug: modsingle[authorize]:
calling eap (rlm_eap)
for request 0
Tue Jan 13 13:18:52 2004 : Debug: modsingle[authorize]:
returned from eap (rlm_eap) for request 0
Tue Jan 13 13:18:52 2004 : Debug: modcall[authorize]: module
"eap" returns noop for request 0
Tue Jan 13 13:18:52 2004 : Debug: modsingle[authorize]:
calling suffix (rlm_realm) for request 0
Tue Jan 13 13:18:52 2004 : Debug: rlm_realm: No '@' in
User-Name = "usertest", looking up realm NULL
Tue Jan 13 13:18:52 2004 : Debug: rlm_realm: No such realm
"NULL"
Tue Jan 13 13:18:52 2004 : Debug: modsingle[authorize]:
returned from suffix (rlm_realm) for request 0
Tue Jan 13 13:18:52 2004 : Debug: modcall[authorize]: module
"suffix" returns
noop for request 0
Tue Jan 13 13:18:52 2004 : Debug: modsingle[authorize]:
calling files (rlm_files) for request 0
Tue Jan 13 13:18:52 2004 : Debug: users: Matched usertest at
130
Tue Jan 13 13:18:52 2004 : Debug: modsingle[authorize]:
returned from files (rlm_files) for request 0
Tue Jan 13 13:18:52 2004 : Debug: modcall[authorize]: module
"files" returns ok for request 0
Tue Jan 13 13:18:52 2004 : Debug: modsingle[authorize]:
calling mschap (rlm_mschap) for request 0
Tue Jan 13 13:18:52 2004 : Debug: modsingle[authorize]:
returned from mschap (rlm_mschap) for request 0
Tue Jan 13 13:18:52 2004 : Debug: modcall[authorize]: module
"mschap" returns
noop for request 0
Tue Jan 13 13:18:52 2004 : Debug: modcall: group authorize
returns ok for request 0
Tue Jan 13 13:18:52 2004 : Debug: rad_check_password: Found
Auth-Type Local
Tue Jan 13 13:18:52 2004 : Debug: auth: type Local
Tue Jan 13 13:18:52 2004 : Debug: auth: user supplied
User-Password matches local User-Password
Sending Access-Accept of id 230 to 86.201.123.45:45454
Service-Type = Login-User
Login-Service = Telnet
Login-TCP-Port = Telnet
Login-IP-Host = 192.168.212.43
Tue Jan 13 13:18:52 2004 : Debug: Finished request 0
Tue Jan 13 13:18:52 2004 : Debug: Going to the next request
Tue Jan 13 13:18:52 2004 : Debug: --- Walking the entire request
list ---
Tue Jan 13 13:18:52 2004 : Debug: Waking up in 5 seconds...
rad_recv: Accounting-Request packet from host
86.201.123.45:45454, id=231, length=97
Acct-Status-Type = Start
NAS-IP-Address = 194.212.130.2
NAS-Port = 21222122
Called-Station-Id = "8037867177"
Calling-Station-Id = "8037127336"
Acct-Delay-Time = 0
User-Name = "usertest"
Acct-Session-Id = "00084DE8"
Acct-Authentic = RADIUS
Tue Jan 13 13:18:52 2004 : Debug: modcall: entering group
preacct for request 1
Tue Jan 13 13:18:52 2004 : Debug: modsingle[preacct]: calling
preprocess (rlm_preprocess) for request 1
Tue Jan 13 13:18:52 2004 : Debug: modsingle[preacct]: returned
from preprocess (rlm_preprocess) for request 1
Tue Jan 13 13:18:52 2004 : Debug: modcall[preacct]: module
"preprocess" returns noop for request 1
Tue Jan 13 13:18:52 2004 : Debug: modsingle[preacct]: calling
suffix (rlm_realm) for request 1
Tue Jan 13 13:18:52 2004 : Debug: rlm_realm: No '@' in
User-Name = "usertest", looking up realm NULL
Tue Jan 13 13:18:52 2004 : Debug: rlm_realm: No such realm
"NULL"
Tue Jan 13 13:18:52 2004 : Debug: modsingle[preacct]: returned
from suffix (rlm_realm) for request 1
Tue Jan 13 13:18:52 2004 : Debug: modcall[preacct]: module
"suffix" returns noop for request 1
Tue Jan 13 13:18:52 2004 : Debug: modsingle[preacct]: calling
files (rlm_files) for request 1
Tue Jan 13 13:18:52 2004 : Debug: modsingle[preacct]: returned
from files (rlm_files) for request 1
Tue Jan 13 13:18:52 2004 : Debug: modcall[preacct]: module
"files" returns noop for request 1
Tue Jan 13 13:18:52 2004 : Debug: modcall: group preacct returns
noop for request 1
Tue Jan 13 13:18:52 2004 : Debug: modcall: entering group
accounting for request 1
Tue Jan 13 13:18:52 2004 : Debug: modsingle[accounting]:
calling acct_unique (rlm_acct_unique) for request 1
Tue Jan 13 13:18:52 2004 : Debug: rlm_acct_unique: WARNING:
Attribute NAS-Port-Id was not found in request, unique ID MAY be
inconsistent
Tue Jan 13 13:18:52 2004 : Debug: rlm_acct_unique: Hashing
',Client-IP-Address = 86.201.123.45,NAS-IP-Address =
194.212.130.2,Acct-Session-Id = "00084DE8",User-Name =
"usertest"'
Tue Jan 13 13:18:52 2004 : Debug: rlm_acct_unique:
Acct-Unique-Session-ID = "093cbc2807xy76ra".
Tue Jan 13 13:18:52 2004 : Debug: modsingle[accounting]:
returned from acct_unique (rlm_acct_unique) for request 1
Tue Jan 13 13:18:52 2004 : Debug: modcall[accounting]: module
"acct_unique" returns ok for request 1
Tue Jan 13 13:18:52 2004 : Debug: modsingle[accounting]:
calling detail (rlm_detail) for request 1
Tue Jan 13 13:18:52 2004 : Debug: radius_xlat:
'/usr/local/var/log/radius/radacct/86.201.123.45/detail-20040113'
Tue Jan 13 13:18:52 2004 : Debug: rlm_detail:
/usr/local/var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d
expands to
/usr/local/var/log/radius/radacct/86.201.123.45/detail-20040113
Tue Jan 13 13:18:52 2004 : Debug: modsingle[accounting]:
returned from detail
(rlm_detail) for request 1
Tue Jan 13 13:18:52 2004 : Debug: modcall[accounting]: module
"detail" returns ok for request 1
Tue Jan 13 13:18:52 2004 : Debug: modsingle[accounting]:
calling unix (rlm_unix) for request 1
Tue Jan 13 13:18:52 2004 : Debug: modsingle[accounting]:
returned from unix (rlm_unix) for request 1
Tue Jan 13 13:18:52 2004 : Debug: modcall[accounting]: module
"unix" returns ok for request 1
Tue Jan 13 13:18:52 2004 : Debug: modsingle[accounting]:
calling radutmp (rlm_radutmp) for request 1
Tue Jan 13 13:18:52 2004 : Debug: radius_xlat:
'/usr/local/var/log/radius/radutmp'
Tue Jan 13 13:18:52 2004 : Debug: radius_xlat: 'usertest'
Tue Jan 13 13:18:52 2004 : Debug: modsingle[accounting]:
returned from radutmp (rlm_radutmp) for request 1
Tue Jan 13 13:18:52 2004 : Debug: modcall[accounting]: module
"radutmp" returns ok for request 1
Tue Jan 13 13:18:52 2004 : Debug: modcall: group accounting
returns ok for request 1
Sending Accounting-Response of id 231 to 86.201.123.45:45454
Tue Jan 13 13:18:52 2004 : Debug: Finished request 1
Tue Jan 13 13:18:52 2004 : Debug: Going to the next request
Tue Jan 13 13:18:52 2004 : Debug: Waking up in 5 seconds...
Tue Jan 13 13:18:57 2004 : Debug: --- Walking the entire request
list ---
Tue Jan 13 13:18:57 2004 : Debug: Cleaning up request 0 ID 230
with timestamp 4004368b
Tue Jan 13 13:18:57 2004 : Debug: Waking up in 1 seconds...
Tue Jan 13 13:18:58 2004 : Debug: --- Walking the entire request
list ---
Tue Jan 13 13:18:58 2004 : Debug: Cleaning up request 1 ID 231
with timestamp 4004368c
Tue Jan 13 13:18:58 2004 : Debug: Nothing to do. Sleeping until
we see a request.
rad_recv: Accounting-Request packet from host
86.201.123.45:45454, id=234, length=103
Acct-Status-Type = Stop
NAS-IP-Address = 194.212.130.2
NAS-Port = 21222122
Called-Station-Id = "8037867177"
Calling-Station-Id = "8037127336"
Acct-Delay-Time = 0
User-Name = "usertest"
Acct-Session-Id = "00084DE8"
Acct-Session-Time = 25
Acct-Authentic = RADIUS
Tue Jan 13 13:19:17 2004 : Debug: modcall: entering group
preacct for request 2
Tue Jan 13 13:19:17 2004 : Debug: modsingle[preacct]: calling
preprocess (rlm_preprocess) for request 2
Tue Jan 13 13:19:17 2004 : Debug: modsingle[preacct]: returned
from preprocess (rlm_preprocess) for request 2
Tue Jan 13 13:19:17 2004 : Debug: modcall[preacct]: module
"preprocess" returns noop for request 2
Tue Jan 13 13:19:17 2004 : Debug: modsingle[preacct]: calling
suffix (rlm_realm) for request 2
Tue Jan 13 13:19:17 2004 : Debug: rlm_realm: No '@' in
User-Name = "usertest", looking up realm NULL
Tue Jan 13 13:19:17 2004 : Debug: rlm_realm: No such realm
"NULL"
Tue Jan 13 13:19:17 2004 : Debug: modsingle[preacct]: returned
from suffix (rlm_realm) for request 2
Tue Jan 13 13:19:17 2004 : Debug: modcall[preacct]: module
"suffix" returns noop for request 2
Tue Jan 13 13:19:17 2004 : Debug: modsingle[preacct]: calling
files (rlm_files) for request 2
Tue Jan 13 13:19:17 2004 : Debug: modsingle[preacct]: returned
from files (rlm_files) for request 2
Tue Jan 13 13:19:17 2004 : Debug: modcall[preacct]: module
"files" returns noop for request 2
Tue Jan 13 13:19:17 2004 : Debug: modcall: group preacct returns
noop for request 2
Tue Jan 13 13:19:17 2004 : Debug: modcall: entering group
accounting for request 2
Tue Jan 13 13:19:17 2004 : Debug: modsingle[accounting]:
calling acct_unique (rlm_acct_unique) for request 2
Tue Jan 13 13:19:17 2004 : Debug: rlm_acct_unique: WARNING:
Attribute NAS-Port-Id was not found in request, unique ID MAY be
inconsistent
Tue Jan 13 13:19:17 2004 : Debug: rlm_acct_unique: Hashing
',Client-IP-Address = 86.201.123.45,NAS-IP-Address =
194.212.130.2,Acct-Session-Id = "00084DE8",User-Name =
"usertest"'
Tue Jan 13 13:19:17 2004 : Debug: rlm_acct_unique:
Acct-Unique-Session-ID = "093cbc2807xy76ra".
Tue Jan 13 13:19:17 2004 : Debug: modsingle[accounting]:
returned from acct_unique (rlm_acct_unique) for request 2
Tue Jan 13 13:19:17 2004 : Debug: modcall[accounting]: module
"acct_unique" returns ok for request 2
Tue Jan 13 13:19:17 2004 : Debug: modsingle[accounting]:
calling detail (rlm_detail) for request 2
Tue Jan 13 13:19:17 2004 : Debug: radius_xlat:
'/usr/local/var/log/radius/radacct/86.201.123.45/detail-20040113'
Tue Jan 13 13:19:17 2004 : Debug: rlm_detail:
/usr/local/var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d
expands to
/usr/local/var/log/radius/radacct/86.201.123.45/detail-20040113
Tue Jan 13 13:19:17 2004 : Debug: modsingle[accounting]:
returned from detail
(rlm_detail) for request 2
Tue Jan 13 13:19:17 2004 : Debug: modcall[accounting]: module
"detail" returns ok for request 2
Tue Jan 13 13:19:17 2004 : Debug: modsingle[accounting]:
calling unix (rlm_unix) for request 2
Tue Jan 13 13:19:17 2004 : Debug: modsingle[accounting]:
returned from unix (rlm_unix) for request 2
Tue Jan 13 13:19:17 2004 : Debug: modcall[accounting]: module
"unix" returns ok for request 2
Tue Jan 13 13:19:17 2004 : Debug: modsingle[accounting]:
calling radutmp (rlm_radutmp) for request 2
Tue Jan 13 13:19:17 2004 : Debug: radius_xlat:
'/usr/local/var/log/radius/radutmp'
Tue Jan 13 13:19:17 2004 : Debug: radius_xlat: 'usertest'
Tue Jan 13 13:19:17 2004 : Debug: modsingle[accounting]:
returned from radutmp (rlm_radutmp) for request 2
Tue Jan 13 13:19:17 2004 : Debug: modcall[accounting]: module
"radutmp" returns ok for request 2
Tue Jan 13 13:19:17 2004 : Debug: modcall: group accounting
returns ok for request 2
Sending Accounting-Response of id 234 to 86.201.123.45:45454
Tue Jan 13 13:19:17 2004 : Debug: Finished request 2
Tue Jan 13 13:19:17 2004 : Debug: Going to the next request
Tue Jan 13 13:19:17 2004 : Debug: --- Walking the entire request
list ---
Tue Jan 13 13:19:17 2004 : Debug: Waking up in 6 seconds...
Tue Jan 13 13:19:23 2004 : Debug: --- Walking the entire request
list ---
Tue Jan 13 13:19:23 2004 : Debug: Cleaning up request 2 ID 234
with timestamp 400436a5
Tue Jan 13 13:19:23 2004 : Debug: Nothing to do. Sleeping until
we see a request.
[DEBUG END]
Your assitance will be greatly appreciated.
Thank you,
Mike
__________________________________
Do you Yahoo!?
New Yahoo! Photos - easier uploading and sharing.
http://photos.yahoo.com/
-
List info/subscribe/unsubscribe? See http://www.freeradius.org/list/users.html
