Write 3GPP attributes accounting into mysql problem

[Matthew Newton]

Hi,

Alan wrote:
> >  No.  You've edited it, and remove most of the useful information.

On Mon, Jan 13, 2014 at 08:57:51PM +0100, bit1 wrote:
> Hi Alan
> It seems you dont belive me. No I havent edited. This is an untouched input from console.

The debug output should start something like

radiusd: FreeRADIUS Version 3.0.1 (git #ca55a63), for host x86_64-unknown-linux-gnu, built on Jan 13 2014 at 20:37:29
Copyright (C) 1999-2014 The FreeRADIUS server project and contributors
There is NO warranty; not even for MERCHANTABILITY or FITNESS FOR A
PARTICULAR PURPOSE
You may redistribute copies of FreeRADIUS under the terms of the
GNU General Public License
For more information about these matters, see the file named COPYRIGHT
Starting - reading configuration files ...
including dictionary file raddb/dictionary
including configuration file raddb/debug.conf
including configuration file raddb/radiusd.conf
...

Not including it all, right from the top, usually means you're
missing something that's important, or useful to help you.

Cheers,

Matthew



> #
> #radclient -f /tmp/auth.packet 127.0.0.1:2813 acct testing123
> rad_recv: Accounting-Request packet from host 127.0.0.1 port 14803, id=236, length=142
> 	Acct-Status-Type = Start
> 	Calling-Station-Id = "12312312312"
> 	Framed-IP-Address = 10.0.0.1
> 	Acct-Session-Id = "d43363551081e4a8"
> 	NAS-IP-Address = 10.11.11.77
> 	Framed-Protocol = GPRS-PDP-Context
> 	Called-Station-Id = "test-gprs"
> 	NAS-Identifier = "test-gprs"
> 	User-Name = "testuser"
> 	NAS-Port-Type = Virtual
> 	Service-Type = Framed-User
> 	3GPP-IMSI = "123123123123123"
> Mon Jan 13 20:45:52 2014 : Info: # Executing section preacct from file /usr/local/etc/raddb/sites-enabled/default
> Mon Jan 13 20:45:52 2014 : Info: +- entering group preacct {...}
> Mon Jan 13 20:45:52 2014 : Info: ++[preprocess] returns ok
> Mon Jan 13 20:45:52 2014 : Info: [acct_unique] WARNING: Attribute NAS-Port was not found in request, unique ID MAY be inconsistent
> Mon Jan 13 20:45:52 2014 : Info: [acct_unique] Hashing ',NAS-Identifier = "test-gprs",NAS-IP-Address = 10.11.11.77,Acct-Session-Id = "d43363551081e4a8",User-Name = "testuser"'
> Mon Jan 13 20:45:52 2014 : Info: [acct_unique] Acct-Unique-Session-ID = "6ce7054b4a610197".
> Mon Jan 13 20:45:52 2014 : Info: ++[acct_unique] returns ok
> Mon Jan 13 20:45:52 2014 : Info: [suffix] No '@' in User-Name = "testuser", looking up realm NULL
> Mon Jan 13 20:45:52 2014 : Info: [suffix] No such realm "NULL"
> Mon Jan 13 20:45:52 2014 : Info: ++[suffix] returns noop
> Mon Jan 13 20:45:52 2014 : Info: # Executing section accounting from file /usr/local/etc/raddb/sites-enabled/default
> Mon Jan 13 20:45:52 2014 : Info: +- entering group accounting {...}
> Mon Jan 13 20:45:52 2014 : Info: [detail] 	expand: %{Packet-Src-IP-Address} -> 127.0.0.1
> Mon Jan 13 20:45:52 2014 : Info: [detail] 	expand: /var/log/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d -> /var/log/radacct/127.0.0.1/detail-20140113
> Mon Jan 13 20:45:52 2014 : Info: [detail] /var/log/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d expands to /var/log/radacct/127.0.0.1/detail-20140113
> Mon Jan 13 20:45:52 2014 : Info: [detail] 	expand: %t -> Mon Jan 13 20:45:52 2014
> Mon Jan 13 20:45:52 2014 : Info: ++[detail] returns ok
> Mon Jan 13 20:45:52 2014 : Info: [sql] 	expand: %{User-Name} -> testuser
> Mon Jan 13 20:45:52 2014 : Info: [sql] sql_set_user escaped user --> 'testuser'
> Mon Jan 13 20:45:52 2014 : Info: [sql] 	expand: %{Acct-Delay-Time} -> 
> Mon Jan 13 20:45:52 2014 : Info: [sql] 	... expanding second conditional
> Mon Jan 13 20:45:52 2014 : Info: [sql] 	expand:            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,    xascendsessionsvrkey, test,fullpacket)           VALUES             ('%{Acct-Session-Id}', '%{Acct-Unique-Session-Id}',              '%{SQL-User-Name}',              '%{Realm}', '%{NAS-IP-Address}', '%{NAS-Port}',              '%{NAS-Port-Type}', '%S', NULL,              '0', '%{Acct-Authentic}', '%{Connect-Info}',              '', '0', '0',              '%{Called-Station-Id}', '%{Calling-Station-Id}', '',              '%{Service-Type}', '%{Framed-Protocol}', '%{Framed-IP
> Mon Jan 13 20:45:52 2014 : Debug: rlm_sql (sql): Reserving sql socket id: 3
> Mon Jan 13 20:45:52 2014 : Debug: rlm_sql (sql): Released sql socket id: 3
> Mon Jan 13 20:45:52 2014 : Info: ++[sql] returns ok
> Mon Jan 13 20:45:52 2014 : Info: ++[exec] returns noop
> Mon Jan 13 20:45:52 2014 : Info: [attr_filter.accounting_response] 	expand: %{User-Name} -> testuser
> Mon Jan 13 20:45:52 2014 : Debug: attr_filter: Matched entry DEFAULT at line 12
> Mon Jan 13 20:45:52 2014 : Info: ++[attr_filter.accounting_response] returns updated
> Sending Accounting-Response of id 236 to 127.0.0.1 port 14803
> Mon Jan 13 20:45:52 2014 : Info: Finished request 0.
> Received response ID 236, code 5, length = 20
> Mon Jan 13 20:45:52 2014 : Info: Cleaning up request 0 ID 236 with timestamp +8
> Mon Jan 13 20:45:52 2014 : Debug: Going to the next request
> Mon Jan 13 20:45:52 2014 : Info: Ready to process requests.
> #
> #

-- 
Matthew Newton, Ph.D. <mcn4 at le.ac.uk>

Systems Specialist, Infrastructure Services,
I.T. Services, University of Leicester, Leicester LE1 7RH, United Kingdom