Re: [PacketFence-users] Delay between authentication on captive portal and network access being enabled

[Fabrice Durand via PacketFence-users]

It looks that the disconnection doesn't work correctly:
Jan 20 07:19:37 pf pfqueue: pfqueue(30210) WARN: [mac:58:d9:c3:5e:56:e5] 
Unable to perform RADIUS Disconnect-Request. Disconnect-NAK received 
with Error-Cause: Session-Context-Not-Found. (pf::Switch::radiusDisconnect)

Check on the Ruckus side if you see something in the logs related to the 
CoA.

Regards

Fabrice


Le 21-02-19 à 06 h 37, Chris Brown via PacketFence-users a écrit :
Does anyone have advice about how to troubleshoot this?

On Jan 25, 2021, at 2:40 PM, Chris Brown <chr...@vcxtechnologies.com 
<mailto:chr...@vcxtechnologies.com>> wrote:

Hi,

After registering on the captive portal and getting the error 
occurred message, if I open a browser and try and go to cnn.com 
<http://cnn.com/> i get the “error occurred” “your network should be 
enable with a minute or two”… message in my browser.

If I leave the device connected to the wifi, it suddenly gets 
internet access about 5 minutes after registering.

On Jan 25, 2021, at 2:12 AM, Ludovic Zammit <lzam...@inverse.ca 
<mailto:lzam...@inverse.ca>> wrote:

Hello Chris,

5 mins is not normal at all, can you try to connect and start a web 
browser on http://cnn.com <http://cnn.com/> and see if it pops up 
the splash page.

