[<prev] [next>] [<thread-prev] [thread-next>] [day] [month] [year] [list]
Message-ID: <10298561-8016-478f-aa82-bf2ea9b33db9@molgen.mpg.de>
Date: Wed, 30 Jul 2025 12:40:04 +0200
From: Paul Menzel <pmenzel@...gen.mpg.de>
To: Baochen Qiang <baochen.qiang@....qualcomm.com>
Cc: Baochen Qiang <quic_bqiang@...cinc.com>,
Jeff Johnson <jjohnson@...nel.org>, ath10k@...ts.infradead.org,
James Prestwood <prestwoj@...il.com>, LKML <linux-kernel@...r.kernel.org>
Subject: ath10k: ath10k_wmi_wait_for_service_ready() called twice during boot
(was: athk10: Poll service ready completion by default to avoid warning
`failed to receive service ready completion, polling..`?)
[Cc: remove scheduler folks]
Dear Baochen,
Thank you for your reply, and let’s fork this thread.
Am 29.07.25 um 04:27 schrieb Baochen Qiang:
[…]
> Never mind, since you can modify the source code, you can simply get
> call stack with:
>
> $ git diff
> diff --git a/drivers/net/wireless/ath/ath10k/wmi.c b/drivers/net/wireless/ath/ath10k/wmi.c
> index cb8ae751eb31..eb591e059103 100644
> --- a/drivers/net/wireless/ath/ath10k/wmi.c
> +++ b/drivers/net/wireless/ath/ath10k/wmi.c
> @@ -1766,6 +1766,8 @@ int ath10k_wmi_wait_for_service_ready(struct ath10k *ar)
> {
> unsigned long time_left, i;
>
> + dump_stack();
> +
> time_left = wait_for_completion_timeout(&ar->wmi.service_ready,
> WMI_SERVICE_READY_TIMEOUT_HZ);
> if (!time_left) {
Thanks, yes, I wanted to avoid it, but now added it to the end, and
started the Linux kernel.
With that, I got:
```
[ 0.000000] Linux version 6.16.0-04406-g154cace907b6
(build@...emianrhapsody.molgen.mpg.de) (gcc (Debian 14.2.0-19) 14.2.0,
GNU ld (GNU Binutils for Debian) 2.44) #98 SMP PREEMPT_DYNAMIC Wed Jul
30 11:59:36 CEST 2025
[ 0.000000] Command line:
BOOT_IMAGE=/vmlinuz-6.16.0-04406-g154cace907b6
root=UUID=32e29882-d94d-4a92-9ee4-4d03002bfa29 ro quiet pci=noaer
mem_sleep_default=deep log_buf_len=16M cryptomgr.notests
usbcore.quirks=0cf3:e300:e,04f3:2234:e,0c45:670c:e
[…]
[ 0.000000] DMI: Dell Inc. XPS 13 9360/0596KF, BIOS 2.21.0 06/02/2022
[…]
[ 14.624206] ath10k_pci 0000:3a:00.0: enabling device (0000 -> 0002)
[ 14.633316] ath10k_pci 0000:3a:00.0: pci irq msi oper_irq_mode 2
irq_mode 0 reset_mode 0
[ 14.662181] audit: type=1400 audit(1753869969.819:2):
apparmor="STATUS" operation="profile_load" profile="unconfined"
name=4D6F6E676F444220436F6D70617373 pid=516 comm="apparmor_parser"
[ 14.662313] audit: type=1400 audit(1753869969.819:3):
apparmor="STATUS" operation="profile_load" profile="unconfined"
name="QtWebEngineProcess" pid=518 comm="apparmor_parser"
[ 14.663112] audit: type=1400 audit(1753869969.819:4):
apparmor="STATUS" operation="profile_load" profile="unconfined"
name="1password" pid=514 comm="apparmor_parser"
[ 14.664059] audit: type=1400 audit(1753869969.819:5):
apparmor="STATUS" operation="profile_load" profile="unconfined"
name="Discord" pid=515 comm="apparmor_parser"
[ 14.664963] audit: type=1400 audit(1753869969.819:6):
apparmor="STATUS" operation="profile_load" profile="unconfined"
name="balena-etcher" pid=523 comm="apparmor_parser"
[ 14.665357] audit: type=1400 audit(1753869969.819:7):
apparmor="STATUS" operation="profile_load" profile="unconfined"
name="buildah" pid=525 comm="apparmor_parser"
[ 14.665479] audit: type=1400 audit(1753869969.819:8):
apparmor="STATUS" operation="profile_load" profile="unconfined"
name="brave" pid=524 comm="apparmor_parser"
[ 14.666813] audit: type=1400 audit(1753869969.823:9):
apparmor="STATUS" operation="profile_load" profile="unconfined"
name="cam" pid=527 comm="apparmor_parser"
[ 14.666817] audit: type=1400 audit(1753869969.823:10):
apparmor="STATUS" operation="profile_load" profile="unconfined"
name="busybox" pid=526 comm="apparmor_parser"
[ 14.667174] audit: type=1400 audit(1753869969.823:11):
apparmor="STATUS" operation="profile_load" profile="unconfined"
name="ch-checkns" pid=528 comm="apparmor_parser"
[ 14.719843] usbcore: registered new interface driver uvcvideo
[ 14.792965] snd_hda_intel 0000:00:1f.3: enabling device (0000 -> 0002)
[ 14.793498] snd_hda_intel 0000:00:1f.3: bound 0000:00:02.0 (ops
intel_audio_component_bind_ops [i915])
[ 14.837438] snd_hda_codec_generic hdaudioC0D0: autoconfig for
Generic: line_outs=1 (0x14/0x0/0x0/0x0/0x0) type:speaker
[ 14.837458] snd_hda_codec_generic hdaudioC0D0: speaker_outs=0
(0x0/0x0/0x0/0x0/0x0)
[ 14.837467] snd_hda_codec_generic hdaudioC0D0: hp_outs=1
(0x21/0x0/0x0/0x0/0x0)
[ 14.837475] snd_hda_codec_generic hdaudioC0D0: mono: mono_out=0x0
[ 14.837480] snd_hda_codec_generic hdaudioC0D0: inputs:
[ 14.837485] snd_hda_codec_generic hdaudioC0D0: Mic=0x12
[ 14.870772] ath10k_pci 0000:3a:00.0: qca6174 hw3.2 target 0x05030000
chip_id 0x00340aff sub 1a56:1535
[ 14.870778] ath10k_pci 0000:3a:00.0: kconfig debug 0 debugfs 0
tracing 0 dfs 0 testmode 0
[ 14.870853] ath10k_pci 0000:3a:00.0: firmware ver
WLAN.RM.4.4.1-00309- api 6 features wowlan,ignore-otp,mfp crc32 0793bcf2
[ 14.877651] input: HDA Intel PCH Front Headphone as
/devices/pci0000:00/0000:00:1f.3/sound/card0/input24
[ 14.877724] input: HDA Intel PCH HDMI/DP,pcm=3 as
/devices/pci0000:00/0000:00:1f.3/sound/card0/input25
[ 14.877788] input: HDA Intel PCH HDMI/DP,pcm=7 as
/devices/pci0000:00/0000:00:1f.3/sound/card0/input26
[ 14.877872] input: HDA Intel PCH HDMI/DP,pcm=8 as
/devices/pci0000:00/0000:00:1f.3/sound/card0/input27
[ 14.944938] Bluetooth: hci0: using NVM file: qca/nvm_usb_00000302.bin
[ 14.946658] ath10k_pci 0000:3a:00.0: board_file api 2 bmi_id N/A
crc32 d2863f91
[ 14.967545] Bluetooth: hci0: HCI Enhanced Setup Synchronous
Connection command is advertised, but not supported.
[ 15.027036] CPU: 1 UID: 0 PID: 41 Comm: kworker/u16:2 Not tainted
6.16.0-04406-g154cace907b6 #98 PREEMPT(voluntary)
[ 15.027044] Hardware name: Dell Inc. XPS 13 9360/0596KF, BIOS 2.21.0
06/02/2022
[ 15.027046] Workqueue: ath10k_wq ath10k_core_register_work [ath10k_core]
[ 15.027084] Call Trace:
[ 15.027088] <TASK>
[ 15.027091] dump_stack_lvl+0x5d/0x80
[ 15.027100] ath10k_wmi_wait_for_service_ready.cold+0x5/0xc [ath10k_core]
[ 15.027136] ath10k_core_start+0xaec/0x1090 [ath10k_core]
[ 15.027169] ath10k_core_register_work+0x5c4/0x930 [ath10k_core]
[ 15.027198] process_one_work+0x185/0x340
[ 15.027205] worker_thread+0x252/0x3a0
[ 15.027210] ? rescuer_thread+0x4b0/0x4b0
[ 15.027215] kthread+0xf9/0x240
[ 15.027219] ? kthreads_online_cpu+0x120/0x120
[ 15.027223] ? kthreads_online_cpu+0x120/0x120
[ 15.027227] ret_from_fork+0x198/0x1d0
[ 15.027232] ? kthreads_online_cpu+0x120/0x120
[ 15.027235] ret_from_fork_asm+0x11/0x20
[ 15.027243] </TASK>
[ 15.046899] ath10k_pci 0000:3a:00.0: htt-ver 3.87 wmi-op 4 htt-op 3
cal otp max-sta 32 raw 0 hwcrypto 1
[ 15.053439] nvme nvme0: using unchecked data buffer
[ 15.120478] ath: EEPROM regdomain: 0x6c
[ 15.120483] ath: EEPROM indicates we should expect a direct regpair map
[ 15.120485] ath: Country alpha2 being used: 00
[ 15.120486] ath: Regpair used: 0x6c
[ 15.153053] ath10k_pci 0000:3a:00.0 wlp58s0: renamed from wlan0
[ 15.644071] CPU: 0 UID: 0 PID: 675 Comm: NetworkManager Not tainted
6.16.0-04406-g154cace907b6 #98 PREEMPT(voluntary)
[ 15.644075] Hardware name: Dell Inc. XPS 13 9360/0596KF, BIOS 2.21.0
06/02/2022
[ 15.644077] Call Trace:
[ 15.644079] <TASK>
[ 15.644082] dump_stack_lvl+0x5d/0x80
[ 15.644089] ath10k_wmi_wait_for_service_ready.cold+0x5/0xc [ath10k_core]
[ 15.644104] ath10k_core_start+0xaec/0x1090 [ath10k_core]
[ 15.644115] ath10k_start+0xb8/0x840 [ath10k_core]
[ 15.644125] drv_start+0x3c/0xf0 [mac80211]
[ 15.644165] ieee80211_do_open+0x2c2/0x7f0 [mac80211]
[ 15.644197] ieee80211_open+0x84/0x90 [mac80211]
[ 15.644230] __dev_open+0xee/0x200
[ 15.644233] __dev_change_flags+0x1ef/0x230
[ 15.644234] ? prep_new_page+0xcc/0x1d0
[ 15.644237] netif_change_flags+0x26/0x60
[ 15.644238] do_setlink.isra.0+0x348/0x1150
[ 15.644241] ? __nla_validate_parse+0x5e/0xc70
[ 15.644243] ? security_capable+0x66/0xa0
[ 15.644245] rtnl_newlink+0x892/0xc20
[ 15.644247] ? update_load_avg+0x80/0x760
[ 15.644249] ? update_curr+0x90/0x170
[ 15.644250] ? mod_memcg_lruvec_state+0x191/0x1d0
[ 15.644253] ? do_setlink.isra.0+0x1150/0x1150
[ 15.644254] rtnetlink_rcv_msg+0x348/0x3e0
[ 15.644257] ? rtnl_calcit.isra.0+0x120/0x120
[ 15.644260] netlink_rcv_skb+0x4f/0x100
[ 15.644263] netlink_unicast+0x273/0x3d0
[ 15.644265] netlink_sendmsg+0x228/0x470
[ 15.644267] __sock_sendmsg+0x38/0x70
[ 15.644270] ? __import_iovec+0x139/0x180
[ 15.644272] ____sys_sendmsg+0x1c0/0x260
[ 15.644275] ? import_iovec+0xb/0x10
[ 15.644277] ___sys_sendmsg+0x95/0xd0
[ 15.644279] __sys_sendmsg+0x7b/0xd0
[ 15.644282] do_syscall_64+0x84/0xae0
[ 15.644284] ? pollwake+0x77/0x90
[ 15.644287] ? wake_up_state+0x10/0x10
[ 15.644288] ? __wake_up_common+0x72/0x90
[ 15.644290] ? eventfd_write+0xe1/0x210
[ 15.644292] ? security_file_permission+0x35/0x70
[ 15.644294] ? vfs_write+0xcc/0x460
[ 15.644296] ? ___sys_sendmsg+0xa1/0xd0
[ 15.644297] ? ksys_write+0xc7/0xe0
[ 15.644298] ? do_syscall_64+0xbc/0xae0
[ 15.644301] ? __sys_sendmsg+0xb6/0xd0
[ 15.644303] ? do_syscall_64+0xbc/0xae0
[ 15.644304] ? do_syscall_64+0xbc/0xae0
[ 15.644306] ? do_syscall_64+0xbc/0xae0
[ 15.644308] entry_SYSCALL_64_after_hwframe+0x4b/0x53
[ 15.644310] RIP: 0033:0x7fa5c3ca49ee
[ 15.644334] Code: 08 0f 85 f5 4b ff ff 49 89 fb 48 89 f0 48 89 d7 48
89 ce 4c 89 c2 4d 89 ca 4c 8b 44 24 08 4c 8b 4c 24 10 4c 89 5c 24 08 0f
05 <c3> 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 80 00 00 00 00 48 83 ec 08
[ 15.644335] RSP: 002b:00007ffc2e929548 EFLAGS: 00000246 ORIG_RAX:
000000000000002e
[ 15.644338] RAX: ffffffffffffffda RBX: 00007fa5c3094580 RCX:
00007fa5c3ca49ee
[ 15.644339] RDX: 0000000000000000 RSI: 00007ffc2e9295d0 RDI:
000000000000000d
[ 15.644340] RBP: 00007ffc2e9295d0 R08: 0000000000000000 R09:
0000000000000000
[ 15.644340] R10: 0000000000000000 R11: 0000000000000246 R12:
000000000000001c
[ 15.644341] R13: 00007ffc2e9297c8 R14: 00007ffc2e9297bc R15:
0000000000000000
[ 15.644343] </TASK>
[ 18.369625] pci 0000:01:00.0: [8086:1576] type 01 class 0x060400 PCIe
Switch Upstream Port
[ 18.369702] pci 0000:01:00.0: PCI bridge to [bus 02-39]
[…]
```
Kind regards,
Paul
Powered by blists - more mailing lists