>From owner-radiator Thu May 25 14:10:23 2000
Received: by oscar.open.com.au (8.9.0/8.9.0) id OAA23198;
Thu, 25 May 2000 14:10:22 +1000 (EST)
>Received: from mailman.ghg.net (mailman.ghg.net [206.29.116.30]) by
>perki.connect.com.au with ESMTP id NAA08346
(8.8.8/IDA-1.7); Thu, 25 May 2000 13:50:18 +1000 (EST)
Received: from mailman.ghg.net (mailman.ghg.net [206.29.116.30]) by
perki.connect.com.au with ESMTP id NAA08346
(8.8.8/IDA-1.7); Thu, 25 May 2000 13:50:18 +1000 (EST)
Received: from localhost by mailman.ghg.net (8.9.1/8.9.1/GHG 1.2) with SMTP id
WAA25685;
Wed, 24 May 2000 22:50:11 -0500 (CDT)
Date: Wed, 24 May 2000 22:50:11 -0500 (CDT)
From: Postmaster <[EMAIL PROTECTED]>
To: Hugh Irvine <[EMAIL PROTECTED]>
cc: [EMAIL PROTECTED]
Subject: Re: logfile
In-Reply-To: <0005211819175X.27586@hugo>
Message-ID: <[EMAIL PROTECTED]>
MIME-Version: 1.0
Content-Type: TEXT/PLAIN; charset=US-ASCII
Hugh,
Here's the scenario:
I started the radiator, let it run in trace4 and while it was idle, it
looks from the logfile that the ASCEND MAX was sending some requests etc
to radiator and radiator gave a few warnings and then I tried to connect
to MAX as 'ali' and since, I typed 'wrong' pass, I was denied and then I
connected just once as 'johndoe' and disconnected. But during this short
time I was connected, I received multiple records in my flatfile
accounting database for the very single session belonging to 'johndoe'. I
need to know, if there is any way, that I can insert the accounting info
only once for 'Start' and 'Stop' session and that too, if the login was
attempt was successfull. How ?
thanks,
[EMAIL PROTECTED]
Here's the user's(johndoe) profile:
johndoe User-Password = "john123"
Service-Type = Framed-User,
Ascend-Metric = 2,
Ascend-Maximum-Channels = 4,
Ascend-Idle-Limit = 0,
Framed-Protocol = MPP,
Framed-Routing = None,
Framed-Compression = Van-Jacobson-TCP-IP,
Framed-Address = 206.29.1.1,
Framed-Netmask = 255.255.255.0
------------------- Begin Log File ----------------------------------
Wed May 24 22:13:12 2000: DEBUG: Reading users file
/usr/ali/raddb/users_testmax
Wed May 24 22:13:13 2000: INFO: Server started
Wed May 24 22:18:29 2000: DEBUG: Packet dump:
*** Received from 206.29.116.6 port 1034 ....
Code: Ascend-Access-Event-Request
Identifier: 235
Authentic: <147><236><143><255><212><152><142><140><180><16>Rncq<20><12>
Attributes:
NAS-Identifier = 206.29.116.6
Ascend-Number-Sessions = ""
Wed May 24 22:18:29 2000: WARNING: Invalid request
'Ascend-Access-Event-Request'
received from 206.29.116.6
Wed May 24 22:18:34 2000: DEBUG: Packet dump:
*** Received from 206.29.116.6 port 1034 ....
Code: Ascend-Access-Event-Request
Identifier: 236
Authentic: ~s<0>><196><178>b<202><242><28><212><24><219>I5<231>
Attributes:
NAS-Identifier = 206.29.116.6
Ascend-Number-Sessions = ""
Wed May 24 22:18:34 2000: WARNING: Invalid request
'Ascend-Access-Event-Request'
received from 206.29.116.6
Wed May 24 22:18:39 2000: DEBUG: Packet dump:
*** Received from 206.29.116.6 port 1034 ....
Code: Ascend-Access-Event-Request
Identifier: 237
Authentic: <149>&<129><184><160><232><253>i<224><17>dL<30><184>W<12>
Attributes:
NAS-Identifier = 206.29.116.6
Ascend-Number-Sessions = ""
Wed May 24 22:18:39 2000: WARNING: Invalid request
'Ascend-Access-Event-Request'
received from 206.29.116.6
Wed May 24 22:18:46 2000: DEBUG: Packet dump:
*** Received from 206.29.116.6 port 1034 ....
Code: Ascend-Access-Event-Request
Identifier: 238
Authentic: /<232><5><25>D<230>i<11>Ng<158>_<200>q<219><239>
Attributes:
NAS-Identifier = 206.29.116.6
Ascend-Number-Sessions = ""
Wed May 24 22:18:46 2000: WARNING: Invalid request
'Ascend-Access-Event-Request'
received from 206.29.116.6
Wed May 24 22:18:51 2000: DEBUG: Packet dump:
*** Received from 206.29.116.6 port 1034 ....
Code: Ascend-Access-Event-Request
Identifier: 239
Authentic: _<147>v<238><13><145>6<191><29><186>N<238><128><136>GK
Attributes:
NAS-Identifier = 206.29.116.6
Ascend-Number-Sessions = ""
Wed May 24 22:18:51 2000: WARNING: Invalid request
'Ascend-Access-Event-Request'
received from 206.29.116.6
Wed May 24 22:18:56 2000: DEBUG: Packet dump:
*** Received from 206.29.116.6 port 1034 ....
Code: Ascend-Access-Event-Request
Identifier: 240
Authentic: 1<216><218><236><207><21><128><223><128><243>F<21><142>qB@
Attributes:
NAS-Identifier = 206.29.116.6
Ascend-Number-Sessions = ""
Wed May 24 22:18:56 2000: WARNING: Invalid request
'Ascend-Access-Event-Request'
received from 206.29.116.6
Wed May 24 22:19:09 2000: DEBUG: Packet dump:
*** Received from 206.29.116.6 port 1034 ....
Code: Ascend-Access-Event-Request
Identifier: 241
Authentic: N<243>F<153>g@<130>|<250><167>"-<191>_O<176>
Attributes:
NAS-Identifier = 206.29.116.6
Ascend-Number-Sessions = ""
Wed May 24 22:19:09 2000: WARNING: Invalid request
'Ascend-Access-Event-Request'
received from 206.29.116.6
Wed May 24 22:19:14 2000: DEBUG: Packet dump:
*** Received from 206.29.116.6 port 1034 ....
Code: Ascend-Access-Event-Request
Identifier: 242
Authentic: <209><0>[o'<217><231>a<148><155><140><222>P<141><230>O
Attributes:
NAS-Identifier = 206.29.116.6
Ascend-Number-Sessions = ""
Wed May 24 22:19:14 2000: WARNING: Invalid request
'Ascend-Access-Event-Request'
received from 206.29.116.6
Code: Ascend-Access-Event-Request
Identifier: 243
Authentic: <248><234><228><180>?ro<226><209>&<30><138><28><155><236><
Attributes:
NAS-Identifier = 206.29.116.6
Ascend-Number-Sessions = ""
Wed May 24 22:19:19 2000: WARNING: Invalid request
'Ascend-Access-Event-Request'
received from 206.29.116.6
Wed May 24 22:19:50 2000: DEBUG: Packet dump:
*** Received from 206.29.116.6 port 1034 ....
Code: Ascend-Access-Event-Request
Identifier: 244
Authentic: <211><2><251><246>|A<239><253><214><138>"<133><252><18><223>m
Attributes:
NAS-Identifier = 206.29.116.6
Ascend-Number-Sessions = ""
Wed May 24 22:19:50 2000: WARNING: Invalid request
'Ascend-Access-Event-Request'
received from 206.29.116.6
Wed May 24 22:19:55 2000: DEBUG: Packet dump:
*** Received from 206.29.116.6 port 1034 ....
Code: Ascend-Access-Event-Request
Identifier: 245
Authentic: <158><181><27><147><5><251><128>Z<214>-;<243>v<162><137><204>
Attributes:
NAS-Identifier = 206.29.116.6
Ascend-Number-Sessions = ""
Wed May 24 22:19:55 2000: WARNING: Invalid request
'Ascend-Access-Event-Request'
received from 206.29.116.6
Wed May 24 22:20:00 2000: DEBUG: Packet dump:
*** Received from 206.29.116.6 port 1034 ....
Code: Ascend-Access-Event-Request
Identifier: 246
Authentic: <170><217>`3<6>a<133><213><232>t<196>e<186><240><161>c
Attributes:
NAS-Identifier = 206.29.116.6
Ascend-Number-Sessions = ""
Wed May 24 22:20:00 2000: WARNING: Invalid request
'Ascend-Access-Event-Request'
received from 206.29.116.6
Wed May 24 22:20:45 2000: DEBUG: Packet dump:
*** Received from 206.29.116.6 port 1025 ....
Code: Access-Request
Identifier: 119
Authentic: <19>i<250><250>?<15><245><228>Q6<196><155>}3<252><195>
Attributes:
User-Name = "ali"
User-Password = "<171><225><246>VGd<161>"
NAS-Identifier = 206.29.116.6
NAS-Port = 20000
NAS-Port-Type = Virtual
Service-Type = Login-User
State = ""
Ascend-Third-Prompt = ""
Client-Port-DNIS = "<225>"
Acct-Session-Id = "269493553"
Wed May 24 22:20:45 2000: DEBUG: Rewrote user name to ali
Wed May 24 22:20:45 2000: DEBUG: Rewrote user name to ali
Wed May 24 22:20:45 2000: DEBUG: Handling with Radius::AuthFILE
Wed May 24 22:20:45 2000: DEBUG: Radius::AuthFILE looks for match with ali
Wed May 24 22:20:45 2000: INFO: Access rejected for ali: No such user
Wed May 24 22:20:45 2000: DEBUG: Packet dump:
*** Sending to 206.29.116.6 port 1025 ....
Code: Access-Reject
Identifier: 119
Authentic: <19>i<250><250>?<15><245><228>Q6<196><155>}3<252><195>
Attributes:
Reply-Message = "Request Denied"
Reply-Message = "No such user"
Wed May 24 22:20:56 2000: DEBUG: Packet dump:
*** Received from 206.29.116.6 port 1025 ....
Code: Access-Request
Identifier: 120
Authentic: <10><31><220><242><175>[d<157>&|F<194><132>RLx
Attributes:
User-Name = "johndoe"
User-Password = "9<132><254><154><215><24>m<129>"
NAS-Identifier = 206.29.116.6
NAS-Port = 20000
NAS-Port-Type = Virtual
Service-Type = Login-User
State = ""
Ascend-Third-Prompt = ""
Client-Port-DNIS = "<225>"
Acct-Session-Id = "269493553"
Wed May 24 22:20:56 2000: DEBUG: Handling request with Handler
'Realm=DEFAULT'
Wed May 24 22:20:56 2000: DEBUG: Rewrote user name to johndoe
Wed May 24 22:20:56 2000: DEBUG: Rewrote user name to johndoe
Wed May 24 22:20:56 2000: DEBUG: Handling with Radius::AuthFILE
Wed May 24 22:20:56 2000: DEBUG: Radius::AuthFILE looks for match with
johndoe
Wed May 24 22:20:56 2000: DEBUG: Radius::AuthFILE ACCEPT:
Wed May 24 22:20:56 2000: DEBUG: Access accepted for johndoe
Wed May 24 22:20:56 2000: DEBUG: Packet dump:
*** Sending to 206.29.116.6 port 1025 ....
Code: Access-Accept
Identifier: 120
Authentic: <10><31><220><242><175>[d<157>&|F<194><132>RLx
Attributes:
Service-Type = Framed-User
Ascend-Metric = 2
Ascend-Maximum-Channels = 4
Ascend-Idle-Limit = 0
Framed-Protocol = MPP
Framed-Routing = None
Framed-Compression = Van-Jacobson-TCP-IP
Framed-Address = 206.29.1.1
Framed-Netmask = 255.255.255.0
Wed May 24 22:20:56 2000: DEBUG: Packet dump:
*** Received from 206.29.116.6 port 1034 ....
Code: Accounting-Request
Identifier: 247
Authentic: <209><28><29><146><185><185><221>b9<249>c<155><29><173>Y<10>
Attributes:
User-Name = "johndoe"
NAS-Identifier = 206.29.116.6
NAS-Port = 20000
NAS-Port-Type = Virtual
Acct-Status-Type = Start
Acct-Delay-Time = 0
Acct-Session-Id = "269493553"
Acct-Authentic = RADIUS
Client-Port-DNIS = "<2>"
Framed-Protocol = PPP
Framed-Address = 206.29.1.1
Wed May 24 22:20:56 2000: DEBUG: Handling request with Handler
'Realm=DEFAULT'
Wed May 24 22:20:56 2000: DEBUG: Rewrote user name to johndoe
Wed May 24 22:20:56 2000: DEBUG: Rewrote user name to johndoe
Wed May 24 22:20:56 2000: DEBUG: Handling with Radius::AuthFILE
Wed May 24 22:20:56 2000: DEBUG: Adding session for johndoe, 206.29.116.6,
20000
Wed May 24 22:20:56 2000: DEBUG: Accounting accepted
Wed May 24 22:20:56 2000: DEBUG: Packet dump:
*** Sending to 206.29.116.6 port 1034 ....
Code: Accounting-Response
Identifier: 247
Authentic: <209><28><29><146><185><185><221>b9<249>c<155><29><173>Y<10>
Attributes:
Wed May 24 22:21:01 2000: DEBUG: Packet dump:
*** Received from 206.29.116.6 port 1034 ....
Code: Accounting-Request
Identifier: 248
Authentic:
<167><228><255><165><22><27>n<205><150><25><19><129>-b<17><167>
Attributes:
User-Name = "johndoe"
NAS-Identifier = 206.29.116.6
NAS-Port = 20000
NAS-Port-Type = Virtual
Acct-Status-Type = Start
Acct-Delay-Time = 5
Acct-Session-Id = "269493553"
Acct-Authentic = RADIUS
Client-Port-DNIS = "<2>"
Framed-Protocol = PPP
Framed-Address = 206.29.1.1
Wed May 24 22:21:01 2000: DEBUG: Handling request with Handler
'Realm=DEFAULT'
Wed May 24 22:21:01 2000: DEBUG: Rewrote user name to johndoe
Wed May 24 22:21:01 2000: DEBUG: Rewrote user name to johndoe
Wed May 24 22:21:01 2000: DEBUG: Handling with Radius::AuthFILE
Wed May 24 22:21:01 2000: DEBUG: Adding session for johndoe, 206.29.116.6,
20000
Wed May 24 22:21:01 2000: DEBUG: Accounting accepted
Wed May 24 22:21:01 2000: DEBUG: Packet dump:
*** Sending to 206.29.116.6 port 1034 ....
Code: Accounting-Response
Identifier: 248
Authentic:
<167><228><255><165><22><27>n<205><150><25><19><129>-b<17><167>
Attributes:
Wed May 24 22:21:06 2000: DEBUG: Packet dump:
*** Received from 206.29.116.6 port 1034 ....
Code: Accounting-Request
Identifier: 249
Authentic: '<27><13><145>~<194>.<220>;<223><208><128><15><0>Hl
Attributes:
User-Name = "johndoe"
NAS-Identifier = 206.29.116.6
NAS-Port = 20000
NAS-Port-Type = Virtual
Acct-Status-Type = Start
Acct-Delay-Time = 10
Acct-Session-Id = "269493553"
Acct-Authentic = RADIUS
Client-Port-DNIS = "<2>"
Framed-Protocol = PPP
Framed-Address = 206.29.1.1
Wed May 24 22:21:06 2000: DEBUG: Handling request with Handler
'Realm=DEFAULT'
Wed May 24 22:21:06 2000: DEBUG: Rewrote user name to johndoe
Wed May 24 22:21:06 2000: DEBUG: Rewrote user name to johndoe
Wed May 24 22:21:06 2000: DEBUG: Handling with Radius::AuthFILE
Wed May 24 22:21:06 2000: DEBUG: Adding session for johndoe, 206.29.116.6,
20000
Wed May 24 22:21:06 2000: DEBUG: Accounting accepted
Wed May 24 22:21:06 2000: DEBUG: Packet dump:
*** Sending to 206.29.116.6 port 1034 ....
Code: Accounting-Response
Identifier: 249
Authentic: '<27><13><145>~<194>.<220>;<223><208><128><15><0>Hl
Attributes:
Wed May 24 22:21:14 2000: DEBUG: Packet dump:
*** Received from 206.29.116.6 port 1034 ....
Code: Accounting-Request
Identifier: 250
Authentic: rE<179><167><24>1<190><138><198>q<163><137>8&<132>h
Attributes:
User-Name = "johndoe"
NAS-Identifier = 206.29.116.6
NAS-Port = 20000
NAS-Port-Type = Virtual
Acct-Status-Type = Start
Acct-Delay-Time = 18
Acct-Session-Id = "269493553"
Acct-Authentic = RADIUS
Client-Port-DNIS = "<2>"
Framed-Protocol = PPP
Framed-Address = 206.29.1.1
Wed May 24 22:21:14 2000: DEBUG: Handling request with Handler
'Realm=DEFAULT'
Wed May 24 22:21:14 2000: DEBUG: Rewrote user name to johndoe
Wed May 24 22:21:14 2000: DEBUG: Rewrote user name to johndoe
Wed May 24 22:21:14 2000: DEBUG: Handling with Radius::AuthFILE
Wed May 24 22:21:14 2000: DEBUG: Adding session for johndoe, 206.29.116.6,
20000
Wed May 24 22:21:14 2000: DEBUG: Accounting accepted
Wed May 24 22:21:14 2000: DEBUG: Packet dump:
*** Sending to 206.29.116.6 port 1034 ....
Code: Accounting-Response
Identifier: 250
Authentic: rE<179><167><24>1<190><138><198>q<163><137>8&<132>h
Attributes:
Wed May 24 22:21:19 2000: DEBUG: Packet dump:
*** Received from 206.29.116.6 port 1034 ....
Code: Accounting-Request
Identifier: 251
Authentic:
<151><223><233><175><20><231><154><250><141><177>?<191><18><251>T<24
6>
Attributes:
User-Name = "johndoe"
NAS-Identifier = 206.29.116.6
NAS-Port = 20000
NAS-Port-Type = Virtual
Acct-Status-Type = Start
Acct-Delay-Time = 23
Acct-Session-Id = "269493553"
Acct-Authentic = RADIUS
Client-Port-DNIS = "<2>"
Framed-Protocol = PPP
Framed-Address = 206.29.1.1
Wed May 24 22:21:19 2000: DEBUG: Handling request with Handler
'Realm=DEFAULT'
Wed May 24 22:21:19 2000: DEBUG: Rewrote user name to johndoe
Wed May 24 22:21:19 2000: DEBUG: Rewrote user name to johndoe
Wed May 24 22:21:19 2000: DEBUG: Handling with Radius::AuthFILE
Wed May 24 22:21:19 2000: DEBUG: Adding session for johndoe, 206.29.116.6,
20000
Wed May 24 22:21:19 2000: DEBUG: Accounting accepted
Wed May 24 22:21:19 2000: DEBUG: Packet dump:
*** Sending to 206.29.116.6 port 1034 ....
Code: Accounting-Response
Identifier: 251
Authentic:
<151><223><233><175><20><231><154><250><141><177>?<191><18><251>T<24
6>
Attributes:
Wed May 24 22:21:24 2000: DEBUG: Packet dump:
*** Received from 206.29.116.6 port 1034 ....
Code: Accounting-Request
Identifier: 252
Authentic: <192>ny<186><3>b<145><0><218><141>$$<217>S<140>4
Attributes:
User-Name = "johndoe"
NAS-Identifier = 206.29.116.6
NAS-Port = 20000
NAS-Port-Type = Virtual
Acct-Status-Type = Start
Acct-Delay-Time = 28
Acct-Session-Id = "269493553"
Acct-Authentic = RADIUS
Client-Port-DNIS = "<2>"
Framed-Protocol = PPP
Framed-Address = 206.29.1.1
Wed May 24 22:21:24 2000: DEBUG: Handling request with Handler
'Realm=DEFAULT'
Wed May 24 22:21:24 2000: DEBUG: Rewrote user name to johndoe
Wed May 24 22:21:24 2000: DEBUG: Rewrote user name to johndoe
Wed May 24 22:21:24 2000: DEBUG: Handling with Radius::AuthFILE
Wed May 24 22:21:24 2000: DEBUG: Adding session for johndoe, 206.29.116.6,
20000
Wed May 24 22:21:24 2000: DEBUG: Accounting accepted
Wed May 24 22:21:24 2000: DEBUG: Packet dump:
*** Sending to 206.29.116.6 port 1034 ....
Code: Accounting-Response
Identifier: 252
Authentic: <192>ny<186><3>b<145><0><218><141>$$<217>S<140>4
Attributes:
Wed May 24 22:21:26 2000: DEBUG: Packet dump:
*** Received from 206.29.116.6 port 1034 ....
Code: Ascend-Access-Event-Request
Identifier: 253
Authentic:
<132><240><195><226><211><13>#<233><235><162><7>s<169><136><174><169
>
Attributes:
NAS-Identifier = 206.29.116.6
Ascend-Number-Sessions = ""
Wed May 24 22:21:26 2000: WARNING: Invalid request
'Ascend-Access-Event-Request'
received from 206.29.116.6
Wed May 24 22:21:31 2000: DEBUG: Packet dump:
*** Received from 206.29.116.6 port 1034 ....
Code: Ascend-Access-Event-Request
Identifier: 254
Authentic: IPlD<181><180>P<157>=4S<255>Z<177><15><173>
Attributes:
NAS-Identifier = 206.29.116.6
Ascend-Number-Sessions = ""
Wed May 24 22:21:31 2000: WARNING: Invalid request
'Ascend-Access-Event-Request'
received from 206.29.116.6
Wed May 24 22:21:36 2000: DEBUG: Packet dump:
*** Received from 206.29.116.6 port 1034 ....
Code: Ascend-Access-Event-Request
Identifier: 255
Authentic: 9<241><139>eY<30>w<162><26><240><14><21><187><188><185><193>
Attributes:
NAS-Identifier = 206.29.116.6
Ascend-Number-Sessions = ""
Wed May 24 22:21:36 2000: WARNING: Invalid request
'Ascend-Access-Event-Request'
received from 206.29.116.6
Wed May 24 22:21:37 2000: DEBUG: Packet dump:
*** Received from 206.29.116.6 port 1034 ....
Code: Accounting-Request
Identifier: 0
Authentic: 9_.E{<18><<131>qJ+/<254>7U2
Attributes:
User-Name = "johndoe"
NAS-Identifier = 206.29.116.6
NAS-Port = 20000
NAS-Port-Type = Virtual
Acct-Status-Type = Start
Acct-Delay-Time = 41
Acct-Session-Id = "269493553"
Acct-Authentic = RADIUS
Client-Port-DNIS = "<2>"
Framed-Protocol = PPP
Framed-Address = 206.29.1.1
Wed May 24 22:21:37 2000: DEBUG: Handling request with Handler
'Realm=DEFAULT'
Wed May 24 22:21:37 2000: DEBUG: Rewrote user name to johndoe
Wed May 24 22:21:37 2000: DEBUG: Rewrote user name to johndoe
Wed May 24 22:21:37 2000: DEBUG: Handling with Radius::AuthFILE
Wed May 24 22:21:37 2000: DEBUG: Adding session for johndoe, 206.29.116.6,
20000
Wed May 24 22:21:37 2000: DEBUG: Accounting accepted
Wed May 24 22:21:37 2000: DEBUG: Packet dump:
*** Sending to 206.29.116.6 port 1034 ....
Code: Accounting-Response
Identifier: 0
Authentic: 9_.E{<18><<131>qJ+/<254>7U2
Attributes:
Wed May 24 22:21:42 2000: DEBUG: Packet dump:
*** Received from 206.29.116.6 port 1034 ....
Code: Accounting-Request
Identifier: 1
Authentic: <136><6>$<158>h<149>3<205>#f]<9>D<146><255><231>
Attributes:
User-Name = "johndoe"
NAS-Identifier = 206.29.116.6
NAS-Port = 20000
NAS-Port-Type = Virtual
Acct-Status-Type = Start
Acct-Delay-Time = 46
Acct-Session-Id = "269493553"
Acct-Authentic = RADIUS
Client-Port-DNIS = "<2>"
Framed-Protocol = PPP
Framed-Address = 206.29.1.1
Wed May 24 22:21:42 2000: DEBUG: Handling request with Handler
'Realm=DEFAULT'
Wed May 24 22:21:42 2000: DEBUG: Rewrote user name to johndoe
Wed May 24 22:21:42 2000: DEBUG: Rewrote user name to johndoe
Wed May 24 22:21:42 2000: DEBUG: Handling with Radius::AuthFILE
Wed May 24 22:21:42 2000: DEBUG: Adding session for johndoe, 206.29.116.6,
20000
Wed May 24 22:21:42 2000: DEBUG: Accounting accepted
Wed May 24 22:21:42 2000: DEBUG: Packet dump:
*** Sending to 206.29.116.6 port 1034 ....
Code: Accounting-Response
Identifier: 1
Authentic: <136><6>$<158>h<149>3<205>#f]<9>D<146><255><231>
Attributes:
Wed May 24 22:21:42 2000: DEBUG: Packet dump:
*** Received from 206.29.116.6 port 1034 ....
Code: Accounting-Request
Identifier: 2
Authentic: ~2<129><175><187>P<143>H<255><149><6><20>t<26>V<157>
Attributes:
User-Name = "johndoe"
NAS-Identifier = 206.29.116.6
NAS-Port = 20000
NAS-Port-Type = Virtual
Acct-Status-Type = Stop
Acct-Delay-Time = 0
Acct-Session-Id = "269493553"
Acct-Authentic = RADIUS
Acct-Session-Time = 46
Acct-Input-Octets = 31
Acct-Output-Octets = 222
Acct-Input-Packets = 0
Acct-Output-Packets = 0
Ascend-Disconnect-Cause = remoteEndHungup
Ascend-Connect-Progress = prLCPStateStarting
Ascend-Xmit-Rate = 64000
Ascend-Data-Rate = 64000
Ascend-PreSession-Time = 25
Ascend-Pre-Input-Octets = 31
Ascend-Pre-Output-Octets = 150
Ascend-Pre-Input-Packets = 0
Ascend-Pre-Output-Packets = 0
Client-Port-DNIS = "<2>"
Framed-Protocol = PPP
Framed-Address = 206.29.1.1
Wed May 24 22:21:42 2000: DEBUG: Handling request with Handler
'Realm=DEFAULT'
Wed May 24 22:21:42 2000: DEBUG: Rewrote user name to johndoe
Wed May 24 22:21:42 2000: DEBUG: Rewrote user name to johndoe
Wed May 24 22:21:42 2000: DEBUG: Handling with Radius::AuthFILE
Wed May 24 22:21:42 2000: DEBUG: Deleting session for johndoe,
206.29.116.6, 200
00
Wed May 24 22:21:42 2000: DEBUG: Accounting accepted
Wed May 24 22:21:42 2000: DEBUG: Packet dump:
*** Sending to 206.29.116.6 port 1034 ....
Code: Accounting-Response
Identifier: 2
Authentic: ~2<129><175><187>P<143>H<255><149><6><20>t<26>V<157>
Attributes:
Wed May 24 22:21:47 2000: DEBUG: Packet dump:
*** Received from 206.29.116.6 port 1034 ....
Code: Accounting-Request
Identifier: 3
Authentic: <132><247><241><163>'C<137>OSG<131>8(<244>uL
Attributes:
User-Name = "johndoe"
NAS-Identifier = 206.29.116.6
NAS-Port = 20000
NAS-Port-Type = Virtual
Acct-Status-Type = Start
Acct-Delay-Time = 51
Acct-Session-Id = "269493553"
Acct-Authentic = RADIUS
Client-Port-DNIS = "<2>"
Wed May 24 22:21:47 2000: DEBUG: Handling request with Handler
'Realm=DEFAULT'
Wed May 24 22:21:47 2000: DEBUG: Rewrote user name to johndoe
Wed May 24 22:21:47 2000: DEBUG: Rewrote user name to johndoe
Wed May 24 22:21:47 2000: DEBUG: Handling with Radius::AuthFILE
Wed May 24 22:21:47 2000: DEBUG: Adding session for johndoe, 206.29.116.6,
20000
Wed May 24 22:21:47 2000: DEBUG: Accounting accepted
Wed May 24 22:21:47 2000: DEBUG: Packet dump:
*** Sending to 206.29.116.6 port 1034 ....
Code: Accounting-Response
Identifier: 3
Authentic: <132><247><241><163>'C<137>OSG<131>8(<244>uL
Attributes:
Wed May 24 22:21:47 2000: DEBUG: Packet dump:
*** Received from 206.29.116.6 port 1034 ....
Code: Accounting-Request
Identifier: 4
Authentic: <248><8><4><160> ?<237><30><128><254>MK<243><153><243><150>
Attributes:
User-Name = "johndoe"
NAS-Identifier = 206.29.116.6
NAS-Port = 20000
NAS-Port-Type = Virtual
Acct-Status-Type = Stop
Acct-Delay-Time = 5
Acct-Session-Id = "269493553"
Acct-Authentic = RADIUS
Acct-Session-Time = 46
Acct-Input-Octets = 31
Acct-Output-Octets = 222
Acct-Input-Packets = 0
Acct-Output-Packets = 0
Ascend-Disconnect-Cause = remoteEndHungup
Ascend-Connect-Progress = prLCPStateStarting
Ascend-Xmit-Rate = 64000
Ascend-Data-Rate = 64000
Ascend-PreSession-Time = 25
Ascend-Pre-Input-Octets = 31
Ascend-Pre-Output-Octets = 150
Ascend-Pre-Input-Packets = 0
Ascend-Pre-Output-Packets = 0
Client-Port-DNIS = "<2>"
Framed-Protocol = PPP
Framed-Address = 206.29.1.1
Wed May 24 22:21:47 2000: DEBUG: Handling request with Handler
'Realm=DEFAULT'
Wed May 24 22:21:47 2000: DEBUG: Rewrote user name to johndoe
Wed May 24 22:21:47 2000: DEBUG: Rewrote user name to johndoe
Wed May 24 22:21:47 2000: DEBUG: Handling with Radius::AuthFILE
Wed May 24 22:21:47 2000: DEBUG: Deleting session for johndoe,
206.29.116.6, 200
00
Wed May 24 22:21:47 2000: DEBUG: Accounting accepted
Wed May 24 22:21:47 2000: DEBUG: Packet dump:
*** Sending to 206.29.116.6 port 1034 ....
Code: Accounting-Response
Identifier: 4
Authentic: <248><8><4><160> ?<237><30><128><254>MK<243><153><243><150>
Attributes:
Wed May 24 22:21:52 2000: DEBUG: Packet dump:
*** Received from 206.29.116.6 port 1034 ....
Code: Accounting-Request
Identifier: 5
Authentic: T<4><2>X<11>`<8><22><243>2<232><15><3>P<136><146>
Attributes:
User-Name = "johndoe"
NAS-Identifier = 206.29.116.6
NAS-Port = 20000
NAS-Port-Type = Virtual
Acct-Status-Type = Stop
Acct-Delay-Time = 10
Acct-Session-Id = "269493553"
Acct-Authentic = RADIUS
Acct-Session-Time = 46
Acct-Input-Octets = 31
Acct-Output-Octets = 222
Acct-Input-Packets = 0
Acct-Output-Packets = 0
Ascend-Disconnect-Cause = remoteEndHungup
Ascend-Connect-Progress = prLCPStateStarting
Ascend-Xmit-Rate = 64000
Ascend-Data-Rate = 64000
Ascend-PreSession-Time = 25
Ascend-Pre-Input-Octets = 31
Ascend-Pre-Output-Octets = 150
Ascend-Pre-Input-Packets = 0
Ascend-Pre-Output-Packets = 0
Client-Port-DNIS = "<2>"
Framed-Protocol = PPP
Framed-Address = 206.29.1.1
Wed May 24 22:21:52 2000: DEBUG: Handling request with Handler
'Realm=DEFAULT'
Wed May 24 22:21:52 2000: DEBUG: Rewrote user name to johndoe
Wed May 24 22:21:52 2000: DEBUG: Rewrote user name to johndoe
Wed May 24 22:21:52 2000: DEBUG: Handling with Radius::AuthFILE
Wed May 24 22:21:52 2000: DEBUG: Deleting session for johndoe,
206.29.116.6, 200
00
Wed May 24 22:21:52 2000: DEBUG: Accounting accepted
Wed May 24 22:21:52 2000: DEBUG: Packet dump:
*** Sending to 206.29.116.6 port 1034 ....
Code: Accounting-Response
Identifier: 5
Authentic: T<4><2>X<11>`<8><22><243>2<232><15><3>P<136><146>
Attributes:
Wed May 24 22:22:00 2000: DEBUG: Packet dump:
*** Received from 206.29.116.6 port 1034 ....
Code: Accounting-Request
Identifier: 6
Authentic: <163>W<180><209><205><166><147><250>T<14>t<240>xE<250>b
Attributes:
User-Name = "johndoe"
NAS-Identifier = 206.29.116.6
NAS-Port = 20000
NAS-Port-Type = Virtual
Acct-Status-Type = Stop
Acct-Delay-Time = 18
Acct-Session-Id = "269493553"
Acct-Authentic = RADIUS
Acct-Session-Time = 46
Acct-Input-Octets = 31
Acct-Output-Octets = 222
Acct-Input-Packets = 0
Acct-Output-Packets = 0
Ascend-Disconnect-Cause = remoteEndHungup
Ascend-Connect-Progress = prLCPStateStarting
Ascend-Xmit-Rate = 64000
Ascend-Data-Rate = 64000
Ascend-PreSession-Time = 25
Ascend-Pre-Input-Octets = 31
Ascend-Pre-Output-Octets = 150
Ascend-Pre-Input-Packets = 0
Ascend-Pre-Output-Packets = 0
Client-Port-DNIS = "<2>"
Framed-Protocol = PPP
Framed-Address = 206.29.1.1
Wed May 24 22:22:00 2000: DEBUG: Handling request with Handler
'Realm=DEFAULT'
Wed May 24 22:22:00 2000: DEBUG: Rewrote user name to johndoe
Wed May 24 22:22:00 2000: DEBUG: Rewrote user name to johndoe
Wed May 24 22:22:00 2000: DEBUG: Handling with Radius::AuthFILE
Wed May 24 22:22:00 2000: DEBUG: Deleting session for johndoe,
206.29.116.6, 200
00
Wed May 24 22:22:00 2000: DEBUG: Accounting accepted
Wed May 24 22:22:00 2000: DEBUG: Packet dump:
*** Sending to 206.29.116.6 port 1034 ....
Code: Accounting-Response
Identifier: 6
Authentic: <163>W<180><209><205><166><147><250>T<14>t<240>xE<250>b
Attributes:
Wed May 24 22:22:05 2000: DEBUG: Packet dump:
*** Received from 206.29.116.6 port 1034 ....
Code: Accounting-Request
Identifier: 7
Authentic: <12>R<21>%&<246><206><27><3>R<217><131><179><154><202><228>
Attributes:
User-Name = "johndoe"
NAS-Identifier = 206.29.116.6
NAS-Port = 20000
NAS-Port-Type = Virtual
Acct-Status-Type = Stop
Acct-Delay-Time = 23
Acct-Session-Id = "269493553"
Acct-Authentic = RADIUS
Acct-Session-Time = 46
Acct-Input-Octets = 31
Acct-Output-Octets = 222
Acct-Input-Packets = 0
Acct-Output-Packets = 0
Ascend-Disconnect-Cause = remoteEndHungup
Ascend-Connect-Progress = prLCPStateStarting
Ascend-Xmit-Rate = 64000
Ascend-Data-Rate = 64000
Ascend-PreSession-Time = 25
Ascend-Pre-Input-Octets = 31
Ascend-Pre-Output-Octets = 150
Ascend-Pre-Input-Packets = 0
Ascend-Pre-Output-Packets = 0
Client-Port-DNIS = "<2>"
Framed-Protocol = PPP
Framed-Address = 206.29.1.1
Wed May 24 22:22:05 2000: DEBUG: Handling request with Handler
'Realm=DEFAULT'
Wed May 24 22:22:05 2000: DEBUG: Rewrote user name to johndoe
Wed May 24 22:22:05 2000: DEBUG: Rewrote user name to johndoe
Wed May 24 22:22:05 2000: DEBUG: Handling with Radius::AuthFILE
Wed May 24 22:22:05 2000: DEBUG: Deleting session for johndoe,
206.29.116.6, 200
00
Wed May 24 22:22:05 2000: DEBUG: Accounting accepted
Wed May 24 22:22:05 2000: DEBUG: Packet dump:
*** Sending to 206.29.116.6 port 1034 ....
Code: Accounting-Response
Identifier: 7
Authentic: <12>R<21>%&<246><206><27><3>R<217><131><179><154><202><228>
Attributes:
/// I killed the radiator with -9 signal //////
--------------------End Log File -----------------------------------
On Sun, 21 May 2000, Hugh Irvine wrote:
>Date: Sun, 21 May 2000 18:14:27 +1000
>From: Hugh Irvine <[EMAIL PROTECTED]>
>To: Mohammed Ali <[EMAIL PROTECTED]>
>Cc: [EMAIL PROTECTED]
>Subject: logfile
>
>
>Hello Mohammed -
>
>On Sun, 21 May 2000, Mohammed Ali wrote:
>> Hi Hugh,
>>
>> Here's some of my logfile, I also noticed that after the first
>> accounting request the rest of the requests I get from ASCEND have "NULL"
>> as the username and they are all inserted into my mSQL Accounting table.
>>
>> P.S: my users flat file has the format:
>>
>> test128 User-Password = "test128"
>> Service-Type = Framed-User,
>> Ascend-Metric = 2,
>> Ascend-Maximum-Channels = 2,
>> Ascend-Idle-Limit = 0,
>> Framed-Protocol = MPP,
>> Framed-Routing = None,
>> Framed-Compression = Van-Jacobson-TCP-IP
>> Framed-Address = 192.168.1.1,
>> Framed-Netmask = 255.255.255.0
>>
>> thanks,
>> [EMAIL PROTECTED]
>> ------------------- snip of logfile ---------------------------
>>
>> Sat May 20 14:27:42 2000: DEBUG: Reading users file
>> /usr/ali/raddb/users_testmax
>> Sat May 20 14:27:42 2000: INFO: Server started
>> Sat May 20 14:28:47 2000: DEBUG: Packet dump:
>> *** Received from 206.29.116.6 port 1025 ....
>> Code: Access-Request
>> Identifier: 111
>> Authentic: x_G<175><203><186>MJ<145><173><210><206><140>d"Q
>> Attributes:
>> User-Name = "test128"
>> User-Password = "#<11><200>h<181>Q<191>2"
>> NAS-Identifier = 206.29.116.6
>> NAS-Port = 20000
>> NAS-Port-Type = Virtual
>> Service-Type = Login-User
>> State = ""
>> Ascend-Third-Prompt = ""
>> Client-Port-DNIS = "<225>"
>> Acct-Session-Id = "269493545"
>>
>> Sat May 20 14:28:47 2000: DEBUG: Handling request with Handler
>> 'Realm=DEFAULT'
>> Sat May 20 14:28:47 2000: DEBUG: Rewrote user name to test128
>> Sat May 20 14:28:47 2000: DEBUG: Rewrote user name to test128
>> Sat May 20 14:28:47 2000: DEBUG: Query is: select NAS_Identifier,
>> NAS_Port, Acct_Session_Id from RADONLINE where Username='test128'
>>
>> Sat May 20 14:28:47 2000: DEBUG: Handling with Radius::AuthSQL
>> Sat May 20 14:28:47 2000: DEBUG: Handling with Radius::AuthFILE
>> Sat May 20 14:28:47 2000: DEBUG: Radius::AuthFILE looks for match with
>> test128
>> Sat May 20 14:28:47 2000: DEBUG: Query is: select NAS_Identifier,
>> NAS_Port, Acct_Session_Id from RADONLINE where Username='test128'
>>
>> Sat May 20 14:28:47 2000: DEBUG: Radius::AuthFILE ACCEPT:
>> Sat May 20 14:28:47 2000: DEBUG: Access accepted for test128
>> Sat May 20 14:28:47 2000: DEBUG: Packet dump:
>> *** Sending to 206.29.116.6 port 1025 ....
>> Code: Access-Accept
>> Identifier: 111
>> Authentic: x_G<175><203><186>MJ<145><173><210><206><140>d"Q
>> Attributes:
>> Service-Type = Framed-User
>> Ascend-Metric = 2
>> Ascend-Maximum-Channels = 2
>> Ascend-Idle-Limit = 0
>> Framed-Protocol = MPP
>> Framed-Routing = None
>> Framed-Compression = Van-Jacobson-TCP-IP Framed-Address =
>> 192.168.1
>> .1
>> Framed-Netmask = 255.255.255.0
>>
>> Sat May 20 14:28:47 2000: DEBUG: Packet dump:
>> *** Received from 206.29.116.6 port 1033 ....
>> Code: Accounting-Request
>> Identifier: 215
>> Authentic: ca'Q<249>N<132><251><189> <251><239><164><218><190><238>
>> Attributes:
>> User-Name = "test128"
>> NAS-Identifier = 206.29.116.6
>> NAS-Port = 20000
>> NAS-Port-Type = Virtual
>> Acct-Status-Type = Start
>> Acct-Delay-Time = 0
>> Acct-Session-Id = "269493545"
>> Acct-Authentic = RADIUS
>> Client-Port-DNIS = "<2>"
>> Framed-Protocol = PPP
>> Framed-Address = 206.29.116.22
>>
>>
>> Sat May 20 14:28:47 2000: DEBUG: Handling request with Handler
>> 'Realm=DEFAULT'
>> Sat May 20 14:28:47 2000: DEBUG: Rewrote user name to test128
>> Sat May 20 14:28:47 2000: DEBUG: Rewrote user name to test128
>> Sat May 20 14:28:47 2000: DEBUG: Handling with Radius::AuthSQL
>> Sat May 20 14:28:47 2000: DEBUG: Handling accounting with Radius::AuthSQL
>> Sat May 20 14:28:47 2000: DEBUG: do query is: insert into ACCOUNTING
>> (Framed_Protocol, Username, NAS_Identifier, NAS_Port,
>> Acct_Session
>> _Id, the_time, Client_Port_DNIS, Framed_Address, Acct_Delay_Time,
>> the_date, Acct_S
>> tatus_Type)
>> values
>> ('PPP', 'test128', '206.29.116.6', 20000, '269493545',
>> '14:28:47',
>> '^B', '206.29.116.22', 0, '20-05-2000', 'Start')
>>
>> Sat May 20 14:28:47 2000: DEBUG: Adding session for test128,
>> 206.29.116.6, 20000
>> Sat May 20 14:28:47 2000: DEBUG: do query is: delete from RADONLINE where
>> Username
>> ='test128' and NAS_Identifier='206.29.116.6' and NAS_Port=20000
>>
>> Sat May 20 14:28:47 2000: DEBUG: do query is: insert into RADONLINE
>> (Username, Tim
>> e_Stamp, NAS_Identifier, NAS_Port, Acct_Session_Id, Framed_Address,
>> Nas_Port_Type,
>> Service_Type) values ('test128', 958850927,'206.29.116.6', 20000,
>> '269493545', ''
>> , '', '')
>>
>> Sat May 20 14:28:47 2000: DEBUG: Accounting accepted
>> Sat May 20 14:28:47 2000: DEBUG: Packet dump:
>> *** Sending to 206.29.116.6 port 1033 ....
>> Code: Accounting-Response
>> Identifier: 215
>> Authentic: ca'Q<249>N<132><251><189> <251><239><164><218><190><238>
>> Attributes:
>>
>
>Thanks for sending the logfile, however there is nothing wrong with it. The log
>correctly shows an Access-Request from the NAS, an Access-Accept returned to
>the NAS, followed by an Accounting-Reqeust from the NAS to start the session,
>and an Accounting-Accept retruned to the NAS. This is all perfectly normal.
>
>You mention above something about additional requests from the NAS with a NULL
>username. Could you send me more of the logfile showing what happens with them?
>
>thanks
>
>Hugh
>
>
>--
>Radiator: the most portable, flexible and configurable RADIUS server
>anywhere. SQL, proxy, DBM, files, LDAP, NIS+, password, NT, Emerald,
>Platypus, Freeside, Interbiller, TACACS+, PAM, external, etc, etc.
>Available on Unix, Linux, FreeBSD, Windows 95/98/2000, NT, MacOS X.
>
>
===
Archive at http://www.starport.net/~radiator/
Announcements on [EMAIL PROTECTED]
To unsubscribe, email '[EMAIL PROTECTED]' with
'unsubscribe radiator' in the body of the message.
