Update on the post:
In the end it was a bug in the AP Firmware which is solved in 9.508
Regards
Benny
-----------
Hi together,
we have a Sophos SG115 with network and wireless security at a fitness center.
There are 2 WLAN's configured.
Test --> Bridge to AP LAN
Members WLAN --> Hotspot with Vouchers (1 year for all members of the fitness center)
80% of the time all wireless networks working flawlessly.
But sometimes (daily, sometimes 1 or 2 times a week) no one can access the wireless network.
You can see the SSID for a short time, but can not connect.
If the users waits for abouth 1 or 2 hours the Wifi go back to work without any doing on the UTM or the AP.
Today we had this issue on about 2PM till 2:25pm. If you manually disable one of the Wifi on the management of the UTM and enable it a second later,
everything is working again.
We already changed cables, POE+ Injector and the AP55C.
Firmware is 9.502-4 (perhaps i upgrade tonight to 9.506-2)
This is the Wireless log, on 2:25pm we switched the state of the Wifi and as you can see clients coming back only immediately.
Live Log: Wireless Protection
2017:12:11-13:47:38 A400472604FA03A hostapd: wlan0: STA 2c:33:61:2f:12:e3 IEEE 802.11: disassociated due to inactivity
2017:12:11-13:47:38 A400472604FA03A awelogger[2382]: id="4102" severity="info" sys="System" sub="WiFi" name="STA disconnected" ssid="Mitglieder WLAN" ssid_id="WLAN0.0" bssid="00:1a:8c:c5:a0:35" sta="2c:33:61:2f:12:e3"
2017:12:11-13:47:39 A400472604FA03A hostapd: wlan0: STA 2c:33:61:2f:12:e3 IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
2017:12:11-13:49:54 A400472604FA03A hostapd: wlan0: STA 74:8d:08:6c:a5:86 IEEE 802.11: disassociated due to inactivity
2017:12:11-13:49:54 A400472604FA03A awelogger[2382]: id="4102" severity="info" sys="System" sub="WiFi" name="STA disconnected" ssid="Mitglieder WLAN" ssid_id="WLAN0.0" bssid="00:1a:8c:c5:a0:35" sta="74:8d:08:6c:a5:86"
2017:12:11-13:49:55 A400472604FA03A hostapd: wlan0: STA 74:8d:08:6c:a5:86 IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
2017:12:11-14:01:57 firewall awed[4528]: [MASTER] start processing configuration change
2017:12:11-14:01:57 firewall awed[4528]: [MASTER] end processing configuration change
2017:12:11-14:16:51 firewall awed[4528]: [MASTER] start processing configuration change
2017:12:11-14:16:52 firewall awed[4528]: [MASTER] end processing configuration change
2017:12:11-14:25:35 firewall awed[4528]: [MASTER] start processing configuration change
2017:12:11-14:25:36 firewall awed[4528]: [MASTER] AP A40047D7EA43FA1: Configuration change detected
2017:12:11-14:25:36 firewall awed[4528]: [MASTER] AP A400472604FA03A: Configuration change detected
2017:12:11-14:25:36 firewall awed[4528]: [MASTER] end processing configuration change
2017:12:11-14:25:40 firewall awed[4528]: [MASTER] start processing configuration change
2017:12:11-14:25:40 firewall awed[4528]: [MASTER] AP A40047D7EA43FA1: Configuration change detected
2017:12:11-14:25:40 firewall awed[4528]: [MASTER] AP A400472604FA03A: Configuration change detected
2017:12:11-14:25:40 firewall awed[4528]: [MASTER] end processing configuration change
2017:12:11-14:25:44 firewall awed[6614]: [A400472604FA03A] (Re-)loaded identity and/or configuration
2017:12:11-14:25:44 A400472604FA03A aweclient[1045]: aweclient.c (2462) Exit from main loop with no error. Get new configuration.
2017:12:11-14:25:45 A400472604FA03A aweclient[1045]: Cloudclient not found on ubus.
2017:12:11-14:25:46 A400472604FA03A netifd: Network device 'wlan1' link is down
2017:12:11-14:25:46 A400472604FA03A kernel: [39071.140000] br-lan: port 2(wlan1) entered disabled state
2017:12:11-14:25:46 A400472604FA03A kernel: [39071.140000] device wlan1 left promiscuous mode
2017:12:11-14:25:46 A400472604FA03A kernel: [39071.150000] br-lan: port 2(wlan1) entered disabled state
2017:12:11-14:25:46 A400472604FA03A kernel: [39071.190000] device wlan0 left promiscuous mode
2017:12:11-14:25:46 A400472604FA03A kernel: [39071.190000] br-vxlan100: port 2(wlan0) entered disabled state
2017:12:11-14:25:46 A400472604FA03A netifd: Network device 'wlan0' link is down
2017:12:11-14:25:46 A400472604FA03A add_radio_mapping.sh: AP55C detected: Using on-the-fly ACS procedure
2017:12:11-14:25:46 A400472604FA03A add_radio_mapping.sh: AP55C detected: Using on-the-fly ACS procedure
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): Configuration file: /var/run/hostapd-phy3.conf
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): eapol_version=1
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: TDLS supported
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: TDLS external setup
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: Supported cipher 00-0f-ac:1
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: Supported cipher 00-0f-ac:5
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: Supported cipher 00-0f-ac:2
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: Supported cipher 00-0f-ac:4
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: Supported cipher 00-0f-ac:6
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: Using driver-based off-channel TX
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: Use separate P2P group interface (driver advertised support)
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: interface wlan0 in phy phy3
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: Set mode ifindex 13 iftype 3 (AP)
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: Setup AP(wlan0) - device_ap_sme=0 use_monitor=0
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: Subscribe to mgmt frames with AP handle 0x5062c0
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: Register frame type=0xb0 (WLAN_FC_STYPE_AUTH) nl_handle=0x5062c0 match=
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: Register frame type=0x0 (WLAN_FC_STYPE_ASSOC_REQ) nl_handle=0x5062c0 match=
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: Register frame type=0x20 (WLAN_FC_STYPE_REASSOC_REQ) nl_handle=0x5062c0 match=
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: Register frame type=0xa0 (WLAN_FC_STYPE_DISASSOC) nl_handle=0x5062c0 match=
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: Register frame type=0xc0 (WLAN_FC_STYPE_DEAUTH) nl_handle=0x5062c0 match=
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x5062c0 match=
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: Register frame type=0x40 (WLAN_FC_STYPE_PROBE_REQ) nl_handle=0x5062c0 match=
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: Add own interface ifindex 7
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: if_indices[16]: 7
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: Add own interface ifindex 5
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: if_indices[16]: 7 5
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: Add own interface ifindex 13
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: if_indices[16]: 7 5 13
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: Adding interface wlan0 into bridge br-vxlan100
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): phy: phy3
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): BSS count 2, BSSID mask 00:00:00:00:00:03 (1 bits)
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): Using existing control interface directory.
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): wlan0: interface state UNINITIALIZED->COUNTRY_UPDATE
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): Previous country code DE, new country code DE
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: Regulatory information - country=DE (DFS-ETSI)
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: 2400-2483 @ 40 MHz 20 mBm
2017:12:11-14:25:56 A400472604FA03A kernel: [39081.690000] device wlan0 entered promiscuous mode
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: 5150-5250 @ 80 MHz 20 mBm (no outdoor)
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: 5250-5350 @ 80 MHz 20 mBm (no outdoor) (DFS)
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: 5470-5725 @ 160 MHz 27 mBm (DFS)
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: 57000-66000 @ 2160 MHz 40 mBm
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: Added 802.11b mode based on 802.11g information
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): Allowed channel: mode=1 chan=1 freq=2412 MHz max_tx_power=20 dBm
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): Allowed channel: mode=1 chan=2 freq=2417 MHz max_tx_power=20 dBm
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): Allowed channel: mode=1 chan=3 freq=2422 MHz max_tx_power=20 dBm
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): Allowed channel: mode=1 chan=4 freq=2427 MHz max_tx_power=20 dBm
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): Allowed channel: mode=1 chan=5 freq=2432 MHz max_tx_power=20 dBm
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): Allowed channel: mode=1 chan=6 freq=2437 MHz max_tx_power=20 dBm
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): Allowed channel: mode=1 chan=7 freq=2442 MHz max_tx_power=20 dBm
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): Allowed channel: mode=1 chan=8 freq=2447 MHz max_tx_power=20 dBm
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): Allowed channel: mode=1 chan=9 freq=2452 MHz max_tx_power=20 dBm
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): Allowed channel: mode=1 chan=10 freq=2457 MHz max_tx_power=20 dBm
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): Allowed channel: mode=1 chan=11 freq=2462 MHz max_tx_power=20 dBm
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): Allowed channel: mode=1 chan=12 freq=2467 MHz max_tx_power=20 dBm
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): Allowed channel: mode=1 chan=13 freq=2472 MHz max_tx_power=20 dBm
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): Channel 14 (2484)is disabled!!
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): Allowed channel: mode=0 chan=1 freq=2412 MHz max_tx_power=20 dBm
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): Allowed channel: mode=0 chan=2 freq=2417 MHz max_tx_power=20 dBm
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): Allowed channel: mode=0 chan=3 freq=2422 MHz max_tx_power=20 dBm
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): Allowed channel: mode=0 chan=4 freq=2427 MHz max_tx_power=20 dBm
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): Allowed channel: mode=0 chan=5 freq=2432 MHz max_tx_power=20 dBm
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): Allowed channel: mode=0 chan=6 freq=2437 MHz max_tx_power=20 dBm
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): Allowed channel: mode=0 chan=7 freq=2442 MHz max_tx_power=20 dBm
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): Allowed channel: mode=0 chan=8 freq=2447 MHz max_tx_power=20 dBm
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): Allowed channel: mode=0 chan=9 freq=2452 MHz max_tx_power=20 dBm
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): Allowed channel: mode=0 chan=10 freq=2457 MHz max_tx_power=20 dBm
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): Allowed channel: mode=0 chan=11 freq=2462 MHz max_tx_power=20 dBm
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): Allowed channel: mode=0 chan=12 freq=2467 MHz max_tx_power=20 dBm
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): Allowed channel: mode=0 chan=13 freq=2472 MHz max_tx_power=20 dBm
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): Channel 14 (2484)is disabled!!
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): ACS: Automatic channel selection started, this may take a bit
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): ACS: skipping channel 14 (2484)
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): ACS: Scanning 1 / 5
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): wlan0: nl80211: scan request
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: Scan frequency 2412 MHz
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: Scan frequency 2417 MHz
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: Scan frequency 2422 MHz
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: Scan frequency 2427 MHz
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: Scan frequency 2432 MHz
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: Scan frequency 2437 MHz
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: Scan frequency 2442 MHz
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: Scan frequency 2447 MHz
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: Scan frequency 2452 MHz
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: Scan frequency 2457 MHz
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: Scan frequency 2462 MHz
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: Scan frequency 2467 MHz
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): nl80211: Scan frequency 2472 MHz
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): Scan requested (ret=0) - scan timeout 10 seconds
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): wlan0: interface state COUNTRY_UPDATE->ACS
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): wlan0: ACS-STARTED
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): Interface initialization will be completed in a callback (ACS)
2017:12:11-14:25:56 A400472604FA03A netifd: radio1 (6524): ctrl_iface not configured!
2017:12:11-14:25:57 A400472604FA03A netifd: radio1 (6524): command failed: No such device (-19)
2017:12:11-14:26:04 A400472604FA03A netifd: Network device 'wlan0' link is up
2017:12:11-14:26:04 A400472604FA03A kernel: [39089.050000] br-vxlan100: port 2(wlan0) entered forwarding state
2017:12:11-14:26:04 A400472604FA03A kernel: [39089.060000] br-vxlan100: port 2(wlan0) entered forwarding state
2017:12:11-14:26:04 A400472604FA03A kernel: [39089.080000] device wlan1 entered promiscuous mode
2017:12:11-14:26:04 A400472604FA03A kernel: [39089.080000] br-lan: port 2(wlan1) entered forwarding state
2017:12:11-14:26:04 A400472604FA03A kernel: [39089.090000] br-lan: port 2(wlan1) entered forwarding state
2017:12:11-14:26:04 A400472604FA03A netifd: Network device 'wlan1' link is up
2017:12:11-14:26:06 A400472604FA03A kernel: [39091.060000] br-vxlan100: port 2(wlan0) entered forwarding state
2017:12:11-14:26:06 A400472604FA03A kernel: [39091.090000] br-lan: port 2(wlan1) entered forwarding state
2017:12:11-14:26:08 A400472604FA03A hostapd: wlan0: STA 60:9a:c1:8c:33:fe IEEE 802.11: authenticated
2017:12:11-14:26:08 A400472604FA03A hostapd: wlan0: STA 60:9a:c1:8c:33:fe IEEE 802.11: associated (aid 1)
2017:12:11-14:26:08 A400472604FA03A awelogger[6623]: id="4103" severity="info" sys="System" sub="WiFi" name="STA authentication" ssid="Mitglieder WLAN" ssid_id="WLAN0.0" bssid="00:1a:8c:c5:a0:35" sta="60:9a:c1:8c:33:fe" status_code="0"
2017:12:11-14:26:08 A400472604FA03A awelogger[6623]: id="4104" severity="info" sys="System" sub="WiFi" name="STA association" ssid="Mitglieder WLAN" ssid_id="WLAN0.0" bssid="00:1a:8c:c5:a0:35" sta="60:9a:c1:8c:33:fe" status_code="0"
2017:12:11-14:26:08 A400472604FA03A awelogger[6623]: id="4101" severity="info" sys="System" sub="WiFi" name="STA connected" ssid="Mitglieder WLAN" ssid_id="WLAN0.0" bssid="00:1a:8c:c5:a0:35" sta="60:9a:c1:8c:33:fe"
2017:12:11-14:26:11 A400472604FA03A hostapd: wlan0: STA d0:22:be:70:59:0c IEEE 802.11: authenticated
2017:12:11-14:26:11 A400472604FA03A hostapd: wlan0: STA d0:22:be:70:59:0c IEEE 802.11: associated (aid 2)
2017:12:11-14:26:11 A400472604FA03A awelogger[6623]: id="4103" severity="info" sys="System" sub="WiFi" name="STA authentication" ssid="Mitglieder WLAN" ssid_id="WLAN0.0" bssid="00:1a:8c:c5:a0:35" sta="d0:22:be:70:59:0c" status_code="0"
2017:12:11-14:26:11 A400472604FA03A awelogger[6623]: id="4104" severity="info" sys="System" sub="WiFi" name="STA association" ssid="Mitglieder WLAN" ssid_id="WLAN0.0" bssid="00:1a:8c:c5:a0:35" sta="d0:22:be:70:59:0c" status_code="0"
2017:12:11-14:26:11 A400472604FA03A awelogger[6623]: id="4101" severity="info" sys="System" sub="WiFi" name="STA connected" ssid="Mitglieder WLAN" ssid_id="WLAN0.0" bssid="00:1a:8c:c5:a0:35" sta="d0:22:be:70:59:0c"
2017:12:11-14:26:13 A400472604FA03A hostapd: wlan0: STA 78:4f:43:5a:b8:5d IEEE 802.11: authenticated
2017:12:11-14:26:13 A400472604FA03A awelogger[6623]: id="4103" severity="info" sys="System" sub="WiFi" name="STA authentication" ssid="Mitglieder WLAN" ssid_id="WLAN0.0" bssid="00:1a:8c:c5:a0:35" sta="78:4f:43:5a:b8:5d" status_code="0"
2017:12:11-14:26:13 A400472604FA03A awelogger[6623]: id="4104" severity="info" sys="System" sub="WiFi" name="STA association" ssid="Mitglieder WLAN" ssid_id="WLAN0.0" bssid="00:1a:8c:c5:a0:35" sta="78:4f:43:5a:b8:5d" status_code="0"
2017:12:11-14:26:14 A400472604FA03A hostapd: wlan0: STA 6c:0b:84:da:8b:05 IEEE 802.11: authenticated
2017:12:11-14:26:14 A400472604FA03A hostapd: wlan0: STA 6c:0b:84:da:8b:05 IEEE 802.11: associated (aid 4)
2017:12:11-14:26:14 A400472604FA03A awelogger[6623]: id="4103" severity="info" sys="System" sub="WiFi" name="STA authentication" ssid="Mitglieder WLAN" ssid_id="WLAN0.0" bssid="00:1a:8c:c5:a0:35" sta="6c:0b:84:da:8b:05" status_code="0"
2017:12:11-14:26:14 A400472604FA03A awelogger[6623]: id="4104" severity="info" sys="System" sub="WiFi" name="STA association" ssid="Mitglieder WLAN" ssid_id="WLAN0.0" bssid="00:1a:8c:c5:a0:35" sta="6c:0b:84:da:8b:05" status_code="0"
2017:12:11-14:26:14 A400472604FA03A awelogger[6623]: id="4101" severity="info" sys="System" sub="WiFi" name="STA connected" ssid="Mitglieder WLAN" ssid_id="WLAN0.0" bssid="00:1a:8c:c5:a0:35" sta="6c:0b:84:da:8b:05"
This thread was automatically locked due to age.