<html xmlns:v="urn:schemas-microsoft-com:vml" xmlns:o="urn:schemas-microsoft-com:office:office" xmlns:w="urn:schemas-microsoft-com:office:word" xmlns:m="http://schemas.microsoft.com/office/2004/12/omml" xmlns="http://www.w3.org/TR/REC-html40">

<head>
<meta http-equiv=Content-Type content="text/html; charset=us-ascii">
<meta name=Generator content="Microsoft Word 12 (filtered medium)">
<style>
<!--
 /* Font Definitions */
 @font-face
        {font-family:Calibri;
        panose-1:2 15 5 2 2 2 4 3 2 4;}
 /* Style Definitions */
 p.MsoNormal, li.MsoNormal, div.MsoNormal
        {margin:0in;
        margin-bottom:.0001pt;
        font-size:11.0pt;
        font-family:"Calibri","sans-serif";}
a:link, span.MsoHyperlink
        {mso-style-priority:99;
        color:blue;
        text-decoration:underline;}
a:visited, span.MsoHyperlinkFollowed
        {mso-style-priority:99;
        color:purple;
        text-decoration:underline;}
span.EmailStyle17
        {mso-style-type:personal-compose;
        font-family:"Calibri","sans-serif";
        color:windowtext;}
.MsoChpDefault
        {mso-style-type:export-only;}
@page Section1
        {size:8.5in 11.0in;
        margin:1.0in 1.0in 1.0in 1.0in;}
div.Section1
        {page:Section1;}
-->
</style>
<!--[if gte mso 9]><xml>
 <o:shapedefaults v:ext="edit" spidmax="1026" />
</xml><![endif]--><!--[if gte mso 9]><xml>
 <o:shapelayout v:ext="edit">
  <o:idmap v:ext="edit" data="1" />
 </o:shapelayout></xml><![endif]-->
</head>

<body lang=EN-US link=blue vlink=purple>

<div class=Section1>

<p class=MsoNormal>I am having a problem with the SQLIPPOOL performance. 
This is migration of an existing radius server using flat user files (old
server is running radius 1.1.0).<o:p></o:p></p>

<p class=MsoNormal><o:p> </o:p></p>

<p class=MsoNormal>Running freeradius-server-snapshot-20070705 on a P4 2.8GHZ
machine<o:p></o:p></p>

<p class=MsoNormal>Database is Postgres 8.1.9 running on a monstrous Sun Opteron
machine.<o:p></o:p></p>

<p class=MsoNormal><o:p> </o:p></p>

<p class=MsoNormal>When radius starts taking requests (it’s  a lot
of requests, about 500/sec), the load on the radius server spikes to 100 and it
eventually stops taking requests completely.  The database is never above
0.05 load.<o:p></o:p></p>

<p class=MsoNormal><o:p> </o:p></p>

<p class=MsoNormal>I see messages like these in the radius logs:<o:p></o:p></p>

<p class=MsoNormal>Wed Jul 25 10:29:15 2007 : Info: IP Allocation FAILED from
general_pool   (did slogic.t-mobile.com cli 14696841693 port 0 user
14696841693)<o:p></o:p></p>

<p class=MsoNormal>Wed Jul 25 10:29:15 2007 : Info: IP Allocation FAILED from
general_pool   (did slogic.t-mobile.com cli 19723023688 port 0 user
19723023688)<o:p></o:p></p>

<p class=MsoNormal>Wed Jul 25 10:29:15 2007 : Info: IP Allocation FAILED from
general_pool   (did slogic.t-mobile.com cli 14696845996 port 0 user
14696845996)<o:p></o:p></p>

<p class=MsoNormal>Wed Jul 25 10:29:15 2007 : Info: IP Allocation FAILED from
general_pool   (did slogic.t-mobile.com cli 14696846177 port 0 user
14696846177)<o:p></o:p></p>

<p class=MsoNormal>Wed Jul 25 10:29:15 2007 : Info: IP Allocation FAILED from
general_pool   (did slogic.t-mobile.com cli 14696825390 port 0 user
14696825390)<o:p></o:p></p>

<p class=MsoNormal>Wed Jul 25 10:29:15 2007 : Info: IP Allocation FAILED from
general_pool   (did slogic.t-mobile.com cli 14694415538 port 0 user
14694415538)<o:p></o:p></p>

<p class=MsoNormal>Wed Jul 25 10:29:15 2007 : Info: IP Allocation FAILED from
general_pool   (did slogic.t-mobile.com cli 14696883664 port 0 user
14696883664)<o:p></o:p></p>

<p class=MsoNormal>Wed Jul 25 10:29:15 2007 : Info: IP Allocation FAILED from
general_pool   (did slogic.t-mobile.com cli 12149915071 port 0 user
12149915071)<o:p></o:p></p>

<p class=MsoNormal>Wed Jul 25 10:29:15 2007 : Info: IP Allocation FAILED from
general_pool   (did slogic.t-mobile.com cli 19728908614 port 0 user
19728908614)<o:p></o:p></p>

