Re: Access-Accept and Access-Reject Log problem

24 Jan 2016

      Hi Everyone,

We use Ubuntu 15.10 Server (32-bit)

We have problem Freeradius + MYSQL log problem.We cannot see Access-Accept
, Access-Reject Log in the /var/log/freeradius/
We see only the MYSQL requests to Freeradius log. We want see Access-Accept
and Access-Reject log in the /var/log/Freeradius.

radias.conf

log {
    destination = files
    file = ${logdir}/radius.log
    requests = ${logdir}/radiusd-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d.log
    syslog_facility = daemon
    stripped_names = no
    auth = no
    auth_badpass = no
    auth_goodpass = no
#   msg_goodpass = ""
#   msg_badpass = ""
}

I want to see username >> Access-accept and username >> Access-Reject logs.
I only see MYSQL request.

What is problem?

Thank you for your help.

Regards

root@tokenserver:/var/log/freeradius# radtest adem
00000000066186770941821001 localhost 0 freeradiussecret
Sending Access-Request of id 100 to 127.0.0.1 port 1812
User-Name = "adem"
User-Password = "00000000066186770941821001"
NAS-IP-Address = 127.0.1.1
NAS-Port = 0
Message-Authenticator = 0x00000000000000000000000000000000
rad_recv: Access-Accept packet from host 127.0.0.1 port 1812, id=100,
length=20
root@tokenserver:/var/log/freeradius#

MYSQL Reguest in the /var/log/freeradius/

drwxr-x--- 2 freerad freerad 4096 Jan 24 06:48 .
drwxrwxr-x 14 root syslog 4096 Jan 24 06:48 ..
-rw-r--r-- 1 freerad freerad 1267 Jan 24 16:12 radius.log
-rw-r--r-- 1 freerad freerad 10888 Jan 24 06:48 radius.log.1
-rw-r--r-- 1 freerad freerad 900 Jan 17 06:38 radius.log.2.gz
-rw-r--r-- 1 freerad freerad 1774 Jan 11 06:28 radius.log.3.gz
-rw-r--r-- 1 freerad freerad 336 Jan 3 06:30 radius.log.4.gz
-rw-r--r-- 1 freerad freerad 0 Dec 30 09:08 radwtmp

radius.log.1

