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