On Fri, Jan 25, 2008 at 02:35:17PM +0100, Alan DeKok wrote:
A.L.M.Buxey@lboro.ac.uk wrote:
Thu Jan 24 22:41:00 2008 : Error: rlm_eap: Failed to remember handler!
Hmm... that means it's out of memory, or that there are multiple EAP sessions that match the same NAS IP/EAP Id combination. Weird.
Speaking of out of memory, I had 2.0.0pre2 exit for lack of memory on me a few nights ago, after a few months of runtime doing PPP authentication with users stored in MySQL. We don't have any fancy stuff. I didn't mention it because it's probably already fixed in the released version of 2.0. I don't know how large the process had grown. I hadn't logged into that box in a month. That box has 2GB RAM, but RADIUS isn't the only thing running there. But to run out of memory would have taken some doing. Mem: 302M Active, 1270M Inact, 222M Wired, 61M Cache, 112M Buf, 146M Free Swap: 4096M Total, 696K Used, 4095M Free On FreeBSD, the Inact memory pool is available to any process which wants it. Until something wants memory, FreeBSD keeps it around as a sort of cache. Currently there is about 1416MB of RAM available. Just in case you might think it is interesting, here are current snapshots of memory usage; still running 2.0.0pre2. The box that ran out of memory: USER PID %CPU %MEM VSZ RSS TT STAT STARTED TIME COMMAND root 7992 0.0 0.7 15820 14148 ?? SsJ Wed06PM 0:51.58 /usr/local/sbin/radiusd That's been running since 6PM Wednesday January 23 when I manually rotated the log files. The secondary box: USER PID %CPU %MEM VSZ RSS TT STAT STARTED TIME COMMAND root 77985 0.0 4.2 25824 21608 ?? Ss 14Jan08 4:09.63 /usr/local/sbin/radiusd Mon Jan 21 00:05:06 2008 : Auth: Login incorrect: [sonya@blah.net] (from client router port 1008) Mon Jan 21 00:05:06 2008 : Auth: Login incorrect (rlm_pap: CRYPT password check failed): [whitencl@sample.net] (from client router port 862) Mon Jan 21 00:05:06 2008 : Auth: Login incorrect (rlm_pap: CRYPT password check failed): [whitencl@sample.net] (from client router port 862) Mon Jan 21 00:05:07 2008 : Auth: Login incorrect (rlm_pap: CRYPT password check failed): [whitencl@sample.net] (from client router port 862) Mon Jan 21 00:05:08 2008 : Auth: Login incorrect: [bmcquire@blah.net] (from client router port 1653) Mon Jan 21 00:05:08 2008 : Auth: Login incorrect (rlm_pap: CRYPT password check failed): [peggydon@sample.net] (from client router port 816) Mon Jan 21 00:05:08 2008 : Error: rlm_radutmp: Logout for NAS router port 268566701, but no Login record Mon Jan 21 00:05:08 2008 : Auth: Login incorrect: [sample.net@blah.net] (from client router port 858) Mon Jan 21 00:05:08 2008 : Auth: Login incorrect: [bmcquire@blah.net] (from client router port 1653) Mon Jan 21 00:05:08 2008 : Auth: Login incorrect: [bmcquire@blah.net] (from client router port 1653) Mon Jan 21 00:05:08 2008 : Error: rlm_sql: Failed to create the pair: out of memory Mon Jan 21 00:05:08 2008 : Error: rlm_sql (sql): Error getting data from database Mon Jan 21 00:05:08 2008 : Error: rlm_sql (sql): Error retrieving reply pairs for group dsl Mon Jan 21 00:05:08 2008 : Error: rlm_sql (sql): Error processing groups; rejecting user Mon Jan 21 00:05:08 2008 : Auth: Login OK: [Leotus@blah.net] (from client router port 929) Mon Jan 21 00:05:09 2008 : Auth: Login incorrect: [sonya@blah.net] (from client router port 1008) Mon Jan 21 00:05:09 2008 : Auth: Login incorrect: [blither.net@blah.net] (from client router port 528) Mon Jan 21 00:05:09 2008 : Error: rlm_sql: Failed to create the pair: out of memory Mon Jan 21 00:05:09 2008 : Error: rlm_sql (sql): Error getting data from database Mon Jan 21 00:05:09 2008 : Error: rlm_sql (sql): SQL query error; rejecting user Mon Jan 21 00:05:09 2008 : Error: rlm_radutmp: Logout entry for NAS router port 271777916 has wrong ID Mon Jan 21 00:05:09 2008 : Error: no memory Mon Jan 21 03:07:59 2008 : Info: Starting - reading configuration files ... Mon Jan 21 03:07:59 2008 : Info: rlm_exec: wait=yes but no output defined. Did you mean output=none? Mon Jan 21 03:08:00 2008 : Info: rlm_eap_tls: Loading the certificate file as a chain Mon Jan 21 03:08:00 2008 : Info: rlm_sql (sql): Driver rlm_sql_mysql (module rlm_sql_mysql) loaded and linked Mon Jan 21 03:08:00 2008 : Info: rlm_sql (sql): Attempting to connect to radius@127.0.0.1:/radius Mon Jan 21 03:08:00 2008 : Info: rlm_sql_mysql: Starting connect to MySQL server for #0 Mon Jan 21 03:08:00 2008 : Info: rlm_sql_mysql: Starting connect to MySQL server for #1 Mon Jan 21 03:08:00 2008 : Info: rlm_sql_mysql: Starting connect to MySQL server for #2 Mon Jan 21 03:08:00 2008 : Info: rlm_sql_mysql: Starting connect to MySQL server for #3 Mon Jan 21 03:08:00 2008 : Info: rlm_sql_mysql: Starting connect to MySQL server for #4 -- Scott Lambert KC5MLE Unix SysAdmin lambert@lambertfam.org