Ubuntu 24.04: "Message 00000010 (seq 10) timeout", "Failed to get patch semaphore"

[RoFz]

Hello samveen,

just tried the driver on a Vivobook with Ubuntu 24:

[   53.817627] mt7902 0000:01:00.0: Entering mt7902_mt76_mcu_send_and_get_msg cmd: 16
[   53.817639] mt7902 0000:01:00.0: mt7902_mt76_mcu_msg_alloc 0000000049ad6045 
[   53.817644] mt7902 0000:01:00.0: mt7902_mt76_mcu_skb_send_and_get_msg acquiring mutex
[   53.817651] mt7902 0000:01:00.0: mt7902_mt76_mcu_skb_send_and_get_msg cmd: 16, ret: 0, wait_resp: 1
[   53.817656] mt7902 0000:01:00.0: mt7902_mt76_mcu_skb_send_and_get_msg expires: 4294724032
[   53.817660] mt7902 0000:01:00.0: mt7902_mt76_mcu_get_response expires: 4294724032
[   53.817663] mt7902 0000:01:00.0: mt7902_mt76_mcu_get_response timeout: 3000
[   53.817798] mt7902 0000:01:00.0: mt7902_mt76_mcu_get_response ret: 00000000c125492d
[   53.817806] CPU: 9 PID: 1383 Comm: kworker/9:2 Tainted: P           O       6.8.0-40-generic #40-Ubuntu
[   53.817813] Hardware name: ASUSTeK COMPUTER INC. Vivobook_ASUSLaptop M1505YA_M1505YA/M1505YA, BIOS M1505YA.303 08/08/2023
[   53.817817] Workqueue: events mt7902_init_work [mt7902_common]
[   53.817849] Call Trace:
[   53.817853]  <TASK>
[   53.817859]  dump_stack_lvl+0x76/0xa0
[   53.817868]  dump_stack+0x10/0x20
[   53.817873]  mt7902_mt76_mcu_get_response+0xb6/0x1a0 [mt7902_common]
[   53.817899]  ? __pfx_autoremove_wake_function+0x10/0x10
[   53.817908]  mt7902_mt76_mcu_skb_send_and_get_msg+0xf7/0x1d0 [mt7902_common]
[   53.817932]  mt7902_mt76_mcu_send_and_get_msg+0xcf/0x100 [mt7902_common]
[   53.817958]  mt7902_mt76_connac2_load_patch+0x74/0x350 [mt7902_common]
[   53.817985]  mt7902_mt792x_load_firmware+0x51/0x1b0 [mt7902_common]
[   53.818010]  mt7902_run_firmware+0x2d/0x4d0 [mt7902_common]
[   53.818033]  ? ____mt7902_mt76_poll_msec+0x79/0xd0 [mt7902_common]
[   53.818061]  mt7902e_mcu_init+0x6d/0xb0 [mt7902]
[   53.818070]  ? srso_alias_return_thunk+0x5/0xfbef5
[   53.818076]  mt7902_init_work+0x39/0x210 [mt7902_common]
[   53.818101]  process_one_work+0x16f/0x350
[   53.818111]  worker_thread+0x306/0x440
[   53.818117]  ? srso_alias_return_thunk+0x5/0xfbef5
[   53.818122]  ? _raw_spin_lock_irqsave+0xe/0x20
[   53.818129]  ? __pfx_worker_thread+0x10/0x10
[   53.818135]  kthread+0xf2/0x120
[   53.818141]  ? __pfx_kthread+0x10/0x10
[   53.818147]  ret_from_fork+0x47/0x70
[   53.818153]  ? __pfx_kthread+0x10/0x10
[   53.818159]  ret_from_fork_asm+0x1b/0x30
[   53.818170]  </TASK>
[   53.818173] mt7902 0000:01:00.0: SKB info: 00000000c125492d
[   53.818178] mt7902 0000:01:00.0: mt7902_mt76_mcu_skb_send_and_get_msg > mcu_parse_response cmd: 16, ret: -11
[   53.818184] mt7902 0000:01:00.0: mt7902_mt76_mcu_skb_send_and_get_msg expires: 4294724032
[   53.818188] mt7902 0000:01:00.0: mt7902_mt76_mcu_get_response expires: 4294724032
[   53.818191] mt7902 0000:01:00.0: mt7902_mt76_mcu_get_response timeout: 3000
[   56.881883] mt7902 0000:01:00.0: mt7902_mt76_mcu_get_response ret: 0000000000000000
[   56.881893] CPU: 9 PID: 1383 Comm: kworker/9:2 Tainted: P           O       6.8.0-40-generic #40-Ubuntu
[   56.881900] Hardware name: ASUSTeK COMPUTER INC. Vivobook_ASUSLaptop M1505YA_M1505YA/M1505YA, BIOS M1505YA.303 08/08/2023
[   56.881905] Workqueue: events mt7902_init_work [mt7902_common]
[   56.881949] Call Trace:
[   56.881954]  <TASK>
[   56.881963]  dump_stack_lvl+0x76/0xa0
[   56.881977]  dump_stack+0x10/0x20
[   56.881982]  mt7902_mt76_mcu_get_response+0xb6/0x1a0 [mt7902_common]
[   56.882006]  ? __pfx_autoremove_wake_function+0x10/0x10
[   56.882016]  mt7902_mt76_mcu_skb_send_and_get_msg+0xf7/0x1d0 [mt7902_common]
[   56.882040]  mt7902_mt76_mcu_send_and_get_msg+0xcf/0x100 [mt7902_common]
[   56.882065]  mt7902_mt76_connac2_load_patch+0x74/0x350 [mt7902_common]
[   56.882092]  mt7902_mt792x_load_firmware+0x51/0x1b0 [mt7902_common]
[   56.882117]  mt7902_run_firmware+0x2d/0x4d0 [mt7902_common]
[   56.882140]  ? ____mt7902_mt76_poll_msec+0x79/0xd0 [mt7902_common]
[   56.882167]  mt7902e_mcu_init+0x6d/0xb0 [mt7902]
[   56.882176]  ? srso_alias_return_thunk+0x5/0xfbef5
[   56.882183]  mt7902_init_work+0x39/0x210 [mt7902_common]
[   56.882206]  process_one_work+0x16f/0x350
[   56.882215]  worker_thread+0x306/0x440
[   56.882221]  ? srso_alias_return_thunk+0x5/0xfbef5
[   56.882226]  ? _raw_spin_lock_irqsave+0xe/0x20
[   56.882233]  ? __pfx_worker_thread+0x10/0x10
[   56.882239]  kthread+0xf2/0x120
[   56.882245]  ? __pfx_kthread+0x10/0x10
[   56.882251]  ret_from_fork+0x47/0x70
[   56.882258]  ? __pfx_kthread+0x10/0x10
[   56.882263]  ret_from_fork_asm+0x1b/0x30
[   56.882276]  </TASK>
[   56.882279] mt7902 0000:01:00.0: SKB info: 0000000000000000
[   56.882284] mt7902 0000:01:00.0: Message 00000010 (seq 10) timeout
[   56.882292] mt7902 0000:01:00.0: mt7902_mt76_mcu_skb_send_and_get_msg > mcu_parse_response cmd: 16, ret: -110
[   56.882296] mt7902 0000:01:00.0: mt7902_mt76_mcu_skb_send_and_get_msg unlocking mutex
[   56.882313] mt7902 0000:01:00.0: Failed to get patch semaphore
[   56.955705] mt7902 0000:01:00.0: hardware init failed

