I keep getting the following entries in my radius.log file and I can't seem
to figure out why:
Thu May 29 07:37:30 2003 : Error: Accounting: logout: login entry for NAS
67.2.0.19 port 7955 not found
I use a dialup aggregator that proxies the request to my radius server.
Here is my radius -X output along with the connect / disconnect log info.
Any help would be greatly appreciated. Thanks.
Starting - reading configuration files ...
reread_config: reading radiusd.conf
Config: including file: /etc/raddb/proxy.conf
Config: including file: /etc/raddb/clients.conf
Config: including file: /etc/raddb/snmp.conf
Config: including file: /etc/raddb/sql.conf
main: prefix = "/usr/local"
main: localstatedir = "/var"
main: logdir = "/var/log/radius"
main: libdir = "/usr/local/lib"
main: radacctdir = "/var/log/radius/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 = no
main: log_file = "/var/log/radius/radius.log"
main: log_auth = yes
main: log_auth_badpass = no
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 = yes
proxy: retry_delay = 5
proxy: retry_count = 3
proxy: synchronous = no
proxy: default_fallback = yes
proxy: dead_time = 120
proxy: servers_per_realm = 15
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
read_config_files: reading clients
read_config_files: reading realms
radiusd: entering modules setup
Module: Library search path is /usr/local/lib
Module: Loaded expr
Module: Instantiated expr (expr)
Module: Loaded PAP
pap: encryption_scheme = "crypt"
Module: Instantiated pap (pap)
Module: Loaded CHAP
Module: Instantiated chap (chap)
Module: Loaded MS-CHAP
mschap: ignore_password = no
mschap: use_mppe = yes
mschap: require_encryption = no
mschap: require_strong = no
mschap: passwd = "(null)"
mschap: authtype = "MS-CHAP"
Module: Instantiated mschap (mschap)
Module: Loaded System
unix: cache = no
unix: passwd = "(null)"
unix: shadow = "(null)"
unix: group = "(null)"
unix: radwtmp = "/var/log/radius/radwtmp"
unix: usegroup = no
unix: cache_reload = 600
Module: Instantiated unix (unix)
Module: Loaded preprocess
preprocess: huntgroups = "/etc/raddb/huntgroups"
preprocess: hints = "/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 realm
realm: format = "suffix"
realm: delimiter = "@"
Module: Instantiated realm (suffix)
Module: Loaded files
files: usersfile = "/etc/raddb/users"
files: acctusersfile = "/etc/raddb/acct_users"
files: preproxy_usersfile = "/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-Id"
Module: Instantiated acct_unique (acct_unique)
Module: Loaded detail
detail: detailfile =
"/var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d"
detail: detailperm = 384
detail: dirperm = 493
detail: locking = no
Module: Instantiated detail (detail)
Module: Loaded radutmp
radutmp: filename = "/var/log/radius/radutmp"
radutmp: username = "%{User-Name}"
radutmp: perm = 384
radutmp: callerid = yes
Module: Instantiated radutmp (radutmp)
Listening on IP address *, ports 1812/udp and 1813/udp, with proxy on
1814/udp.
Ready to process requests.
rad_recv: Access-Request packet from host 63.110.140.25:4799, id=34,
length=165
Framed-Protocol = PPP
User-Name = "[EMAIL PROTECTED]"
CHAP-Password = 0xfa65689fe6bc792c40f3bfc43003ed6567
Called-Station-Id = "5032190945"
Calling-Station-Id = "5038850150"
Cisco-NAS-Port = "Async3/63*Serial7/0:15:16"
NAS-Port = 7632
NAS-Port-Type = Async
Service-Type = Framed-User
NAS-IP-Address = 67.2.0.19
Acct-Session-Id = "04527AF6"
modcall: entering group authorize
modcall[authorize]: module "preprocess" returns ok
rlm_chap: Adding Auth-Type = CHAP
modcall[authorize]: module "chap" returns ok
modcall[authorize]: module "mschap" returns notfound
rlm_realm: Looking up realm sterling.net for User-Name =
"[EMAIL PROTECTED]"
rlm_realm: No such realm sterling.net
modcall[authorize]: module "suffix" returns noop
users: Matched [EMAIL PROTECTED] at 1
users: Matched DEFAULT at 4
modcall[authorize]: module "files" returns ok
modcall: group authorize returns ok
rad_check_password: Found Auth-Type Local
auth: type Local
auth: user supplied CHAP-Password matches local User-Password
Login OK: [EMAIL PROTECTED] (from client ynp1 port 7632 cli
5038850150)
Sending Access-Accept of id 34 to 63.110.140.25:4799
Service-Type = Framed-User
Framed-Protocol = PPP
Framed-IP-Address = 255.255.255.254
Framed-IP-Netmask = 255.255.255.255
Framed-MTU = 1500
Framed-Compression = Van-Jacobson-TCP-IP
Idle-Timeout = 900
Session-Timeout = 14400
Finished request 0
Going to the next request
--- Walking the entire request list ---
Waking up in 6 seconds...
rad_recv: Accounting-Request packet from host 63.110.140.25:4807, id=80,
length=256
Acct-Session-Id = "04527AF6"
Framed-Protocol = PPP
Framed-IP-Address = 67.2.53.66
Connect-Info = "48000/21600 V90/V42bis/LAPM"
X-Ascend-Connect-Progress = 60
Cisco-AVPair = "connect-progress=LAN Ses Up"
Acct-Authentic = RADIUS
User-Name = "[EMAIL PROTECTED]"
Acct-Status-Type = Start
Called-Station-Id = "5032190945"
Calling-Station-Id = "5038850150"
Cisco-NAS-Port = "Async3/63*Serial7/0:15:16"
NAS-Port = 7632
NAS-Port-Type = Async
Service-Type = Framed-User
NAS-IP-Address = 67.2.0.19
X-Ascend-Session-Svr-Key = "872A24BB"
Acct-Delay-Time = 0
Attr-103 = 0x3ed62ab0
modcall: entering group preacct
modcall[preacct]: module "preprocess" returns noop
rlm_realm: Looking up realm sterling.net for User-Name =
"[EMAIL PROTECTED]"
rlm_realm: No such realm sterling.net
modcall[preacct]: module "suffix" returns noop
modcall[preacct]: module "files" returns noop
modcall: group preacct returns noop
modcall: entering group accounting
rlm_acct_unique: WARNING: Attribute 87 was not found in request, unique ID
MAY be inconsistent
rlm_acct_unique: Hashing ',Client-IP-Address = 63.110.140.25,NAS-IP-Address
= 67.2.0.19,Acct-Session-Id = "04527AF6",User-Name =
"[EMAIL PROTECTED]"'
rlm_acct_unique: Acct-Unique-Session-ID = "e68528391fa05ffe".
modcall[accounting]: module "acct_unique" returns ok
radius_xlat: '/var/log/radius/radacct/63.110.140.25/detail-20030529'
rlm_detail: /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d
expands to /var/log/radius/radacct/63.110.140.25/detail-20030529
modcall[accounting]: module "detail" returns ok
modcall[accounting]: module "unix" returns ok
radius_xlat: '[EMAIL PROTECTED]'
modcall[accounting]: module "radutmp" returns ok
modcall: group accounting returns ok
Sending Accounting-Response of id 80 to 63.110.140.25:4807
Finished request 1
Going to the next request
--- Walking the entire request list ---
Cleaning up request 1 ID 80 with timestamp 3ed62b6e
Waking up in 5 seconds...
--- Walking the entire request list ---
Cleaning up request 0 ID 34 with timestamp 3ed62b6d
Nothing to do. Sleeping until we see a request.
rad_recv: Accounting-Request packet from host 63.110.140.25:4807, id=81,
length=379
Acct-Session-Id = "04527AF6"
Framed-Protocol = PPP
Framed-IP-Address = 67.2.53.66
Acct-Authentic = RADIUS
X-Ascend-Connect-Progress = 60
Cisco-AVPair = "connect-progress=LAN Ses Up"
X-Ascend-PreSession-Time = 32
X-Ascend-Xmit-Rate = 48000
X-Ascend-Data-Rate = 21600
Acct-Session-Time = 15
Connect-Info = "48000/21600 V90/V42bis/LAPM"
Acct-Input-Octets = 792
Acct-Output-Octets = 120
X-Ascend-Pre-Input-Octets = 241
X-Ascend-Pre-Output-Octets = 248
Acct-Input-Packets = 8
Acct-Output-Packets = 6
X-Ascend-Pre-Input-Packets = 8
X-Ascend-Pre-Output-Packets = 8
Acct-Terminate-Cause = User-Request
X-Ascend-Disconnect-Cause = 45
Cisco-AVPair = "disc-cause-ext=PPP Receive Term"
User-Name = "[EMAIL PROTECTED]"
Acct-Status-Type = Stop
Called-Station-Id = "5032190945"
Calling-Station-Id = "5038850150"
Cisco-NAS-Port = "Async3/63*Serial7/0:15:16"
NAS-Port = 7632
NAS-Port-Type = Async
Service-Type = Framed-User
NAS-IP-Address = 67.2.0.19
X-Ascend-Session-Svr-Key = "872A24BB"
Acct-Delay-Time = 0
Attr-103 = 0x3ed62abf
modcall: entering group preacct
modcall[preacct]: module "preprocess" returns noop
rlm_realm: Looking up realm sterling.net for User-Name =
"[EMAIL PROTECTED]"
rlm_realm: No such realm sterling.net
modcall[preacct]: module "suffix" returns noop
modcall[preacct]: module "files" returns noop
modcall: group preacct returns noop
modcall: entering group accounting
rlm_acct_unique: WARNING: Attribute 87 was not found in request, unique ID
MAY be inconsistent
rlm_acct_unique: Hashing ',Client-IP-Address = 63.110.140.25,NAS-IP-Address
= 67.2.0.19,Acct-Session-Id = "04527AF6",User-Name =
"[EMAIL PROTECTED]"'
rlm_acct_unique: Acct-Unique-Session-ID = "e68528391fa05ffe".
modcall[accounting]: module "acct_unique" returns ok
radius_xlat: '/var/log/radius/radacct/63.110.140.25/detail-20030529'
rlm_detail: /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d
expands to /var/log/radius/radacct/63.110.140.25/detail-20030529
modcall[accounting]: module "detail" returns ok
modcall[accounting]: module "unix" returns ok
radius_xlat: '[EMAIL PROTECTED]'
Accounting: logout: login entry for NAS 67.2.0.19 port 7632 not found
modcall[accounting]: module "radutmp" returns ok
modcall: group accounting returns ok
Sending Accounting-Response of id 81 to 63.110.140.25:4807
Finished request 2
Going to the next request
--- Walking the entire request list ---
Cleaning up request 2 ID 81 with timestamp 3ed62b7c
Nothing to do. Sleeping until we see a request.
-
List info/subscribe/unsubscribe? See http://www.freeradius.org/list/users.html
