FW: (RADIATOR) Problem on HPUX 64bit
Daud Yusof
daudy at bee-net.com
Thu Jul 19 03:39:15 CDT 2001
Daud Yusof
Application Manager
BEENET Singapore Pte Ltd
Thanks Hugh,
Here's the Trace.
--START--
Wed Jul 18 00:01:33 2001: DEBUG: Packet dump:
*** Received from 203.211.128.6 port 1168 ....
Code: Access-Request
Identifier: 248
Authentic: <7>[<0><0>jG<0><0><253><7><0><0><130><<0><0>
Attributes:
User-Name = "ovuser"
User-Password =
"'<222><227>s<243><128>2<134><230><197><10>+<211><180><136>l"
Wed Jul 18 00:01:33 2001: DEBUG: Rewrote user name to ovuser
Wed Jul 18 00:01:33 2001: DEBUG: Handling request with Handler
'Realm=network'
Wed Jul 18 00:01:33 2001: DEBUG: Deleting session for ovuser,
203.211.128.6,
Wed Jul 18 00:01:33 2001: DEBUG: do query is: delete from RADONLINE where
NASIDENTIFIER='203.211.128.6' and NASPORT=0
Wed Jul 18 00:01:33 2001: DEBUG: Handling with Radius::AuthRADMIN
Wed Jul 18 00:01:33 2001: DEBUG: do query is: insert into RADMESSAGES
(TIME_STAMP, TYPE, MESSAGE) values (995385693, 4, 'Handling with
Radius::AuthRADMIN')
Wed Jul 18 00:01:33 2001: DEBUG: Handling with Radius::AuthRADMIN
Wed Jul 18 00:01:33 2001: DEBUG: do query is: insert into RADMESSAGES
(TIME_STAMP, TYPE, MESSAGE) values (995385693, 4, 'Handling with
Radius::AuthRADMIN')
Wed Jul 18 00:01:33 2001: DEBUG: Query is: select PASS_WORD,
STATICADDRESS,TIMELEFT, MAXLOGINS from RADUSERS where
USERNAME='ovuser at network' and BADLOGINS < 10 and VALIDFROM < 995385693 and
VALIDTO > 995385693 and SERVICENAME='NETWORK'
Wed Jul 18 00:01:33 2001: DEBUG: Radius::AuthRADMIN looks for match with
ovuser at network
Wed Jul 18 00:01:33 2001: DEBUG: do query is:
Wed Jul 18 00:01:33 2001: ERR: do failed for '': ORA-24373: invalid length
specified for statement (DBD ERROR: OCIStmtPrepare)
Wed Jul 18 00:01:33 2001: ERR: do failed for '': ORA-24373: invalid length
specified for statement (DBD ERROR: OCIStmtPrepare)
Wed Jul 18 00:01:33 2001: DEBUG: Query is: select NASIDENTIFIER, NASPORT,
ACCTSESSIONID, FRAMEDIPADDRESS from RADONLINE where USERNAME='ovuser'
Wed Jul 18 00:01:33 2001: DEBUG: Radius::AuthRADMIN ACCEPT:
Wed Jul 18 00:01:33 2001: DEBUG: do query is: insert into RADMESSAGES
(TIME_STAMP, TYPE, MESSAGE) values (995385693, 4, 'Radius::AuthRADMIN
ACCEPT: ')
Wed Jul 18 00:01:33 2001: DEBUG: do query is: update RADUSERS set
BADLOGINS=0 where USERNAME='ovuser at network'
Wed Jul 18 00:01:33 2001: DEBUG: Access accepted for ovuser at network
Wed Jul 18 00:01:33 2001: DEBUG: Packet dump:
*** Sending to 203.211.128.6 port 1168 ....
Code: Access-Accept
Identifier: 248
Authentic: <7>[<0><0>jG<0><0><253><7><0><0><130><<0><0>
Attributes:
Framed-Protocol = PPP
Framed-IP-Netmask = 255.255.255.255
Framed-Routing = None
Framed-MTU = 1500
Framed-Compression = Van-Jacobson-TCP-IP
Wed Jul 18 00:16:34 2001: DEBUG: Packet dump:
*** Received from 203.211.128.6 port 3885 ....
Code: Access-Request
Identifier: 10
Authentic: <133>f<0><0><196><12><0><0><127>!<0><0><24><16><0><0>
Attributes:
User-Name = "ovuser"
User-Password = "Y`<173><221><17><210>e$C(=:<201>*<4> "
Wed Jul 18 00:16:34 2001: DEBUG: Rewrote user name to ovuser
Wed Jul 18 00:16:34 2001: DEBUG: Handling request with Handler
'Realm=network'
Wed Jul 18 00:16:34 2001: DEBUG: Deleting session for ovuser,
203.211.128.6,
Wed Jul 18 00:16:34 2001: DEBUG: do query is: delete from RADONLINE where
NASIDENTIFIER='203.211.128.6' and NASPORT=0
Wed Jul 18 00:16:34 2001: DEBUG: Handling with Radius::AuthRADMIN
Wed Jul 18 00:16:34 2001: DEBUG: do query is: insert into RADMESSAGES
(TIME_STAMP, TYPE, MESSAGE) values (995386594, 4, 'Handling with
Radius::AuthRADMIN')
Wed Jul 18 00:16:34 2001: DEBUG: Handling with Radius::AuthRADMIN
Wed Jul 18 00:16:34 2001: DEBUG: do query is: insert into RADMESSAGES
(TIME_STAMP, TYPE, MESSAGE) values (995386594, 4, 'Handling with
Radius::AuthRADMIN')
Wed Jul 18 00:16:34 2001: DEBUG: Query is: select PASS_WORD,
STATICADDRESS,TIMELEFT, MAXLOGINS from RADUSERS where
USERNAME='ovuser at network' and BADLOGINS < 10 and VALIDFROM < 995386594 and
VALIDTO > 995386594 and SERVICENAME='NETWORK'
Wed Jul 18 00:16:34 2001: DEBUG: Radius::AuthRADMIN looks for match with
ovuser at network
Wed Jul 18 00:16:34 2001: DEBUG: do query is:
Wed Jul 18 00:16:34 2001: ERR: do failed for '': ORA-24373: invalid length
specified for statement (DBD ERROR: OCIStmtPrepare)
Wed Jul 18 00:16:35 2001: ERR: do failed for '': ORA-24373: invalid length
specified for statement (DBD ERROR: OCIStmtPrepare)
Wed Jul 18 00:16:35 2001: DEBUG: Query is: select NASIDENTIFIER, NASPORT,
ACCTSESSIONID, FRAMEDIPADDRESS from RADONLINE where USERNAME='ovuser'
Wed Jul 18 00:16:35 2001: DEBUG: Radius::AuthRADMIN ACCEPT:
Wed Jul 18 00:16:35 2001: DEBUG: do query is: insert into RADMESSAGES
(TIME_STAMP, TYPE, MESSAGE) values (995386595, 4, 'Radius::AuthRADMIN
ACCEPT: ')
Wed Jul 18 00:16:35 2001: DEBUG: do query is: update RADUSERS set
BADLOGINS=0 where USERNAME='ovuser at network'
Wed Jul 18 00:16:35 2001: DEBUG: Access accepted for ovuser at network
Wed Jul 18 00:16:35 2001: DEBUG: Packet dump:
*** Sending to 203.211.128.6 port 3885 ....
Code: Access-Accept
Identifier: 10
Authentic: <133>f<0><0><196><12><0><0><127>!<0><0><24><16><0><0>
Attributes:
Framed-Protocol = PPP
Framed-IP-Netmask = 255.255.255.255
Framed-Routing = None
Framed-MTU = 1500
Framed-Compression = Van-Jacobson-TCP-IP
Wed Jul 18 12:45:25 2001: DEBUG: Packet dump:
*** Received from 203.211.129.50 port 1645 ....
Code: Access-Request
Identifier: 111
Authentic: ]7<250><167>4<204><235><218>2T<6><182><197>_):
Attributes:
NAS-IP-Address = 203.211.152.1
NAS-Port = 34
NAS-Port-Type = Async
User-Name = "testxyz at xyz.com"
Called-Station-Id = "8835018"
Calling-Station-Id = "8839055"
User-Password = "5<238>J<205>(<229><186>X0<165>n<235>y<130><239><169>"
Service-Type = Framed-User
Framed-Protocol = PPP
Wed Jul 18 12:45:25 2001: DEBUG: Rewrote user name to testxyz at xyz.com
Wed Jul 18 12:45:25 2001: DEBUG: Check if Handler
Called-Station-Id=8835018,Realm=abc.com should be used to handle this
request
Wed Jul 18 12:45:25 2001: DEBUG: Check if Handler
Called-Station-Id=8835018,Realm=batman.com should be used to handle this
request
Wed Jul 18 12:45:25 2001: DEBUG: Check if Handler
Called-Station-Id=8835018,Realm=xyz.com should be used to handle this
request
Wed Jul 18 12:45:25 2001: DEBUG: Handling request with Handler
'Called-Station-Id=8835018,Realm=xyz.com'
Wed Jul 18 12:45:25 2001: DEBUG: Deleting session for testxyz at xyz.com,
203.211.152.1, 34
Wed Jul 18 12:45:25 2001: DEBUG: do query is: delete from RADONLINE where
NASIDENTIFIER='203.211.152.1' and NASPORT=034
Wed Jul 18 12:45:25 2001: DEBUG: Handling with Radius::AuthRADMIN
Wed Jul 18 12:45:25 2001: DEBUG: do query is: insert into RADMESSAGES
(TIME_STAMP, TYPE, MESSAGE) values (995431525, 4, 'Handling with
Radius::AuthRADMIN')
Wed Jul 18 12:45:25 2001: DEBUG: Handling with Radius::AuthRADMIN
Wed Jul 18 12:45:25 2001: DEBUG: do query is: insert into RADMESSAGES
(TIME_STAMP, TYPE, MESSAGE) values (995431525, 4, 'Handling with
Radius::AuthRADMIN')
Wed Jul 18 12:45:25 2001: DEBUG: Query is: select PASS_WORD,
STATICADDRESS,TIMELEFT, MAXLOGINS from RADUSERS where
USERNAME='testxyz at xyz.com' and BADLOGINS < 10 and VALIDFROM < 995431525 and
VALIDTO > 995431525 and SERVICENAME='RAS'
Wed Jul 18 12:45:25 2001: DEBUG: Radius::AuthRADMIN looks for match with
testxyz at xyz.com
Wed Jul 18 12:45:25 2001: DEBUG: do query is:
Wed Jul 18 12:45:25 2001: ERR: do failed for '': ORA-24373: invalid length
specified for statement (DBD ERROR: OCIStmtPrepare)
Wed Jul 18 12:45:25 2001: ERR: do failed for '': ORA-24373: invalid length
specified for statement (DBD ERROR: OCIStmtPrepare)
Wed Jul 18 12:45:25 2001: DEBUG: Query is: select NASIDENTIFIER, NASPORT,
ACCTSESSIONID, FRAMEDIPADDRESS from RADONLINE where
USERNAME='testxyz at xyz.com'
Wed Jul 18 12:45:25 2001: DEBUG: Radius::AuthRADMIN ACCEPT:
Wed Jul 18 12:45:25 2001: DEBUG: do query is: insert into RADMESSAGES
(TIME_STAMP, TYPE, MESSAGE) values (995431525, 4, 'Radius::AuthRADMIN
ACCEPT: ')
Wed Jul 18 12:45:25 2001: DEBUG: do query is: update RADUSERS set
BADLOGINS=0 where USERNAME='testxyz at xyz.com'
Wed Jul 18 12:45:25 2001: DEBUG: Access accepted for testxyz at xyz.com
Wed Jul 18 12:45:25 2001: DEBUG: Packet dump:
*** Sending to 203.211.129.50 port 1645 ....
Code: Access-Accept
Identifier: 111
Authentic: ]7<250><167>4<204><235><218>2T<6><182><197>_):
Attributes:
Framed-Protocol = PPP
Framed-IP-Netmask = 255.255.255.255
Framed-Routing = None
Framed-MTU = 1500
Framed-Compression = Van-Jacobson-TCP-IP
Wed Jul 18 12:45:25 2001: DEBUG: Packet dump:
*** Received from 203.211.129.50 port 1646 ....
Code: Accounting-Request
Identifier: 112
Authentic: <19><219><0><178>Z<216><176><23><246><5>Yx<25><149>]f
Attributes:
NAS-IP-Address = 203.211.152.1
NAS-Port = 34
NAS-Port-Type = Async
User-Name = "testxyz at xyz.com"
Called-Station-Id = "8835018"
Calling-Station-Id = "8839055"
Acct-Status-Type = Start
Acct-Authentic = RADIUS
Service-Type = Framed-User
Acct-Session-Id = "00000051"
Framed-Protocol = PPP
Acct-Delay-Time = 0
Wed Jul 18 12:45:25 2001: DEBUG: Rewrote user name to testxyz at xyz.com
Wed Jul 18 12:45:25 2001: DEBUG: Check if Handler
Called-Station-Id=8835018,Realm=abc.com should be used to handle this
request
Wed Jul 18 12:45:25 2001: DEBUG: Check if Handler
Called-Station-Id=8835018,Realm=batman.com should be used to handle this
request
Wed Jul 18 12:45:25 2001: DEBUG: Check if Handler
Called-Station-Id=8835018,Realm=xyz.com should be used to handle this
request
Wed Jul 18 12:45:25 2001: DEBUG: Handling request with Handler
'Called-Station-Id=8835018,Realm=xyz.com'
Wed Jul 18 12:45:25 2001: DEBUG: Adding session for testxyz at xyz.com,
203.211.152.1, 34
Wed Jul 18 12:45:25 2001: DEBUG: do query is: delete from RADONLINE where
NASIDENTIFIER='203.211.152.1' and NASPORT=034
Wed Jul 18 12:45:25 2001: DEBUG: do query is: insert into RADONLINE
(USERNAME, NASIDENTIFIER, NASPORT, ACCTSESSIONID, TIME_STAMP,
FRAMEDIPADDRESS, NASPORTTYPE, SERVICETYPE) values ('testxyz at xyz.com',
'203.211.152.1', 034, '00000051', 995431525, '', 'Async', 'Framed-User')
Wed Jul 18 12:45:25 2001: DEBUG: Handling with Radius::AuthRADMIN
Wed Jul 18 12:45:25 2001: DEBUG: do query is: insert into RADMESSAGES
(TIME_STAMP, TYPE, MESSAGE) values (995431525, 4, 'Handling with
Radius::AuthRADMIN')
Wed Jul 18 12:45:25 2001: DEBUG: Handling accounting with Radius::AuthRADMIN
Wed Jul 18 12:45:25 2001: DEBUG: do query is: insert into RADMESSAGES
(TIME_STAMP, TYPE, MESSAGE) values (995431525, 4, 'Handling accounting with
Radius::AuthRADMIN')
Wed Jul 18 12:45:25 2001: DEBUG: do query is: update RADUSERS set
TIMELEFT=TIMELEFT-0%{Acct-S
Wed Jul 18 12:45:25 2001: ERR: do failed for 'update RADUSERS set
TIMELEFT=TIMELEFT-0%{Acct-S': ORA-00911: invalid character (DBD ERROR:
OCIStmtExecute)
Wed Jul 18 12:45:26 2001: ERR: do failed for 'update RADUSERS set
TIMELEFT=TIMELEFT-0%{Acct-S': ORA-00911: invalid character (DBD ERROR:
OCIStmtExecute)
Wed Jul 18 12:45:26 2001: DEBUG: do query is: insert into RADUSAGE
(USERNAME, TIME_STAMP, ACCTSTATUSTYPE, ACCTDELAYTIME, ACCTSESSIONID,
NASIDENTIFIER, NASPORT, DNIS)
values
(, 995431525, 1, 0, , , 34, )
Wed Jul 18 12:45:26 2001: ERR: do failed for 'insert into RADUSAGE
(USERNAME, TIME_STAMP, ACCTSTATUSTYPE, ACCTDELAYTIME, ACCTSESSIONID,
NASIDENTIFIER, NASPORT, DNIS)
values
(, 995431525, 1, 0, , , 34, )': ORA-00936: missing expression (DBD ERROR:
OCIStmtExecute)
Wed Jul 18 12:45:26 2001: ERR: do failed for 'insert into RADUSAGE
(USERNAME, TIME_STAMP, ACCTSTATUSTYPE, ACCTDELAYTIME, ACCTSESSIONID,
NASIDENTIFIER, NASPORT, DNIS)
values
(, 995431525, 1, 0, , , 34, )': ORA-00936: missing expression (DBD ERROR:
OCIStmtExecute)
Wed Jul 18 12:45:26 2001: DEBUG: Accounting accepted
Wed Jul 18 12:45:26 2001: DEBUG: Packet dump:
*** Sending to 203.211.129.50 port 1646 ....
Code: Accounting-Response
Identifier: 112
Authentic: <19><219><0><178>Z<216><176><23><246><5>Yx<25><149>]f
Attributes:
---END---
-----Original Message-----
From: Hugh Irvine [mailto:hugh at open.com.au]
Sent: Thursday, July 19, 2001 1:46 PM
To: Daud Yusof; Radiator
Subject: Re: (RADIATOR) Problem on HPUX 64bit
Hello Daudy -
On Thursday 19 July 2001 15:16, Daud Yusof wrote:
> I'm seeing ORA error in the logfile but the access is accepted. What
> happenned here ?
>
> >Wed Jul 18 00:01:33 2001: DEBUG: Radius::AuthRADMIN looks for match with
>
> ovuser at network
>
> >Wed Jul 18 00:01:33 2001: DEBUG: do query is:
> >Wed Jul 18 00:01:33 2001: ERR: do failed for '': ORA-24373: invalid
length
>
> specified for statement (DBD ERROR: OCIStmtPrepare)
>
> >Wed Jul 18 00:01:33 2001: DEBUG: do query is: insert into RADMESSAGES
>
> (TIME_STAMP, TYPE, MESSAGE) values (995385693, 4, 'Radius::AuthRADMIN
> ACCEPT: ')
>
I think the error message above refers to the SQL statement preceding the
message rather than the one following, but I would need to see a more
complete trace 4 to tell for sure.
>
> I'm also getting an error in updating the account table for some of the
> Realms due to missing User-Name etc.
>
> >Wed Jul 18 12:45:26 2001: ERR: do failed for 'insert into
>
> RADUSAGE(USERNAME, TIME_STAMP, ACCTSTATUSTYPE, ACCTDELAYTIME,
> ACCTSESSIONID, NASIDENTIFIER, NASPORT, DNIS) values (, 995431525, 1, 0, ,
,
> 34, )': ORA-00936: missing expression (DBD ERROR: OCIStmtExecute)
>
This looks like an Accounting Start when a NAS reboots. You can either
ignore
the message, or set up a special Hander to trap these packets.
hth
Hugh
--
Radiator: the most portable, flexible and configurable RADIUS server
anywhere. Available on *NIX, *BSD, Windows 95/98/2000, NT, MacOS X.
-
Nets: internetwork inventory and management - graphical, extensible,
flexible with hardware, software, platform and database independence.
===
Archive at http://www.open.com.au/archives/radiator/
Announcements on radiator-announce at open.com.au
To unsubscribe, email 'majordomo at open.com.au' with
'unsubscribe radiator' in the body of the message.
More information about the radiator
mailing list