Sun Jan 17 06:38:07 2016 : Info: Loaded virtual server inner-tunnel
Mon Jan 18 13:05:16 2016 : Info: rlm_sql (sql): Attempting to connect
rlm_sql_mysql #4
Mon Jan 18 13:05:16 2016 : Info: rlm_sql_mysql: Starting connect to MySQL
server for #4
Mon Jan 18 13:05:16 2016 : Info: rlm_sql (sql): Connected new DB handle, #4
Mon Jan 18 13:05:16 2016 : Info: rlm_sql (sql): Attempting to connect
rlm_sql_mysql #3
Mon Jan 18 13:05:16 2016 : Info: rlm_sql_mysql: Starting connect to MySQL
server for #3
Mon Jan 18 13:05:16 2016 : Info: rlm_sql (sql): Connected new DB handle, #3
Mon Jan 18 13:07:03 2016 : Info: rlm_sql (sql): Attempting to connect
rlm_sql_mysql #2
Mon Jan 18 13:07:03 2016 : Info: rlm_sql_mysql: Starting connect to MySQL
server for #2
Mon Jan 18 13:07:03 2016 : Info: rlm_sql (sql): Connected new DB handle, #2
Mon Jan 18 13:07:25 2016 : Info: rlm_sql (sql): Attempting to connect
rlm_sql_mysql #1
Mon Jan 18 13:07:25 2016 : Info: rlm_sql_mysql: Starting connect to MySQL
server for #1
Mon Jan 18 13:07:25 2016 : Info: rlm_sql (sql): Connected new DB handle, #1
Mon Jan 18 13:07:25 2016 : Info: rlm_sql (sql): Attempting to connect
rlm_sql_mysql #0
Mon Jan 18 13:07:25 2016 : Info: rlm_sql_mysql: Starting connect to MySQL
server for #0
Mon Jan 18 13:07:25 2016 : Info: rlm_sql (sql): Connected new DB handle, #0
Tue Jan 19 10:41:29 2016 : Info: Signalled to terminate
Tue Jan 19 10:41:29 2016 : Info: Exiting normally.
Tue Jan 19 10:41:29 2016 : Info: rlm_sql (sql): Closing sqlsocket 4
Tue Jan 19 10:41:29 2016 : Info: rlm_sql (sql): Closing sqlsocket 3
Tue Jan 19 10:41:29 2016 : Info: rlm_sql (sql): Closing sqlsocket 2
Tue Jan 19 10:41:29 2016 : Info: rlm_sql (sql): Closing sqlsocket 1
Tue Jan 19 10:41:29 2016 : Info: rlm_sql (sql): Closing sqlsocket 0
Tue Jan 19 10:42:16 2016 : Info: rlm_sql (sql): Driver rlm_sql_mysql
(module rlm_sql_mysql) loaded and linked
Tue Jan 19 10:42:16 2016 : Info: rlm_sql (sql): Attempting to connect to
radius@localhost:/radius
Tue Jan 19 10:42:16 2016 : Info: rlm_sql (sql): Attempting to connect
rlm_sql_mysql #0
Tue Jan 19 10:42:16 2016 : Info: rlm_sql_mysql: Starting connect to MySQL
server for #0
Tue Jan 19 10:42:16 2016 : Info: rlm_sql (sql): Connected new DB handle, #0
Tue Jan 19 10:42:16 2016 : Info: rlm_sql (sql): Attempting to connect
rlm_sql_mysql #1
Tue Jan 19 10:42:16 2016 : Info: rlm_sql_mysql: Starting connect to MySQL
server for #1
Tue Jan 19 10:42:16 2016 : Info: rlm_sql (sql): Connected new DB handle, #1
Tue Jan 19 10:42:16 2016 : Info: rlm_sql (sql): Attempting to connect
rlm_sql_mysql #2
Tue Jan 19 10:42:16 2016 : Info: rlm_sql_mysql: Starting connect to MySQL
server for #2
Tue Jan 19 10:42:16 2016 : Info: rlm_sql (sql): Connected new DB handle, #2

