radwho is not working

[Angel L. Mateo]

El sáb, 06-01-2007 a las 00:02 -0500, Alan DeKok escribió:
> Angel L. Mateo wrote:
> 
> > 	But, if this was the problem, why freeradius updates the file? (I have
> > checked that the file is modified every time freeradius makes an
> > authentication)
> 
>   Does debugging mode show "radutmp returns noop"?  If so, nothing new
> was written.
> 
Hello,

	I continue with this problem. radwho doesn't display any connection,
although I am sure I have a lot. I have checked that debug doesn't
display any "radutmp returns noop" and I haven't got any line as this.

	I have also checked that RASes are sending the accounting info. As long
as I can see I think it is, because we have a lot of "Acct-Status-Type =
Start" (and Stop) entries in my detail files.

	For example, this is the debug of an accounting packet I am receiving:

rad_recv: Accounting-Request packet from host 155.54.213.20:1440,
id=235, length=135
        User-Name = "rociogon at um.es"
        NAS-Port = 28167
        Service-Type = Framed-User
        Framed-Protocol = PPP
        Framed-IP-Address = 155.54.194.77
        Class = "rpvum"
        Calling-Station-Id = "147.84.74.78"
        Acct-Status-Type = Start
        Acct-Session-Id = "FA904B8A"
        Tunnel-Client-Endpoint:0 = "147.84.74.78"
        Acct-Authentic = RADIUS
        Acct-Delay-Time = 0
        NAS-IP-Address = 155.54.213.20
        NAS-Port-Type = Virtual
  Processing the preacct section of radiusd.conf
modcall: entering group preacct for request 2
  modcall[preacct]: module "preprocess" returns noop for request 2
rlm_acct_unique: Hashing 'NAS-Port = 28167,Client-IP-Address =
155.54.213.20,NAS-IP-Address = 155.54.213.20,Acct-Session-Id =
"FA904B8A",User-Name = "rociogon at um.es"'
rlm_acct_unique: Acct-Unique-Session-ID = "62eeb2626cd2e4c0".
  modcall[preacct]: module "acct_unique" returns ok for request 2
    rlm_realm: Looking up realm "um.es" for User-Name = "rociogon at um.es"
    rlm_realm: Found realm "um.es"
    rlm_realm: Adding Stripped-User-Name = "rociogon"
    rlm_realm: Proxying request from user rociogon to realm um.es
    rlm_realm: Adding Realm = "um.es"
    rlm_realm: Accounting realm is LOCAL.
  modcall[preacct]: module "suffix" returns noop for request 2
  modcall[preacct]: module "files" returns noop for request 2
modcall: leaving group preacct (returns ok) for request 2
  Processing the accounting section of radiusd.conf
modcall: entering group accounting for request 2
radius_xlat:
'/var/log/freeradius/radacct/155.54.213.20/detail-20070205'
rlm_detail: /var/log/freeradius/radacct/%{Huntgroup-Name:-%{Client-IP-Address}}/detail-%Y%m%d expands to /var/log/freeradius/radacct/155.54.213.20/detail-20070205
  modcall[accounting]: module "detail" returns ok for request 2
  modcall[accounting]: module "unix" returns ok for request 2
radius_xlat:  '/var/log/freeradius/radutmp'
radius_xlat:  'rociogon at um.es'
  modcall[accounting]: module "radutmp" returns ok for request 2
rlm_ippool: This is not an Accounting-Stop. Return NOOP.
  modcall[accounting]: module "pool_vpn" returns noop for request 2
rlm_ippool: This is not an Accounting-Stop. Return NOOP.
  modcall[accounting]: module "pool_vpn_alu" returns noop for request 2
rlm_ippool: This is not an Accounting-Stop. Return NOOP.
  modcall[accounting]: module "pool_ono" returns noop for request 2
rlm_ippool: This is not an Accounting-Stop. Return NOOP.
  modcall[accounting]: module "pool_ono_alu" returns noop for request 2
rlm_sql_log (sql_log): Processing sql_log_accounting
radius_xlat:  'INSERT INTO radacct (AcctSessionId, UserName, ??
RealUserName, ?? NASIPAddress, FramedIPAddress, AcctStartTime,
AcctStopTime, ?? AcctSessionTime, AcctTerminateCause) VALUES ??
('FA904B8A', 'rociogon at um.es', ?? 'rpvum', '155.54.213.20', ??
'155.54.194.77', '2007-02-05 10:40:41', '0', '0', '');'
radius_xlat:  '/var/log/freeradius/radacct/sql-relay'
  modcall[accounting]: module "sql_log" returns ok for request 2
modcall: leaving group accounting (returns ok) for request 2
Sending Accounting-Response of id 235 to 155.54.213.20 port 1440
Finished request 2

	I think it is recording the entry because I can see it in the detail
file and I can see a line "modcall[accounting]: module "radutmp" returns
ok for request 2" in this debug log.

	But radwho still displays nothing.

	My configuration is:

...
modules {
	...
        unix {
	        cache = no
	        cache_reload = 600
	        shadow = /etc/shadow
	        radwtmp = ${logdir}/radwtmp
        }
	...
        detail {
	        detailfile =
${radacctdir}/%{Huntgroup-Name:-%{Client-IP-Address}}/detail-%Y%m%d
	        detailperm = 0600
        }
        detail auth_log {
                detailfile =
${radacctdir}/%{Huntgroup-Name:-%{Client-IP-Address}}/auth-detail-%Y%m%d
                 detailperm = 0600
        }

	...

        radutmp {
                filename = ${logdir}/radutmp
                username = %{User-Name}
                case_sensitive = yes
                check_with_nas = yes
                perm = 0600
                callerid = "yes"
	}
        radutmp sradutmp {
                filename = ${logdir}/sradutmp
                perm = 0644
                callerid = "no"
        }
	...
}

...

accounting {
        detail
        unix
        radutmp
        pool_vpn
        pool_vpn_alu
        pool_ono
        pool_ono_alu
        sql_log
}

session {
        radutmp
}


	Any idea?


-- 
Angel L. Mateo Martínez
Sección de Telemática
Área de Tecnologías de la Información       _o)
y las Comunicaciones Aplicadas (ATICA)      / \\
http://www.um.es/atica                    _(___V
Tfo: 968367590
Fax: 968398337