Also related to #2 , #5 and #9

[samveen]

@RoFz Thank you for taking the time to try this out. as per the latest state here, the driver tries to load the firmware to the device, but my understanding of the communication mechanism is lacking, and thus I'm unable to figure out the issue with the loading.

Further, my professional life's take a turn towards very very busy over the last 2 months, and I've not been able to devout any time towards this (or other projects I contribute to).

[marcusbritanicus]

I tried a small variation in the hope it might change something. My laptop is a ASUS Vivobook 16. I replaced the firmware bits from ASUS site (windows drivers, of course). I'm afraid noting much has changed, including the error message:

[   11.517015] mt7902 0000:01:00.0: SKB info: 000000007402359a
[   11.517019] mt7902 0000:01:00.0: mt7902_mt76_mcu_skb_send_and_get_msg > mcu_parse_response cmd: 16, ret: -11
[   11.517022] mt7902 0000:01:00.0: mt7902_mt76_mcu_skb_send_and_get_msg expires: 4294881627
[   11.517025] mt7902 0000:01:00.0: mt7902_mt76_mcu_get_response expires: 4294881627
[   11.517027] mt7902 0000:01:00.0: mt7902_mt76_mcu_get_response timeout: 899
[   12.639470] wlan0: authenticate with de:df:c3:32:71:c5 (local address=f0:a7:31:19:ee:70)
[   12.781907] wlan0: send auth to de:df:c3:32:71:c5 (try 1/3)
[   12.784437] wlan0: authenticated
[   12.787012] wlan0: associate with de:df:c3:32:71:c5 (try 1/3)
[   12.808004] wlan0: RX AssocResp from de:df:c3:32:71:c5 (capab=0x1431 status=0 aid=1)
[   12.843925] wlan0: associated
[   12.967840] wlan0: Limiting TX power to 30 (30 - 0) dBm as advertised by de:df:c3:32:71:c5
[   14.637319] mt7902 0000:01:00.0: mt7902_mt76_mcu_get_response ret: 0000000000000000
[   14.637338] CPU: 12 UID: 0 PID: 223 Comm: kworker/12:1 Tainted: G           OE      6.11.8-artix1-2 #1 1400000003000000474e5500ee8cc4000e5172c2
[   14.637352] Tainted: [O]=OOT_MODULE, [E]=UNSIGNED_MODULE
[   14.637355] Hardware name: ASUSTeK COMPUTER INC. Vivobook_ASUSLaptop M1605YA_M1605YA/M1605YA, BIOS M1605YA.300 12/27/2022
[   14.637360] Workqueue: events mt7902_init_work [mt7902_common]
[   14.637398] Call Trace:
[   14.637405]  <TASK>
[   14.637410]  dump_stack_lvl+0x5d/0x80
[   14.637424]  mt7902_mt76_mcu_get_response+0x146/0x16f [mt7902_common 1400000003000000474e55008a5950f0e97fcf4a]
[   14.637452]  ? __pfx_autoremove_wake_function+0x10/0x10
[   14.637463]  mt7902_mt76_mcu_skb_send_and_get_msg.cold+0x44/0xc1 [mt7902_common 1400000003000000474e55008a5950f0e97fcf4a]
[   14.637488]  mt7902_mt76_connac2_load_patch+0x79/0x110 [mt7902_common 1400000003000000474e55008a5950f0e97fcf4a]
[   14.637521]  mt7902_mt792x_load_firmware+0x50/0x160 [mt7902_common 1400000003000000474e55008a5950f0e97fcf4a]
[   14.637548]  mt7902_run_firmware+0x2d/0x3c0 [mt7902_common 1400000003000000474e55008a5950f0e97fcf4a]
[   14.637573]  ? ____mt7902_mt76_poll_msec+0x75/0xb0 [mt7902_common 1400000003000000474e55008a5950f0e97fcf4a]
[   14.637604]  mt7902e_mcu_init+0x65/0xa0 [mt7902 1400000003000000474e5500719c0bd26726fa5c]
[   14.637613]  ? srso_alias_return_thunk+0x5/0xfbef5
[   14.637620]  mt7902_init_work+0x3a/0x170 [mt7902_common 1400000003000000474e55008a5950f0e97fcf4a]
[   14.637645]  process_one_work+0x17e/0x330
[   14.637656]  worker_thread+0x2ce/0x3f0
[   14.637665]  ? __pfx_worker_thread+0x10/0x10
[   14.637671]  kthread+0xd2/0x100
[   14.637681]  ? __pfx_kthread+0x10/0x10
[   14.637689]  ret_from_fork+0x34/0x50
[   14.637697]  ? __pfx_kthread+0x10/0x10
[   14.637704]  ret_from_fork_asm+0x1a/0x30
[   14.637720]  </TASK>