rad_recv: Access-Request packet from host 192.168.0.7:32865, id=112, length=182
NAS-Port-Type = Ethernet
Calling-Station-Id = "00:16:D4:AE:23:AE"
Called-Station-Id = "Empresa Gateway 1"
NAS-Port-Id = "ether2"
User-Name = "castro"
NAS-Port = 2159018021
Acct-Session-Id = "80b00025"
Framed-IP-Address = 10.6.0.2
Mikrotik-Attr-10 = 0x0a060002
User-Password = "123456"
Service-Type = Login-User
WISPr-Logoff-URL = "http://10.6.0.1/logout"
NAS-Identifier = "serv7"
NAS-IP-Address = 192.168.0.7
Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 0
modcall[authorize]: module "preprocess" returns ok for request 0
modcall[authorize]: module "chap" returns noop for request 0
modcall[authorize]: module "mschap" returns noop for request 0
rlm_realm: No '@' in User-Name = "castro", looking up realm NULL
rlm_realm: No such realm "NULL"
modcall[authorize]: module "suffix" returns noop for request 0
rlm_eap: No EAP-Message, not doing EAP
modcall[authorize]: module "eap" returns noop for request 0
radius_xlat: 'castro'
rlm_sql (sql): sql_set_user escaped user --> 'castro'
radius_xlat: 'SELECT ID, USERNAME, ATRIBUTO, CONTEUDO, OP FROM RADIUS_CHECK('castro') '
rlm_sql (sql): Reserving sql socket id: 6
query: SELECT ID, USERNAME, ATRIBUTO, CONTEUDO, OP FROM RADIUS_CHECK('castro')
radius_xlat: ''
radius_xlat: 'SELECT ID, USERNAME, ATRIBUTO, CONTEUDO, OP FROM RADIUS_REPLY('castro') '
query: SELECT ID, USERNAME, ATRIBUTO, CONTEUDO, OP FROM RADIUS_REPLY('castro')
radius_xlat: ''
rlm_sql (sql): Released sql socket id: 6
modcall[authorize]: module "sql" returns ok for request 0
modcall: leaving group authorize (returns ok) for request 0
auth: type Local
auth: user supplied User-Password matches local User-Password
Processing the post-auth section of radiusd.conf
modcall: entering group post-auth for request 0
rlm_sql (sql): Processing sql_postauth
radius_xlat: 'castro'
rlm_sql (sql): sql_set_user escaped user --> 'castro'
radius_xlat: 'INSERT INTO ASS_POST_AUTH (USERNAME,PASS,REPLY,DATA_HORA) VALUES ('castro', '123456', 'Access-Accept', '2008-04-01 21:30:40')'
radius_xlat: '/var/log/freeradius/sqltrace.sql'
rlm_sql (sql) in sql_postauth: query is INSERT INTO ASS_POST_AUTH (USERNAME,PASS,REPLY,DATA_HORA) VALUES ('castro', '123456', 'Access-Accept', '2008-04-01 21:30:40')
rlm_sql (sql): Reserving sql socket id: 5
query: INSERT INTO ASS_POST_AUTH (USERNAME,PASS,REPLY,DATA_HORA) VALUES ('castro', '123456', 'Access-Accept', '2008-04-01 21:30:40')
rlm_sql (sql): Released sql socket id: 5
modcall[post-auth]: module "sql" returns ok for request 0
modcall: leaving group post-auth (returns ok) for request 0
Sending Access-Accept of id 112 to 192.168.0.7 port 32865
Mikrotik-Rate-Limit := "256k/256k"
WISPr-Redirection-URL := "http://www.empresa.com.br/"
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 192.168.0.7:32865, id=113, length=146
Acct-Status-Type = Start
NAS-Port-Type = Ethernet
Calling-Station-Id = "00:16:D4:AE:23:AE"
Called-Station-Id = "Empresa Gateway 1"
NAS-Port-Id = "ether2"
User-Name = "castro"
NAS-Port = 2159018021
Acct-Session-Id = "80b00025"
Framed-IP-Address = 10.6.0.2
Mikrotik-Attr-10 = 0x0a060002
Event-Timestamp = "Jan 4 2000 10:30:24 BRT"
NAS-Identifier = "serv7"
NAS-IP-Address = 192.168.0.7
Acct-Delay-Time = 0
Processing the preacct section of radiusd.conf
modcall: entering group preacct for request 1
modcall[preacct]: module "preprocess" returns noop for request 1
rlm_acct_unique: Hashing 'NAS-Port = 2159018021,Client-IP-Address = 192.168.0.7,NAS-IP-Address = 192.168.0.7,Acct-Session-Id = "80b00025",User-Name = "castro"'
rlm_acct_unique: Acct-Unique-Session-ID = "f58da41769feab44".
modcall[preacct]: module "acct_unique" returns ok for request 1
rlm_realm: No '@' in User-Name = "castro", looking up realm NULL
rlm_realm: No such realm "NULL"
modcall[preacct]: module "suffix" returns noop for request 1
modcall: leaving group preacct (returns ok) for request 1
Processing the accounting section of radiusd.conf
modcall: entering group accounting for request 1
radius_xlat: '/var/log/freeradius/radacct/192.168.0.7/detail-20080401'
rlm_detail: /var/log/freeradius/radacct/%{Client-IP-Address}/detail-%Y%m%d expands to /var/log/freeradius/radacct/192.168.0.7/detail-20080401
modcall[accounting]: module "detail" returns ok for request 1
modcall[accounting]: module "unix" returns ok for request 1
radius_xlat: '/var/log/freeradius/radutmp'
radius_xlat: 'castro'
modcall[accounting]: module "radutmp" returns ok for request 1
radius_xlat: 'castro'
rlm_sql (sql): sql_set_user escaped user --> 'castro'
radius_xlat: 'INSERT INTO ASS_ACCT (SESSION_ID, USERNAME, SERVICO, SERVIDOR, SERV_IP, IP, MAC, INICIO) VALUES ('80b00025', 'castro', 'X', 'serv7', '192.168.0.7', '10.6.0.2', '00:16:D4:AE:23:AE', '2008-04-01 21:30:40')'
radius_xlat: '/var/log/freeradius/sqltrace.sql'
rlm_sql (sql): Reserving sql socket id: 4
query: INSERT INTO ASS_ACCT (SESSION_ID, USERNAME, SERVICO, SERVIDOR, SERV_IP, IP, MAC, INICIO) VALUES ('80b00025', 'castro', 'X', 'serv7', '192.168.0.7', '10.6.0.2', '00:16:D4:AE:23:AE', '2008-04-01 21:30:40')
rlm_sql (sql): Released sql socket id: 4
modcall[accounting]: module "sql" returns ok for request 1
modcall: leaving group accounting (returns ok) for request 1
Sending Accounting-Response of id 113 to 192.168.0.7 port 32865
Finished request 1
Going to the next request
Waking up in 6 seconds...
--- Walking the entire request list ---
Cleaning up request 0 ID 112 with timestamp 47f2d3b0
Cleaning up request 1 ID 113 with timestamp 47f2d3b0
Nothing to do. Sleeping until we see a request.