<div dir="ltr">Hi all! I'm using 'sql' module in accounting to log all the radius packets from remote radius client (cisco 2600). I've investigated, that accounting packets are received in right order ("Start" then "Stop"), but putted into DB log table in wrong order ("Stop" then "Start"). Here are the logs:<br>
<br>============ /var/log/radius/radacct/x.y.z.a/detail-20080724 =================<br><br>Thu Jul 24 09:48:26 2008<br> Acct-Session-Id = "57000000000008F7"<br> Calling-Station-Id = "4959636156"<br>
Called-Station-Id = "74955891937"<br> Cisco-AVPair = "call-id=<a href="mailto:F217BA24-587A11DD-83D18BF5-E033E9AB@89.208.190.6">F217BA24-587A11DD-83D18BF5-E033E9AB@89.208.190.6</a>"<br> h323-setup-time = ".09:48:16.191 MSD Thu Jul 24 2008"<br>
h323-gw-id = "<a href="http://voice5.di-net.ru">voice5.di-net.ru</a>"<br> h323-conf-id = "F0FF23AA 587A11DD B8009A2D 9B5B4497"<br> h323-call-origin = "originate"<br> h323-call-type = "VoIP"<br>
Cisco-AVPair = "h323-incoming-conf-id=F0FF23AA 587A11DD B8009A2D 9B5B4497"<br> Cisco-AVPair = "subscriber=Unknown"<br> Cisco-AVPair = "session-protocol=sipv2"<br> Cisco-AVPair = "gw-rxd-cdn=ton:0,npi:0,#:74955891937"<br>
User-Name = "4959636156"<br> Cisco-AVPair = "connect-progress=Call Up"<br> Acct-Status-Type = <b>Start</b><br> Service-Type = Login-User<br> NAS-IP-Address = <a href="http://89.208.190.6">89.208.190.6</a><br>
Acct-Delay-Time = 0<br> call-id = "<a href="mailto:F217BA24-587A11DD-83D18BF5-E033E9AB@89.208.190.6">F217BA24-587A11DD-83D18BF5-E033E9AB@89.208.190.6</a>"<br> h323-incoming-conf-id = "F0FF23AA 587A11DD B8009A2D 9B5B4497"<br>
subscriber = "Unknown"<br> session-protocol = "sipv2"<br> gw-rxd-cdn = "ton:0,npi:0,#:74955891937"<br> Client-IP-Address = <a href="http://89.208.190.6">89.208.190.6</a><br>
Acct-Unique-Session-Id = "51b334248c332b3b"<br> Timestamp = 1216878506<br><br>Thu Jul 24 09:48:27 2008<br> Acct-Session-Id = "57000000000008F7"<br> Calling-Station-Id = "4959636156"<br>
Called-Station-Id = "74955891937"<br> Cisco-AVPair = "call-id=<a href="mailto:F217BA24-587A11DD-83D18BF5-E033E9AB@89.208.190.6">F217BA24-587A11DD-83D18BF5-E033E9AB@89.208.190.6</a>"<br> h323-setup-time = ".09:48:16.191 MSD Thu Jul 24 2008"<br>
h323-gw-id = "<a href="http://voice5.di-net.ru">voice5.di-net.ru</a>"<br> h323-conf-id = "F0FF23AA 587A11DD B8009A2D 9B5B4497"<br> h323-call-origin = "originate"<br> h323-call-type = "VoIP"<br>
Cisco-AVPair = "h323-incoming-conf-id=F0FF23AA 587A11DD B8009A2D 9B5B4497"<br> Cisco-AVPair = "subscriber=Unknown"<br> Cisco-AVPair = "session-protocol=sipv2"<br> Cisco-AVPair = "gw-rxd-cdn=ton:0,npi:0,#:74955891937"<br>
Acct-Input-Octets = 0<br> Acct-Output-Octets = 0<br> Acct-Input-Packets = 0<br> Acct-Output-Packets = 0<br> Acct-Session-Time = 0<br> h323-connect-time = ".09:48:16.371 MSD Thu Jul 24 2008"<br>
h323-disconnect-time = ".09:48:16.371 MSD Thu Jul 24 2008"<br> h323-disconnect-cause = "1"<br> h323-remote-address = "<a href="http://89.208.190.4">89.208.190.4</a>"<br>
Cisco-AVPair = "release-source=4"<br> h323-voice-quality = "0"<br> Cisco-AVPair = "gw-rxd-cgn=ton:0,npi:0,pi:1,si:0,#:4959636156"<br> Cisco-AVPair = "gw-final-xlated-cdn=ton:0,npi:0,#:74955891937"<br>
Cisco-AVPair = "gw-final-xlated-cgn=ton:0,npi:0,pi:1,si:0,#:4959636156"<br> User-Name = "4959636156"<br> Acct-Status-Type = <b>Stop</b><br> Service-Type = Login-User<br> NAS-IP-Address = <a href="http://89.208.190.6">89.208.190.6</a><br>
Acct-Delay-Time = 0<br> call-id = "<a href="mailto:F217BA24-587A11DD-83D18BF5-E033E9AB@89.208.190.6">F217BA24-587A11DD-83D18BF5-E033E9AB@89.208.190.6</a>"<br> h323-incoming-conf-id = "F0FF23AA 587A11DD B8009A2D 9B5B4497"<br>
subscriber = "Unknown"<br> session-protocol = "sipv2"<br> gw-rxd-cdn = "ton:0,npi:0,#:74955891937"<br> release-source = "4"<br> gw-rxd-cgn = "ton:0,npi:0,pi:1,si:0,#:4959636156"<br>
gw-final-xlated-cdn = "ton:0,npi:0,#:74955891937"<br> gw-final-xlated-cgn = "ton:0,npi:0,pi:1,si:0,#:4959636156"<br> Client-IP-Address = <a href="http://89.208.190.6">89.208.190.6</a><br>
Acct-Unique-Session-Id = "51b334248c332b3b"<br> Timestamp = 1216878507<br><br>=========== /var/log/radius/sqltrace.sql ================<br><br>INSERT INTO ACC ("ACCT-STATUS-TYPE", "NAS-IP-ADDRESS", "H323-CALL-ORIGIN", "CALLED-STATION-ID", "CALLING-STATION-ID", "ACCT-SESSION-ID", "CALL-ID", "SIP-TO-TAG", "SIP-FROM-TAG", "SIP-TRANSLATED-REQUEST-URI", "USER-NAME", "SIP-SOURCE-IP-ADDRESS", "SIP-SOURCE-PORT", "ACCT-SESSION-TIME", "H323-CONNECT-TIME", "H323-SETUP-TIME", "H323-DISCONNECT-TIME", "H323-DISCONNECT-CAUSE", "IPHOP-COUNT", "IPHOP1", "IPHOP2", "IPHOP3", "H323-CONF-ID") VALUES ('<b>Start</b>', '<a href="http://89.208.190.6">89.208.190.6</a>', 'originate', '74955891937', '4959636156', '57000000000008F7', '<a href="mailto:F217BA24-587A11DD-83D18BF5-E033E9AB@89.208.190.6">F217BA24-587A11DD-83D18BF5-E033E9AB@89.208.190.6</a>', '', '', '', '4959636156', '', '', '', '', '.09:48:16.191 MSD Thu Jul 24 2008', '', '', '', '', '', '', 'F0FF23AA 587A11DD B8009A2D 9B5B4497');<br>
<br>INSERT INTO ACC ("ACCT-STATUS-TYPE", "NAS-IP-ADDRESS", "H323-CALL-ORIGIN", "CALLED-STATION-ID", "CALLING-STATION-ID", "ACCT-SESSION-ID", "CALL-ID", "SIP-TO-TAG", "SIP-FROM-TAG", "SIP-TRANSLATED-REQUEST-URI", "USER-NAME", "SIP-SOURCE-IP-ADDRESS", "SIP-SOURCE-PORT", "ACCT-SESSION-TIME", "H323-CONNECT-TIME", "H323-SETUP-TIME", "H323-DISCONNECT-TIME", "H323-DISCONNECT-CAUSE", "IPHOP-COUNT", "IPHOP1", "IPHOP2", "IPHOP3", "H323-CONF-ID") VALUES ('<b>Stop</b>', '<a href="http://89.208.190.6">89.208.190.6</a>', 'originate', '74955891937', '4959636156', '57000000000008F7', '<a href="mailto:F217BA24-587A11DD-83D18BF5-E033E9AB@89.208.190.6">F217BA24-587A11DD-83D18BF5-E033E9AB@89.208.190.6</a>', '', '', '', '4959636156', '', '', '0', '.09:48:16.371 MSD Thu Jul 24 2008', '.09:48:16.191 MSD Thu Jul 24 2008', '.09:48:16.371 MSD Thu Jul 24 2008', '1', '', '', '', '', 'F0FF23AA 587A11DD B8009A2D 9B5B4497');<br>
<br>========== Data from DB acc table ===========<br><br><span style="font-family: courier new,monospace;"> ID ACCT-STATUS-TYPE CALL-ID H323-CONF-ID </span><br style="font-family: courier new,monospace;">
<span style="font-family: courier new,monospace;">---------- ------------------ ----------------------------------------------------- ------------------------------------</span><br style="font-family: courier new,monospace;">
<span style="font-family: courier new,monospace;"></span><span style="font-family: courier new,monospace;"> 1134861 Stop <a href="mailto:F217BA24-587A11DD-83D18BF5-E033E9AB@89.208.190.6">F217BA24-587A11DD-83D18BF5-E033E9AB@89.208.190.6</a> F0FF23AA 587A11DD B8009A2D 9B5B4497 </span><br style="font-family: courier new,monospace;">
<span style="font-family: courier new,monospace;"> 1134862 Start <a href="mailto:F217BA24-587A11DD-83D18BF5-E033E9AB@89.208.190.6">F217BA24-587A11DD-83D18BF5-E033E9AB@89.208.190.6</a> F0FF23AA 587A11DD B8009A2D 9B5B4497 </span><br style="font-family: courier new,monospace;">
<span style="font-family: courier new,monospace;"></span><br style="font-family: courier new,monospace;">ID here is autoincremented primary key (taked from sequence - DB is Oracle 10gR2). As you can see, right order of packets ("Start" then "Stop") is violated. I need them to appear in database in right order. This error happens once-twice per day, in hours of medium load average. Maybe I should use only one database connection in oracle_sql (now num_sql_socks=5 in /etc/raddb/oraclesql.conf)? Or what it can be? Give me a clue, please <br>
<br>Server info:<br>FreeRADIUS Version 1.1.7, for host x86_64-redhat-linux-gnu, built on Jan 31 2008 at 18:32:53<br>CentOS release 5.2 (Final)<br>Linux Registrar 2.6.18-53.1.21.el5 #1 SMP Tue May 20 09:35:07 EDT 2008 x86_64 x86_64 x86_64 GNU/Linux<br>
<br></div>