<p class="MsoNormal"><span>Hi</span></p><p class="MsoNormal"><span dir="RTL" lang="FA"> </span></p><p class="MsoNormal"><span>I’m
running freeradius 1.1.8 with rlm_exec. External program on ACCTOUNTING
packets runs and my external program returns OK! And printout the
following in its logfile:</span></p><p class="MsoNormal"><span style="font-size:9.0pt"> </span></p><p class="MsoNormal"><span style="font-size:9.0pt;font-family:"Courier New"">(PID 5036) 2011-09-15 18:24:28 Packet: updt UserName: hhsde0100413@tct9 <a href="mailto:187923@172.31.3.7" target="_blank">187923@172.31.3.7</a> 10320 Sec 3993228/61717432 Bytes</span></p>
<p class="MsoNormal"> </p><p class="MsoNormal"><span>FreeRADIUS Debugging shows log as the following and shows everything goes fine for that request. </span></p><p class="MsoNormal" dir="RTL" style="text-align:right;direction:rtl">
<span dir="LTR"> </span></p><p class="MsoNormal"><span style="font-size:9.0pt;font-family:"Courier New"">Thu Sep 15 18:24:28 2011 : Debug: Thread 1 handling request 175, (31 handled so far)</span></p><p class="MsoNormal">
<span style="font-size:9.0pt;font-family:"Courier New""> User-Name = "hhsde0100413@tct9"</span></p><p class="MsoNormal"><span style="font-size:9.0pt;font-family:"Courier New""> NAS-Port = 136339956</span></p>
<p class="MsoNormal"><span style="font-size:9.0pt;font-family:"Courier New""> NAS-IP-Address = 172.31.3.7</span></p><p class="MsoNormal"><span style="font-size:9.0pt;font-family:"Courier New""> Framed-IP-Address = 2.179.17.211</span></p>
<p class="MsoNormal"><span style="font-size:9.0pt;font-family:"Courier New""> Filter-Id = "tct9"</span></p><p class="MsoNormal"><span style="font-size:9.0pt;font-family:"Courier New""> Class = 0x68687364653031303034313340746374397c387c3137322e33312e332e377c316361666637623036653264</span></p>
<p class="MsoNormal"><span style="font-size:9.0pt;font-family:"Courier New""> rad_recv: Accounting-Request packet from host <a href="http://172.31.3.7:1813/" target="_blank">172.31.3.7:1813</a>, id=92, length=530</span></p>
<p class="MsoNormal"><span style="font-size:9.0pt;font-family:"Courier New"">NAS-Identifier = "KJ-RJ-ME60X16-01"</span></p><p class="MsoNormal"><span style="font-size:9.0pt;font-family:"Courier New""> Acct-Status-Type = Interim-Update</span></p>
<p class="MsoNormal"><span style="font-size:9.0pt;font-family:"Courier New""> Acct-Delay-Time = 0</span></p><p class="MsoNormal"><span style="font-size:9.0pt;font-family:"Courier New""> Acct-Input-Octets = 3993228</span></p>
<p class="MsoNormal"><span style="font-size:9.0pt;font-family:"Courier New""> Acct-Output-Octets = 61717432</span></p><p class="MsoNormal"><span style="font-size:9.0pt;font-family:"Courier New""> Acct-Session-Id = "KJ-RJ-M08206050000000890a6e187923"</span></p>
<p class="MsoNormal"><span style="font-size:9.0pt;font-family:"Courier New""> Acct-Authentic = RADIUS</span></p><p class="MsoNormal"><span style="font-size:9.0pt;font-family:"Courier New""> Acct-Session-Time = 10320</span></p>
<p class="MsoNormal"><span style="font-size:9.0pt;font-family:"Courier New""> Acct-Input-Packets = 45602</span></p><p class="MsoNormal"><span style="font-size:9.0pt;font-family:"Courier New""> Acct-Output-Packets = 50322</span></p>
<p class="MsoNormal"><span style="font-size:9.0pt;font-family:"Courier New""> Acct-Input-Gigawords = 0</span></p><p class="MsoNormal"><span style="font-size:9.0pt;font-family:"Courier New""> Acct-Output-Gigawords = 0</span></p>
<p class="MsoNormal"><span style="font-size:9.0pt;font-family:"Courier New""> </span></p><p class="MsoNormal"><span style="font-size:9.0pt;font-family:"Courier New"">Thu Sep 15 18:24:28 2011 : Debug: Thread 1 handling request 175, (31 handled so far)</span></p>
<p class="MsoNormal"><span style="font-size:9.0pt;font-family:"Courier New"">Thu Sep 15 18:24:28 2011 : Debug: modcall: entering group preacct for request 175</span></p><p class="MsoNormal"><span style="font-size:9.0pt;font-family:"Courier New"">Thu Sep 15 18:24:28 2011 : Debug: modsingle[preacct]: calling preprocess (rlm_preprocess) for request 175</span></p>
<p class="MsoNormal"><span style="font-size:9.0pt;font-family:"Courier New"">
Acct-Session-Time = 10320Thu Sep 15 18:24:28 2011 : Debug:
modsingle[preacct]: returned from preprocess (rlm_preprocess) for
request 175</span></p><p class="MsoNormal"><span style="font-size:9.0pt;font-family:"Courier New""> Thu Sep 15 18:24:28 2011 : Debug: modcall[preacct]: module "preprocess" returns noop for request 175</span></p>
<p class="MsoNormal"><span style="font-size:9.0pt;font-family:"Courier New"">Thu Sep 15 18:24:28 2011 : Debug: modsingle[preacct]: calling acct_unique (rlm_acct_unique) for request 175</span></p><p class="MsoNormal">
<span style="font-size:9.0pt;font-family:"Courier New""> Thu Sep 15 18:24:28 2011 : Debug: modsingle[preacct]: returned from acct_unique (rlm_acct_unique) for request 175</span></p><p class="MsoNormal">
<span style="font-size:9.0pt;font-family:"Courier New""> Thu Sep 15 18:24:28 2011 : Debug: modcall[preacct]: module "acct_unique" returns ok for request 175</span></p><p class="MsoNormal"><span style="font-size:9.0pt;font-family:"Courier New""> Thu Sep 15 18:24:28 2011 : Debug: modcall: leaving group preacct (returns ok) for request 175</span></p>
<p class="MsoNormal"><span style="font-size:9.0pt;font-family:"Courier New""> Thu Sep 15 18:24:28 2011 : Debug: modcall: entering group accounting for request 175</span></p><p class="MsoNormal"><span style="font-size:9.0pt;font-family:"Courier New""> Thu Sep 15 18:24:28 2011 : Debug: modsingle[accounting]: calling detail (rlm_detail) for request 175</span></p>
<p class="MsoNormal"><span style="font-size:9.0pt;font-family:"Courier New"">Thu Sep 15 18:24:28 2011 : Debug: modsingle[accounting]: returned from detail (rlm_detail) for request 175</span></p><p class="MsoNormal">
<span style="font-size:9.0pt;font-family:"Courier New"">Thu Sep 15 18:24:28 2011 : Debug: modcall[accounting]: module "detail" returns ok for request 175</span></p><p class="MsoNormal"><span style="font-size:9.0pt;font-family:"Courier New"">Thu Sep 15 18:24:28 2011 : Debug: modsingle[accounting]: calling netbill_acct (rlm_exec) for request 175</span></p>
<p class="MsoNormal"><span style="font-family:"Courier New""> </span></p><p class="MsoNormal"><span>Tcpdump shows the accounting packet issued and returned back to the RAS:</span></p><p class="MsoNormal"> </p><p class="MsoNormal">
<span dir="RTL" style="font-size:10.0pt;font-family:"Courier New"" lang="FA"> </span></p><p class="MsoNormal"><span style="font-size:9.0pt;font-family:"Courier New"">IP 172.31.3.7.radius-acct > 172.26.60.3.radius-acct: RADIUS, Accounting Request (4), id: 0xx length: 527</span></p>
<p class="MsoNormal"><span style="font-size:9.0pt;font-family:"Courier New"">IP 172.26.60.3.radius-acct > 172.31.3.7.radius-acct: RADIUS, Accounting Response (5), id: 0xxx length: 20<span dir="RTL" lang="FA"></span></span></p>
<p class="MsoNormal"> </p><p class="MsoNormal"><span>But after about 30 secound, I got the following error in freeRADIUS’s log!!!! </span></p><p class="MsoNormal"><span dir="RTL" lang="FA"> </span></p><p class="MsoNormal">
<span style="font-size:9.0pt;font-family:"Courier New"">Thu
Sep 15 18:24:59 2011 : Error: WARNING: Unresponsive child (id
3086457744) for request 175 (in component accounting module rlm_exec)
hhsde0100413@tct9 Thu Sep 15 18:24:59 2011 : Debug: Server
rejecting request 175.</span></p><p class="MsoNormal"> </p><p class="MsoNormal"><span>And then shows:</span></p><p class="MsoNormal"> </p><p class="MsoNormal"><span style="font-size:9.0pt">Thu Sep 15 18:43:54 2011 : Error: Dropping conflicting packet from client </span><span style="font-size:9.0pt">172.31.3.7</span><span style="font-size:9.0pt">:1646 - ID: 151 due to unfinished request 175</span></p>
<p class="MsoNormal"> </p><span>My
external program returns 0 (means OK) and freeRADIUS getting it and
returns back to the BRAS, so why freeradius gives such error?!<br><br><br>Kind Regards,<br>Ali Majdzadeh Kohbanani<br></span>