Wifi bug
Hannu Nyman
hannu.nyman at iki.fi
Sun Sep 26 03:54:53 PDT 2021
e9hack kirjoitti 26.9.2021 klo 10.02:
> Am 24.09.2021 um 22:04 schrieb e9hack:
>> In the past (a few days ago), it was possible to disable or shut-down wifi
>> by introduce the command 'wifi down'. This doesn't work currently. After
>> some seconds, wifi is start again.
>
> It may be related to a page fault of netifd. Netifd is restart afterwards:
>
> [ 236.658379] do_page_fault(): sending SIGSEGV to netifd for invalid write
> access to 77cdd048
> [ 236.666942] epc = 77d7ded5 in libubox.so.20210819[77d78000+18000]
> [ 236.673212] ra = 77d7dec9 in libubox.so.20210819[77d78000+18000]
>
> This occurs after 'wifi down'.
Wifi coming up again happens also with R7800 (ipq806x, ath10k), master build
from two days ago.
System log shoes wifi goes down, then the whole network stack gets restarted
(?), and finally wifi comes back up.
OpenWrt SNAPSHOT, r17581-2c9a07ed28
-----------------------------------------------------
root at router1:~# wifi down; logread -f
Sun Sep 26 13:42:39 2021 daemon.notice hostapd: Remove interface 'wlan0'
Sun Sep 26 13:42:39 2021 daemon.notice hostapd: wlan0: interface state
ENABLED->DISABLED
Sun Sep 26 13:42:39 2021 daemon.notice hostapd: wlan0: AP-STA-DISCONNECTED
ac:57:75:56:c1:e0
Sun Sep 26 13:42:39 2021 daemon.notice hostapd: wlan0: AP-STA-DISCONNECTED
e0:c3:77:ae:0a:30
Sun Sep 26 13:42:39 2021 daemon.notice hostapd: wlan0: AP-DISABLED
Sun Sep 26 13:42:39 2021 daemon.notice hostapd: wlan0: CTRL-EVENT-TERMINATING
Sun Sep 26 13:42:39 2021 daemon.notice hostapd: nl80211: deinit ifname=wlan0
disabled_11b_rates=0
Sun Sep 26 13:42:39 2021 kern.info kernel: [96769.105006] device wlan0 left
promiscuous mode
Sun Sep 26 13:42:39 2021 kern.info kernel: [96769.105082] br-lan: port
2(wlan0) entered disabled state
Sun Sep 26 13:42:39 2021 daemon.notice netifd: Network device 'wlan0' link is
down
Sun Sep 26 13:42:39 2021 kern.info kernel: [96769.128708] ath10k_pci
0000:01:00.0: mac flush null vif, drop 0 queues 0xffff
Sun Sep 26 13:42:39 2021 kern.warn kernel: [96769.130466] ath10k_pci
0000:01:00.0: peer-unmap-event: unknown peer id 0
Sun Sep 26 13:42:39 2021 kern.warn kernel: [96769.134851] ath10k_pci
0000:01:00.0: peer-unmap-event: unknown peer id 0
Sun Sep 26 13:42:39 2021 kern.warn kernel: [96769.141696] ath10k_pci
0000:01:00.0: peer-unmap-event: unknown peer id 0
Sun Sep 26 13:42:39 2021 daemon.notice hostapd: Remove interface 'wlan1'
Sun Sep 26 13:42:39 2021 daemon.notice hostapd: wlan1: interface state
ENABLED->DISABLED
Sun Sep 26 13:42:39 2021 daemon.notice hostapd: wlan1: AP-STA-DISCONNECTED
30:cd:a7:b3:33:5d
Sun Sep 26 13:42:39 2021 daemon.notice hostapd: wlan1: AP-DISABLED
Sun Sep 26 13:42:39 2021 daemon.notice hostapd: wlan1: CTRL-EVENT-TERMINATING
Sun Sep 26 13:42:39 2021 daemon.notice hostapd: nl80211: deinit ifname=wlan1
disabled_11b_rates=0
Sun Sep 26 13:42:39 2021 kern.info kernel: [96769.328057] device wlan1 left
promiscuous mode
Sun Sep 26 13:42:39 2021 kern.info kernel: [96769.328145] br-lan: port
3(wlan1) entered disabled state
Sun Sep 26 13:42:39 2021 kern.info kernel: [96769.378176] ath10k_pci
0001:01:00.0: mac flush null vif, drop 0 queues 0xffff
Sun Sep 26 13:42:39 2021 kern.warn kernel: [96769.379365] ath10k_pci
0001:01:00.0: peer-unmap-event: unknown peer id 0
Sun Sep 26 13:42:39 2021 kern.warn kernel: [96769.384347] ath10k_pci
0001:01:00.0: peer-unmap-event: unknown peer id 0
Sun Sep 26 13:42:39 2021 kern.warn kernel: [96769.391288] ath10k_pci
0001:01:00.0: peer-unmap-event: unknown peer id 0
Sun Sep 26 13:42:39 2021 daemon.notice netifd: Network device 'wlan1' link is
down
Sun Sep 26 13:42:44 2021 user.notice : Added device handler type: bonding
Sun Sep 26 13:42:44 2021 user.notice : Added device handler type: 8021ad
Sun Sep 26 13:42:44 2021 user.notice : Added device handler type: 8021q
Sun Sep 26 13:42:44 2021 user.notice : Added device handler type: macvlan
Sun Sep 26 13:42:44 2021 user.notice : Added device handler type: veth
Sun Sep 26 13:42:44 2021 user.notice : Added device handler type: bridge
Sun Sep 26 13:42:44 2021 user.notice : Added device handler type: Network device
Sun Sep 26 13:42:44 2021 user.notice : Added device handler type: tunnel
Sun Sep 26 13:42:45 2021 kern.info kernel: [96775.307649] br-lan: port
1(eth1.1) entered disabled state
Sun Sep 26 13:42:45 2021 user.notice odhcpd: *** ODHCPD triggers DNSMASQ
reload ***
Sun Sep 26 13:42:45 2021 kern.info kernel: [96775.319347] device eth1.1 left
promiscuous mode
Sun Sep 26 13:42:45 2021 kern.info kernel: [96775.319372] device eth1 left
promiscuous mode
Sun Sep 26 13:42:45 2021 kern.info kernel: [96775.322799] br-lan: port
1(eth1.1) entered disabled state
Sun Sep 26 13:42:45 2021 daemon.info dnsmasq[5668]: read /etc/hosts - 4 addresses
Sun Sep 26 13:42:45 2021 daemon.info dnsmasq[5668]: read
/tmp/hosts/dhcp.cfg01411c - 3 addresses
Sun Sep 26 13:42:45 2021 daemon.info dnsmasq[5668]: read /tmp/hosts/odhcpd -
0 addresses
Sun Sep 26 13:42:45 2021 daemon.info dnsmasq-dhcp[5668]: read /etc/ethers - 0
addresses
Sun Sep 26 13:42:45 2021 kern.info kernel: [96775.485937] ipq806x-gmac-dwmac
37200000.ethernet eth0: Link is Down
Sun Sep 26 13:42:45 2021 daemon.err odhcp6c[1630]: Failed to send RS
(Permission denied)
Sun Sep 26 13:42:45 2021 daemon.err odhcp6c[1630]: Failed to send RELEASE
message to ff02::1:2 (Permission denied)
Sun Sep 26 13:42:45 2021 daemon.warn dnsmasq[5668]: no servers found in
/tmp/resolv.conf.d/resolv.conf.auto, will retry
Sun Sep 26 13:42:45 2021 kern.info kernel: [96775.508327] ipq806x-gmac-dwmac
37400000.ethernet eth1: Link is Down
Sun Sep 26 13:42:45 2021 kern.info kernel: [96775.521213] dwmac1000: Master
AXI performs any burst length
Sun Sep 26 13:42:45 2021 kern.info kernel: [96775.521246] ipq806x-gmac-dwmac
37400000.ethernet eth1: No Safety Features support found
Sun Sep 26 13:42:45 2021 kern.info kernel: [96775.525599] ipq806x-gmac-dwmac
37400000.ethernet eth1: IEEE 1588-2008 Advanced Timestamp supported
Sun Sep 26 13:42:45 2021 kern.info kernel: [96775.533802] ipq806x-gmac-dwmac
37400000.ethernet eth1: registered PTP clock
Sun Sep 26 13:42:45 2021 kern.info kernel: [96775.542705] ipq806x-gmac-dwmac
37400000.ethernet eth1: configuring for fixed/sgmii link mode
Sun Sep 26 13:42:45 2021 kern.info kernel: [96775.549879] ipq806x-gmac-dwmac
37400000.ethernet eth1: Link is Up - 1Gbps/Full - flow control off
Sun Sep 26 13:42:45 2021 kern.info kernel: [96775.558381] IPv6:
ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready
Sun Sep 26 13:42:46 2021 kern.info kernel: [96775.567187] IPv6:
ADDRCONF(NETDEV_CHANGE): eth1.1: link becomes ready
Sun Sep 26 13:42:46 2021 kern.info kernel: [96775.574257] br-lan: port
1(eth1.1) entered blocking state
Sun Sep 26 13:42:46 2021 kern.info kernel: [96775.579718] br-lan: port
1(eth1.1) entered disabled state
Sun Sep 26 13:42:46 2021 kern.info kernel: [96775.585137] device eth1.1
entered promiscuous mode
Sun Sep 26 13:42:46 2021 kern.info kernel: [96775.590460] device eth1 entered
promiscuous mode
Sun Sep 26 13:42:46 2021 daemon.notice netifd: Interface 'lan' is enabled
Sun Sep 26 13:42:46 2021 daemon.notice netifd: Interface 'lan' is setting up now
Sun Sep 26 13:42:46 2021 kern.info kernel: [96775.597154] br-lan: port
1(eth1.1) entered blocking state
Sun Sep 26 13:42:46 2021 kern.info kernel: [96775.599808] br-lan: port
1(eth1.1) entered forwarding state
Sun Sep 26 13:42:46 2021 daemon.notice netifd: Interface 'lan' is now up
Sun Sep 26 13:42:46 2021 daemon.notice netifd: bridge 'br-lan' link is up
Sun Sep 26 13:42:46 2021 daemon.notice netifd: Interface 'lan' has link
connectivity
Sun Sep 26 13:42:46 2021 daemon.notice netifd: VLAN 'eth1.1' link is up
Sun Sep 26 13:42:46 2021 daemon.notice netifd: Interface 'loopback' is enabled
Sun Sep 26 13:42:46 2021 daemon.notice netifd: Interface 'loopback' is
setting up now
Sun Sep 26 13:42:46 2021 daemon.notice netifd: Interface 'loopback' is now up
Sun Sep 26 13:42:46 2021 kern.info kernel: [96775.621446] dwmac1000: Master
AXI performs any burst length
Sun Sep 26 13:42:46 2021 kern.info kernel: [96775.621478] ipq806x-gmac-dwmac
37200000.ethernet eth0: No Safety Features support found
Sun Sep 26 13:42:46 2021 kern.info kernel: [96775.625828] ipq806x-gmac-dwmac
37200000.ethernet eth0: IEEE 1588-2008 Advanced Timestamp supported
Sun Sep 26 13:42:46 2021 kern.info kernel: [96775.634009] ipq806x-gmac-dwmac
37200000.ethernet eth0: registered PTP clock
Sun Sep 26 13:42:46 2021 kern.info kernel: [96775.642921] ipq806x-gmac-dwmac
37200000.ethernet eth0: configuring for fixed/rgmii link mode
Sun Sep 26 13:42:46 2021 kern.info kernel: [96775.650538] ipq806x-gmac-dwmac
37200000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off
Sun Sep 26 13:42:46 2021 user.notice odhcpd: *** ODHCPD triggers DNSMASQ
reload ***
Sun Sep 26 13:42:46 2021 kern.info kernel: [96775.659532] IPv6:
ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
Sun Sep 26 13:42:46 2021 daemon.notice netifd: Interface 'wan' is enabled
Sun Sep 26 13:42:46 2021 daemon.notice netifd: Interface 'wan6' is enabled
Sun Sep 26 13:42:46 2021 kern.info kernel: [96775.668808] IPv6:
ADDRCONF(NETDEV_CHANGE): eth0.2: link becomes ready
Sun Sep 26 13:42:46 2021 daemon.notice netifd: Network device 'eth1' link is up
Sun Sep 26 13:42:46 2021 daemon.notice netifd: Network device 'lo' link is up
Sun Sep 26 13:42:46 2021 daemon.notice netifd: Interface 'loopback' has link
connectivity
Sun Sep 26 13:42:46 2021 daemon.notice netifd: Network device 'eth0' link is up
Sun Sep 26 13:42:46 2021 daemon.notice netifd: VLAN 'eth0.2' link is up
Sun Sep 26 13:42:46 2021 daemon.notice netifd: Interface 'wan' has link
connectivity
Sun Sep 26 13:42:46 2021 daemon.notice netifd: Interface 'wan' is setting up now
Sun Sep 26 13:42:46 2021 daemon.notice netifd: Interface 'wan6' has link
connectivity
Sun Sep 26 13:42:46 2021 daemon.notice netifd: Interface 'wan6' is setting up now
Sun Sep 26 13:42:46 2021 daemon.info dnsmasq[5668]: read /etc/hosts - 4 addresses
Sun Sep 26 13:42:46 2021 daemon.info dnsmasq[5668]: read
/tmp/hosts/dhcp.cfg01411c - 3 addresses
Sun Sep 26 13:42:46 2021 daemon.info dnsmasq[5668]: read /tmp/hosts/odhcpd -
1 addresses
Sun Sep 26 13:42:46 2021 daemon.info dnsmasq-dhcp[5668]: read /etc/ethers - 0
addresses
Sun Sep 26 13:42:46 2021 daemon.err odhcp6c[23536]: Failed to send RS
(Address not available)
Sun Sep 26 13:42:46 2021 daemon.notice netifd: wan (23543): udhcpc: started,
v1.34.0
Sun Sep 26 13:42:46 2021 daemon.notice netifd: wan (23543): udhcpc:
broadcasting discover
Sun Sep 26 13:42:46 2021 user.notice firewall: Reloading firewall due to ifup
of lan (br-lan)
Sun Sep 26 13:42:46 2021 daemon.notice netifd: wan (23543): udhcpc:
broadcasting select for 87..., server 62.241.198.74
Sun Sep 26 13:42:46 2021 daemon.notice netifd: wan (23543): udhcpc: lease of
87... obtained from 62.241.198.74, lease time 3600
Sun Sep 26 13:42:46 2021 daemon.notice netifd: Interface 'wan' is now up
Sun Sep 26 13:42:46 2021 daemon.info dnsmasq[5668]: reading
/tmp/resolv.conf.d/resolv.conf.auto
Sun Sep 26 13:42:46 2021 daemon.info dnsmasq[5668]: using nameserver
62.241.198.245#53
...
Sun Sep 26 13:42:46 2021 daemon.notice hostapd: Configuration file:
/var/run/hostapd-phy0.conf (phy wlan0) --> new PHY
Sun Sep 26 13:42:46 2021 daemon.err odhcp6c[1630]: Failed to send SOLICIT
message to ff02::1:2 (Address not available)
Sun Sep 26 13:42:46 2021 daemon.err odhcp6c[1630]: Failed to send RS (Address
not available)
Sun Sep 26 13:42:46 2021 daemon.err odhcp6c[1630]: Failed to send SOLICIT
message to ff02::1:2 (Address not available)
Sun Sep 26 13:42:46 2021 daemon.err odhcp6c[23536]: Failed to send SOLICIT
message to ff02::1:2 (Address not available)
Sun Sep 26 13:42:47 2021 user.notice nlbwmon: Reloading nlbwmon due to ifup
of lan (br-lan)
Sun Sep 26 13:42:47 2021 daemon.err odhcpd[1706]: Failed to send to
ff02::1%lan at br-lan (Address not available)
Sun Sep 26 13:42:47 2021 daemon.err odhcpd[1706]: Failed to send to
fe80::2b1e:a62f:e006:8cc1%lan at br-lan (Address not available)
Sun Sep 26 13:42:47 2021 daemon.err odhcp6c[1630]: Failed to send SOLICIT
message to ff02::1:2 (Address not available)
Sun Sep 26 13:42:48 2021 user.notice nlbwmon: Reloading nlbwmon due to ifup
of loopback (lo)
Sun Sep 26 13:42:48 2021 user.notice SQM: Stopping SQM on eth0.2
Sun Sep 26 13:42:50 2021 daemon.err odhcp6c[1630]: Failed to send SOLICIT
message to ff02::1:2 (Address not available)
Sun Sep 26 13:42:50 2021 daemon.err odhcp6c[1630]: Failed to send RS (Address
not available)
Sun Sep 26 13:42:51 2021 daemon.err odhcpd[1706]: Failed to send to
fe80::bd44:eebe:d223:1f1%lan at br-lan (Address not available)
Sun Sep 26 13:42:52 2021 kern.warn kernel: [96782.190616] ath10k_pci
0000:01:00.0: 10.4 wmi init: vdevs: 16 peers: 48 tid: 96
Sun Sep 26 13:42:52 2021 kern.warn kernel: [96782.190647] ath10k_pci
0000:01:00.0: msdu-desc: 2500 skid: 32
Sun Sep 26 13:42:52 2021 kern.info kernel: [96782.273037] ath10k_pci
0000:01:00.0: wmi print 'P 48/48 V 16 K 144 PH 176 T 186 msdu-desc: 2500
sw-crypt: 0 ct-sta: 0'
Sun Sep 26 13:42:52 2021 kern.info kernel: [96782.273884] ath10k_pci
0000:01:00.0: wmi print 'free: 84920 iram: 13156 sram: 11224'
Sun Sep 26 13:42:53 2021 daemon.err odhcp6c[23536]: Failed to send RS
(Address not available)
Sun Sep 26 13:42:53 2021 daemon.info dnsmasq[5668]: read /etc/hosts - 4 addresses
Sun Sep 26 13:42:53 2021 daemon.info dnsmasq[5668]: read
/tmp/hosts/dhcp.cfg01411c - 3 addresses
Sun Sep 26 13:42:53 2021 daemon.info dnsmasq[5668]: read /tmp/hosts/odhcpd -
1 addresses
Sun Sep 26 13:42:53 2021 daemon.info dnsmasq-dhcp[5668]: read /etc/ethers - 0
addresses
Sun Sep 26 13:42:53 2021 kern.info kernel: [96782.657767] ath10k_pci
0000:01:00.0: rts threshold -1
Sun Sep 26 13:42:53 2021 kern.warn kernel: [96782.658153] ath10k_pci
0000:01:00.0: Firmware lacks feature flag indicating a retry limit of > 2 is
OK, requested limit: 4
Sun Sep 26 13:42:53 2021 kern.info kernel: [96782.679283] br-lan: port
2(wlan0) entered blocking state
Sun Sep 26 13:42:53 2021 kern.info kernel: [96782.679312] br-lan: port
2(wlan0) entered disabled state
Sun Sep 26 13:42:53 2021 daemon.notice hostapd: wlan0: interface state
UNINITIALIZED->COUNTRY_UPDATE
Sun Sep 26 13:42:53 2021 kern.info kernel: [96782.699398] device wlan0
entered promiscuous mode
Sun Sep 26 13:42:53 2021 daemon.notice hostapd: wlan0: interface state
COUNTRY_UPDATE->HT_SCAN
Sun Sep 26 13:42:53 2021 daemon.notice hostapd: Configuration file:
/var/run/hostapd-phy1.conf (phy wlan1) --> new PHY
Sun Sep 26 13:42:54 2021 user.info adblock-4.1.3[24681]: adblock instance
started ::: action: start, priority: 0, pid: 24681
Sun Sep 26 13:42:59 2021 kern.warn kernel: [96789.299430] ath10k_pci
0001:01:00.0: 10.4 wmi init: vdevs: 16 peers: 48 tid: 96
Sun Sep 26 13:42:59 2021 kern.warn kernel: [96789.299461] ath10k_pci
0001:01:00.0: msdu-desc: 2500 skid: 32
Sun Sep 26 13:42:59 2021 kern.info kernel: [96789.383454] ath10k_pci
0001:01:00.0: wmi print 'P 48/48 V 16 K 144 PH 176 T 186 msdu-desc: 2500
sw-crypt: 0 ct-sta: 0'
Sun Sep 26 13:42:59 2021 kern.info kernel: [96789.384312] ath10k_pci
0001:01:00.0: wmi print 'free: 84920 iram: 13156 sram: 11224'
Sun Sep 26 13:43:00 2021 kern.info kernel: [96789.787195] ath10k_pci
0001:01:00.0: rts threshold -1
Sun Sep 26 13:43:00 2021 kern.warn kernel: [96789.787739] ath10k_pci
0001:01:00.0: Firmware lacks feature flag indicating a retry limit of > 2 is
OK, requested limit: 4
Sun Sep 26 13:43:00 2021 daemon.notice netifd: Interface 'wan6' is now up
Sun Sep 26 13:43:00 2021 kern.info kernel: [96789.810680] br-lan: port
3(wlan1) entered blocking state
Sun Sep 26 13:43:00 2021 kern.info kernel: [96789.810708] br-lan: port
3(wlan1) entered disabled state
...
Sun Sep 26 13:43:00 2021 kern.info kernel: [96789.826801] device wlan1
entered promiscuous mode
Sun Sep 26 13:43:00 2021 daemon.notice hostapd: wlan1: interface state
UNINITIALIZED->COUNTRY_UPDATE
...
Sun Sep 26 13:43:00 2021 daemon.info dnsmasq[5668]: read /etc/hosts - 4 addresses
Sun Sep 26 13:43:00 2021 daemon.info dnsmasq[5668]: read
/tmp/hosts/dhcp.cfg01411c - 3 addresses
Sun Sep 26 13:43:00 2021 daemon.info dnsmasq[5668]: read /tmp/hosts/odhcpd -
2 addresses
Sun Sep 26 13:43:00 2021 daemon.info dnsmasq-dhcp[5668]: read /etc/ethers - 0
addresses
Sun Sep 26 13:43:00 2021 kern.info kernel: [96790.180188] IPv6:
ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
Sun Sep 26 13:43:00 2021 kern.info kernel: [96790.180330] br-lan: port
3(wlan1) entered blocking state
Sun Sep 26 13:43:00 2021 kern.info kernel: [96790.185615] br-lan: port
3(wlan1) entered forwarding state
Sun Sep 26 13:43:00 2021 daemon.notice hostapd: wlan1: interface state
COUNTRY_UPDATE->ENABLED
Sun Sep 26 13:43:00 2021 daemon.notice hostapd: wlan1: AP-ENABLED
Sun Sep 26 13:43:00 2021 daemon.info dnsmasq[5668]: exiting on receipt of SIGTERM
Sun Sep 26 13:43:00 2021 kern.info kernel: [96790.517192] IPv6:
ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Sun Sep 26 13:43:00 2021 kern.info kernel: [96790.517343] br-lan: port
2(wlan0) entered blocking state
Sun Sep 26 13:43:00 2021 kern.info kernel: [96790.522616] br-lan: port
2(wlan0) entered forwarding state
Sun Sep 26 13:43:00 2021 daemon.notice netifd: Network device 'wlan0' link is up
Sun Sep 26 13:43:00 2021 daemon.notice hostapd: wlan0: interface state
HT_SCAN->ENABLED
Sun Sep 26 13:43:00 2021 daemon.notice hostapd: wlan0: AP-ENABLED
Sun Sep 26 13:43:01 2021 daemon.notice netifd: Network device 'wlan1' link is up
More information about the openwrt-devel
mailing list