Hello


I'm facing a problem with the server. 
I set it and test it with a basic radtest and it exits without warning or error message.
The last line is 
Thu Feb 21 08:33:14 2013 : Info: +- entering group authorize {...}

So i guessed there was a problem in the very beginning of authorize, as the preprocess module doesn't output anything.
But i couldn't find anything weird in the first lines :

authorize {
    
    #  The prepro cess module takes care of sanitizing some bizarre
    #  attributes in the request, and turning them into attributes
    #  which are more standard.
    #
    #  It takes care of processing the 'raddb/hints' and the
    #  'raddb/huntgroups' files.
    preprocess


I didn't do big changes in the configuration files I only uncommented the sql related lines und set the SQL server info.
Do you have an idea of the origin of the problem ?


Here is the debug ! output of the server :


Thu Feb 21 08:33:10 2013 : Info: rlm_sql (sql): Driver rlm_sql_mysql (module rlm_sql_mysql) loaded and linked
Thu Feb 21 08:33:10 2013 : Info: rlm_sql (sql): Attempting to connect to radius@localhost:/radius
Thu Feb 21 08:33:10 2013 : Debug: rlm_sql (sql): starting 0
Thu Feb 21 08:33:10 2013 : Info: rlm_sql (sql): Attempting to connect rlm_sql_mysql #0
Thu Feb 21 08:33:10 2013 : Info: rlm_sql_mysql: Starting connect to MySQL server for #0
Thu Feb 21 08:33:10 2013 : Info: rlm_sql (sql): Connected new DB handle, #0
Thu Feb 21 08:33:10 2013 : Debug: rlm_sql (s! ql): starting 1
Thu Feb 21 08:33:10 2013 : Info: rlm_sql (sql): Attempting to connect rlm_sql_mysql #1
Thu Feb 21 08:33:10 2013 : Info: rlm_sql_mysql: Starting connect to MySQL server for #1
Thu Feb 21 08:33:10 2013 : Info: rlm_sql (sql): Connected new DB handle, #1
Thu Feb 21 08:33:10 2013 : Debug: rlm_sql (sql): starting 2
Thu Feb 21 08:33:10 2013 : Info: rlm_sql (sql): Attempting to connect rlm_sql_mysql #2
Thu Feb 21 08:33:10 2013 : Info: rlm_sql_mysql: Starting connect to MySQL server for #2
Thu Feb 21 08:33:10 2013 : Info: rlm_sql (sql): Connected new DB handle, #2
Thu Feb 21 08:33:10 2013 : Debug: rlm_sql (sql): starting 3
Thu Feb 21 08:33:10 2013 : Info: rlm_sql (sql): Attempting to connect rlm_sql_mysql #3
Thu Feb 21 08:33:10 2013 : Info: rlm_sql_mysql: Starting connect to MySQL server for #3
Thu Feb 21 08:33:10 2013 : Info: rlm_sql (sql): Connected new DB handle, #3
Thu Feb 21 08:33:10 2013 : Debug: rlm_sql (sql): starting 4
Thu Feb 21 08:33:10 2013 ! : Info: rlm_sql (sql): Attempting to connect rlm_sql_mysql #4Thu Feb 21 08:33:10 2013 : Info: rlm_sql_mysql: Starting connect to MySQL server for #4
Thu Feb 21 08:33:10 2013 : Info: rlm_sql (sql): Connected new DB handle, #4
Thu Feb 21 08:33:10 2013 : Debug:  Module: Checking preacct {...} for more modules to load
Thu Feb 21 08:33:10 2013 : Debug:     (Loaded rlm_acct_unique, checking if it's valid)
Thu Feb 21 08:33:10 2013 : Debug: ! ; Module: Linked to module rlm_acct_unique
Thu Feb 21 08:33:10 2013 : Debug:  Module: Instantiating module "acct_unique" from file /etc/freeradius/modules/acct_unique
Thu Feb 21 08:33:10 2013 : Debug:   acct_unique {
Thu Feb 21 08:33:10 2013 : Debug:     key = "User-Name, Acct-Session-Id, NAS-IP-Address, Client-IP-Address, NAS-Port"
Thu Feb 21 08:33:10 2013 : Debug:   }
Thu Feb 21 08:33:10 2013 : Debug:  Module: Checki! ng accounting {...} for more modules to load
Thu Feb 21 08:33:10 2013 : Debug:     (Loaded rlm_detail, checking if it's valid)
Thu Feb 21 08:33:10 2013 : Debug:  Module: Linked to module rlm_detail
Thu Feb 21 08:33:10 2013 : Debug:  Module: Instantiating module "detail" from file /etc/freeradius/modules/detail
Thu Feb 21 08:33:10 2013 : Debug:   detail {
Thu Feb 21 08:33:10 2013 : Debug:     detailfile = "/va! r/log/freeradius/radacct/%{Client-IP-Address}/detail-%Y%m%d"
Thu Feb 21 08:33:10 2013 : Debug:     header = "%t"
Thu Feb 21 08:33:10 2013 : Debug:     detailperm = 384
Thu Feb 21 08:33:10 2013 : Debug:     dirperm = 493
Thu Feb 21 08:33:10 2013 : Debug:     locking = no
Thu Feb 21 08:33:10 2013 : Debug:     log_packet_header = no
Thu Feb 21 08:33:10 2013 : Debug:   }
Thu Feb 21 08:33:10 2013 : Debug:  Module: Instantiating module "attr_filter.accounting_response" from file /etc/freeradius/modules/attr_filter
Thu Feb 21 08:33:10 2013 : Debug:   attr_filter attr_filter.accounting_response {
Thu Feb 21 08:33:10 2013 : Debug:     attrsfile = "/etc/freeradius/attrs.accounting_response"
Thu Feb 21 08:33:10 2013 : Debug:     key = "%{User-Name}"
Thu Feb 21 08:33:10 2013 : Debug:     relaxed = no
Thu Feb 21 08:33:10 2013 : Debug:   }
Thu Feb 21 08:33:10 2013 : Debug:  Module: Chec! king session {...} for more modules to load
Thu Feb 21 08:33:10 2013 : Debug:  Module: Checking post-proxy {...} for more modules to load
Thu Feb 21 08:33:10 2013 : Debug:  Module: Checking post-auth {...} for more modules to load
Thu Feb 21 08:33:10 2013 : Debug:  } # modules
Thu Feb 21 08:33:10 2013 : Debug: } # server
Thu Feb 21 08:33:10 2013 : Debug: radiusd: #### Opening IP addresses and Ports ####
Thu Feb 21 08:33:10 2013 : Debug: listen {
Thu Feb 21 08:33:10 2013 : Debug:     type = "auth"
Thu Feb 21 08:33:10 2013 : Debug:    ;  ipaddr = *
Thu Feb 21 08:33:10 2013 : Debug:     port = 0
Thu Feb 21 08:33:10 2013 : Debug: }
Thu Feb 21 08:33:10 2013 : Debug: listen {
Thu Feb 21 08:33:10 2013 : Debug:     type = "acct"
Thu Feb 21 08:33:10 2013 : Debug:     ipaddr = *
Thu Feb 21 08:33:10 2013 : Debug:     port = 0
Thu Feb 21 08:33:10 2013 : Debug: }
Thu Feb 21 08:33:10 2013 : Debug: listen {
Thu Feb 21 08:33:10 2013 : Debug:     type = "auth"
Thu Feb 21 08:33:10 2013 : Debug:     ipaddr = 127.0.0.1
Thu Feb 21 08:33:10 2013 : Debug:   & nbsp; port = 18120
Thu Feb 21 08:33:10 2013 : Debug: }
Thu Feb 21 08:33:10 2013 : Debug: Listening on authentication address * port 1812
Thu Feb 21 08:33:10 2013 : Debug: Listening on accounting address * port 1813
Thu Feb 21 08:33:10 2013 : Debug: Listening on authentication address 127.0.0.1 port 18120 as server inner-tunnel
Thu Feb 21 08:33:10 2013 : Debug: Listening on proxy address * port ! 1814
Thu Feb 21 08:33:10 2013 : Info: Ready to process requests.
rad_recv: Access-Request packet from host 127.0.0.1 port 43468, id=182, length=56
    User-Name = "jean"
    User-Password = "pw"
    NAS-IP-Address = 127.0.1.1
    NAS-Port = 0
Thu Feb 21 08:33:14 2013 : Info: # Executing section authorize from file /etc/freeradius/sites-enabled/default
Thu Feb 21 08:33:14 2013 : Info: +- entering group authorize {...}

Thank you