simultaneous-Use is not worrking
Miha
miha at softnet.si
Sat Nov 3 21:11:52 CET 2012
HI Alan,
log with simultaneous-Use := 1:
rad_recv: Accounting-Request packet from host
xxx.xxx.xxx.xxx port 40165, id=56, length=327
Acct-Status-Type = Start
Acct-Session-Id = "21613e25-697e-4d90-a741-586503d4abcb"
User-Name = "018108753.enterprise"
Freeswitch-Src = "018108753.enterprise"
Freeswitch-CLID = "018108753.enterprise"
Freeswitch-Dst = "031681796"
Freeswitch-Dialplan = "XML"
Framed-IP-Address = 84.41.126.2
Freeswitch-Context = "enterprise.fs2.softnet.si"
Freeswitch-Ani = "018108753.enterprise"
Freeswitch-Source = "mod_sofia"
Freeswitch-Callstartdate =
"2012-11-03T21:07:03.383945+0100"
NAS-Port = 0
Acct-Delay-Time = 0
NAS-IP-Address = xxx.xxx.xxx.xxx
# Executing section preacct from file
/usr/local/etc/raddb/sites-enabled/default
+- entering group preacct {...}
++[preprocess] returns ok
[acct_unique] Hashing 'NAS-Port = 0,Client-IP-Address =
xxx.xxx.xxx.xxx,NAS-IP-Address =
xxx.xxx.xxx.xxx,Acct-Session-Id =
"21613e25-697e-4d90-a741-586503d4abcb",User-Name =
"018108753.enterprise"'
[acct_unique] Acct-Unique-Session-ID = "ccb7c871be618e52".
++[acct_unique] returns ok
[suffix] No '@' in User-Name = "018108753.enterprise",
looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
++[files] returns noop
# Executing section accounting from file
/usr/local/etc/raddb/sites-enabled/default
+- entering group accounting {...}
[detail] expand: %{Packet-Src-IP-Address} ->
xxx.xxx.xxx.xxx
[detail] expand:
/usr/local/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d
->
/usr/local/var/log/radius/radacct/xxxx.xxxx.xxxx.xxxx/detail-20121103
[detail]
/usr/local/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d
expands to
/usr/local/var/log/radius/radacct/xxxx.xxxx.xxxx.xxxx/detail-20121103
[detail] expand: %t -> Sat Nov 3 21:01:26 2012
++[detail] returns ok
++[unix] returns ok
[radutmp] expand: /usr/local/var/log/radius/radutmp ->
/usr/local/var/log/radius/radutmp
[radutmp] expand: %{User-Name} -> 018108753.enterprise
++[radutmp] returns ok
[sql] expand: %{User-Name} -> 018108753.enterprise
[sql] sql_set_user escaped user --> '018108753.enterprise'
[sql] expand: %{Acct-Delay-Time} -> 0
[sql] expand: INSERT INTO radacct
(acctsessionid, acctuniqueid, username,
realm, nasipaddress, nasportid,
nasporttype, acctstarttime,
acctstoptime, acctsessiontime,
acctauthentic, connectinfo_start,
connectinfo_stop, acctinputoctets,
acctoutputoctets, calledstationid,
callingstationid, acctterminatecause,
servicetype, framedprotocol,
framedipaddress, acctstartdelay,
acctstopdelay, xascendsessionsvrkey) VALUES
('%{Acct-Session-Id}',
'%{Acct-Unique-Session-Id}',
'%{Freeswitch-Src}', '%{Realm}',
'%{NAS-IP-Address}', '%{NAS-Port}',
'%{NAS-Port-Type}', '%S', NULL,
'0', '%{Acct-Authentic}', '%{Connect-Info}',
'', '0', '0',
'%{Called-Station-Id}',
'%{Calling-Station-Id}', '',
'%{Service-Type}', '%{Framed-Protocol}',
'%{Framed-IP-Address}',
rlm_sql (sql): Reserving sql socket id: 4
rlm_sql (sql): Released sql socket id: 4
++[sql] returns ok
++[exec] returns noop
[attr_filter.accounting_response] expand: %{User-Name} ->
018108753.enterprise
attr_filter: Matched entry DEFAULT at line 12
++[attr_filter.accounting_response] returns updated
Sending Accounting-Response of id 56 to xxx.xxx.xxx.xxx
port 40165
Finished request 0.
Cleaning up request 0 ID 56 with timestamp +2994
Going to the next request
Ready to process requests.
rad_recv: Access-Request packet from host xxx.xxx.xxx.xxx
port 43949, id=57, length=193
User-Name = "018108753.enterprise"
User-Password = "xxxxxxx"
h323-conf-id =
"h323-conf-id=21613e25-697e-4d90-a741-586503d4abcb"
h323-prompt-id = "h323-prompt-id=031681796"
NAS-Port-Type = Async
NAS-Port-Id = "ISDN 3/0:D:14"
User-Name = "1"
NAS-Port = 0
NAS-IP-Address = xxxx.xxxx.xxxx.xxxx
# Executing section authorize from file
/usr/local/etc/raddb/sites-enabled/default
+- entering group authorize {...}
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
++[digest] returns noop
[suffix] No '@' in User-Name = "018108753.enterprise",
looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] No EAP-Message, not doing EAP
++[eap] returns noop
++[files] returns noop
[sql] expand: %{User-Name} -> 018108753.enterprise
[sql] sql_set_user escaped user --> '018108753.enterprise'
rlm_sql (sql): Reserving sql socket id: 3
[sql] expand: SELECT id, username, attribute, value, op
FROM radcheck WHERE username =
'%{SQL-User-Name}' ORDER BY id -> SELECT id,
username, attribute, value, op FROM radcheck
WHERE username = '018108753.enterprise'
ORDER BY id
[sql] User found in radcheck table
[sql] expand: SELECT id, username, attribute, value, op
FROM radreply WHERE username =
'%{SQL-User-Name}' ORDER BY id -> SELECT id,
username, attribute, value, op FROM radreply
WHERE username = '018108753.enterprise'
ORDER BY id
[sql] expand: SELECT groupname FROM radusergroup
WHERE username = '%{SQL-User-Name}'
ORDER BY priority -> SELECT groupname
FROM radusergroup WHERE username =
'018108753.enterprise' ORDER BY priority
[sql] expand: SELECT id, groupname, attribute,
Value, op FROM radgroupcheck
WHERE groupname = '%{Sql-Group}' ORDER
BY id -> SELECT id, groupname, attribute, Value,
op FROM radgroupcheck WHERE groupname =
'testservice' ORDER BY id
[sql] User found in group testservice
[sql] expand: SELECT id, groupname, attribute,
value, op FROM radgroupreply
WHERE groupname = '%{Sql-Group}' ORDER
BY id -> SELECT id, groupname, attribute, value,
op FROM radgroupreply WHERE groupname =
'testservice' ORDER BY id
rlm_sql (sql): Released sql socket id: 3
++[sql] returns ok
++[expiration] returns noop
++[logintime] returns noop
++[pap] returns updated
Found Auth-Type = PAP
# Executing group from file
/usr/local/etc/raddb/sites-enabled/default
+- entering group PAP {...}
[pap] login attempt with password "52166"
[pap] Using clear text password "52166"
[pap] User authenticated successfully
++[pap] returns ok
# Executing section session from file
/usr/local/etc/raddb/sites-enabled/default
+- entering group session {...}
[radutmp] expand: /usr/local/var/log/radius/radutmp ->
/usr/local/var/log/radius/radutmp
[radutmp] expand: %{User-Name} -> 018108753.enterprise
checkrad: No NAS type, or type "other" not checking
++[radutmp] returns ok
Using Post-Auth-Type Reject
# Executing group from file
/usr/local/etc/raddb/sites-enabled/default
+- entering group REJECT {...}
++- group REJECT returns noop
Delaying reject of request 1 for 1 seconds
Going to the next request
Waking up in 0.9 seconds.
Sending delayed reject for request 1
Sending Access-Reject of id 57 to xxxx.xxxx.xxxx.xxxx port
43949
Reply-Message := "\r\nYou are already logged in - access
denied\r\n\n"
Waking up in 4.9 seconds.
Cleaning up request 1 ID 57 with timestamp +2994
Ready to process requests.
-------
simultaneous-Use := 2
rad_recv: Accounting-Request packet from host
xxx.xxx.xxx.xxx port 56503, id=58, length=327
Acct-Status-Type = Start
Acct-Session-Id = "5eb406e9-c555-4471-a7c6-8d475cdf506f"
User-Name = "018108753.enterprise"
Freeswitch-Src = "018108753.enterprise"
Freeswitch-CLID = "018108753.enterprise"
Freeswitch-Dst = "031681796"
Freeswitch-Dialplan = "XML"
Framed-IP-Address = 84.41.126.2
Freeswitch-Context = "enterprise.fs2.softnet.si"
Freeswitch-Ani = "018108753.enterprise"
Freeswitch-Source = "mod_sofia"
Freeswitch-Callstartdate =
"2012-11-03T21:07:50.523808+0100"
NAS-Port = 0
Acct-Delay-Time = 0
NAS-IP-Address = xxx.xxx.xxx.xxx
# Executing section preacct from file
/usr/local/etc/raddb/sites-enabled/default
+- entering group preacct {...}
++[preprocess] returns ok
[acct_unique] Hashing 'NAS-Port = 0,Client-IP-Address =
xxx.xxx.xxx.xxx,NAS-IP-Address =
xxx.xxx.xxx.xxx,Acct-Session-Id =
"5eb406e9-c555-4471-a7c6-8d475cdf506f",User-Name =
"018108753.enterprise"'
[acct_unique] Acct-Unique-Session-ID = "3168bcb9932a1636".
++[acct_unique] returns ok
[suffix] No '@' in User-Name = "018108753.enterprise",
looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
++[files] returns noop
# Executing section accounting from file
/usr/local/etc/raddb/sites-enabled/default
+- entering group accounting {...}
[detail] expand: %{Packet-Src-IP-Address} ->
xxx.xxx.xxx.xxx
[detail] expand:
/usr/local/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d
->
/usr/local/var/log/radius/radacct/xxx.xxx.xxx.xxx/detail-20121103
[detail]
/usr/local/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d
expands to
/usr/local/var/log/radius/radacct/xxx.xxx.xxx.xxx/detail-20121103
[detail] expand: %t -> Sat Nov 3 21:02:13 2012
++[detail] returns ok
++[unix] returns ok
[radutmp] expand: /usr/local/var/log/radius/radutmp ->
/usr/local/var/log/radius/radutmp
[radutmp] expand: %{User-Name} -> 018108753.enterprise
++[radutmp] returns ok
[sql] expand: %{User-Name} -> 018108753.enterprise
[sql] sql_set_user escaped user --> '018108753.enterprise'
[sql] expand: %{Acct-Delay-Time} -> 0
[sql] expand: INSERT INTO radacct
(acctsessionid, acctuniqueid, username,
realm, nasipaddress, nasportid,
nasporttype, acctstarttime,
acctstoptime, acctsessiontime,
acctauthentic, connectinfo_start,
connectinfo_stop, acctinputoctets,
acctoutputoctets, calledstationid,
callingstationid, acctterminatecause,
servicetype, framedprotocol,
framedipaddress, acctstartdelay,
acctstopdelay, xascendsessionsvrkey) VALUES
('%{Acct-Session-Id}',
'%{Acct-Unique-Session-Id}',
'%{Freeswitch-Src}', '%{Realm}',
'%{NAS-IP-Address}', '%{NAS-Port}',
'%{NAS-Port-Type}', '%S', NULL,
'0', '%{Acct-Authentic}', '%{Connect-Info}',
'', '0', '0',
'%{Called-Station-Id}',
'%{Calling-Station-Id}', '',
'%{Service-Type}', '%{Framed-Protocol}',
'%{Framed-IP-Address}',
rlm_sql (sql): Reserving sql socket id: 2
rlm_sql (sql): Released sql socket id: 2
++[sql] returns ok
++[exec] returns noop
[attr_filter.accounting_response] expand: %{User-Name} ->
018108753.enterprise
attr_filter: Matched entry DEFAULT at line 12
++[attr_filter.accounting_response] returns updated
Sending Accounting-Response of id 58 to xxx.xxx.xxx.xxx
port 56503
Finished request 2.
Cleaning up request 2 ID 58 with timestamp +3041
Going to the next request
Ready to process requests.
rad_recv: Access-Request packet from host xxx.xxx.xxx.xxx
port 37173, id=59, length=193
User-Name = "018108753.enterprise"
User-Password = "xxxxxx"
h323-conf-id =
"h323-conf-id=5eb406e9-c555-4471-a7c6-8d475cdf506f"
h323-prompt-id = "h323-prompt-id=031681796"
NAS-Port-Type = Async
NAS-Port-Id = "ISDN 3/0:D:14"
User-Name = "1"
NAS-Port = 0
NAS-IP-Address = xxx.xxx.xxx.xxx
# Executing section authorize from file
/usr/local/etc/raddb/sites-enabled/default
+- entering group authorize {...}
++[preprocess] returns ok
++[chap] returns noop
++[mschap] returns noop
++[digest] returns noop
[suffix] No '@' in User-Name = "018108753.enterprise",
looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[eap] No EAP-Message, not doing EAP
++[eap] returns noop
++[files] returns noop
[sql] expand: %{User-Name} -> 018108753.enterprise
[sql] sql_set_user escaped user --> '018108753.enterprise'
rlm_sql (sql): Reserving sql socket id: 1
[sql] expand: SELECT id, username, attribute, value, op
FROM radcheck WHERE username =
'%{SQL-User-Name}' ORDER BY id -> SELECT id,
username, attribute, value, op FROM radcheck
WHERE username = '018108753.enterprise'
ORDER BY id
[sql] User found in radcheck table
[sql] expand: SELECT id, username, attribute, value, op
FROM radreply WHERE username =
'%{SQL-User-Name}' ORDER BY id -> SELECT id,
username, attribute, value, op FROM radreply
WHERE username = '018108753.enterprise'
ORDER BY id
[sql] expand: SELECT groupname FROM radusergroup
WHERE username = '%{SQL-User-Name}'
ORDER BY priority -> SELECT groupname
FROM radusergroup WHERE username =
'018108753.enterprise' ORDER BY priority
[sql] expand: SELECT id, groupname, attribute,
Value, op FROM radgroupcheck
WHERE groupname = '%{Sql-Group}' ORDER
BY id -> SELECT id, groupname, attribute, Value,
op FROM radgroupcheck WHERE groupname =
'testservice' ORDER BY id
[sql] User found in group testservice
[sql] expand: SELECT id, groupname, attribute,
value, op FROM radgroupreply
WHERE groupname = '%{Sql-Group}' ORDER
BY id -> SELECT id, groupname, attribute, value,
op FROM radgroupreply WHERE groupname =
'testservice' ORDER BY id
rlm_sql (sql): Released sql socket id: 1
++[sql] returns ok
++[expiration] returns noop
++[logintime] returns noop
++[pap] returns updated
Found Auth-Type = PAP
# Executing group from file
/usr/local/etc/raddb/sites-enabled/default
+- entering group PAP {...}
[pap] login attempt with password "52166"
[pap] Using clear text password "52166"
[pap] User authenticated successfully
++[pap] returns ok
# Executing section session from file
/usr/local/etc/raddb/sites-enabled/default
+- entering group session {...}
[radutmp] expand: /usr/local/var/log/radius/radutmp ->
/usr/local/var/log/radius/radutmp
[radutmp] expand: %{User-Name} -> 018108753.enterprise
++[radutmp] returns ok
# Executing section post-auth from file
/usr/local/etc/raddb/sites-enabled/default
+- entering group post-auth {...}
++[exec] returns noop
Sending Access-Accept of id 59 to xxx.xxx.xxx.xxx port
37173
Finished request 3.
Going to the next request
Waking up in 4.9 seconds.
Cleaning up request 3 ID 59 with timestamp +3041
Ready to process requests.
On Sat, 03 Nov 2012 15:27:41 -0400
Alan DeKok <aland at deployingradius.com> wrote:
> Miha wrote:
> > i am turning out you due to issue with
> simultaneous-Use. I
> > readed mailing list but did not find any appropriate
> > answer.
> ...
> > my config:
> >
> > [root at localhost sites-available]# radiusd -X
> > FreeRADIUS Version 2.1.12, for host
> ...
> > Ready to process requests.
>
> The reason to post the debug output is to show what
> happens when the
> server receives a packet. You didn't show that. So the
> debug output is
> useless, and we can't help you.
>
> Alan DeKok.
> -
> List info/subscribe/unsubscribe? See
> http://www.freeradius.org/list/users.html
More information about the Freeradius-Users
mailing list