Hi,
my Schenker S306 (notebook) suffers from issues regarding WiFi (Intel AC 3160). The keyboard shortcut to toggle WiFi enables a hard & softlock (as observed by rfkill) but when pressing that button again it only removes the softlock.
I did find a workaround. Creating the file:sudo nano /etc/modprobe.d/wifi.confblacklist wmi
But this points to a different/deeper issue with wmi events for this particular machine, and if possible, I’d like to report that issue as well.
BR
pobrn
18 October 2020 19:07
2
Please remove the blacklist, then reboot and post the output of ls -l /sys/class/rfkill/ and lsmod | grep wmi. Also install acpid, then sudo systemctl start acpi_listen. Then start acpi_listen and sudo dmesg -w on two different terminals, then press the key on the keyboard, wait 10 seconds, press it again. Post whatever appeared in both terminals after you pressed the key for the first time.
ls -l /sys/class/rfkill/
insgesamt 0
lrwxrwxrwx 1 root root 0 19. Okt 07:57 rfkill0 -> ../../devices/pci0000:00/0000:00:14.0/usb1/1-3/1-3:1.0/bluetooth/hci0/rfkill0
lrwxrwxrwx 1 root root 0 19. Okt 07:57 rfkill1 -> ../../devices/pci0000:00/0000:00:1c.0/0000:01:00.0/ieee80211/phy0/rfkill1
Surprisinglylsmod | grep wmi
Despite having acpid already installedsystemctl start acpi_listenFailed to start acpi_listen.service: Unit acpi_listen.service not found.
pobrn
19 October 2020 06:15
4
I should’ve specified, but acpi_listen is not a systemd service, it’s a program, so you should’ve just run it.
I fixed the issue. I don’t need to launch sudo systemctl start acpi_listen but instead just sudo systemctl start acpi.
Okay, apparently there is a difference between desktop environments. Yesterday I changed from XFCE to Plasma, and now the WiFi only irreversibly hardlocks when toggling bluetooth.
Regradless:
For the (in Plasma working) WiFi switch:
[ 46.042092] wlp1s0: deauthenticating from 38:d5:47:c3:bd:cc by local choice (Reason: 3=DEAUTH_LEAVING)
[ 46.095078] audit: type=1130 audit(1603098189.059:113): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-rfkill comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 46.324109] audit: type=1130 audit(1603098189.285:114): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 47.765108] audit: type=1131 audit(1603098190.729:115): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=blueman-mechanism comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 49.632317] audit: type=1105 audit(1603098192.595:116): pid=1892 uid=1000 auid=4294967295 ses=4294967295 msg='op=PAM:session_open grantors=pam_limits acct="nx" exe="/usr/NX/bin/nxexec" hostname=? addr=? terminal=ssh res=success'
[ 52.969065] audit: type=1131 audit(1603098195.929:117): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-rfkill comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
acpi_listen
button/wlan WLAN 00000080 00000000 K
button/wlan WLAN 00000080 00000000 K
For the now broken Bluetooth toggle:
[ 92.878053] topstar_laptop: unknown event = 0x89
[ 93.001234] usb 1-3: USB disconnect, device number 2
[ 93.055620] audit: type=1130 audit(1603097788.034:129): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-rfkill comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 93.057473] iwlwifi 0000:01:00.0: RF_KILL bit toggled to disable radio.
[ 93.057493] iwlwifi 0000:01:00.0: reporting RF_KILL (radio disabled)
[ 93.110704] ------------[ cut here ]------------
[ 93.110707] Timeout waiting for hardware access (CSR_GP_CNTRL 0x000003d8)
[ 93.110760] WARNING: CPU: 0 PID: 5 at drivers/net/wireless/intel/iwlwifi/pcie/trans.c:2066 iwl_trans_pcie_grab_nic_access+0x1af/0x1e0 [iwlwifi]
[ 93.110760] Modules linked in: ccm rfcomm xt_multiport iptable_filter ip6table_filter ip6_tables joydev mousedev cmac snd_soc_skl snd_soc_sst_ipc snd_soc_sst_dsp algif_hash snd_hda_ext_core snd_soc_acpi_intel_match snd_hda_codec_hdmi snd_soc_acpi snd_soc_core snd_hda_codec_realtek algif_skcipher snd_hda_codec_generic af_alg snd_compress ledtrig_audio bnep ac97_bus snd_pcm_dmaengine snd_hda_intel intel_rapl_msr snd_intel_dspcfg intel_rapl_common iwlmvm snd_hda_codec fuse mac80211 x86_pkg_temp_thermal i915 intel_powerclamp kvm_intel coretemp libarc4 iTCO_wdt snd_hda_core kvm iwlwifi intel_pmc_bxt at24 squashfs mei_hdcp uvcvideo iTCO_vendor_support snd_hwdep btusb irqbypass nls_iso8859_1 btrtl btbcm btintel videobuf2_vmalloc rapl snd_pcm nls_cp437 i2c_algo_bit videobuf2_memops vfat videobuf2_v4l2 intel_cstate fat drm_kms_helper snd_timer bluetooth loop intel_uncore videobuf2_common cfg80211 cec snd psmouse input_leds videodev pcspkr rc_core soundcore intel_gtt mei_me i2c_i801 ecdh_generic
[ 93.110795] i2c_smbus syscopyarea mc sysfillrect sysimgblt intel_xhci_usb_role_switch ecc roles rfkill fb_sys_fops mei intel_pch_thermal topstar_laptop sparse_keymap tpm_crb battery evdev mac_hid tpm_tis ac tpm_tis_core uinput vboxnetflt(OE) vboxnetadp(OE) vboxdrv(OE) drm crypto_user agpgart ip_tables x_tables ext4 crc32c_generic crc16 mbcache jbd2 dm_crypt cbc encrypted_keys dm_mod trusted tpm rng_core serio_raw atkbd libps2 crct10dif_pclmul crc32_pclmul crc32c_intel ghash_clmulni_intel aesni_intel xhci_pci crypto_simd cryptd glue_helper xhci_hcd i8042 serio
[ 93.110822] CPU: 0 PID: 5 Comm: kworker/0:0 Tainted: G IOE 5.9.0-3-MANJARO #1
[ 93.110823] Hardware name: Schenker Technologies GmbH Schenker VIA13/U931, BIOS 5.11 07/26/2016
[ 93.110856] Workqueue: events cfg80211_rfkill_block_work [cfg80211]
[ 93.110869] RIP: 0010:iwl_trans_pcie_grab_nic_access+0x1af/0x1e0 [iwlwifi]
[ 93.110872] Code: ff cc 49 8d 56 08 bf 40 01 00 00 e8 fb 80 92 cb e9 3a ff ff ff 89 c6 48 c7 c7 88 0e db c0 c6 05 b2 ba 03 00 01 e8 d7 92 90 cb <0f> 0b e9 f5 fe ff ff 48 8b 7d 38 48 c7 c1 f0 0e db c0 31 d2 31 f6
[ 93.110873] RSP: 0000:ffffaa0e80067c20 EFLAGS: 00010082
[ 93.110875] RAX: 0000000000000000 RBX: ffffaa0e80067c50 RCX: 0000000000000000
[ 93.110876] RDX: 0000000000000002 RSI: ffffffff8d983a82 RDI: 00000000ffffffff
[ 93.110877] RBP: ffff9309a70e4018 R08: 0000000000000386 R09: 0000000000000001
[ 93.110878] R10: 0000000000000000 R11: 0000000000000001 R12: 0000000000000000
[ 93.110879] R13: ffff9309a70e6644 R14: 00000000000003d8 R15: ffff930997fb3538
[ 93.110881] FS: 0000000000000000(0000) GS:ffff9309abc00000(0000) knlGS:0000000000000000
[ 93.110882] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 93.110883] CR2: 0000133befc3c008 CR3: 0000000225fcc001 CR4: 00000000003706f0
[ 93.110884] Call Trace:
[ 93.110899] iwl_set_bits_prph+0x3f/0xa0 [iwlwifi]
[ 93.110912] iwl_fw_dbg_stop_restart_recording.part.0+0x237/0x260 [iwlwifi]
[ 93.110926] __iwl_mvm_mac_stop+0x79/0x140 [iwlmvm]
[ 93.110936] iwl_mvm_mac_stop+0x5e/0x80 [iwlmvm]
[ 93.110969] drv_stop+0x2f/0x130 [mac80211]
[ 93.110995] ieee80211_do_stop+0x5a7/0x8c0 [mac80211]
[ 93.111021] ieee80211_stop+0x16/0x20 [mac80211]
[ 93.111025] __dev_close_many+0xaa/0x120
[ 93.111029] dev_close_many+0x92/0x140
[ 93.111032] dev_close+0x6d/0x90
[ 93.111054] cfg80211_shutdown_all_interfaces+0x71/0xd0 [cfg80211]
[ 93.111076] cfg80211_rfkill_block_work+0x1a/0x20 [cfg80211]
[ 93.111079] process_one_work+0x1da/0x3d0
[ 93.111081] worker_thread+0x4d/0x3d0
[ 93.111084] ? rescuer_thread+0x410/0x410
[ 93.111086] kthread+0x142/0x160
[ 93.111088] ? __kthread_bind_mask+0x60/0x60
[ 93.111091] ret_from_fork+0x22/0x30
[ 93.111095] ---[ end trace a0394acb55ebc7c0 ]---
[ 93.111099] iwlwifi 0000:01:00.0: iwlwifi transaction failed, dumping registers
[ 93.111101] iwlwifi 0000:01:00.0: iwlwifi device config registers:
[ 93.111295] iwlwifi 0000:01:00.0: 00000000: 08b38086 00100406 02800083 00000000 df000004 00000000 00000000 00000000
[ 93.111298] iwlwifi 0000:01:00.0: 00000020: 00000000 00000000 00000000 80708086 00000000 000000c8 00000000 0000010b
[ 93.111300] iwlwifi 0000:01:00.0: 00000040: 00020010 10008ec0 00100c10 0006ec11 10110140 00000000 00000000 00000000
[ 93.111302] iwlwifi 0000:01:00.0: 00000060: 00000000 00080812 00000405 00000000 00010001 00000000 00000000 00000000
[ 93.111304] iwlwifi 0000:01:00.0: 00000080: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[ 93.111306] iwlwifi 0000:01:00.0: 000000a0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[ 93.111308] iwlwifi 0000:01:00.0: 000000c0: 00000000 00000000 c823d001 0d000000 00814005 fee002b8 00000000 00000000
[ 93.111310] iwlwifi 0000:01:00.0: 000000e0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[ 93.111312] iwlwifi 0000:01:00.0: 00000100: 14010001 00000000 00000000 00462031 00000000 00002000 00000000 00000000
[ 93.111314] iwlwifi 0000:01:00.0: 00000120: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[ 93.111317] iwlwifi 0000:01:00.0: 00000140: 14c10003 ffc95559 2c6e85ff 15410018 10031003 0001000b 0141cafe 00f01e1f
[ 93.111319] iwlwifi 0000:01:00.0: iwlwifi device memory mapped registers:
[ 93.111357] iwlwifi 0000:01:00.0: 00000000: 00c00000 80000000 00000000 00000080 00000000 00000000 00000000 00000000
[ 93.111360] iwlwifi 0000:01:00.0: 00000020: 00000001 000003d8 00000164 00000000 80000000 803a0000 80008040 00080046
[ 93.111363] iwlwifi 0000:01:00.0: iwlwifi device AER capability structure:
[ 93.111388] iwlwifi 0000:01:00.0: 00000000: 14010001 00000000 00000000 00462031 00000000 00002000 00000000 00000000
[ 93.111389] iwlwifi 0000:01:00.0: 00000020: 00000000 00000000 00000000
[ 93.111391] iwlwifi 0000:01:00.0: iwlwifi parent port (0000:00:1c.0) config registers:
[ 93.111471] iwlwifi 0000:00:1c.0: 00000000: 9d148086 00100407 060400f1 00810000 00000000 00000000 00010100 200000f0
[ 93.111474] iwlwifi 0000:00:1c.0: 00000020: df00df00 0001fff1 00000000 00000000 00000000 00000040 00000000 0012010b
[ 93.111476] iwlwifi 0000:00:1c.0: 00000040: 01428010 00008001 0010000f 05724013 70110040 0044b200 01400000 00000008
[ 93.111478] iwlwifi 0000:00:1c.0: 00000060: 00000000 00000837 00000400 0000000e 00010003 00000000 00000000 00000000
[ 93.111480] iwlwifi 0000:00:1c.0: 00000080: 00019005 fee00218 00000000 00000000 0000a00d 9d148086 00000000 00000000
[ 93.111483] iwlwifi 0000:00:1c.0: 000000a0: c8030001 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[ 93.111485] iwlwifi 0000:00:1c.0: 000000c0: 00000000 00000000 00000000 00000000 07001001 00001842 0b1e0008 00000000
[ 93.111487] iwlwifi 0000:00:1c.0: 000000e0: 00f3b700 8c548c54 00128016 00000000 00000150 40000300 08300fb3 01000004
[ 93.111489] iwlwifi 0000:00:1c.0: 00000100: 14010001 00000000 00010000 00060011 00000000 00002000 00000000 00000000
[ 93.111491] iwlwifi 0000:00:1c.0: 00000120: 00000000 00000000 00000000 00000007 00000000 00000000 00000000 00000000
[ 93.111494] iwlwifi 0000:00:1c.0: 00000140: 2201000d 0000000f 00000000 00000000 00000000 00000000 00000000 00000000
[ 93.111496] iwlwifi 0000:00:1c.0: 00000160: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[ 93.111498] iwlwifi 0000:00:1c.0: 00000180: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[ 93.111500] iwlwifi 0000:00:1c.0: 000001a0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[ 93.111502] iwlwifi 0000:00:1c.0: 000001c0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[ 93.111504] iwlwifi 0000:00:1c.0: 000001e0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[ 93.111505] iwlwifi 0000:00:1c.0: 00000200: 00000000 0028281f 00000000
[ 93.111507] iwlwifi 0000:01:00.0: iwlwifi root port (0000:00:1c.0) AER cap structure:
[ 93.111517] iwlwifi 0000:00:1c.0: 00000000: 14010001 00000000 00010000 00060011 00000000 00002000 00000000 00000000
[ 93.111518] iwlwifi 0000:00:1c.0: 00000020: 00000000 00000000 00000000 00000007 00000000 00000000
[ 98.074103] audit: type=1131 audit(1603097793.050:130): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-rfkill comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
while
Okay, I moved over to a fresh install, and tried to reproduce the issue. It still happens, and now again when using the WiFi hotkey (maybe I broke something else when moving over to Plasma).
acpi_listen:
button/wlan WLAN 00000080 00000000 K
button/wlan WLAN 00000080 00000000 K
dmesg:
[ 40.390328] wlp1s0: send auth to 38:d5:47:c3:bd:cc (try 1/3)
[ 40.391183] wlp1s0: authenticated
[ 40.401155] wlp1s0: associate with 38:d5:47:c3:bd:cc (try 1/3)
[ 40.402294] wlp1s0: RX AssocResp from 38:d5:47:c3:bd:cc (capab=0x931 status=0 aid=2)
[ 40.403756] wlp1s0: associated
[ 40.426493] wlp1s0: Limiting TX power to 20 (20 - 0) dBm as advertised by 38:d5:47:c3:bd:cc
[ 40.980332] IPv6: ADDRCONF(NETDEV_CHANGE): wlp1s0: link becomes ready
[ 41.010788] audit: type=1130 audit(1603640913.966:72): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 41.041512] audit: type=1111 audit(1603640913.999:73): pid=405 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='op=statistics arg="refresh-rate-ms" pid=904 uid=1000 result=success exe="/usr/bin/NetworkManager" hostname=? addr=? terminal=? res=success'
[ 51.016536] audit: type=1131 audit(1603640923.972:74): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 64.048671] audit: type=1100 audit(1603640937.006:75): pid=1276 uid=1000 auid=1000 ses=1 subj==unconfined msg='op=PAM:authentication grantors=pam_faillock,pam_permit,pam_faillock acct="arthemis" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/5 res=success'
[ 64.050018] audit: type=1101 audit(1603640937.006:76): pid=1276 uid=1000 auid=1000 ses=1 subj==unconfined msg='op=PAM:accounting grantors=pam_permit,pam_time acct="arthemis" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/5 res=success'
[ 64.050254] audit: type=1110 audit(1603640937.006:77): pid=1276 uid=0 auid=1000 ses=1 subj==unconfined msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/5 res=success'
[ 64.050932] audit: type=1105 audit(1603640937.006:78): pid=1276 uid=0 auid=1000 ses=1 subj==unconfined msg='op=PAM:session_open grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/5 res=success'
[ 64.061957] audit: type=1130 audit(1603640937.019:79): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=acpid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 64.064168] audit: type=1106 audit(1603640937.019:80): pid=1276 uid=0 auid=1000 ses=1 subj==unconfined msg='op=PAM:session_close grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/5 res=success'
[ 64.064253] audit: type=1104 audit(1603640937.019:81): pid=1276 uid=0 auid=1000 ses=1 subj==unconfined msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/5 res=success'
[ 76.918303] audit: type=1100 audit(1603640949.876:82): pid=1311 uid=1000 auid=1000 ses=1 subj==unconfined msg='op=PAM:authentication grantors=pam_faillock,pam_permit,pam_faillock acct="arthemis" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/3 res=success'
[ 76.919684] audit: type=1101 audit(1603640949.876:83): pid=1311 uid=1000 auid=1000 ses=1 subj==unconfined msg='op=PAM:accounting grantors=pam_permit,pam_time acct="arthemis" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/3 res=success'
[ 76.919994] audit: type=1110 audit(1603640949.876:84): pid=1311 uid=0 auid=1000 ses=1 subj==unconfined msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/3 res=success'
[ 76.920890] audit: type=1105 audit(1603640949.876:85): pid=1311 uid=0 auid=1000 ses=1 subj==unconfined msg='op=PAM:session_open grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/3 res=success'
[ 92.104553] wlp1s0: deauthenticating from 38:d5:47:c3:bd:cc by local choice (Reason: 3=DEAUTH_LEAVING)
[ 92.171679] audit: type=1130 audit(1603640965.129:86): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-rfkill comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 92.233667] audit: type=1130 audit(1603640965.189:87): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 92.393556] iwlwifi 0000:01:00.0: RF_KILL bit toggled to disable radio.
[ 92.393565] iwlwifi 0000:01:00.0: reporting RF_KILL (radio disabled)
[ 100.836117] audit: type=1131 audit(1603640973.792:88): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-rfkill comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 102.019277] audit: type=1131 audit(1603640974.976:89): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Now blacklisting the wmi doesn’t fix the issue. Instead I need to remove the intel wifi firmware, reboot, add it again, and reboot again for wifi to work again.
pobrn
25 October 2020 15:56
7
But there is no “Timeout waiting for hardware”, right? What can be seen in dmesg when you try to enable it using the hotkey?
dmesg doesn’t show anything when I try enabling WiFi with the hotkey… So I think it just doesn’t toggle the RF_KILL bit.
pobrn
25 October 2020 16:02
9
So acpi_listen shows the keypress, but there is nothing in dmesg that indicates that the rfkill state has changed?
As far as I can tell, yeah, that’s exactly what happens.
pobrn
25 October 2020 16:14
11
That’s interesting, is the kernel version different now?
No, I was running 5.9.1-1 both times.
The biggest difference between my old and new installation is that I used to run XFCE4 and switched over to Plasma on the former, and installed the Plasma image directly on the latter.
pobrn
25 October 2020 16:19
13
Does sudo rfkill unblock ... do anything?
Yes.
Using the shortcut soft & hardlocks the Wifi device, and only soft unlocks it. I can however soft unlock it with rfkill too. The device does stay hardlocked regardless.