(part 2/2)
Following this, I tried to connect to the network, but am using the same laptop so I lost a few of the messages. However, I think I got the useful parts from logread
that might be helpful:
Mon Apr 3 21:44:37 2023 daemon.info hostapd: wlan1: STA <LAPTOP_MAC> IEEE 802.11: authenticated
Mon Apr 3 21:44:37 2023 daemon.info hostapd: wlan1: STA <LAPTOP_MAC> IEEE 802.11: associated (aid 1)
Mon Apr 3 21:44:37 2023 daemon.notice hostapd: wlan1: AP-STA-CONNECTED <LAPTOP_MAC>
Mon Apr 3 21:44:37 2023 daemon.info hostapd: wlan1: STA <LAPTOP_MAC> WPA: pairwise key handshake completed (RSN)
Mon Apr 3 21:44:37 2023 daemon.notice hostapd: wlan1: EAPOL-4WAY-HS-COMPLETED <LAPTOP_MAC>
Mon Apr 3 21:44:37 2023 daemon.warn dnsmasq-dhcp[1]: DHCP packet received on wlan1 which has no address
Mon Apr 3 21:44:39 2023 daemon.warn dnsmasq-dhcp[1]: DHCP packet received on wlan1 which has no address
Mon Apr 3 21:44:44 2023 daemon.warn dnsmasq-dhcp[1]: DHCP packet received on wlan1 which has no address
Mon Apr 3 21:44:52 2023 daemon.warn dnsmasq-dhcp[1]: DHCP packet received on wlan1 which has no address
Mon Apr 3 21:45:08 2023 daemon.warn dnsmasq-dhcp[1]: DHCP packet received on wlan1 which has no address
Mon Apr 3 21:45:23 2023 daemon.notice hostapd: wlan1: AP-STA-DISCONNECTED <LAPTOP_MAC>
Mon Apr 3 21:45:26 2023 daemon.warn dnsmasq-dhcp[1]: no address range available for DHCP request via br-lan
However, this isn't entirely related as it appears the laptop is trying to connect to the 5GHz network which doesn't hav the hidden flag enabled. So here's what happens when I try that:
First enabling Hide ESSID for the 5GHz network in luci:
# dmesg messages
[ 4358.608360] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
# logread messages
Mon Apr 3 21:53:35 2023 daemon.notice hostapd: Remove interface 'wlan1'
Mon Apr 3 21:53:35 2023 daemon.notice hostapd: wlan1: interface state ENABLED->DISABLED
Mon Apr 3 21:53:35 2023 daemon.notice hostapd: wlan1: AP-DISABLED
Mon Apr 3 21:53:35 2023 daemon.notice hostapd: wlan1: CTRL-EVENT-TERMINATING
Mon Apr 3 21:53:35 2023 daemon.err hostapd: rmdir[ctrl_interface=/var/run/hostapd]: Permission denied
Mon Apr 3 21:53:35 2023 daemon.notice hostapd: nl80211: deinit ifname=wlan1 disabled_11b_rates=0
Mon Apr 3 21:53:35 2023 daemon.notice netifd: Wireless device 'radio1' is now down
Mon Apr 3 21:53:36 2023 daemon.notice hostapd: Configuration file: /var/run/hostapd-phy1.conf (phy wlan1) --> new PHY
Mon Apr 3 21:53:36 2023 daemon.notice hostapd: wlan1: interface state UNINITIALIZED->HT_SCAN
Mon Apr 3 21:53:37 2023 daemon.notice hostapd: Switch own primary and secondary channel to get secondary channel with no Beacons from other BSSes
Mon Apr 3 21:53:37 2023 kern.info kernel: [ 4358.608360] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
Mon Apr 3 21:53:37 2023 daemon.notice hostapd: wlan1: interface state HT_SCAN->ENABLED
Mon Apr 3 21:53:37 2023 daemon.notice hostapd: wlan1: AP-ENABLED
Mon Apr 3 21:53:37 2023 daemon.info dnsmasq[1]: read /etc/hosts - 4 addresses
Mon Apr 3 21:53:37 2023 daemon.info dnsmasq[1]: read /tmp/hosts/dhcp.cfg01411c - 1 addresses
Mon Apr 3 21:53:37 2023 daemon.info dnsmasq[1]: read /tmp/hosts/odhcpd - 0 addresses
Mon Apr 3 21:53:37 2023 daemon.info dnsmasq-dhcp[1]: read /etc/ethers - 0 addresses
Mon Apr 3 21:53:37 2023 daemon.notice netifd: Wireless device 'radio1' is now up
Mon Apr 3 21:53:37 2023 daemon.notice netifd: Network device 'wlan1' link is up
Mon Apr 3 21:53:37 2023 daemon.notice netifd: Interface 'wifi' is disabled
Mon Apr 3 21:53:37 2023 daemon.notice netifd: Interface 'wifi' is now down
Mon Apr 3 21:53:37 2023 daemon.notice netifd: Interface 'wifi' is enabled
Mon Apr 3 21:53:37 2023 daemon.notice netifd: Interface 'wifi' is setting up now
Mon Apr 3 21:53:37 2023 daemon.notice netifd: Interface 'wifi' is now up
Mon Apr 3 21:53:38 2023 user.notice firewall: Reloading firewall due to ifup of wifi (wlan0)
Mon Apr 3 21:53:40 2023 daemon.info dnsmasq[1]: read /etc/hosts - 4 addresses
Mon Apr 3 21:53:40 2023 daemon.info dnsmasq[1]: read /tmp/hosts/dhcp.cfg01411c - 1 addresses
Mon Apr 3 21:53:40 2023 daemon.info dnsmasq[1]: read /tmp/hosts/odhcpd - 0 addresses
Mon Apr 3 21:53:40 2023 daemon.info dnsmasq-dhcp[1]: read /etc/ethers - 0 addresses
Mon Apr 3 21:56:51 2023 daemon.warn dnsmasq-dhcp[1]: no address range available for DHCP request via br-lan
Similar to before, I can see that this line was added to the config wifi-iface 'default_radio1'
config block on /etc/config/wireless
:
option hidden '1'
At this point, I'm not sure why, but I'm seeing an old wifi name appear in my list of wireless networks. It says that it can see an OpenWrt8
even though I can see in /etc/config/wireless
that both SSIDs are still OpenWrt-tower57
.
When trying to connect to the OpenWrt8
network - which shouldn't exist - it looks like it might be trying. The logs blink while the laptop is trying to join the network and I'm offline, but I do get this line in the logs:
# no dmesg messages
# logread messages (maybe the rest were missing?)
Mon Apr 3 22:14:01 2023 daemon.warn dnsmasq-dhcp[1]: no address range available for DHCP request via br-lan
I fired up another laptop that I had available to connect to the network that shouldn't exist and hasn't connected to this WAC104 at all. When it tried to connect to OpenWrt8
, I did get this log again in the logread
tail:
Mon Apr 3 22:17:58 2023 daemon.warn dnsmasq-dhcp[1]: no address range available for DHCP request via br-lan
So there is something really strange happening here.
I checked /etc/config/wireless
again to see if somehow the config changed without me, but I still see this:
config wifi-device 'radio0'
option type 'mac80211'
option path '1e140000.pcie/pci0000:00/0000:00:01.0/0000:02:00.0'
option channel '1'
option band '2g'
option htmode 'HT20'
option cell_density '0'
config wifi-iface 'default_radio0'
option device 'radio0'
option network 'wifi'
option mode 'ap'
option encryption 'psk2'
option key 'FAKE_PASSWORD'
option ssid 'OpenWrt-tower57'
option hidden '1'
config wifi-device 'radio1'
option type 'mac80211'
option path '1e140000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0'
option channel '36'
option band '5g'
option htmode 'VHT80'
option cell_density '0'
config wifi-iface 'default_radio1'
option device 'radio1'
option network 'wifi'
option mode 'ap'
option encryption 'psk2'
option key 'FAKE_PASSWORD'
option ssid 'OpenWrt-tower57'
option hidden '1'
I have no idea how OpenWrt8
is being found or how the device is responding to it. I also try to connect to the current network OpenWrt-tower57
, and it does still try to do that too:
Mon Apr 3 22:25:30 2023 daemon.info hostapd: wlan1: STA <LAPTOP_MAC> IEEE 802.11: authenticated
Mon Apr 3 22:25:30 2023 daemon.info hostapd: wlan1: STA <LAPTOP_MAC> IEEE 802.11: associated (aid 1)
Mon Apr 3 22:25:30 2023 daemon.notice hostapd: wlan1: AP-STA-CONNECTED <LAPTOP_MAC>
Mon Apr 3 22:25:30 2023 daemon.info hostapd: wlan1: STA <LAPTOP_MAC> WPA: pairwise key handshake completed (RSN)
Mon Apr 3 22:25:30 2023 daemon.notice hostapd: wlan1: EAPOL-4WAY-HS-COMPLETED <LAPTOP_MAC>
Mon Apr 3 22:25:30 2023 daemon.warn dnsmasq-dhcp[1]: DHCP packet received on wlan1 which has no address
Mon Apr 3 22:25:31 2023 daemon.warn dnsmasq-dhcp[1]: no address range available for DHCP request via br-lan
Mon Apr 3 22:25:32 2023 daemon.warn dnsmasq-dhcp[1]: DHCP packet received on wlan1 which has no address
Mon Apr 3 22:25:35 2023 daemon.warn dnsmasq-dhcp[1]: no address range available for DHCP request via br-lan
Mon Apr 3 22:25:35 2023 daemon.warn dnsmasq-dhcp[1]: no address range available for DHCP request via br-lan
Mon Apr 3 22:25:37 2023 daemon.warn dnsmasq-dhcp[1]: DHCP packet received on wlan1 which has no address
Mon Apr 3 22:25:45 2023 daemon.warn dnsmasq-dhcp[1]: DHCP packet received on wlan1 which has no address
Mon Apr 3 22:26:02 2023 daemon.warn dnsmasq-dhcp[1]: DHCP packet received on wlan1 which has no address
Mon Apr 3 22:26:15 2023 daemon.notice hostapd: wlan1: AP-STA-DISCONNECTED <LAPTOP_MAC>
Mon Apr 3 22:26:20 2023 daemon.warn dnsmasq-dhcp[1]: no address range available for DHCP request via br-lan
While I had access to the other laptop, I figured I'd verify that the OpenWrt-tower57
network wouldn't work there either and watch the logs without losing my connection to the network device. But it does something even more inconsistent: it connects! That ancient machine only has 2.4GHz so I'm not sure if that helps or hurts. Both laptops are running ubuntu. Here is the tail from logread
for that connection:
Mon Apr 3 22:31:08 2023 daemon.info hostapd: wlan0: STA <LAPTOP2_MAC> IEEE 802.11: authenticated
Mon Apr 3 22:31:08 2023 daemon.info hostapd: wlan0: STA <LAPTOP2_MAC> IEEE 802.11: associated (aid 1)
Mon Apr 3 22:31:08 2023 daemon.notice hostapd: wlan0: AP-STA-CONNECTED <LAPTOP2_MAC>
Mon Apr 3 22:31:08 2023 daemon.info hostapd: wlan0: STA <LAPTOP2_MAC> WPA: pairwise key handshake completed (RSN)
Mon Apr 3 22:31:08 2023 daemon.notice hostapd: wlan0: EAPOL-4WAY-HS-COMPLETED <LAPTOP2_MAC>
Mon Apr 3 22:31:12 2023 daemon.info dnsmasq-dhcp[1]: DHCPDISCOVER(wlan0) <LAPTOP2_MAC>
Mon Apr 3 22:31:12 2023 daemon.info dnsmasq-dhcp[1]: DHCPOFFER(wlan0) 10.0.0.107 <LAPTOP2_MAC>
Mon Apr 3 22:31:12 2023 daemon.info dnsmasq-dhcp[1]: DHCPDISCOVER(wlan0) <LAPTOP2_MAC>
Mon Apr 3 22:31:12 2023 daemon.info dnsmasq-dhcp[1]: DHCPOFFER(wlan0) 10.0.0.107 <LAPTOP2_MAC>
Mon Apr 3 22:31:12 2023 daemon.info dnsmasq-dhcp[1]: DHCPREQUEST(wlan0) 10.0.0.107 <LAPTOP2_MAC>
Mon Apr 3 22:31:12 2023 daemon.info dnsmasq-dhcp[1]: DHCPACK(wlan0) 10.0.0.107 <LAPTOP2_MAC> <LAPTOP2_HOSTNAME>
I'm a bit lost. Hopefully this makes sense. I didn't do the logging for the SSID change yet because I'm not sure we need to add more mess to this.