Thanks,
Ludovic Zammit
lzam...@inverse.ca  <mailto:lzam...@inverse.ca>  ::  +1.514.447.4918 (x145) 
::www.inverse.ca
Inverse inc. :: Leaders behind SOGo (http://www.sogo.nu) and PacketFence 
(http://packetfence.org)




On Jan 23, 2021, at 6:57 AM, Chris Brown via PacketFence-users 
<packetfence-users@lists.sourceforge.net 
<mailto:packetfence-users@lists.sourceforge.net>> wrote:

I did some testing with a Unifi AP and the captive portal works 
correctly with the Unifi AP. I immediately get network access with 
the Unifi AP.

Does anyone have advice on how to troubleshoot the delay between 
registering on the captive portal and getting network access on the 
Ruckus APs?

On Jan 20, 2021, at 7:50 AM, Chris Brown 
<chr...@vcxtechnologies.com <mailto:chr...@vcxtechnologies.com>> 
wrote:

Hi,

After logging into via the captive portal, users get a message 
saying that an error occurred while trying to enable network access.

At this point, if I look in the Packetfence nodes list, the device 
will now show as registered and offline.

If the device stays connected to the network for the next 5-10 
minutes, Packetfence will eventually show the node as online, and 
the device will begin getting network access.



packetfence.log follows. There is a bunch of activity around the 
07:19:xx timestamp when the users device authenticates on the 
captive portal. Then access to the network is actually enabled 
around 5 minutes later, around the 7:24:xx time stamp (after the 
break in the log). Can anyone give me advise on how to shorten the 
time between a node registering and getting network access?

Jan 20 07:19:03 pf packetfence_httpd.aaa: httpd.aaa(1775) INFO: 
[mac:58:d9:c3:5e:56:e5] handling radius autz request: from 
switch_ip => (10.200.0.130), connection_type => 
Wireless-802.11-NoEAP,switch_mac => (8c:0c:90:15:14:9c), mac => 
[58:d9:c3:5e:56:e5], port => 0, username => "58d9c35e56e5", ssid 
=> VCX HOTSPOT (pf::radius::authorize)
Jan 20 07:19:03 pf packetfence_httpd.aaa: httpd.aaa(1775) INFO: 
[mac:58:d9:c3:5e:56:e5] Instantiate profile guest 
(pf::Connection::ProfileFactory::_from_profile)
Jan 20 07:19:03 pf packetfence_httpd.aaa: httpd.aaa(1775) INFO: 
[mac:58:d9:c3:5e:56:e5] is of status unreg; belongs into 
registration VLAN (pf::role::getRegistrationRole)
Jan 20 07:19:03 pf packetfence_httpd.aaa: httpd.aaa(1775) INFO: 
[mac:58:d9:c3:5e:56:e5] According to rules in fetchRoleForNode 
this node must be kicked out. Returning USERLOCK 
(pf::Switch::handleRadiusDeny)
Jan 20 07:19:04 pf packetfence_httpd.aaa: httpd.aaa(1775) INFO: 
[mac:58:d9:c3:5e:56:e5] handling radius autz request: from 
switch_ip => (10.200.0.130), connection_type => 
Wireless-802.11-NoEAP,switch_mac => (8c:0c:90:15:14:98), mac => 
[58:d9:c3:5e:56:e5], port => 0, username => "58d9c35e56e5", ssid 
=> VCX HOTSPOT (pf::radius::authorize)
Jan 20 07:19:04 pf packetfence_httpd.aaa: httpd.aaa(1775) INFO: 
[mac:58:d9:c3:5e:56:e5] Instantiate profile guest 
(pf::Connection::ProfileFactory::_from_profile)
Jan 20 07:19:04 pf packetfence_httpd.aaa: httpd.aaa(1775) INFO: 
[mac:58:d9:c3:5e:56:e5] is of status unreg; belongs into 
registration VLAN (pf::role::getRegistrationRole)
Jan 20 07:19:04 pf packetfence_httpd.aaa: httpd.aaa(1775) INFO: 
[mac:58:d9:c3:5e:56:e5] According to rules in fetchRoleForNode 
this node must be kicked out. Returning USERLOCK 
(pf::Switch::handleRadiusDeny)
Jan 20 07:19:14 pf packetfence_httpd.portal: httpd.portal(29816) 
INFO: [mac:00:11:22:33:44:55] URI '/Ruckus' is detected as an 
external captive portal URI (pf::web::externalportal::handle)
Jan 20 07:19:14 pf packetfence_httpd.portal: httpd.portal(27867) 
INFO: [mac:unknown] External captive portal detected ! 
(captiveportal::PacketFence::Model::Portal::Session::_build_dispatcherSession)
Jan 20 07:19:14 pf packetfence_httpd.portal: httpd.portal(27867) 
INFO: [mac:unknown] Detected external portal client. Using the IP 
10.201.0.18 address in it's session. 
(captiveportal::PacketFence::Model::Portal::Session::_build_clientIP)
Jan 20 07:19:14 pf packetfence_httpd.portal: httpd.portal(27867) 
INFO: [mac:58:d9:c3:5e:56:e5] External captive portal detected ! 
(captiveportal::PacketFence::Model::Portal::Session::_build_dispatcherSession)
Jan 20 07:19:14 pf packetfence_httpd.portal: httpd.portal(27867) 
INFO: [mac:58:d9:c3:5e:56:e5] Detected external portal client. 
Using the IP 10.201.0.18 address in it's session. 
(captiveportal::PacketFence::Model::Portal::Session::_build_clientIP)
Jan 20 07:19:14 pf packetfence_httpd.portal: httpd.portal(27867) 
INFO: [mac:58:d9:c3:5e:56:e5] Instantiate profile guest 
(pf::Connection::ProfileFactory::_from_profile)
Jan 20 07:19:14 pf packetfence_httpd.portal: httpd.portal(27867) 
INFO: [mac:58:d9:c3:5e:56:e5] Replacing destination URL 
http://connectivitycheck.gstatic.com/generate_204 since it is a 
network detection URL 
(captiveportal::PacketFence::DynamicRouting::Application::process_destination_url)
Jan 20 07:19:17 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:unknown] External captive portal detected ! 
(captiveportal::PacketFence::Model::Portal::Session::_build_dispatcherSession)
Jan 20 07:19:17 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:unknown] Detected external portal client. Using the IP 
10.201.0.18 address in it's session. 
(captiveportal::PacketFence::Model::Portal::Session::_build_clientIP)
Jan 20 07:19:17 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] External captive portal detected ! 
(captiveportal::PacketFence::Model::Portal::Session::_build_dispatcherSession)
Jan 20 07:19:17 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] Detected external portal client. 
Using the IP 10.201.0.18 address in it's session. 
(captiveportal::PacketFence::Model::Portal::Session::_build_clientIP)
Jan 20 07:19:17 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] Instantiate profile guest 
(pf::Connection::ProfileFactory::_from_profile)
Jan 20 07:19:17 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] Replacing destination URL 
http://connectivitycheck.gstatic.com/generate_204 since it is a 
network detection URL 
(captiveportal::PacketFence::DynamicRouting::Application::process_destination_url)
Jan 20 07:19:18 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:unknown] External captive portal detected ! 
(captiveportal::PacketFence::Model::Portal::Session::_build_dispatcherSession)
Jan 20 07:19:18 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:unknown] Detected external portal client. Using the IP 
10.201.0.18 address in it's session. 
(captiveportal::PacketFence::Model::Portal::Session::_build_clientIP)
Jan 20 07:19:18 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] External captive portal detected ! 
(captiveportal::PacketFence::Model::Portal::Session::_build_dispatcherSession)
Jan 20 07:19:18 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] Detected external portal client. 
Using the IP 10.201.0.18 address in it's session. 
(captiveportal::PacketFence::Model::Portal::Session::_build_clientIP)
Jan 20 07:19:18 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] Instantiate profile guest 
(pf::Connection::ProfileFactory::_from_profile)
Jan 20 07:19:18 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] Replacing destination URL 
http://connectivitycheck.gstatic.com/generate_204 since it is a 
network detection URL 
(captiveportal::PacketFence::DynamicRouting::Application::process_destination_url)
Jan 20 07:19:18 pf packetfence_httpd.portal: httpd.portal(29816) 
INFO: [mac:unknown] External captive portal detected ! 
(captiveportal::PacketFence::Model::Portal::Session::_build_dispatcherSession)
Jan 20 07:19:18 pf packetfence_httpd.portal: httpd.portal(29816) 
INFO: [mac:unknown] Detected external portal client. Using the IP 
10.201.0.18 address in it's session. 
(captiveportal::PacketFence::Model::Portal::Session::_build_clientIP)
Jan 20 07:19:18 pf packetfence_httpd.portal: httpd.portal(29816) 
INFO: [mac:58:d9:c3:5e:56:e5] External captive portal detected ! 
(captiveportal::PacketFence::Model::Portal::Session::_build_dispatcherSession)
Jan 20 07:19:18 pf packetfence_httpd.portal: httpd.portal(29816) 
INFO: [mac:58:d9:c3:5e:56:e5] Detected external portal client. 
Using the IP 10.201.0.18 address in it's session. 
(captiveportal::PacketFence::Model::Portal::Session::_build_clientIP)
Jan 20 07:19:18 pf packetfence_httpd.portal: httpd.portal(29816) 
INFO: [mac:58:d9:c3:5e:56:e5] Instantiate profile guest 
(pf::Connection::ProfileFactory::_from_profile)
Jan 20 07:19:18 pf packetfence_httpd.portal: httpd.portal(29816) 
INFO: [mac:58:d9:c3:5e:56:e5] Replacing destination URL 
http://connectivitycheck.gstatic.com/generate_204 since it is a 
network detection URL 
(captiveportal::PacketFence::DynamicRouting::Application::process_destination_url)
Jan 20 07:19:34 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:unknown] External captive portal detected ! 
(captiveportal::PacketFence::Model::Portal::Session::_build_dispatcherSession)
Jan 20 07:19:34 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:unknown] Detected external portal client. Using the IP 
10.201.0.18 address in it's session. 
(captiveportal::PacketFence::Model::Portal::Session::_build_clientIP)
Jan 20 07:19:34 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] External captive portal detected ! 
(captiveportal::PacketFence::Model::Portal::Session::_build_dispatcherSession)
Jan 20 07:19:34 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] Detected external portal client. 
Using the IP 10.201.0.18 address in it's session. 
(captiveportal::PacketFence::Model::Portal::Session::_build_clientIP)
Jan 20 07:19:34 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] Instantiate profile guest 
(pf::Connection::ProfileFactory::_from_profile)
Jan 20 07:19:34 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] Replacing destination URL 
http://connectivitycheck.gstatic.com/generate_204 since it is a 
network detection URL 
(captiveportal::PacketFence::DynamicRouting::Application::process_destination_url)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] Found authentication source(s) : 
'local' for realm 'null' 
(pf::config::util::filter_authentication_sources)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] Authenticating user using sources : 
local 
(captiveportal::PacketFence::DynamicRouting::Module::Authentication::Login::authenticate)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] Authentication successful for Test 
in source local (SQL) (pf::authentication::authenticate)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] User Test has authenticated on the 
portal. (Class::MOP::Class:::after)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] Found source local in session. 
(Class::MOP::Class:::around)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] Found source local in session. 
(Class::MOP::Class:::around)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] Successfully authenticated Test 
(captiveportal::PacketFence::DynamicRouting::Module::Authentication::Login::authenticate)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] Found source local in session. 
(Class::MOP::Class:::around)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] Found source local in session. 
(Class::MOP::Class:::around)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] Found source local in session. 
(Class::MOP::Class:::around)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] User Test has authenticated on the 
portal. (Class::MOP::Class:::after)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
WARN: [mac:58:d9:c3:5e:56:e5] Calling match with empty/invalid 
rule class. Defaulting to 'authentication' (pf::authentication::match)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] Using sources local for matching 
(pf::authentication::match)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] Found source local in session. 
(Class::MOP::Class:::around)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] User Test has authenticated on the 
portal. (Class::MOP::Class:::after)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
WARN: [mac:58:d9:c3:5e:56:e5] Calling match with empty/invalid 
rule class. Defaulting to 'authentication' (pf::authentication::match)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] Using sources local for matching 
(pf::authentication::match)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] Found source local in session. 
(Class::MOP::Class:::around)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] User Test has authenticated on the 
portal. (Class::MOP::Class:::after)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
WARN: [mac:58:d9:c3:5e:56:e5] Calling match with empty/invalid 
rule class. Defaulting to 'authentication' (pf::authentication::match)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] Using sources local for matching 
(pf::authentication::match)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] Found source local in session. 
(Class::MOP::Class:::around)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] User Test has authenticated on the 
portal. (Class::MOP::Class:::after)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
WARN: [mac:58:d9:c3:5e:56:e5] Calling match with empty/invalid 
rule class. Defaulting to 'authentication' (pf::authentication::match)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] Using sources local for matching 
(pf::authentication::match)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] Found source local in session. 
(Class::MOP::Class:::around)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] Found source local in session. 
(Class::MOP::Class:::around)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(29816) 
INFO: [mac:unknown] External captive portal detected ! 
(captiveportal::PacketFence::Model::Portal::Session::_build_dispatcherSession)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(29816) 
INFO: [mac:unknown] Detected external portal client. Using the IP 
10.201.0.18 address in it's session. 
(captiveportal::PacketFence::Model::Portal::Session::_build_clientIP)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(29816) 
INFO: [mac:58:d9:c3:5e:56:e5] External captive portal detected ! 
(captiveportal::PacketFence::Model::Portal::Session::_build_dispatcherSession)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(29816) 
INFO: [mac:58:d9:c3:5e:56:e5] Detected external portal client. 
Using the IP 10.201.0.18 address in it's session. 
(captiveportal::PacketFence::Model::Portal::Session::_build_clientIP)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(29816) 
INFO: [mac:58:d9:c3:5e:56:e5] Instantiate profile guest 
(pf::Connection::ProfileFactory::_from_profile)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27867) 
INFO: [mac:unknown] External captive portal detected ! 
(captiveportal::PacketFence::Model::Portal::Session::_build_dispatcherSession)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27867) 
INFO: [mac:unknown] Detected external portal client. Using the IP 
10.201.0.18 address in it's session. 
(captiveportal::PacketFence::Model::Portal::Session::_build_clientIP)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27867) 
INFO: [mac:58:d9:c3:5e:56:e5] External captive portal detected ! 
(captiveportal::PacketFence::Model::Portal::Session::_build_dispatcherSession)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27867) 
INFO: [mac:58:d9:c3:5e:56:e5] Detected external portal client. 
Using the IP 10.201.0.18 address in it's session. 
(captiveportal::PacketFence::Model::Portal::Session::_build_clientIP)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27867) 
INFO: [mac:58:d9:c3:5e:56:e5] Instantiate profile guest 
(pf::Connection::ProfileFactory::_from_profile)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27867) 
INFO: [mac:58:d9:c3:5e:56:e5] User Test has authenticated on the 
portal. (Class::MOP::Class:::after)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27867) 
INFO: [mac:58:d9:c3:5e:56:e5] No provisioner found for 
58:d9:c3:5e:56:e5. Continuing. 
(captiveportal::PacketFence::DynamicRouting::Module::Provisioning::execute_child)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27867) 
INFO: [mac:58:d9:c3:5e:56:e5] User Test has authenticated on the 
portal. (Class::MOP::Class:::after)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27867) 
INFO: [mac:58:d9:c3:5e:56:e5] User Test has authenticated on the 
portal. (Class::MOP::Class:::after)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27867) 
INFO: [mac:58:d9:c3:5e:56:e5] User Test has authenticated on the 
portal. (Class::MOP::Class:::after)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27867) 
INFO: [mac:58:d9:c3:5e:56:e5] User Test has authenticated on the 
portal. (Class::MOP::Class:::after)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27867) 
INFO: [mac:58:d9:c3:5e:56:e5] User Test has authenticated on the 
portal. (Class::MOP::Class:::after)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27867) 
INFO: [mac:58:d9:c3:5e:56:e5] security_event 1300003 force-closed 
for 58:d9:c3:5e:56:e5 (pf::security_event::security_event_force_close)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27867) 
INFO: [mac:58:d9:c3:5e:56:e5] Instantiate profile guest 
(pf::Connection::ProfileFactory::_from_profile)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
WARN: [mac:unknown] locale from the URL  is not supported 
(pf::Portal::Session::getLanguages)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:unknown] External captive portal detected ! 
(captiveportal::PacketFence::Model::Portal::Session::_build_dispatcherSession)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:unknown] Detected external portal client. Using the IP 
10.201.0.18 address in it's session. 
(captiveportal::PacketFence::Model::Portal::Session::_build_clientIP)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
WARN: [mac:58:d9:c3:5e:56:e5] locale from the URL  is not 
supported (pf::Portal::Session::getLanguages)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] External captive portal detected ! 
(captiveportal::PacketFence::Model::Portal::Session::_build_dispatcherSession)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] Detected external portal client. 
Using the IP 10.201.0.18 address in it's session. 
(captiveportal::PacketFence::Model::Portal::Session::_build_clientIP)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] Instantiate profile guest 
(pf::Connection::ProfileFactory::_from_profile)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
WARN: [mac:58:d9:c3:5e:56:e5] locale from the URL  is not 
supported (captiveportal::PacketFence::Controller::Root::getLanguages)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] Releasing device 
(captiveportal::PacketFence::DynamicRouting::Module::Root::release)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] User default has authenticated on 
the portal. (Class::MOP::Class:::after)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
WARN: [mac:58:d9:c3:5e:56:e5] locale from the URL  is not 
supported (pf::Portal::Session::getLanguages)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] re-evaluating access 
(manage_register called) (pf::enforcement::reevaluate_access)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] Instantiate profile guest 
(pf::Connection::ProfileFactory::_from_profile)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] VLAN reassignment is forced. 
(pf::enforcement::_should_we_reassign_vlan)
Jan 20 07:19:35 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] switch port is (10.200.0.130) 
ifIndex 0connection type: WiFi MAC Auth 
(pf::enforcement::_vlan_reevaluation)
Jan 20 07:19:37 pf pfqueue: pfqueue(30210) INFO: 
[mac:58:d9:c3:5e:56:e5] [58:d9:c3:5e:56:e5] DesAssociating mac on 
switch (10.200.0.130) (pf::api::desAssociate)
Jan 20 07:19:37 pf pfqueue: pfqueue(30210) INFO: 
[mac:58:d9:c3:5e:56:e5] deauthenticating 
(pf::Switch::radiusDisconnect)
Jan 20 07:19:37 pf pfqueue: pfqueue(30210) INFO: 
[mac:58:d9:c3:5e:56:e5] controllerIp is set, we will use 
controller 10.200.0.130 to perform deauth 
(pf::Switch::radiusDisconnect)
Jan 20 07:19:37 pf pfqueue: pfqueue(30210) WARN: 
[mac:58:d9:c3:5e:56:e5] Unable to perform RADIUS 
Disconnect-Request. Disconnect-NAK received with Error-Cause: 
Session-Context-Not-Found. (pf::Switch::radiusDisconnect)
Jan 20 07:19:46 pf packetfence_httpd.portal: httpd.portal(27867) 
INFO: [mac:58:d9:c3:5e:56:e5] URI '/Ruckus' is detected as an 
external captive portal URI (pf::web::externalportal::handle)
Jan 20 07:19:46 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:unknown] External captive portal detected ! 
(captiveportal::PacketFence::Model::Portal::Session::_build_dispatcherSession)
Jan 20 07:19:46 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:unknown] Detected external portal client. Using the IP 
10.201.0.18 address in it's session. 
(captiveportal::PacketFence::Model::Portal::Session::_build_clientIP)
Jan 20 07:19:46 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] External captive portal detected ! 
(captiveportal::PacketFence::Model::Portal::Session::_build_dispatcherSession)
Jan 20 07:19:46 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] Detected external portal client. 
Using the IP 10.201.0.18 address in it's session. 
(captiveportal::PacketFence::Model::Portal::Session::_build_clientIP)
Jan 20 07:19:46 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] Instantiate profile guest 
(pf::Connection::ProfileFactory::_from_profile)
Jan 20 07:19:46 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] User default has authenticated on 
the portal. (Class::MOP::Class:::after)
Jan 20 07:19:46 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] Reevaluating access of device. 
(captiveportal::PacketFence::DynamicRouting::Module::Root::unknown_state)
Jan 20 07:19:46 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] re-evaluating access 
(manage_register called) (pf::enforcement::reevaluate_access)
Jan 20 07:19:46 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] Instantiate profile guest 
(pf::Connection::ProfileFactory::_from_profile)
Jan 20 07:19:46 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] VLAN reassignment is forced. 
(pf::enforcement::_should_we_reassign_vlan)
Jan 20 07:19:46 pf packetfence_httpd.portal: httpd.portal(27864) 
INFO: [mac:58:d9:c3:5e:56:e5] switch port is (10.200.0.130) 
ifIndex 0connection type: WiFi MAC Auth 
(pf::enforcement::_vlan_reevaluation)
Jan 20 07:19:47 pf packetfence_httpd.portal: httpd.portal(27867) 
INFO: [mac:unknown] External captive portal detected ! 
(captiveportal::PacketFence::Model::Portal::Session::_build_dispatcherSession)
Jan 20 07:19:47 pf packetfence_httpd.portal: httpd.portal(27867) 
INFO: [mac:unknown] Detected external portal client. Using the IP 
10.201.0.18 address in it's session. 
(captiveportal::PacketFence::Model::Portal::Session::_build_clientIP)
Jan 20 07:19:47 pf packetfence_httpd.portal: httpd.portal(27867) 
INFO: [mac:58:d9:c3:5e:56:e5] External captive portal detected ! 
(captiveportal::PacketFence::Model::Portal::Session::_build_dispatcherSession)
Jan 20 07:19:47 pf packetfence_httpd.portal: httpd.portal(27867) 
INFO: [mac:58:d9:c3:5e:56:e5] Detected external portal client. 
Using the IP 10.201.0.18 address in it's session. 
(captiveportal::PacketFence::Model::Portal::Session::_build_clientIP)
Jan 20 07:19:47 pf packetfence_httpd.portal: httpd.portal(27867) 
INFO: [mac:58:d9:c3:5e:56:e5] Instantiate profile guest 
(pf::Connection::ProfileFactory::_from_profile)
Jan 20 07:19:47 pf pfqueue: pfqueue(30222) INFO: 
[mac:58:d9:c3:5e:56:e5] [58:d9:c3:5e:56:e5] DesAssociating mac on 
switch (10.200.0.130) (pf::api::desAssociate)
Jan 20 07:19:47 pf pfqueue: pfqueue(30222) INFO: 
[mac:58:d9:c3:5e:56:e5] deauthenticating 
(pf::Switch::radiusDisconnect)
Jan 20 07:19:47 pf pfqueue: pfqueue(30222) INFO: 
[mac:58:d9:c3:5e:56:e5] controllerIp is set, we will use 
controller 10.200.0.130 to perform deauth 
(pf::Switch::radiusDisconnect)
Jan 20 07:19:48 pf pfqueue: pfqueue(30222) WARN: 
[mac:58:d9:c3:5e:56:e5] Unable to perform RADIUS 
Disconnect-Request. Disconnect-NAK received with Error-Cause: 
Session-Context-Not-Found. (pf::Switch::radiusDisconnect)



