SQLIPPool performance issue

[Roy Walker]

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).

 

Running freeradius-server-snapshot-20070705 on a P4 2.8GHZ machine

Database is Postgres 8.1.9 running on a monstrous Sun Opteron machine.

 

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.

 

I see messages like these in the radius logs:

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)

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)

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)

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)

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)

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)

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)

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)

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)

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)

Wed Jul 25 10:29:15 2007 : Error: Discarding duplicate request from
client nas01 port 1812 - ID: 102 due to unfinished request 3988

Wed Jul 25 10:29:15 2007 : Error: Discarding duplicate request from
client nas01 port 1812 - ID: 62 due to unfinished request 3987

Wed Jul 25 10:29:15 2007 : Error: Discarding duplicate request from
client nas01 port 1812 - ID: 116 due to unfinished request 3989

Wed Jul 25 10:29:15 2007 : Error: Discarding duplicate request from
client nas01 port 1812 - ID: 121 due to unfinished request 3990

Wed Jul 25 10:29:15 2007 : Error: Discarding duplicate request from
client nas01 port 1812 - ID: 26 due to unfinished request 3991

Wed Jul 25 10:29:15 2007 : Info: rlm_sql (sql): There are no DB handles
to use! skipped 0, tried to connect 0

Wed Jul 25 10:29:15 2007 : Info: rlm_sql (sql): There are no DB handles
to use! skipped 0, tried to connect 0

Wed Jul 25 10:29:15 2007 : Info: rlm_sql (sql): There are no DB handles
to use! skipped 0, tried to connect 0

Wed Jul 25 10:29:15 2007 : Info: rlm_sql (sql): There are no DB handles
to use! skipped 0, tried to connect 0

Wed Jul 25 10:29:15 2007 : Info: rlm_sql (sql): There are no DB handles
to use! skipped 0, tried to connect 0

Wed Jul 25 10:29:15 2007 : Info: rlm_sql (sql): There are no DB handles
to use! skipped 0, tried to connect 0

Wed Jul 25 10:29:15 2007 : Info: rlm_sql (sql): There are no DB handles
to use! skipped 0, tried to connect 0

Wed Jul 25 10:29:15 2007 : Info: rlm_sql (sql): There are no DB handles
to use! skipped 0, tried to connect 0

Wed Jul 25 10:29:15 2007 : Info: rlm_sql (sql): There are no DB handles
to use! skipped 0, tried to connect 0

Wed Jul 25 10:29:15 2007 : Info: rlm_sql (sql): There are no DB handles
to use! skipped 0, tried to connect 0

Wed Jul 25 10:29:15 2007 : Error: Discarding duplicate request from
client nas01 port 1812 - ID: 105 due to unfinished request 3992

Wed Jul 25 10:29:15 2007 : Error: Discarding duplicate request from
client nas01 port 1812 - ID: 74 due to unfinished request 3993

Wed Jul 25 10:29:15 2007 : Error: Discarding duplicate request from
client nas01 port 1812 - ID: 29 due to unfinished request 3994

Wed Jul 25 10:29:15 2007 : Info: rlm_sql (sql): There are no DB handles
to use! skipped 0, tried to connect 0

Wed Jul 25 10:29:15 2007 : Auth: Login OK: [12146063348] (from client
nas01 port 0 cli 12146063348)

 

I have tried playing with the numbers of threads (I used the current
radius servers setting to start) and the results are the same.

 

Anyone have any ideas?

 

Thanks,

Roy

-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.freeradius.org/pipermail/freeradius-users/attachments/20070725/4c70eac0/attachment.html>