2016-01-24 17:17 GMT+02:00 Adem Darguner <adarguner@gmail.com>:
...
Hi Everyone,
We use Ubuntu 15.10 Server (32-bit)
We have problem Freeradius + MYSQL log problem.We cannot see Access-Accept
, Access-Reject Log in the /var/log/freeradius/
We see only the MYSQL requests to Freeradius log. We want see
Access-Accept and Access-Reject log in the /var/log/Freeradius.
radias.conf
log {
    destination = files
    file = ${logdir}/radius.log    requests = ${logdir}/radiusd-%{%{Virtual-Server}:-DEFAULT}-%Y%m%d.log
    syslog_facility = daemon
    stripped_names = no
    auth = no
    auth_badpass = no
    auth_goodpass = no
#   msg_goodpass = ""
#   msg_badpass = ""
}
I want to see username >> Access-accept and username >> Access-Reject
logs. I only see MYSQL request.
What is problem?
Thank you for your help.
Regards
root@tokenserver:/var/log/freeradius# *radtest adem
00000000066186770941821001 localhost 0 freeradiussecret*
Sending Access-Request of id 100 to 127.0.0.1 port 1812
User-Name = "adem"
User-Password = "00000000066186770941821001"
NAS-IP-Address = 127.0.1.1
NAS-Port = 0
Message-Authenticator = 0x00000000000000000000000000000000
rad_recv: Access-Accept packet from host 127.0.0.1 port 1812, id=100,
length=20
root@tokenserver:/var/log/freeradius#
*MYSQL Reguest in the /var/log/freeradius/*
drwxr-x--- 2 freerad freerad 4096 Jan 24 06:48 .
drwxrwxr-x 14 root syslog 4096 Jan 24 06:48 ..
-rw-r--r-- 1 freerad freerad 1267 Jan 24 16:12 radius.log
-rw-r--r-- 1 freerad freerad 10888 Jan 24 06:48 radius.log.1
-rw-r--r-- 1 freerad freerad 900 Jan 17 06:38 radius.log.2.gz
-rw-r--r-- 1 freerad freerad 1774 Jan 11 06:28 radius.log.3.gz
-rw-r--r-- 1 freerad freerad 336 Jan 3 06:30 radius.log.4.gz
-rw-r--r-- 1 freerad freerad 0 Dec 30 09:08 radwtmp
* radius.log.1 *
Sun Jan 17 06:38:07 2016 : Info: Loaded virtual server inner-tunnel
Mon Jan 18 13:05:16 2016 : Info: rlm_sql (sql): Attempting to connect
rlm_sql_mysql #4
Mon Jan 18 13:05:16 2016 : Info: rlm_sql_mysql: Starting connect to MySQL
server for #4
Mon Jan 18 13:05:16 2016 : Info: rlm_sql (sql): Connected new DB handle, #4
Mon Jan 18 13:05:16 2016 : Info: rlm_sql (sql): Attempting to connect
rlm_sql_mysql #3
Mon Jan 18 13:05:16 2016 : Info: rlm_sql_mysql: Starting connect to MySQL
server for #3
Mon Jan 18 13:05:16 2016 : Info: rlm_sql (sql): Connected new DB handle, #3
Mon Jan 18 13:07:03 2016 : Info: rlm_sql (sql): Attempting to connect
rlm_sql_mysql #2
Mon Jan 18 13:07:03 2016 : Info: rlm_sql_mysql: Starting connect to MySQL
server for #2
Mon Jan 18 13:07:03 2016 : Info: rlm_sql (sql): Connected new DB handle, #2
Mon Jan 18 13:07:25 2016 : Info: rlm_sql (sql): Attempting to connect
rlm_sql_mysql #1
Mon Jan 18 13:07:25 2016 : Info: rlm_sql_mysql: Starting connect to MySQL
server for #1
Mon Jan 18 13:07:25 2016 : Info: rlm_sql (sql): Connected new DB handle, #1
Mon Jan 18 13:07:25 2016 : Info: rlm_sql (sql): Attempting to connect
rlm_sql_mysql #0
Mon Jan 18 13:07:25 2016 : Info: rlm_sql_mysql: Starting connect to MySQL
server for #0
Mon Jan 18 13:07:25 2016 : Info: rlm_sql (sql): Connected new DB handle, #0
Tue Jan 19 10:41:29 2016 : Info: Signalled to terminate
Tue Jan 19 10:41:29 2016 : Info: Exiting normally.
Tue Jan 19 10:41:29 2016 : Info: rlm_sql (sql): Closing sqlsocket 4
Tue Jan 19 10:41:29 2016 : Info: rlm_sql (sql): Closing sqlsocket 3
Tue Jan 19 10:41:29 2016 : Info: rlm_sql (sql): Closing sqlsocket 2
Tue Jan 19 10:41:29 2016 : Info: rlm_sql (sql): Closing sqlsocket 1
Tue Jan 19 10:41:29 2016 : Info: rlm_sql (sql): Closing sqlsocket 0
Tue Jan 19 10:42:16 2016 : Info: rlm_sql (sql): Driver rlm_sql_mysql
(module rlm_sql_mysql) loaded and linked
Tue Jan 19 10:42:16 2016 : Info: rlm_sql (sql): Attempting to connect to
radius@localhost:/radius
Tue Jan 19 10:42:16 2016 : Info: rlm_sql (sql): Attempting to connect
rlm_sql_mysql #0
Tue Jan 19 10:42:16 2016 : Info: rlm_sql_mysql: Starting connect to MySQL
server for #0
Tue Jan 19 10:42:16 2016 : Info: rlm_sql (sql): Connected new DB handle, #0
Tue Jan 19 10:42:16 2016 : Info: rlm_sql (sql): Attempting to connect
rlm_sql_mysql #1
Tue Jan 19 10:42:16 2016 : Info: rlm_sql_mysql: Starting connect to MySQL
server for #1
Tue Jan 19 10:42:16 2016 : Info: rlm_sql (sql): Connected new DB handle, #1
Tue Jan 19 10:42:16 2016 : Info: rlm_sql (sql): Attempting to connect
rlm_sql_mysql #2
Tue Jan 19 10:42:16 2016 : Info: rlm_sql_mysql: Starting connect to MySQL
server for #2
Tue Jan 19 10:42:16 2016 : Info: rlm_sql (sql): Connected new DB handle,
#2
-- 
Adem DARGÜNER
Network Engineer