Jan 20 07:24:41 pf packetfence_httpd.aaa: httpd.aaa(1775) INFO: 
[mac:58:d9:c3:5e:56:e5] handling radius autz request: from 
switch_ip => (10.200.0.130), connection_type => 
Wireless-802.11-NoEAP,switch_mac => (8c:0c:90:15:14:9c), mac => 
[58:d9:c3:5e:56:e5], port => 0, username => "58d9c35e56e5", ssid 
=> VCX HOTSPOT (pf::radius::authorize)
Jan 20 07:24:41 pf packetfence_httpd.aaa: httpd.aaa(1775) INFO: 
[mac:58:d9:c3:5e:56:e5] Instantiate profile guest 
(pf::Connection::ProfileFactory::_from_profile)
Jan 20 07:24:41 pf packetfence_httpd.aaa: httpd.aaa(1775) INFO: 
[mac:58:d9:c3:5e:56:e5] Found authentication source(s) : 'local' 
for realm 'null' (pf::config::util::filter_authentication_sources)
Jan 20 07:24:41 pf packetfence_httpd.aaa: httpd.aaa(1775) INFO: 
[mac:58:d9:c3:5e:56:e5] Connection type is MAC-AUTH. Getting role 
from node_info (pf::role::getRegisteredRole)
Jan 20 07:24:41 pf packetfence_httpd.aaa: httpd.aaa(1775) INFO: 
[mac:58:d9:c3:5e:56:e5] Username was defined "58d9c35e56e5" - 
returning role 'guest' (pf::role::getRegisteredRole)
Jan 20 07:24:41 pf packetfence_httpd.aaa: httpd.aaa(1775) INFO: 
[mac:58:d9:c3:5e:56:e5] PID: "Test", Status: reg Returned VLAN: 
(undefined), Role: guest (pf::role::fetchRoleForNode)
Jan 20 07:24:41 pf packetfence_httpd.aaa: httpd.aaa(1775) INFO: 
[mac:58:d9:c3:5e:56:e5] (8c:0c:90:15:14:9c) Added VLAN 501 to the 
returned RADIUS Access-Accept (pf::Switch::returnRadiusAccessAccept)
Jan 20 07:24:41 pf packetfence_httpd.aaa: httpd.aaa(1775) INFO: 
[mac:58:d9:c3:5e:56:e5] (8c:0c:90:15:14:9c) Added role Hotspot to 
the returned RADIUS Access-Accept 
(pf::Switch::returnRadiusAccessAccept)
Jan 20 07:24:49 pf packetfence_httpd.aaa: httpd.aaa(1775) INFO: 
[mac:58:d9:c3:5e:56:e5] Updating locationlog from accounting 
request (pf::api::handle_accounting_metadata)




_______________________________________________
PacketFence-users mailing list
PacketFence-users@lists.sourceforge.net 
<mailto:PacketFence-users@lists.sourceforge.net>
https://lists.sourceforge.net/lists/listinfo/packetfence-users





_______________________________________________
PacketFence-users mailing list
PacketFence-users@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/packetfence-users

--
Fabrice Durand
fdur...@inverse.ca ::  +1.514.447.4918 (x135) ::  www.inverse.ca
Inverse inc. :: Leaders behind SOGo (http://www.sogo.nu) and PacketFence 
(http://packetfence.org)

_______________________________________________
PacketFence-users mailing list
PacketFence-users@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/packetfence-users