Bug#959757: This is a regression
Control: found -1 5.5.0-0.bpo.2-amd64
I started to see this problem after upgrading from 5.4.0-0.bpo.4-amd64
to 5.5.0-0.bpo.2-amd64. It goes like:
[21825.096887] rtw_pci 0000:04:00.0: start vif dc:f5:05:54:f6:0d on port 0
[21829.406272] wlo1: authenticate with 38:43:7d:8a:89:0e
[21829.914017] wlo1: send auth to 38:43:7d:8a:89:0e (try 1/3)
[21829.935816] wlo1: authenticated
[21829.941105] wlo1: associate with 38:43:7d:8a:89:0e (try 1/3)
[21829.984243] wlo1: RX AssocResp from 38:43:7d:8a:89:0e (capab=0x1511 status=0 aid=3)
[21829.986178] rtw_pci 0000:04:00.0: sta 38:43:7d:8a:89:0e joined with macid 0
[21830.045633] rtw_pci 0000:04:00.0: wrong bfee role
[21830.048864] wlo1: associated
[21830.050828] ------------[ cut here ]------------
[21830.051893] invalid ra report c2h length
[21830.052890] WARNING: CPU: 2 PID: 280 at drivers/net/wireless/realtek/rtw88/fw.c:117 rtw_fw_c2h_cmd_handle+0x11a/0x130 [rtw88]
[21830.053924] Modules linked in: cmac bnep bbswitch(OE) snd_sof_pci snd_sof_intel_hda_common snd_sof_intel_hda snd_sof_intel_byt snd_sof_intel_ipc snd_sof snd_sof_xtensa_dsp nls_ascii snd_soc_skl nls_cp437 snd_soc_hdac_hda vfat btusb snd_hda_ext_core fat btrtl snd_soc_sst_ipc snd_hda_codec_hdmi btbcm snd_soc_sst_dsp btintel ext4 snd_soc_acpi_intel_match x86_pkg_temp_thermal snd_soc_acpi snd_hda_codec_realtek intel_powerclamp rtwpci mbcache coretemp snd_soc_core snd_hda_codec_generic bluetooth jbd2 rtw88 ledtrig_audio snd_compress kvm_intel snd_hda_intel mac80211 drbg kvm snd_intel_dspcfg uvcvideo videobuf2_vmalloc irqbypass snd_hda_codec joydev videobuf2_memops ansi_cprng videobuf2_v4l2 asus_nb_wmi snd_hda_core cfg80211 videobuf2_common ecdh_generic intel_cstate asus_wmi snd_hwdep ecc intel_rapl_msr efi_pstore sparse_keymap intel_uncore videodev snd_pcm serio_raw intel_rapl_perf pcspkr snd_timer mc iTCO_wdt efivars wmi_bmof snd mei_me processor_thermal_device rfkill iTCO_vendor_support
[21830.053944] soundcore hid_multitouch watchdog crc16 libarc4 intel_rapl_common mei int3403_thermal sg intel_soc_dts_iosf intel_pch_thermal ac int340x_thermal_zone tpm_crb tpm_tis tpm_tis_core tpm rng_core int3400_thermal evdev acpi_thermal_rel acpi_pad acpi_tad efivarfs ip_tables x_tables autofs4 btrfs blake2b_generic xor zstd_decompress zstd_compress raid6_pq libcrc32c crc32c_generic algif_skcipher af_alg dm_crypt dm_mod sd_mod hid_generic spi_pxa2xx_platform dw_dmac dw_dmac_core i2c_designware_platform i2c_designware_core i915 crct10dif_pclmul crc32_pclmul crc32c_intel ghash_clmulni_intel ahci libahci i2c_algo_bit mxm_wmi drm_kms_helper xhci_pci xhci_hcd aesni_intel libata drm usbcore crypto_simd cryptd glue_helper scsi_mod i2c_hid i2c_i801 intel_lpss_pci hid intel_lpss idma64 usb_common mfd_core battery wmi video button
[21830.063782] CPU: 2 PID: 280 Comm: kworker/u16:2 Tainted: G OE 5.5.0-0.bpo.2-amd64 #1 Debian 5.5.17-1~bpo10+1
[21830.064901] Hardware name: ASUSTeK COMPUTER INC. VivoBook_ASUSLaptop X430FN_S430FN/X430FN, BIOS X430FN.308 05/28/2019
[21830.066026] Workqueue: phy0 rtw_c2h_work [rtw88]
[21830.067158] RIP: 0010:rtw_fw_c2h_cmd_handle+0x11a/0x130 [rtw88]
[21830.068241] Code: cc 35 00 00 e8 f7 33 0d 00 e9 77 ff ff ff 48 89 ee 4c 89 f7 e8 07 70 ff ff e9 67 ff ff ff 48 c7 c7 c1 e4 2d c1 e8 a1 6c 80 de <0f> 0b e9 54 ff ff ff e8 4a 6a 80 de 66 2e 0f 1f 84 00 00 00 00 00
[21830.069391] RSP: 0018:ffffac2d8073be30 EFLAGS: 00010286
[21830.070515] RAX: 0000000000000000 RBX: ffff93e3a126bfd8 RCX: 0000000000000000
[21830.071635] RDX: ffff93e3a5ca9740 RSI: ffff93e3a5c99a48 RDI: ffff93e3a5c99a48
[21830.072694] RBP: ffff93e39b91b000 R08: 0000000000000426 R09: 0000000000aaaaaa
[21830.073764] R10: 0000000000000000 R11: ffffac2da09d8220 R12: ffff93e3a0a65b78
[21830.074835] R13: 0000000000000006 R14: ffff93e3a0a61e60 R15: ffff93e3a0a65c30
[21830.075842] FS: 0000000000000000(0000) GS:ffff93e3a5c80000(0000) knlGS:0000000000000000
[21830.076866] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[21830.077827] CR2: 0000558372174ed8 CR3: 000000015540a004 CR4: 00000000003606e0
[21830.078780] Call Trace:
[21830.079736] ? skb_dequeue+0x52/0x60
[21830.080674] rtw_c2h_work+0x40/0x60 [rtw88]
[21830.081602] process_one_work+0x1a7/0x360
[21830.082532] worker_thread+0x30/0x390
[21830.083458] ? create_worker+0x1a0/0x1a0
[21830.084385] kthread+0x112/0x130
[21830.085353] ? kthread_park+0x80/0x80
[21830.086276] ret_from_fork+0x35/0x40
[21830.087246] ---[ end trace defb67375a180861 ]---
[21831.010482] wlo1: deauthenticated from 38:43:7d:8a:89:0e (Reason: 2=PREV_AUTH_NOT_VALID)
[21831.045061] rtw_pci 0000:04:00.0: sta 38:43:7d:8a:89:0e with macid 0 left
[21831.722504] wlo1: authenticate with 38:43:7d:8a:89:41
[21832.230266] wlo1: send auth to 38:43:7d:8a:89:41 (try 1/3)
[21832.236978] wlo1: authenticated
[21832.242505] wlo1: associating with AP with corrupt probe response
[21832.247217] wlo1: associate with 38:43:7d:8a:89:41 (try 1/3)
[21832.274448] wlo1: RX AssocResp from 38:43:7d:8a:89:41 (capab=0x1411 status=0 aid=6)
[21832.279033] rtw_pci 0000:04:00.0: sta 38:43:7d:8a:89:41 joined with macid 0
[21832.337599] wlo1: associated
[21832.342388] ------------[ cut here ]------------
[21832.347171] invalid ra report c2h length
[21832.351787] WARNING: CPU: 3 PID: 424 at drivers/net/wireless/realtek/rtw88/fw.c:117 rtw_fw_c2h_cmd_handle+0x11a/0x130 [rtw88]
[...]
The tainting out-of-tree unsigned module is bbswitch. And despite the
biggish timestamp, this is right after boot, because the timestamp jumped:
Jun 12 14:47:17 fin kernel: [ 1.807541] clocksource: Switched to clocksource tsc
Jun 12 14:47:17 fin kernel: [21820.301228] NET: Registered protocol family 38
Another discrepancy I noticed is the "loss" of 6 GB of memory (user
space "free" can see it all the same):
5.4: Memory: 7844620K/8236380K available (10243K kernel code, 1188K rwdata, 3752K rodata, 1664K init, 2064K bss, 391760K reserved, 0K cma-reserved)
5.5: Memory: 2148144K/8236380K available (10243K kernel code, 1210K rwdata, 3984K rodata, 1664K init, 1992K bss, 392988K reserved, 0K cma-reserved)
5.6: Memory: 2147832K/8236380K available (10243K kernel code, 1275K rwdata, 3988K rodata, 1604K init, 1988K bss, 393300K reserved, 0K cma-reserved)
Current untainted 5.6.0-0.bpo.2-amd64 still exhibits the problem (not
the timestamp jump):
rtw_pci 0000:04:00.0: enabling device (0000 -> 0003)
rtw_pci 0000:04:00.0: firmware: direct-loading firmware rtw88/rtw8822b_fw.bin
Bluetooth: hci0: RTL: loading rtl_bt/rtl8822b_fw.bin
rtw_pci 0000:04:00.0: Firmware version 13.1.0, H2C version 6
rtw_pci 0000:04:00.0 wlo1: renamed from wlan0
rtw_pci 0000:04:00.0: start vif dc:f5:05:54:f6:0d on port 0
wlo1: authenticate with 38:43:7d:8a:89:0e
wlo1: send auth to 38:43:7d:8a:89:0e (try 1/3)
wlo1: authenticated
wlo1: associate with 38:43:7d:8a:89:0e (try 1/3)
wlo1: RX AssocResp from 38:43:7d:8a:89:0e (capab=0x1511 status=0 aid=3)
rtw_pci 0000:04:00.0: sta 38:43:7d:8a:89:0e joined with macid 0
rtw_pci 0000:04:00.0: wrong bfee role
wlo1: associated
------------[ cut here ]------------
invalid ra report c2h length
WARNING: CPU: 7 PID: 634 at drivers/net/wireless/realtek/rtw88/fw.c:118 rtw_fw_c2h_cmd_handle+0x11a/0x130 [rtw88]
Modules linked in: cmac bnep snd_sof_pci snd_sof_intel_hda_common snd_sof_intel_hda snd_sof_intel_byt snd_sof_intel_ipc snd_sof snd_sof_xtensa_dsp snd_soc_skl snd_soc_hdac_hda snd_hda_codec_hdmi snd_hda_ext_core btusb rtwpci snd_soc_sst_ipc nls_ascii btrtl snd_soc_sst_dsp btbcm rtw88 nls_cp437 btintel snd_soc_acpi_intel_match vfat snd_soc_acpi snd_hda_codec_realtek fat snd_soc_core snd_hda_codec_generic x86_pkg_temp_thermal ledtrig_audio snd_compress intel_powerclamp bluetooth ext4 mac80211 snd_hda_intel snd_intel_dspcfg coretemp snd_hda_codec mbcache drbg jbd2 kvm_intel snd_hda_core cfg80211 uvcvideo ansi_cprng snd_hwdep joydev asus_nb_wmi kvm videobuf2_vmalloc asus_wmi videobuf2_memops ecdh_generic videobuf2_v4l2 sparse_keymap ecc intel_rapl_msr snd_pcm videobuf2_common irqbypass intel_cstate snd_timer videodev intel_uncore iTCO_wdt efi_pstore iTCO_vendor_support intel_rapl_perf mc snd serio_raw efivars pcspkr wmi_bmof mei_me watchdog sg soundcore rfkill libarc4 crc16
hid_multitouch processor_thermal_device mei intel_rapl_common intel_soc_dts_iosf intel_pch_thermal ac int3403_thermal int340x_thermal_zone tpm_crb tpm_tis tpm_tis_core tpm rng_core int3400_thermal acpi_thermal_rel acpi_tad evdev acpi_pad efivarfs ip_tables x_tables autofs4 btrfs blake2b_generic xor zstd_decompress zstd_compress raid6_pq libcrc32c crc32c_generic algif_skcipher af_alg dm_crypt dm_mod usbhid sd_mod t10_pi crc_t10dif crct10dif_generic hid_generic spi_pxa2xx_platform dw_dmac i2c_designware_platform dw_dmac_core i2c_designware_core i915 crct10dif_pclmul crct10dif_common crc32_pclmul crc32c_intel ghash_clmulni_intel ahci libahci i2c_algo_bit libata xhci_pci drm_kms_helper xhci_hcd cec aesni_intel mxm_wmi drm libaes crypto_simd usbcore scsi_mod cryptd glue_helper i2c_hid i2c_i801 hid intel_lpss_pci intel_lpss idma64 usb_common mfd_core battery wmi video button
CPU: 7 PID: 634 Comm: kworker/u16:7 Not tainted 5.6.0-0.bpo.2-amd64 #1 Debian 5.6.14-2~bpo10+1
Hardware name: ASUSTeK COMPUTER INC. VivoBook_ASUSLaptop X430FN_S430FN/X430FN, BIOS X430FN.308 05/28/2019
Workqueue: phy0 rtw_c2h_work [rtw88]
RIP: 0010:rtw_fw_c2h_cmd_handle+0x11a/0x130 [rtw88]
Code: d4 35 00 00 e8 97 92 bf ff e9 77 ff ff ff 48 89 ee 4c 89 f7 e8 b7 6f ff ff e9 67 ff ff ff 48 c7 c7 4c 56 50 c1 e8 d1 fb 1d d3 <0f> 0b e9 54 ff ff ff e8 7a f9 1d d3 66 2e 0f 1f 84 00 00 00 00 00
RSP: 0018:ffffacc84094be30 EFLAGS: 00010286
RAX: 0000000000000000 RBX: ffff8b019cac2258 RCX: 0000000000000000
RDX: ffff8b01a5de9840 RSI: ffff8b01a5dd9a48 RDI: ffff8b01a5dd9a48
RBP: ffff8b0167320100 R08: 0000000000000433 R09: 0000000000aaaaaa
R10: 0000000000000000 R11: ffffacc8609d8220 R12: ffff8b0198b75b80
R13: 0000000000000006 R14: ffff8b0198b71e60 R15: ffff8b0198b75c38
FS: 0000000000000000(0000) GS:ffff8b01a5dc0000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 000056406a0d9f30 CR3: 0000000226e0a003 CR4: 00000000003606e0
Call Trace:
? skb_dequeue+0x57/0x70
rtw_c2h_work+0x40/0x60 [rtw88]
process_one_work+0x1ad/0x370
worker_thread+0x30/0x390
? create_worker+0x1a0/0x1a0
kthread+0x112/0x130
? kthread_park+0x80/0x80
ret_from_fork+0x35/0x40
---[ end trace b453bc52aa910fd8 ]---
wlo1: deauthenticated from 38:43:7d:8a:89:0e (Reason: 2=PREV_AUTH_NOT_VALID)
rtw_pci 0000:04:00.0: sta 38:43:7d:8a:89:0e with macid 0 left
wlo1: authenticate with 38:43:7d:8a:89:41
wlo1: send auth to 38:43:7d:8a:89:41 (try 1/3)
wlo1: authenticated
wlo1: associating with AP with corrupt probe response
wlo1: associate with 38:43:7d:8a:89:41 (try 1/3)
wlo1: RX AssocResp from 38:43:7d:8a:89:41 (capab=0x1411 status=17 aid=5)
wlo1: 38:43:7d:8a:89:41 denied association (code=17)
wlo1: authenticate with 38:43:7d:8a:89:0e
wlo1: send auth to 38:43:7d:8a:89:0e (try 1/3)
wlo1: authenticated
wlo1: associate with 38:43:7d:8a:89:0e (try 1/3)
wlo1: RX AssocResp from 38:43:7d:8a:89:0e (capab=0x1511 status=0 aid=3)
rtw_pci 0000:04:00.0: sta 38:43:7d:8a:89:0e joined with macid 0
rtw_pci 0000:04:00.0: wrong bfee role
wlo1: associated
------------[ cut here ]------------
invalid ra report c2h length
[...]
Interestingly, I failed to find other reports with this message on the
net... Any ideas?
--
Thanks,
Feri
Reply to: