On Tuesday 07 December 2004 03:25, Lim Han Shyong wrote: Hi Lim
Thanks for all your help. I found the problem, on the cisco router i had radius bound to the loopback Interface therefor it was not part of the Lan address, Basically ID10T Problem.. Adam > Hi: > > Mean did u add the > Service-Type = NAS -Prompt-User > into your sql database..? i mean in the reply message. > > > > HSL > > > -----Original Message----- > From: [EMAIL PROTECTED] > [mailto:[EMAIL PROTECTED] Behalf Of Frog > Sent: Monday, December 06, 2004 8:02 PM > To: [EMAIL PROTECTED] > Subject: Re: auth from cisco to freeradius msql > > > I'm using mysql for authentication. > > On Monday 06 December 2004 10:29, Lim Han Shyong wrote: > > Hi: > > > > Hmm.. What u set in your user file ? > > > > user Auth-Type:= Local, User-Password = "testing" > > Service-Type = NAS-Prompt-User > > > > > > this mine, it can done basic authentication. Probably can have a try ^_^ > > > > > > HSL > > > > > > > > > > -----Original Message----- > > From: [EMAIL PROTECTED] > > [mailto:[EMAIL PROTECTED] Behalf Of Frog > > Sent: Monday, December 06, 2004 4:10 PM > > To: [EMAIL PROTECTED] > > Subject: Re: auth from cisco to freeradius msql > > > > > > Yes but if I try get access from my router or dialup the auth fails. > > > > > > Adam > > > > On Friday 03 December 2004 11:02, Lim Han Shyong wrote: > > > Hi, > > > > > > Not understand what u want... radius already authenticate and send > > > the Access accept packet back. > > > > > > > > > HSL > > > > > > > > > > > > -----Original Message----- > > > From: [EMAIL PROTECTED] > > > [mailto:[EMAIL PROTECTED] Behalf Of Frog > > > Sent: Friday, December 03, 2004 4:53 PM > > > To: [EMAIL PROTECTED] > > > Subject: auth from cisco to freeradius msql > > > > > > > > > Hi list > > > > > > I'm in the process of setting up my first radius server. Radius starts > > ok > > > > and > > > auths with radtest but from cisco it does not. I'm running Fedora Core > > > 2 with > > > freeradius ver 1.0.1 using cisco 1601 (testing) > > > > > > here is the cisco setup followed by radius radiusd -fxxxx startup then > > > > when > > > > > I > > > try and authenticate in debug mode. > > > > ------------------------------------------------------------------------- > > > > >- > > > > - > > > > >- ----------------- > > > > > > aaa new-model > > > aaa authentication login default group radius none > > > aaa authentication login none none > > > aaa authentication login unilynx local > > > aaa authentication login consoleport none > > > aaa authentication ppp default group radius none > > > aaa authentication ppp unilynx-radius group radius local > > > aaa authentication ppp radppp if-needed group radius > > > aaa authorization network default group radius > > > aaa accounting update newinfo > > > aaa accounting network default wait-start group radius > > > > > > > > > > > > ip radius source-interface Loopback0 > > > snmp-server engineID local 000000090200000142062DED > > > snmp-server community public RO 97 > > > radius-server host 192.168.2.254 auth-port 1812 acct-port 1813 > > > > non-standard > > > > > radius-server retransmit 2 > > > radius-server timeout 8 > > > radius-server deadtime 2 > > > radius-server key password > > > > ------------------------------------------------------------------------- > > > > >- > > > > - > > > > >- ----------------------- > > > Fri Dec 3 10:38:47 2004 : Info: Starting - reading configuration files > > > > ... > > > > > Fri Dec 3 10:38:47 2004 : Debug: reread_config: reading radiusd.conf > > > Fri Dec 3 10:38:47 2004 : Debug: Config: including > > > file: /usr/local/etc/raddb/proxy.conf > > > Fri Dec 3 10:38:47 2004 : Debug: Config: including > > > file: /usr/local/etc/raddb/clients.conf > > > Fri Dec 3 10:38:47 2004 : Debug: Config: including > > > file: /usr/local/etc/raddb/snmp.conf > > > Fri Dec 3 10:38:47 2004 : Debug: Config: including > > > file: /usr/local/etc/raddb/sql.conf > > > Fri Dec 3 10:38:47 2004 : Debug: main: prefix = "/usr/local" > > > Fri Dec 3 10:38:47 2004 : Debug: main: localstatedir = > > "/usr/local/var" > > > > Fri Dec 3 10:38:47 2004 : Debug: main: logdir = > > > "/usr/local/var/log/radius" > > > Fri Dec 3 10:38:47 2004 : Debug: main: libdir = > > > "/home/temp/freeradius/freeradius-1.0.1/src/modules" > > > Fri Dec 3 10:38:47 2004 : Debug: main: radacctdir = > > > "/usr/local/var/log/radius/radacct" > > > Fri Dec 3 10:38:47 2004 : Debug: main: hostname_lookups = no > > > Fri Dec 3 10:38:47 2004 : Debug: main: max_request_time = 30 > > > Fri Dec 3 10:38:47 2004 : Debug: main: cleanup_delay = 5 > > > Fri Dec 3 10:38:47 2004 : Debug: main: max_requests = 1024 > > > Fri Dec 3 10:38:47 2004 : Debug: main: delete_blocked_requests = 0 > > > Fri Dec 3 10:38:47 2004 : Debug: main: port = 0 > > > Fri Dec 3 10:38:47 2004 : Debug: main: allow_core_dumps = no > > > Fri Dec 3 10:38:47 2004 : Debug: main: log_stripped_names = no > > > Fri Dec 3 10:38:47 2004 : Debug: main: log_file = > > > "/usr/local/var/log/radius/radius.log" > > > Fri Dec 3 10:38:47 2004 : Debug: main: log_auth = no > > > Fri Dec 3 10:38:47 2004 : Debug: main: log_auth_badpass = no > > > Fri Dec 3 10:38:47 2004 : Debug: main: log_auth_goodpass = no > > > Fri Dec 3 10:38:47 2004 : Debug: main: pidfile = > > > "/usr/local/var/run/radiusd/radiusd.pid" > > > Fri Dec 3 10:38:47 2004 : Debug: main: user = "(null)" > > > Fri Dec 3 10:38:47 2004 : Debug: main: group = "(null)" > > > Fri Dec 3 10:38:47 2004 : Debug: main: usercollide = no > > > Fri Dec 3 10:38:47 2004 : Debug: main: lower_user = "no" > > > Fri Dec 3 10:38:47 2004 : Debug: main: lower_pass = "no" > > > Fri Dec 3 10:38:47 2004 : Debug: main: nospace_user = "no" > > > Fri Dec 3 10:38:47 2004 : Debug: main: nospace_pass = "no" > > > Fri Dec 3 10:38:47 2004 : Debug: main: checkrad = > > > "/usr/local/sbin/checkrad" > > > Fri Dec 3 10:38:47 2004 : Debug: main: proxy_requests = yes > > > Fri Dec 3 10:38:47 2004 : Debug: proxy: retry_delay = 5 > > > Fri Dec 3 10:38:47 2004 : Debug: proxy: retry_count = 3 > > > Fri Dec 3 10:38:47 2004 : Debug: proxy: synchronous = no > > > Fri Dec 3 10:38:47 2004 : Debug: proxy: default_fallback = no > > > Fri Dec 3 10:38:47 2004 : Debug: proxy: dead_time = 120 > > > Fri Dec 3 10:38:47 2004 : Debug: proxy: post_proxy_authorize = no > > > Fri Dec 3 10:38:47 2004 : Debug: proxy: wake_all_if_all_dead = no > > > Fri Dec 3 10:38:47 2004 : Debug: security: max_attributes = 200 > > > Fri Dec 3 10:38:47 2004 : Debug: security: reject_delay = 1 > > > Fri Dec 3 10:38:47 2004 : Debug: security: status_server = no > > > Fri Dec 3 10:38:47 2004 : Debug: main: debug_level = 0 > > > Fri Dec 3 10:38:47 2004 : Debug: read_config_files: reading > > > dictionary Fri Dec 3 10:38:47 2004 : Debug: read_config_files: > > > reading naslist Fri Dec 3 10:38:47 2004 : Info: Using deprecated > > > naslist file. Support for this will go away soon. > > > Fri Dec 3 10:38:47 2004 : Debug: read_config_files: reading clients > > > Fri Dec 3 10:38:47 2004 : Info: Using deprecated clients file. > > > Support for this will go away soon. > > > Fri Dec 3 10:38:47 2004 : Debug: read_config_files: reading realms > > > Fri Dec 3 10:38:47 2004 : Info: Using deprecated realms file. Support > > > > for > > > > > this will go away soon. > > > Fri Dec 3 10:38:47 2004 : Debug: radiusd: entering modules setup > > > Fri Dec 3 10:38:47 2004 : Debug: Module: Library search path > > > is /home/temp/freeradius/freeradius-1.0.1/src/modules > > > Fri Dec 3 10:38:47 2004 : Debug: Module: Loaded exec > > > Fri Dec 3 10:38:47 2004 : Debug: exec: wait = yes > > > Fri Dec 3 10:38:47 2004 : Debug: exec: program = "(null)" > > > Fri Dec 3 10:38:47 2004 : Debug: exec: input_pairs = "request" > > > Fri Dec 3 10:38:47 2004 : Debug: exec: output_pairs = "(null)" > > > Fri Dec 3 10:38:47 2004 : Debug: exec: packet_type = "(null)" > > > Fri Dec 3 10:38:47 2004 : Info: rlm_exec: Wait=yes but no output > > > defined. Did > > > you mean output=none? > > > Fri Dec 3 10:38:47 2004 : Debug: Module: Instantiated exec (exec) > > > Fri Dec 3 10:38:47 2004 : Debug: Module: Loaded expr > > > Fri Dec 3 10:38:47 2004 : Debug: Module: Instantiated expr (expr) > > > Fri Dec 3 10:38:47 2004 : Debug: Module: Loaded PAP > > > Fri Dec 3 10:38:47 2004 : Debug: pap: encryption_scheme = "crypt" > > > Fri Dec 3 10:38:47 2004 : Debug: Module: Instantiated pap (pap) > > > Fri Dec 3 10:38:47 2004 : Debug: Module: Loaded CHAP > > > Fri Dec 3 10:38:47 2004 : Debug: Module: Instantiated chap (chap) > > > Fri Dec 3 10:38:47 2004 : Debug: Module: Loaded MS-CHAP > > > Fri Dec 3 10:38:47 2004 : Debug: mschap: use_mppe = yes > > > Fri Dec 3 10:38:47 2004 : Debug: mschap: require_encryption = no > > > Fri Dec 3 10:38:47 2004 : Debug: mschap: require_strong = no > > > Fri Dec 3 10:38:47 2004 : Debug: mschap: passwd = "(null)" > > > Fri Dec 3 10:38:47 2004 : Debug: mschap: authtype = "MS-CHAP" > > > Fri Dec 3 10:38:47 2004 : Debug: Module: Instantiated mschap (mschap) > > > Fri Dec 3 10:38:47 2004 : Debug: Module: Loaded System > > > Fri Dec 3 10:38:47 2004 : Debug: unix: cache = no > > > Fri Dec 3 10:38:47 2004 : Debug: unix: passwd = "(null)" > > > Fri Dec 3 10:38:47 2004 : Debug: unix: shadow = "(null)" > > > Fri Dec 3 10:38:47 2004 : Debug: unix: group = "(null)" > > > Fri Dec 3 10:38:47 2004 : Debug: unix: radwtmp = > > > "/usr/local/var/log/radius/radwtmp" > > > Fri Dec 3 10:38:47 2004 : Debug: unix: usegroup = no > > > Fri Dec 3 10:38:47 2004 : Debug: unix: cache_reload = 600 > > > Fri Dec 3 10:38:47 2004 : Debug: Module: Instantiated unix (unix) > > > Fri Dec 3 10:38:47 2004 : Debug: Module: Loaded preprocess > > > Fri Dec 3 10:38:47 2004 : Debug: preprocess: huntgroups = > > > "/usr/local/etc/raddb/huntgroups" > > > Fri Dec 3 10:38:47 2004 : Debug: preprocess: hints = > > > "/usr/local/etc/raddb/hints" > > > Fri Dec 3 10:38:47 2004 : Debug: preprocess: with_ascend_hack = no > > > Fri Dec 3 10:38:47 2004 : Debug: preprocess: ascend_channels_per_line > > = > > > > 23 Fri Dec 3 10:38:47 2004 : Debug: preprocess: with_ntdomain_hack = > > no > > > > Fri Dec 3 10:38:47 2004 : Debug: preprocess: > > > with_specialix_jetstream_hack = > > > no > > > Fri Dec 3 10:38:47 2004 : Debug: preprocess: with_cisco_vsa_hack = > > > yes Fri Dec 3 10:38:47 2004 : Debug: Module: Instantiated preprocess > > > (preprocess) > > > Fri Dec 3 10:38:47 2004 : Debug: Module: Loaded realm > > > Fri Dec 3 10:38:47 2004 : Debug: realm: format = "suffix" > > > Fri Dec 3 10:38:47 2004 : Debug: realm: delimiter = "@" > > > Fri Dec 3 10:38:47 2004 : Debug: Module: Instantiated realm (suffix) > > > Fri Dec 3 10:38:47 2004 : Debug: Module: Loaded files > > > Fri Dec 3 10:38:47 2004 : Debug: files: usersfile = > > > "/usr/local/etc/raddb/users" > > > Fri Dec 3 10:38:47 2004 : Debug: files: acctusersfile = > > > "/usr/local/etc/raddb/acct_users" > > > Fri Dec 3 10:38:47 2004 : Debug: files: preproxy_usersfile = > > > "/usr/local/etc/raddb/preproxy_users" > > > Fri Dec 3 10:38:47 2004 : Debug: files: compat = "no" > > > Fri Dec 3 10:38:47 2004 : Debug: Module: Instantiated files (files) > > > Fri Dec 3 10:38:47 2004 : Debug: Module: Loaded SQL > > > Fri Dec 3 10:38:47 2004 : Debug: sql: driver = "rlm_sql_mysql" > > > Fri Dec 3 10:38:47 2004 : Debug: sql: server = "localhost" > > > Fri Dec 3 10:38:47 2004 : Debug: sql: port = "" > > > Fri Dec 3 10:38:47 2004 : Debug: sql: login = "root" > > > Fri Dec 3 10:38:47 2004 : Debug: sql: password = "password" > > > Fri Dec 3 10:38:47 2004 : Debug: sql: radius_db = "radius" > > > Fri Dec 3 10:38:47 2004 : Debug: sql: acct_table = "radacct" > > > Fri Dec 3 10:38:47 2004 : Debug: sql: acct_table2 = "radacct" > > > Fri Dec 3 10:38:47 2004 : Debug: sql: authcheck_table = "radcheck" > > > Fri Dec 3 10:38:47 2004 : Debug: sql: authreply_table = "radreply" > > > Fri Dec 3 10:38:47 2004 : Debug: sql: groupcheck_table = > > > "radgroupcheck" Fri Dec 3 10:38:47 2004 : Debug: sql: > > > groupreply_table = "radgroupreply" Fri Dec 3 10:38:47 2004 : Debug: > > > sql: > > usergroup_table > > > > = "usergroup" Fri Dec 3 10:38:47 2004 : Debug: sql: nas_table = "nas" > > > Fri Dec 3 10:38:47 2004 : Debug: sql: dict_table = "dictionary" > > > Fri Dec 3 10:38:47 2004 : Debug: sql: sqltrace = yes > > > Fri Dec 3 10:38:47 2004 : Debug: sql: sqltracefile = > > > "/var/log/radius/sqltrace.sql" > > > Fri Dec 3 10:38:47 2004 : Debug: sql: deletestalesessions = yes > > > Fri Dec 3 10:38:47 2004 : Debug: sql: num_sql_socks = 5 > > > Fri Dec 3 10:38:47 2004 : Debug: sql: sql_user_name = "%{User-Name}" > > > Fri Dec 3 10:38:47 2004 : Debug: sql: default_user_profile = "" > > > Fri Dec 3 10:38:47 2004 : Debug: sql: query_on_not_found = no > > > Fri Dec 3 10:38:47 2004 : Debug: sql: authorize_check_query = "SELECT > > > id,UserName,Attribute,Value,op FROM radcheck WHERE Username = > > > '%{SQL-User-Name}' ORDER BY id" > > > Fri Dec 3 10:38:47 2004 : Debug: sql: authorize_reply_query = "SELECT > > > id,UserName,Attribute,Value,op FROM radreply WHERE Username = > > > '%{SQL-User-Name}' ORDER BY id" > > > Fri Dec 3 10:38:47 2004 : Debug: sql: authorize_group_check_query = > > > "SELECT > > radgroupcheck.id,radgroupcheck.GroupName,radgroupcheck.Attribute,radgroupch > > > >e ck.Value,radgroupcheck.op > > > FROM radgroupcheck,usergroup WHERE usergroup.Username = > > > '%{SQL-User-Name}' AND usergroup.GroupName = radgroupcheck.GroupName > > > ORDER BY > > > radgroupcheck.id" Fri Dec 3 10:38:47 2004 : Debug: sql: > > > authorize_group_reply_query = "SELECT > > radgroupreply.id,radgroupreply.GroupName,radgroupreply.Attribute,radgroupre > > > >p ly.Value,radgroupreply.op > > > FROM radgroupreply,usergroup WHERE usergroup.Username = > > > '%{SQL-User-Name}' AND usergroup.GroupName = radgroupreply.GroupName > > > ORDER BY > > > radgroupreply.id" Fri Dec 3 10:38:47 2004 : Debug: sql: > > > accounting_onoff_query = "UPDATE radacct SET AcctStopTime='%S', > > > AcctSessionTime=unix_timestamp('%S') - unix_timestamp(AcctStartTime), > > > AcctTerminateCause='%{Acct-Terminate-Cause}', AcctStopDelay = > > > '%{Acct-Delay-Time}' WHERE AcctSessionTime=0 AND > > > AcctStopTime=0 AND NASIPAddress= '%{NAS-IP-Address}' AND AcctStartTime > > <= > > > > '%S'" > > > Fri Dec 3 10:38:47 2004 : Debug: sql: accounting_update_query = > > "UPDATE > > > > radacct ? SET FramedIPAddress = '%{Framed-IP-Address}', ? > > AcctSessionTime > > > = > > > > > '%{Acct-Session-Time}', ? AcctInputOctets = '%{Acct-Input-Octets}', ? > > > AcctOutputOctets = '%{Acct-Output-Octets}' ? WHERE AcctSessionId = > > > '%{Acct-Session-Id}' ? AND UserName = '%{SQL-User-Name}' ? AND > > > NASIPAddress= '%{NAS-IP-Address}'" > > > Fri Dec 3 10:38:47 2004 : Debug: sql: accounting_start_query = > > > "INSERT into > > > radacct (AcctSessionId, AcctUniqueId, UserName, Realm, NASIPAddress, > > > NASPortId, NASPortType, AcctStartTime, AcctStopTime, AcctSessionTime, > > > AcctAuthentic, ConnectInfo_start, ConnectInfo_stop, AcctInputOctets, > > > AcctOutputOctets, CalledStationId, CallingStationId, > > > AcctTerminateCause, ServiceType, FramedProtocol, FramedIPAddress, > > > AcctStartDelay, > > > AcctStopDelay) values('%{Acct-Session-Id}', > > > '%{Acct-Unique-Session-Id}', '%{SQL-User-Name}', > > > '%{Realm}', '%{NAS-IP-Address}', '%{NAS-Port}', '%{NAS-Port-Type}', > > '%S', > > > > '0', '0', '%{Acct-Authentic}', '%{Connect-Info}', '', '0', '0', > > > '%{Called-Station-Id}', '%{Calling-Station-Id}', '', '%{Service-Type}', > > > '%{Framed-Protocol}', '%{Framed-IP-Address}', '%{Acct-Delay-Time}', > > '0')" > > > > Fri Dec 3 10:38:47 2004 : Debug: sql: accounting_start_query_alt = > > > "UPDATE radacct SET AcctStartTime = '%S', AcctStartDelay = > > > '%{Acct-Delay-Time}', ConnectInfo_start = '%{Connect-Info}' WHERE > > > AcctSessionId = > > > '%{Acct-Session-Id}' AND UserName = '%{SQL-User-Name}' AND NASIPAddress > > = > > > > '%{NAS-IP-Address}'" > > > Fri Dec 3 10:38:47 2004 : Debug: sql: accounting_start_query = > > > "INSERT into > > > radacct (AcctSessionId, AcctUniqueId, UserName, Realm, NASIPAddress, > > > NASPortId, NASPortType, AcctStartTime, AcctStopTime, AcctSessionTime, > > > AcctAuthentic, ConnectInfo_start, ConnectInfo_stop, AcctInputOctets, > > > AcctOutputOctets, CalledStationId, CallingStationId, > > > AcctTerminateCause, ServiceType, FramedProtocol, FramedIPAddress, > > > AcctStartDelay, > > > AcctStopDelay) values('%{Acct-Session-Id}', > > > '%{Acct-Unique-Session-Id}', '%{SQL-User-Name}', > > > '%{Realm}', '%{NAS-IP-Address}', '%{NAS-Port}', '%{NAS-Port-Type}', > > '%S', > > > > '0', '0', '%{Acct-Authentic}', '%{Connect-Info}', '', '0', '0', > > > '%{Called-Station-Id}', '%{Calling-Station-Id}', '', '%{Service-Type}', > > > '%{Framed-Protocol}', '%{Framed-IP-Address}', '%{Acct-Delay-Time}', > > '0')" > > > > Fri Dec 3 10:38:47 2004 : Debug: sql: accounting_start_query_alt = > > > "UPDATE radacct SET AcctStartTime = '%S', AcctStartDelay = > > > '%{Acct-Delay-Time}', ConnectInfo_start = '%{Connect-Info}' WHERE > > > AcctSessionId = > > > '%{Acct-Session-Id}' AND UserName = '%{SQL-User-Name}' AND NASIPAddress > > = > > > > '%{NAS-IP-Address}'" > > > Fri Dec 3 10:38:47 2004 : Debug: sql: accounting_stop_query = "UPDATE > > > radacct SET AcctStopTime = '%S', AcctSessionTime = > > > '%{Acct-Session-Time}', AcctInputOctets = '%{Acct-Input-Octets}', > > > AcctOutputOctets = > > > '%{Acct-Output-Octets}', AcctTerminateCause = > > > '%{Acct-Terminate-Cause}', AcctStopDelay = '%{Acct-Delay-Time}', > > > ConnectInfo_stop = > > > '%{Connect-Info}' WHERE AcctSessionId = '%{Acct-Session-Id}' AND > > UserName > > > > = > > > '%{SQL-User-Name}' AND NASIPAddress = '%{NAS-IP-Address}'" > > > Fri Dec 3 10:38:47 2004 : Debug: sql: accounting_stop_query_alt = > > > > "INSERT > > > > > into radacct (AcctSessionId, AcctUniqueId, UserName, Realm, > > NASIPAddress, > > > > NASPortId, NASPortType, AcctStartTime, AcctStopTime, AcctSessionTime, > > > AcctAuthentic, ConnectInfo_start, ConnectInfo_stop, AcctInputOctets, > > > AcctOutputOctets, CalledStationId, CallingStationId, > > > AcctTerminateCause, ServiceType, FramedProtocol, FramedIPAddress, > > > AcctStartDelay, > > > AcctStopDelay) values('%{Acct-Session-Id}', > > > '%{Acct-Unique-Session-Id}', '%{SQL-User-Name}', > > > '%{Realm}', '%{NAS-IP-Address}', '%{NAS-Port}', '%{NAS-Port-Type}', > > > DATE_SUB('%S', INTERVAL (%{Acct-Session-Time:-0} + > > %{Acct-Delay-Time:-0}) > > > > SECOND), '%S', '%{Acct-Session-Time}', '%{Acct-Authentic}', '', > > > '%{Connect-Info}', '%{Acct-Input-Octets}', '%{Acct-Output-Octets}', > > > '%{Called-Station-Id}', '%{Calling-Station-Id}', > > > > '%{Acct-Terminate-Cause}', > > > > > '%{Service-Type}', '%{Framed-Protocol}', '%{Framed-IP-Address}', '0', > > > '%{Acct-Delay-Time}')" > > > Fri Dec 3 10:38:47 2004 : Debug: sql: group_membership_query = > > > "SELECT GroupName FROM usergroup WHERE UserName='%{SQL-User-Name}'" > > > Fri Dec 3 10:38:47 2004 : Debug: sql: connect_failure_retry_delay = > > > 60 Fri Dec 3 10:38:47 2004 : Debug: sql: simul_count_query = "" Fri > > > Dec 3 10:38:47 2004 : Debug: sql: simul_verify_query = "SELECT > > > RadAcctId, AcctSessionId, UserName, NASIPAddress, NASPortId, > > > FramedIPAddress, > > > CallingStationId, FramedProtocol FROM radacct WHERE > > > UserName='%{SQL-User-Name}' AND AcctStopTime = 0"Fri Dec 3 10:38:47 > > 2004 > > > > Info: rlm_sql (sql): Driver rlm_sql_mysql (module rlm_sql_mysql) loaded > > > > and > > > > > linked > > > Fri Dec 3 10:38:47 2004 : Info: rlm_sql (sql): Attempting to connect > > > to [EMAIL PROTECTED]:/radius > > > Fri Dec 3 10:38:47 2004 : Debug: rlm_sql (sql): starting 0 > > > Fri Dec 3 10:38:47 2004 : Debug: rlm_sql (sql): Attempting to connect > > > rlm_sql_mysql #0 > > > Fri Dec 3 10:38:47 2004 : Info: rlm_sql_mysql: Starting connect to > > MySQL > > > > server for #0 > > > Fri Dec 3 10:38:47 2004 : Debug: rlm_sql (sql): Connected new DB > > handle, > > > > #0 Fri Dec 3 10:38:47 2004 : Debug: rlm_sql (sql): starting 1 > > > Fri Dec 3 10:38:47 2004 : Debug: rlm_sql (sql): Attempting to connect > > > rlm_sql_mysql #1 > > > Fri Dec 3 10:38:47 2004 : Info: rlm_sql_mysql: Starting connect to > > MySQL > > > > server for #1 > > > Fri Dec 3 10:38:47 2004 : Debug: rlm_sql (sql): Connected new DB > > handle, > > > > #1 Fri Dec 3 10:38:47 2004 : Debug: rlm_sql (sql): starting 2 > > > Fri Dec 3 10:38:47 2004 : Debug: rlm_sql (sql): Attempting to connect > > > rlm_sql_mysql #2 > > > Fri Dec 3 10:38:47 2004 : Info: rlm_sql_mysql: Starting connect to > > MySQL > > > > server for #2 > > > Fri Dec 3 10:38:47 2004 : Debug: rlm_sql (sql): Connected new DB > > handle, > > > > #2 Fri Dec 3 10:38:47 2004 : Debug: rlm_sql (sql): starting 3 > > > Fri Dec 3 10:38:47 2004 : Debug: rlm_sql (sql): Attempting to connect > > > rlm_sql_mysql #3 > > > Fri Dec 3 10:38:47 2004 : Info: rlm_sql_mysql: Starting connect to > > MySQL > > > > server for #3 > > > Fri Dec 3 10:38:47 2004 : Debug: rlm_sql (sql): Connected new DB > > handle, > > > > #3 Fri Dec 3 10:38:47 2004 : Debug: rlm_sql (sql): starting 4 > > > Fri Dec 3 10:38:47 2004 : Debug: rlm_sql (sql): Attempting to connect > > > rlm_sql_mysql #4 > > > Fri Dec 3 10:38:47 2004 : Info: rlm_sql_mysql: Starting connect to > > MySQL > > > > server for #4 > > > Fri Dec 3 10:38:47 2004 : Debug: rlm_sql (sql): Connected new DB > > handle, > > > > #4 Fri Dec 3 10:38:47 2004 : Debug: Module: Instantiated sql (sql) > > > Fri Dec 3 10:38:47 2004 : Debug: Module: Loaded Acct-Unique-Session-Id > > > Fri Dec 3 10:38:47 2004 : Debug: acct_unique: key = "User-Name, > > > Acct-Session-Id, NAS-IP-Address, Client-IP-Address, NAS-Port" > > > Fri Dec 3 10:38:47 2004 : Debug: Module: Instantiated acct_unique > > > (acct_unique) > > > Fri Dec 3 10:38:47 2004 : Debug: Module: Loaded detail > > > Fri Dec 3 10:38:47 2004 : Debug: detail: detailfile = > > > "/usr/local/var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d" > > > Fri Dec 3 10:38:47 2004 : Debug: detail: detailperm = 384 > > > Fri Dec 3 10:38:47 2004 : Debug: detail: dirperm = 493 > > > Fri Dec 3 10:38:47 2004 : Debug: detail: locking = no > > > Fri Dec 3 10:38:47 2004 : Debug: Module: Instantiated detail (detail) > > > Fri Dec 3 10:38:47 2004 : Debug: Module: Loaded radutmp > > > Fri Dec 3 10:38:47 2004 : Debug: radutmp: filename = > > > "/usr/local/var/log/radius/radutmp" > > > Fri Dec 3 10:38:47 2004 : Debug: radutmp: username = "%{User-Name}" > > > Fri Dec 3 10:38:47 2004 : Debug: radutmp: case_sensitive = yes > > > Fri Dec 3 10:38:47 2004 : Debug: radutmp: check_with_nas = yes > > > Fri Dec 3 10:38:47 2004 : Debug: radutmp: perm = 384 > > > Fri Dec 3 10:38:47 2004 : Debug: radutmp: callerid = yes > > > Fri Dec 3 10:38:47 2004 : Debug: Module: Instantiated radutmp > > > (radutmp) Fri Dec 3 10:38:47 2004 : Debug: Initializing the thread > > > pool... Fri Dec 3 10:38:47 2004 : Debug: thread: start_servers = 5 > > > Fri Dec 3 10:38:47 2004 : Debug: thread: max_servers = 32 > > > Fri Dec 3 10:38:47 2004 : Debug: thread: min_spare_servers = 3 > > > Fri Dec 3 10:38:47 2004 : Debug: thread: max_spare_servers = 10 > > > Fri Dec 3 10:38:47 2004 : Debug: thread: max_requests_per_server = 0 > > > Fri Dec 3 10:38:47 2004 : Debug: thread: cleanup_delay = 5 > > > Fri Dec 3 10:38:47 2004 : Debug: Thread 1 waiting to be assigned a > > > > request > > > > > Fri Dec 3 10:38:47 2004 : Debug: Thread spawned new child 1. Total > > > > threads > > > > > in > > > pool: 1 > > > Fri Dec 3 10:38:47 2004 : Debug: Thread 2 waiting to be assigned a > > > > request > > > > > Fri Dec 3 10:38:47 2004 : Debug: Thread spawned new child 2. Total > > > > threads > > > > > in > > > pool: 2 > > > Fri Dec 3 10:38:47 2004 : Debug: Thread 3 waiting to be assigned a > > > > request > > > > > Fri Dec 3 10:38:47 2004 : Debug: Thread spawned new child 3. Total > > > > threads > > > > > in > > > pool: 3 > > > Fri Dec 3 10:38:47 2004 : Debug: Thread 4 waiting to be assigned a > > > > request > > > > > Fri Dec 3 10:38:47 2004 : Debug: Thread spawned new child 4. Total > > > > threads > > > > > in > > > pool: 4 > > > Fri Dec 3 10:38:47 2004 : Debug: Thread 5 waiting to be assigned a > > > > request > > > > > Fri Dec 3 10:38:47 2004 : Debug: Thread spawned new child 5. Total > > > > threads > > > > > in > > > pool: 5 > > > Fri Dec 3 10:38:47 2004 : Info: Listening on IP address *, ports > > > 1812/udp and > > > 1813/udp, with proxy on 1814/udp. > > > Fri Dec 3 10:38:47 2004 : Info: Ready to process requests. > > > > ------------------------------------------------------------------------- > > > > >- > > > > - > > > > >- ----------------------- > > > > > > NAS-IP-Address = 192.168.0.250 > > > NAS-Port = 2 > > > NAS-Port-Type = Virtual > > > User-Name = "unilynx" > > > Calling-Station-Id = "192.168.2.177" > > > User-Password = "password" > > > Fri Dec 3 10:30:25 2004 : Debug: modcall: entering group authorize for > > > request 12 > > > Fri Dec 3 10:30:25 2004 : Debug: modsingle[authorize]: calling > > > preprocess (rlm_preprocess) for request 12 > > > Fri Dec 3 10:30:25 2004 : Debug: modsingle[authorize]: returned from > > > preprocess (rlm_preprocess) for request 12 > > > Fri Dec 3 10:30:25 2004 : Debug: modcall[authorize]: module > > > > "preprocess" > > > > > returns ok for request 12 > > > Fri Dec 3 10:30:25 2004 : Debug: modsingle[authorize]: calling chap > > > (rlm_chap) for request 12 > > > Fri Dec 3 10:30:25 2004 : Debug: modsingle[authorize]: returned from > > > chap (rlm_chap) for request 12 > > > Fri Dec 3 10:30:25 2004 : Debug: modcall[authorize]: module "chap" > > > returns > > > noop for request 12 > > > Fri Dec 3 10:30:25 2004 : Debug: modsingle[authorize]: calling > > > mschap (rlm_mschap) for request 12 > > > Fri Dec 3 10:30:25 2004 : Debug: modsingle[authorize]: returned from > > > mschap > > > (rlm_mschap) for request 12 > > > Fri Dec 3 10:30:25 2004 : Debug: modcall[authorize]: module "mschap" > > > returns noop for request 12 > > > Fri Dec 3 10:30:25 2004 : Debug: modsingle[authorize]: calling > > > suffix (rlm_realm) for request 12 > > > Fri Dec 3 10:30:25 2004 : Debug: rlm_realm: No '@' in User-Name = > > > "unilynx", looking up realm NULL > > > Fri Dec 3 10:30:25 2004 : Debug: rlm_realm: No such realm "NULL" > > > Fri Dec 3 10:30:25 2004 : Debug: modsingle[authorize]: returned from > > > suffix > > > (rlm_realm) for request 12 > > > Fri Dec 3 10:30:25 2004 : Debug: modcall[authorize]: module "suffix" > > > returns noop for request 12 > > > Fri Dec 3 10:30:25 2004 : Debug: modsingle[authorize]: calling files > > > (rlm_files) for request 12 > > > Fri Dec 3 10:30:25 2004 : Debug: modsingle[authorize]: returned from > > > files > > > (rlm_files) for request 12 > > > Fri Dec 3 10:30:25 2004 : Debug: modcall[authorize]: module "files" > > > returns > > > notfound for request 12 > > > Fri Dec 3 10:30:25 2004 : Debug: modsingle[authorize]: calling sql > > > (rlm_sql) for request 12 > > > Fri Dec 3 10:30:25 2004 : Debug: radius_xlat: 'unilynx' > > > Fri Dec 3 10:30:25 2004 : Debug: rlm_sql (sql): sql_set_user escaped > > > user --> > > > 'unilynx' > > > Fri Dec 3 10:30:25 2004 : Debug: radius_xlat: 'SELECT > > > id,UserName,Attribute,Value,op FROM radcheck WHERE Username = 'unilynx' > > > ORDER > > > BY id' > > > Fri Dec 3 10:30:25 2004 : Debug: rlm_sql (sql): Reserving sql socket > > id: > > 2 > > > > > Fri Dec 3 10:30:25 2004 : Debug: rlm_sql_mysql: query: SELECT > > > id,UserName,Attribute,Value,op FROM radcheck WHERE Username = 'unilynx' > > > ORDER > > > BY id > > > Fri Dec 3 10:30:25 2004 : Debug: radius_xlat: 'SELECT > > radgroupcheck.id,radgroupcheck.GroupName,radgroupcheck.Attribute,radgroupch > > > >e ck.Value,radgroupcheck.op > > > FROM radgroupcheck,usergroup WHERE usergroup.Username = 'unilynx' AND > > > usergroup.GroupName = radgroupcheck.GroupName ORDER BY > > > radgroupcheck.id' Fri Dec 3 10:30:25 2004 : Debug: rlm_sql_mysql: > > > query: SELECT > > radgroupcheck.id,radgroupcheck.GroupName,radgroupcheck.Attribute,radgroupch > > > >e ck.Value,radgroupcheck.op > > > FROM radgroupcheck,usergroup WHERE usergroup.Username = 'unilynx' AND > > > usergroup.GroupName = radgroupcheck.GroupName ORDER BY radgroupcheck.id > > > Fri Dec 3 10:30:25 2004 : Debug: radius_xlat: 'SELECT > > > id,UserName,Attribute,Value,op FROM radreply WHERE Username = 'unilynx' > > > ORDER > > > BY id' > > > Fri Dec 3 10:30:25 2004 : Debug: rlm_sql_mysql: query: SELECT > > > id,UserName,Attribute,Value,op FROM radreply WHERE Username = 'unilynx' > > > ORDER > > > BY id > > > Fri Dec 3 10:30:25 2004 : Debug: radius_xlat: 'SELECT > > radgroupreply.id,radgroupreply.GroupName,radgroupreply.Attribute,radgroupre > > > >p ly.Value,radgroupreply.op > > > FROM radgroupreply,usergroup WHERE usergroup.Username = 'unilynx' AND > > > usergroup.GroupName = radgroupreply.GroupName ORDER BY > > > radgroupreply.id' Fri Dec 3 10:30:25 2004 : Debug: rlm_sql_mysql: > > > query: SELECT > > radgroupreply.id,radgroupreply.GroupName,radgroupreply.Attribute,radgroupre > > > >p ly.Value,radgroupreply.op > > > FROM radgroupreply,usergroup WHERE usergroup.Username = 'unilynx' AND > > > usergroup.GroupName = radgroupreply.GroupName ORDER BY radgroupreply.id > > > Fri Dec 3 10:30:25 2004 : Debug: rlm_sql (sql): Released sql socket > > > id: 2 Fri Dec 3 10:30:25 2004 : Debug: modsingle[authorize]: > > > returned > > from > > > sql > > > > > (rlm_sql) for request 12 > > > Fri Dec 3 10:30:25 2004 : Debug: modcall[authorize]: module "sql" > > > returns ok for request 12 > > > Fri Dec 3 10:30:25 2004 : Debug: modcall: group authorize returns ok > > for > > > > request 12 > > > Fri Dec 3 10:30:25 2004 : Debug: auth: type Local > > > Fri Dec 3 10:30:25 2004 : Debug: auth: user supplied User-Password > > > > matches > > > > > local User-Password > > > Sending Access-Accept of id 4 to 192.168.0.250:1645 > > > Fri Dec 3 10:30:25 2004 : Debug: Finished request 12 > > > Fri Dec 3 10:30:25 2004 : Debug: Going to the next request > > > Fri Dec 3 10:30:25 2004 : Debug: Thread 3 waiting to be assigned a > > > > request > > > > > Fri Dec 3 10:30:30 2004 : Debug: --- Walking the entire request > > list --- > > > > Fri Dec 3 10:30:30 2004 : Debug: Threads: total/active/spare threads = > > > 5/0/5 > > > Fri Dec 3 10:30:30 2004 : Debug: Waking up in 1 seconds... > > > Fri Dec 3 10:30:31 2004 : Debug: --- Walking the entire request > > list --- > > > > Fri Dec 3 10:30:31 2004 : Debug: Cleaning up request 12 ID 4 with > > > timestamp 41b02421 > > > Fri Dec 3 10:30:31 2004 : Debug: Nothing to do. Sleeping until we see > > a > > > > request. > > > > ------------------------------------------------------------------------- > > > > >- > > > > - > > > > >- ----------------------------------- > > > > > > > > > Sorry about the big logs but I think that it will spark a thought from > > > somebody who has been through this setup a few times and has trouble > > > shooted problems with freeradius. > > > > > > > > > Thanking you in advance > > > > > > Adam > > > > > > - > > > List info/subscribe/unsubscribe? See > > > http://www.freeradius.org/list/users.html > > > > > > > > > - > > > List info/subscribe/unsubscribe? See > > > http://www.freeradius.org/list/users.html > > > > > > > > > This message has been scanned for viruses and > > > malicious content by Unilynx Africa mail scanner, and is > > > believed to be clean. > > > > - > > List info/subscribe/unsubscribe? See > > http://www.freeradius.org/list/users.html > > > > > > - > > List info/subscribe/unsubscribe? See > > http://www.freeradius.org/list/users.html > > > > > > This message has been scanned for viruses and > > malicious content by Unilynx Africa mail scanner, and is > > believed to be clean. > > - > List info/subscribe/unsubscribe? See > http://www.freeradius.org/list/users.html > > > - > List info/subscribe/unsubscribe? See > http://www.freeradius.org/list/users.html > > > This message has been scanned for viruses and > malicious content by Unilynx Africa mail scanner, and is > believed to be clean. - List info/subscribe/unsubscribe? See http://www.freeradius.org/list/users.html