<p class=MsoNormal>Wed Jul 25 10:29:15 2007 : Info: IP Allocation FAILED from
general_pool   (did slogic.t-mobile.com cli 19723023948 port 0 user
19723023948)<o:p></o:p></p>

<p class=MsoNormal>Wed Jul 25 10:29:15 2007 : Error: Discarding duplicate
request from client nas01 port 1812 - ID: 102 due to unfinished request 3988<o:p></o:p></p>

<p class=MsoNormal>Wed Jul 25 10:29:15 2007 : Error: Discarding duplicate
request from client nas01 port 1812 - ID: 62 due to unfinished request 3987<o:p></o:p></p>

<p class=MsoNormal>Wed Jul 25 10:29:15 2007 : Error: Discarding duplicate
request from client nas01 port 1812 - ID: 116 due to unfinished request 3989<o:p></o:p></p>

<p class=MsoNormal>Wed Jul 25 10:29:15 2007 : Error: Discarding duplicate
request from client nas01 port 1812 - ID: 121 due to unfinished request 3990<o:p></o:p></p>

<p class=MsoNormal>Wed Jul 25 10:29:15 2007 : Error: Discarding duplicate
request from client nas01 port 1812 - ID: 26 due to unfinished request 3991<o:p></o:p></p>

<p class=MsoNormal>Wed Jul 25 10:29:15 2007 : Info: rlm_sql (sql): There are no
DB handles to use! skipped 0, tried to connect 0<o:p></o:p></p>

<p class=MsoNormal>Wed Jul 25 10:29:15 2007 : Info: rlm_sql (sql): There are no
DB handles to use! skipped 0, tried to connect 0<o:p></o:p></p>

<p class=MsoNormal>Wed Jul 25 10:29:15 2007 : Info: rlm_sql (sql): There are no
DB handles to use! skipped 0, tried to connect 0<o:p></o:p></p>

<p class=MsoNormal>Wed Jul 25 10:29:15 2007 : Info: rlm_sql (sql): There are no
DB handles to use! skipped 0, tried to connect 0<o:p></o:p></p>

<p class=MsoNormal>Wed Jul 25 10:29:15 2007 : Info: rlm_sql (sql): There are no
DB handles to use! skipped 0, tried to connect 0<o:p></o:p></p>

<p class=MsoNormal>Wed Jul 25 10:29:15 2007 : Info: rlm_sql (sql): There are no
DB handles to use! skipped 0, tried to connect 0<o:p></o:p></p>

<p class=MsoNormal>Wed Jul 25 10:29:15 2007 : Info: rlm_sql (sql): There are no
DB handles to use! skipped 0, tried to connect 0<o:p></o:p></p>

<p class=MsoNormal>Wed Jul 25 10:29:15 2007 : Info: rlm_sql (sql): There are no
DB handles to use! skipped 0, tried to connect 0<o:p></o:p></p>

<p class=MsoNormal>Wed Jul 25 10:29:15 2007 : Info: rlm_sql (sql): There are no
DB handles to use! skipped 0, tried to connect 0<o:p></o:p></p>

<p class=MsoNormal>Wed Jul 25 10:29:15 2007 : Info: rlm_sql (sql): There are no
DB handles to use! skipped 0, tried to connect 0<o:p></o:p></p>

<p class=MsoNormal>Wed Jul 25 10:29:15 2007 : Error: Discarding duplicate
request from client nas01 port 1812 - ID: 105 due to unfinished request 3992<o:p></o:p></p>

<p class=MsoNormal>Wed Jul 25 10:29:15 2007 : Error: Discarding duplicate
request from client nas01 port 1812 - ID: 74 due to unfinished request 3993<o:p></o:p></p>

<p class=MsoNormal>Wed Jul 25 10:29:15 2007 : Error: Discarding duplicate
request from client nas01 port 1812 - ID: 29 due to unfinished request 3994<o:p></o:p></p>

<p class=MsoNormal>Wed Jul 25 10:29:15 2007 : Info: rlm_sql (sql): There are no
DB handles to use! skipped 0, tried to connect 0<o:p></o:p></p>

<p class=MsoNormal>Wed Jul 25 10:29:15 2007 : Auth: Login OK: [12146063348]
(from client nas01 port 0 cli 12146063348)<o:p></o:p></p>

<p class=MsoNormal><o:p> </o:p></p>

<p class=MsoNormal>I have tried playing with the numbers of threads (I used the
current radius servers setting to start) and the results are the same.<o:p></o:p></p>

<p class=MsoNormal><o:p> </o:p></p>

<p class=MsoNormal>Anyone have any ideas?<o:p></o:p></p>

<p class=MsoNormal><o:p> </o:p></p>

<p class=MsoNormal>Thanks,<o:p></o:p></p>

<p class=MsoNormal>Roy<o:p></o:p></p>

</div>

</body>

</html>