Access-Accept and Access-Reject Log problem

[Adem Darguner]

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 at 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 at 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 at 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 at 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 at 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 at 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 at 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