Unexpected closed sessions in database

[Marinko Tarlac]

I have at
Tue Feb 17 11:22:44 2009 and
Tue Feb 17 11:24:14 2009 which is OK because the updates are set to every 90
seconds.

According to database the session was closed at 2009-02-17 11:23:16

After Tue Feb 17 11:24:14 2009, radius still receives regular updates.
acctterminatecause is empty and when I kill this session in NAS list, I see
that Stop was received and the same session is updated (acctterminatecause
and AcctStopTime was updated which means that NAS didn't lost this session
and still has its unique ID.)

Huh... :)

Database and server was at 0.50 load (average) and it works perfectly.
Radius log is empty and I don't see anything inside... Except ...

Tue Feb 17 04:00:06 2009 : Error: Received conflicting packet from client
pppoe port 55074 - ID: 133 due to unfinished request 301628.  Giving up o$
Tue Feb 17 04:00:06 2009 : Error: Received conflicting packet from client
pppoe port 60609 - ID: 134 due to unfinished request 301629.  Giving up o$
Tue Feb 17 04:00:06 2009 : Info: rlm_sql (sql): There are no DB handles to
use! skipped 0, tried to connect 0
Tue Feb 17 04:00:07 2009 : Error: Received conflicting packet from client
pppoe port 50529 - ID: 136 due to unfinished request 301631.  Giving up o$
Tue Feb 17 04:00:07 2009 : Error: Received conflicting packet from client
pppoe port 46437 - ID: 137 due to unfinished request 301632.  Giving up o$
Tue Feb 17 04:00:07 2009 : Error: Received conflicting packet from client
pppoe port 51690 - ID: 135 due to unfinished request 301630.  Giving up o$
Tue Feb 17 04:00:07 2009 : Info: rlm_sql (sql): There are no DB handles to
use! skipped 0, tried to connect 0
Tue Feb 17 04:00:08 2009 : Info: rlm_sql (sql): There are no DB handles to
use! skipped 0, tried to connect 0
Tue Feb 17 04:00:08 2009 : Error: Received conflicting packet from client
pppoe port 56049 - ID: 138 due to unfinished request 301633.  Giving up o$
Tue Feb 17 04:00:08 2009 : Error: Received conflicting packet from client
pppoe port 44656 - ID: 139 due to unfinished request 301634.  Giving up o$
Tue Feb 17 04:00:09 2009 : Info: rlm_sql (sql): There are no DB handles to
use! skipped 0, tried to connect 0
Tue Feb 17 04:00:09 2009 : Error: Received conflicting packet from client
pppoe port 34330 - ID: 141 due to unfinished request 301636.  Giving up o$
Tue Feb 17 04:00:09 2009 : Error: Received conflicting packet from client
pppoe port 39034 - ID: 140 due to unfinished request 301635.  Giving up o$
Tue Feb 17 04:00:09 2009 : Error: Received conflicting packet from client
pppoe port 55074 - ID: 133 due to unfinished request 301637.  Giving up o$
Tue Feb 17 04:00:10 2009 : Info: rlm_sql (sql): There are no DB handles to
use! skipped 0, tried to connect 0
Tue Feb 17 04:00:11 2009 : Info: rlm_sql (sql): There are no DB handles to
use! skipped 0, tried to connect 0
Tue Feb 17 04:00:12 2009 : Info: rlm_sql (sql): There are no DB handles to
use! skipped 0, tried to connect 0
Tue Feb 17 04:00:13 2009 : Info: rlm_sql (sql): There are no DB handles to
use! skipped 0, tried to connect 0
Tue Feb 17 04:00:14 2009 : Info: rlm_sql (sql): There are no DB handles to
use! skipped 0, tried to connect 0
Tue Feb 17 04:00:15 2009 : Info: rlm_sql (sql): There are no DB handles to
use! skipped 0, tried to connect 0
Tue Feb 17 04:00:16 2009 : Info: rlm_sql (sql): There are no DB handles to
use! skipped 0, tried to connect 0
Tue Feb 17 04:00:17 2009 : Info: rlm_sql (sql): There are no DB handles to
use! skipped 0, tried to connect 0
Tue Feb 17 04:00:18 2009 : Info: rlm_sql (sql): There are no DB handles to
use! skipped 0, tried to connect 0
Tue Feb 17 04:00:19 2009 : Info: rlm_sql (sql): There are no DB handles to
use! skipped 0, tried to connect 0
Tue Feb 17 04:00:20 2009 : Info: rlm_sql (sql): There are no DB handles to
use! skipped 0, tried to connect 0

which is coused by a sheduled backup at 4:00:00 in the morning :)

Thanks for your time in any case.... :)

On Wed, Feb 18, 2009 at 1:23 PM, <tnt at kalik.net> wrote:

> >For start record I have
> >
> >Tue Feb 17 00:21:11 2009
> >    Service-Type = Framed-User
> >    Framed-Protocol = PPP
> >    NAS-Port = 416808
> >    NAS-Port-Type = Ethernet
> >    User-Name = "branka"
> >    Calling-Station-Id = "00:4F:62:09:3C:C9"
> >    NAS-Port-Id = "konc-javne"
> >    Acct-Session-Id = "8135a552"
> >    Framed-IP-Address = 192.168.98.165
> >    Acct-Authentic = RADIUS
> >    Event-Timestamp = "Feb 17 2009 02:20:53 CET"
> >    Acct-Status-Type = Start
> >    NAS-Identifier = "1.24 - PPPoE"
> >    NAS-IP-Address = 192.168.1.24
> >    Acct-Delay-Time = 0
> >    Acct-Unique-Session-Id = "1582d68f4c109a25"
> >    Timestamp = 1234826471
> >    Request-Authenticator = Verified
> >
> >and during the whole day, I receive regular updates
> >
> >Tue Feb 17 02:22:42 2009
> >    Service-Type = Framed-User
> >    Framed-Protocol = PPP
> >    NAS-Port = 416808
> >    NAS-Port-Type = Ethernet
> >    User-Name = "branka"
> >    Calling-Station-Id = "00:4F:62:09:3C:C9"
> >    NAS-Port-Id = "konc-javne"
> >    Acct-Session-Id = "8135a552"
> >    Framed-IP-Address = 192.168.98.165
> >    Acct-Authentic = RADIUS
> >    Event-Timestamp = "Feb 17 2009 04:22:23 CET"
> >    Acct-Session-Time = 7291
> >    Acct-Input-Octets = 4618
> >    Acct-Input-Gigawords = 0
> >    Acct-Input-Packets = 34
> >    Acct-Output-Octets = 5882
> >    Acct-Output-Gigawords = 0
> >    Acct-Output-Packets = 41
> >    Acct-Status-Type = Interim-Update
> >    NAS-Identifier = "1.24 - PPPoE"
> >    NAS-IP-Address = 192.168.1.24
> >    Acct-Delay-Time = 0
> >    Acct-Unique-Session-Id = "1582d68f4c109a25"
> >    Timestamp = 1234833762
> >    Request-Authenticator = Verified
> >
> >At 11:23:16 (according to database) the session was closed and I cant find
> >Stop or Accounting-Off. NAS continues to send updates at regular basis.
>
> Is there an update packet at that time?
>
> Ivan Kalik
> Kalik Informatika ISP
>
> -
> List info/subscribe/unsubscribe? See
> http://www.freeradius.org/list/users.html
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.freeradius.org/pipermail/freeradius-users/attachments/20090218/f785bd61/attachment.html>