From nobody Mon Apr 22 17:27:55 2024 X-Original-To: wireless@mlmmj.nyi.freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2610:1c1:1:606c::19:1]) by mlmmj.nyi.freebsd.org (Postfix) with ESMTP id 4VNXJW6NzSz5HWVF for ; Mon, 22 Apr 2024 17:27:55 +0000 (UTC) (envelope-from bugzilla-noreply@freebsd.org) Received: from mxrelay.nyi.freebsd.org (mxrelay.nyi.freebsd.org [IPv6:2610:1c1:1:606c::19:3]) (using TLSv1.3 with cipher TLS_AES_256_GCM_SHA384 (256/256 bits) key-exchange X25519 server-signature RSA-PSS (4096 bits) server-digest SHA256 client-signature RSA-PSS (4096 bits) client-digest SHA256) (Client CN "mxrelay.nyi.freebsd.org", Issuer "R3" (verified OK)) by mx1.freebsd.org (Postfix) with ESMTPS id 4VNXJW5M4Rz4RLy for ; Mon, 22 Apr 2024 17:27:55 +0000 (UTC) (envelope-from bugzilla-noreply@freebsd.org) ARC-Seal: i=1; s=dkim; d=freebsd.org; t=1713806875; a=rsa-sha256; cv=none; b=nujzeDBKboOuYTgi0CYBbyA1oU40GARzDsbj+GVbFMFxhhdBnss2ro917dnlB+8pimKT/R HBqrC/djska+qb1N1M7AhywHBlCtz5IPfRnZGCJADxBz1bmhVhMUZswrIIqjgq1EnPsYvu 4j4npoJ1Ny6ugJjZUlE/rfGikcqRBTKyy7XkTfpv70sPEatohAUEeLZl1zSUuc2K14pr1Q /XDdPE+Y9rIuQPeMKcw9lUFFUQPie2UnjO0FboRlynz75t++XMdVil63SAQ+WSU2VBOtSv WwICkziEBcnWb+dpl+EL/S1n8x3+VKcnIYnswRixKnlQt2llqG9LclvxdwrRGg== ARC-Authentication-Results: i=1; mx1.freebsd.org; none ARC-Message-Signature: i=1; a=rsa-sha256; c=relaxed/relaxed; d=freebsd.org; s=dkim; t=1713806875; h=from:from:reply-to:subject:subject:date:date:message-id:message-id: to:to:cc:mime-version:mime-version:content-type:content-type: content-transfer-encoding:content-transfer-encoding: in-reply-to:in-reply-to:references:references; bh=+Or0HknutzJC1oXEaXV2n/g/BoZ5KOlxRcWD+k8imDM=; b=pyQiD+ulniegyUonZcBKF8jI8a5QXsFxM1jSW2DUDaVKIbl+Rzgb3bIW6Jc7qsx8aC/giF QDYCMFw3PrI772FIq+r9JQzkrQC7wVEKkcuRz6uBbNG3AkTrzHBEOG96EWxcEWcHOwe261 JBvF2Fe+/V4UgnDs9ZiSx1ZBPRFp0d/Lxbbm5ItJHFzZXhjD3JVYJ/kZp0XbjqS/pjyEEV mybpoakw9yPPtngZUhT3HGJwb2543v4MIofuBQ2cjFoIqRCAewKmKLAwqQu/lD0Ktr64Td 8uYZnBaoydA2mxkwmMWDpitHi0W/JFIF6QP6BXCiYsfZ7VcO/BX5loTNepPNRA== Received: from kenobi.freebsd.org (kenobi.freebsd.org [IPv6:2610:1c1:1:606c::50:1d]) (using TLSv1.3 with cipher TLS_AES_256_GCM_SHA384 (256/256 bits) key-exchange X25519 server-signature RSA-PSS (4096 bits) server-digest SHA256) (Client did not present a certificate) by mxrelay.nyi.freebsd.org (Postfix) with ESMTPS id 4VNXJW4wh2zxd7 for ; Mon, 22 Apr 2024 17:27:55 +0000 (UTC) (envelope-from bugzilla-noreply@freebsd.org) Received: from kenobi.freebsd.org ([127.0.1.5]) by kenobi.freebsd.org (8.15.2/8.15.2) with ESMTP id 43MHRtkf036382 for ; Mon, 22 Apr 2024 17:27:55 GMT (envelope-from bugzilla-noreply@freebsd.org) Received: (from www@localhost) by kenobi.freebsd.org (8.15.2/8.15.2/Submit) id 43MHRtnR036379 for wireless@FreeBSD.org; Mon, 22 Apr 2024 17:27:55 GMT (envelope-from bugzilla-noreply@freebsd.org) X-Authentication-Warning: kenobi.freebsd.org: www set sender to bugzilla-noreply@freebsd.org using -f From: bugzilla-noreply@freebsd.org To: wireless@FreeBSD.org Subject: [Bug 275255] iwlwifi: panic after iwlwifi0: lkpi_iv_newstate: error -5 during state transition 5 (RUN) -> 0 (INIT) (8xxx/9xxx chipsets) Date: Mon, 22 Apr 2024 17:27:55 +0000 X-Bugzilla-Reason: CC X-Bugzilla-Type: changed X-Bugzilla-Watch-Reason: None X-Bugzilla-Product: Base System X-Bugzilla-Component: wireless X-Bugzilla-Version: CURRENT X-Bugzilla-Keywords: X-Bugzilla-Severity: Affects Some People X-Bugzilla-Who: ml@netfence.it X-Bugzilla-Status: In Progress X-Bugzilla-Resolution: X-Bugzilla-Priority: --- X-Bugzilla-Assigned-To: bz@FreeBSD.org X-Bugzilla-Flags: mfc-stable14? mfc-stable13? X-Bugzilla-Changed-Fields: cc Message-ID: In-Reply-To: References: Content-Type: text/plain; charset="UTF-8" Content-Transfer-Encoding: quoted-printable X-Bugzilla-URL: https://bugs.freebsd.org/bugzilla/ Auto-Submitted: auto-generated List-Id: Discussions List-Archive: https://lists.freebsd.org/archives/freebsd-wireless List-Help: List-Post: List-Subscribe: List-Unsubscribe: X-BeenThere: freebsd-wireless@freebsd.org Sender: owner-freebsd-wireless@FreeBSD.org MIME-Version: 1.0 https://bugs.freebsd.org/bugzilla/show_bug.cgi?id=3D275255 ml@netfence.it changed: What |Removed |Added ---------------------------------------------------------------------------- CC| |ml@netfence.it --- Comment #14 from ml@netfence.it --- I'm seeing very frequent (i.e. several times per day) panics after upgrading from 13.2R to 13.3R and possibly they are related to this bug. I'm not able to get a proper core on this machine (and I still can't unders= tand why), but in the logs I find: Apr 18 15:00:25 hector kernel: iwlwifi0: linuxkpi_ieee80211_beacon_loss: vif 0xfffffe00ac5b1e80 vap 0xfffffe00ac5b1010 state RUN Apr 18 15:00:28 hector syslogd: last message repeated 1 times Apr 18 15:00:29 hector kernel: ipfw: 9999 Deny UDP 192.168.113.82:137 192.168.113.255:137 in via wlan0 Apr 18 15:00:29 hector wpa_supplicant[93738]: wlan0: CTRL-EVENT-DISCONNECTED bssid=3Dfc:f5:28:ca:f1:12 reason=3D0 Apr 18 15:00:29 hector kernel: iwlwifi0: linuxkpi_ieee80211_connection_loss: vif 0xfffffe00ac5b1e80 vap 0xfffffe00ac5b1010 state RUN Apr 18 15:00:29 hector kernel: wlan0: link state changed to DOWN Apr 18 15:00:29 hector wpa_supplicant[93738]: BSSID fc:f5:28:ca:f1:12 ignore list count incremented to 2, ignoring for 10 seconds Apr 18 15:00:29 hector wpa_supplicant[93738]: ioctl[SIOCS80211, op=3D20, va= l=3D0, arg_len=3D7]: Can't assign requested address Apr 18 15:00:29 hector dhclient[97089]: wlan0 link state up -> down Apr 18 15:00:29 hector devd[99040]: Processing event '!system=3DIFNET subsystem=3Dwlan0 type=3DLINK_DOWN' Apr 18 15:00:29 hector devd[99040]: Pushing table Apr 18 15:00:29 hector devd[99040]: Processing notify event Apr 18 15:00:29 hector devd[99040]: Popping table Apr 18 15:00:29 hector dbus-daemon[3053]: [system] Activating service name=3D'org.freedesktop.ConsoleKit' requested by ':1.2' (uid=3D0 pid=3D2434= 6 comm=3D"") (using servicehelper) Apr 18 15:00:29 hector kernel: iwlwifi0: Couldn't drain frames for staid 0, status 0x8 Apr 18 15:00:29 hector kernel: iwlwifi0: lkpi_sta_run_to_init:2173: mo_sta_state(NOTEXIST) failed: -5 Apr 18 15:00:29 hector kernel: iwlwifi0: lkpi_iv_newstate: error -5 during state transition 5 (RUN) -> 0 (INIT) Apr 18 15:00:29 hector dbus-daemon[3053]: [system] Activating service name=3D'org.freedesktop.PolicyKit1' requested by ':1.3' (uid=3D0 pid=3D2429= 3 comm=3D"") (using servicehelper) Apr 18 15:00:29 hector dbus-daemon[3053]: [system] Successfully activated service 'org.freedesktop.ConsoleKit' Apr 18 15:00:29 hector polkitd[24756]: Started polkitd version 124 Apr 18 15:00:29 hector polkitd[24756]: Loading rules from directory /usr/local/etc/polkit-1/rules.d Apr 18 15:00:29 hector polkitd[24756]: Loading rules from directory /usr/local/share/polkit-1/rules.d Apr 18 15:00:29 hector polkitd[24756]: Finished loading, compiling and executing 1 rules Apr 18 15:00:29 hector dbus-daemon[3053]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Apr 18 15:00:29 hector polkitd[24756]: Acquired the name org.freedesktop.PolicyKit1 on the system bus Apr 18 15:00:29 hector dbus-daemon[29677]: [session uid=3D1001 pid=3D28981] Activating service name=3D'org.a11y.Bus' requested by ':1.0' (uid=3D1001 pi= d=3D25777 comm=3D"") Apr 18 15:00:29 hector dbus-daemon[29677]: [session uid=3D1001 pid=3D28981] Successfully activated service 'org.a11y.Bus' Apr 18 15:00:29 hector dbus-daemon[29677]: [session uid=3D1001 pid=3D28981] Activating service name=3D'org.xfce.Xfconf' requested by ':1.2' (uid=3D1001 pid=3D25777 comm=3D"") Apr 18 15:00:29 hector dbus-daemon[29677]: [session uid=3D1001 pid=3D28981] Successfully activated service 'org.xfce.Xfconf' Apr 18 15:00:30 hector dbus-daemon[29677]: [session uid=3D1001 pid=3D28981] Activating service name=3D'org.gtk.vfs.Daemon' requested by ':1.6' (uid=3D1= 001 pid=3D36842 comm=3D"") Apr 18 15:00:30 hector dbus-daemon[29677]: [session uid=3D1001 pid=3D28981] Successfully activated service 'org.gtk.vfs.Daemon' Apr 18 15:00:30 hector dbus-daemon[3053]: [system] Activating service name=3D'org.freedesktop.UPower' requested by ':1.6' (uid=3D1001 pid=3D40674= comm=3D"") (using servicehelper) Apr 18 15:00:30 hector dbus-daemon[3053]: [system] Successfully activated service 'org.freedesktop.UPower' Apr 18 15:00:30 hector wpa_supplicant[93738]: wlan0: Trying to associate wi= th fc:f5:28:ca:f1:13 (SSID=3D'CCBiesse' freq=3D5180 MHz) Apr 18 15:00:30 hector kernel: iwlwifi0: lkpi_sta_scan_to_auth:1033: lvif 0xfffffe00ac5b1000 vap 0xfffffe00ac5b1010 iv_bss 0xfffffe00adb4c000 lvif_bss 0xfffff8000565a000 lvif_bss->ni 0xfffffe00aed99000 synched 0 Apr 18 15:00:30 hector kernel: iwlwifi0: lkpi_iv_newstate: error 16 during state transition 1 (SCAN) -> 2 (AUTH) Apr 18 15:01:09 hector syslogd: restart Apr 18 15:01:09 hector syslogd: kernel boot file is /boot/kernel/kernel Apr 18 15:01:09 hector kernel: Sleeping thread (tid 100785, pid 0) owns a non-sleepable lock Apr 18 15:01:09 hector kernel: KDB: stack backtrace of thread 100785: Apr 18 15:01:09 hector kernel: sched_switch() at sched_switch+0x7d1/frame 0xfffffe00ab30fe20 Apr 18 15:01:09 hector kernel: mi_switch() at mi_switch+0xbf/frame 0xfffffe00ab30fe40 Apr 18 15:01:09 hector kernel: _sleep() at _sleep+0x1f0/frame 0xfffffe00ab30fec0 Apr 18 15:01:09 hector kernel: taskqueue_thread_loop() at taskqueue_thread_loop+0xb1/frame 0xfffffe00ab30fef0 Apr 18 15:01:09 hector kernel: fork_exit() at fork_exit+0x7d/frame 0xfffffe00ab30ff30 Apr 18 15:01:09 hector kernel: fork_trampoline() at fork_trampoline+0xe/fra= me 0xfffffe00ab30ff30 Apr 18 15:01:09 hector kernel: --- trap 0xc, rip =3D 0x63639d6e3da, rsp =3D 0x6364d128f48, rbp =3D 0x6364d128f60 --- Apr 18 15:01:09 hector kernel: panic: sleeping thread Apr 18 15:01:09 hector kernel: cpuid =3D 2 Apr 18 15:01:09 hector kernel: time =3D 1713445230 Apr 18 15:01:09 hector kernel: KDB: stack backtrace: Apr 18 15:01:09 hector kernel: db_trace_self_wrapper() at db_trace_self_wrapper+0x2b/frame 0xfffffe00ab2ba960 Apr 18 15:01:09 hector kernel: vpanic() at vpanic+0x152/frame 0xfffffe00ab2ba9b0 Apr 18 15:01:09 hector kernel: panic() at panic+0x43/frame 0xfffffe00ab2baa= 10 Apr 18 15:01:09 hector kernel: propagate_priority() at propagate_priority+0x293/frame 0xfffffe00ab2baa50 Apr 18 15:01:09 hector kernel: turnstile_wait() at turnstile_wait+0x314/fra= me 0xfffffe00ab2baaa0 Apr 18 15:01:09 hector kernel: __mtx_lock_sleep() at __mtx_lock_sleep+0x17b/frame 0xfffffe00ab2bab30 Apr 18 15:01:09 hector kernel: linuxkpi_ieee80211_find_sta() at linuxkpi_ieee80211_find_sta+0xd0/frame 0xfffffe00ab2bab70 Apr 18 15:01:09 hector kernel: linuxkpi_ieee80211_find_sta_by_ifaddr() at linuxkpi_ieee80211_find_sta_by_ifaddr+0x7f/frame 0xfffffe00ab2babc0 Apr 18 15:01:09 hector kernel: iwl_mvm_rx_mpdu_mq() at iwl_mvm_rx_mpdu_mq+0x420/frame 0xfffffe00ab2bacd0 Apr 18 15:01:09 hector kernel: iwl_pcie_rx_handle() at iwl_pcie_rx_handle+0x444/frame 0xfffffe00ab2badd0 Apr 18 15:01:09 hector kernel: iwl_pcie_napi_poll_msix() at iwl_pcie_napi_poll_msix+0x30/frame 0xfffffe00ab2bae20 Apr 18 15:01:09 hector kernel: lkpi_napi_task() at lkpi_napi_task+0xf/frame 0xfffffe00ab2bae40 Apr 18 15:01:09 hector kernel: taskqueue_run_locked() at taskqueue_run_locked+0x182/frame 0xfffffe00ab2baec0 Apr 18 15:01:09 hector kernel: taskqueue_thread_loop() at taskqueue_thread_loop+0xc2/frame 0xfffffe00ab2baef0 Apr 18 15:01:09 hector kernel: fork_exit() at fork_exit+0x7d/frame 0xfffffe00ab2baf30 Apr 18 15:01:09 hector kernel: fork_trampoline() at fork_trampoline+0xe/fra= me 0xfffffe00ab2baf30 Apr 18 15:01:09 hector kernel: --- trap 0xc, rip =3D 0x63639d6e3da, rsp =3D 0x63644046f48, rbp =3D 0x63644046f60 --- Apr 18 15:01:09 hector kernel: Uptime: 33s Another: Apr 18 15:05:32 hector wpa_supplicant[21281]: wlan0: CTRL-EVENT-DISCONNECTED bssid=3Dfc:f5:28:ca:f1:13 reason=3D0 Apr 18 15:05:32 hector kernel: iwlwifi0: linuxkpi_ieee80211_beacon_loss: vif 0xfffffe00abd8de80 vap 0xfffffe00abd8d010 state RUN Apr 18 15:05:32 hector syslogd: last message repeated 1 times Apr 18 15:05:32 hector kernel: wlan0: link state changed to DOWN Apr 18 15:05:32 hector devd[76299]: Processing event '!system=3DIFNET subsystem=3Dwlan0 type=3DLINK_DOWN' Apr 18 15:05:32 hector dhclient[22403]: wlan0 link state up -> down Apr 18 15:05:32 hector devd[76299]: Pushing table Apr 18 15:05:32 hector devd[76299]: Processing notify event Apr 18 15:05:32 hector kernel: iwlwifi0: Couldn't drain frames for staid 0, status 0x8 Apr 18 15:05:32 hector kernel: iwlwifi0: lkpi_sta_run_to_init:2173: mo_sta_state(NOTEXIST) failed: -5 Apr 18 15:05:32 hector kernel: iwlwifi0: lkpi_iv_newstate: error -5 during state transition 5 (RUN) -> 1 (SCAN) Apr 18 15:05:32 hector devd[76299]: Popping table Apr 18 15:05:33 hector wpa_supplicant[21281]: wlan0: Trying to associate wi= th fc:f5:28:ca:f1:13 (SSID=3D'CCBiesse' freq=3D5180 MHz) Apr 18 15:05:33 hector kernel: iwlwifi0: lkpi_sta_scan_to_auth:1033: lvif 0xfffffe00abd8d000 vap 0xfffffe00abd8d010 iv_bss 0xfffffe00ade45000 lvif_bss 0xfffff80005c8f800 lvif_bss->ni 0xfffffe00ac03c000 synched 0 Apr 18 15:05:33 hector kernel: iwlwifi0: lkpi_iv_newstate: error 16 during state transition 1 (SCAN) -> 2 (AUTH) Apr 18 15:05:33 hector kernel: Sleeping thread (tid 100787, pid 0) owns a non-sleepable lock Apr 18 15:05:33 hector kernel: KDB: stack backtrace of thread 100787: Apr 18 15:05:33 hector kernel: sched_switch() at sched_switch+0x7d1/frame 0xfffffe00b0d0ae20 Apr 18 15:05:33 hector kernel: mi_switch() at mi_switch+0xbf/frame 0xfffffe00b0d0ae40 Apr 18 15:05:33 hector kernel: _sleep() at _sleep+0x1f0/frame 0xfffffe00b0d0aec0 Apr 18 15:06:26 hector syslogd: restart Apr 18 15:06:26 hector syslogd: kernel boot file is /boot/kernel/kernel Apr 18 15:06:26 hector kernel: fork_exit() at fork_exit+0x7d/frame 0xfffffe00b0d0af30 Apr 18 15:06:26 hector kernel: fork_trampoline() at fork_trampoline+0xe/fra= me 0xfffffe00b0d0af30 Apr 18 15:06:26 hector kernel: --- trap 0xfc9226bb, rip =3D 0x9e2138e7aa6c8= 07d, rsp =3D 0xce04afe17783e5b4, rbp =3D 0x7adcc9aeee864891 --- Apr 18 15:06:26 hector kernel: panic: sleeping thread Apr 18 15:06:26 hector kernel: cpuid =3D 3 Apr 18 15:06:26 hector kernel: time =3D 1713445533 Apr 18 15:06:26 hector kernel: KDB: stack backtrace: Apr 18 15:06:26 hector kernel: db_trace_self_wrapper() at db_trace_self_wrapper+0x2b/frame 0xfffffe00b0dbc960 Apr 18 15:06:26 hector kernel: vpanic() at vpanic+0x152/frame 0xfffffe00b0dbc9b0 Apr 18 15:06:26 hector kernel: panic() at panic+0x43/frame 0xfffffe00b0dbca= 10 Apr 18 15:06:26 hector kernel: propagate_priority() at propagate_priority+0x293/frame 0xfffffe00b0dbca50 Apr 18 15:06:26 hector kernel: turnstile_wait() at turnstile_wait+0x314/fra= me 0xfffffe00b0dbcaa0 Apr 18 15:06:26 hector kernel: __mtx_lock_sleep() at __mtx_lock_sleep+0x17b/frame 0xfffffe00b0dbcb30 Apr 18 15:06:26 hector kernel: linuxkpi_ieee80211_find_sta() at linuxkpi_ieee80211_find_sta+0xd0/frame 0xfffffe00b0dbcb70 Apr 18 15:06:26 hector kernel: linuxkpi_ieee80211_find_sta_by_ifaddr() at linuxkpi_ieee80211_find_sta_by_ifaddr+0x7f/frame 0xfffffe00b0dbcbc0 Apr 18 15:06:26 hector kernel: iwl_mvm_rx_mpdu_mq() at iwl_mvm_rx_mpdu_mq+0x420/frame 0xfffffe00b0dbccd0 Apr 18 15:06:26 hector kernel: iwl_pcie_rx_handle() at iwl_pcie_rx_handle+0x444/frame 0xfffffe00b0dbcdd0 Apr 18 15:06:26 hector kernel: iwl_pcie_napi_poll_msix() at iwl_pcie_napi_poll_msix+0x30/frame 0xfffffe00b0dbce20 Apr 18 15:06:26 hector kernel: lkpi_napi_task() at lkpi_napi_task+0xf/frame 0xfffffe00b0dbce40 Apr 18 15:06:26 hector kernel: taskqueue_run_locked() at taskqueue_run_locked+0x182/frame 0xfffffe00b0dbcec0 Apr 18 15:06:26 hector kernel: taskqueue_thread_loop() at taskqueue_thread_loop+0xc2/frame 0xfffffe00b0dbcef0 Apr 18 15:06:26 hector kernel: fork_exit() at fork_exit+0x7d/frame 0xfffffe00b0dbcf30 Apr 18 15:06:26 hector kernel: fork_trampoline() at fork_trampoline+0xe/fra= me 0xfffffe00b0dbcf30 Apr 18 15:06:26 hector kernel: --- trap 0, rip =3D 0, rsp =3D 0, rbp =3D 0 = --- Apr 18 15:06:26 hector kernel: Uptime: 4m36s I've got several others. Once I also entered a loop where the laptop would panic and reboot before I reached the prompt. Luckily entering single-user mode, starting network from there and then mov= ing to multi-user mode solved.=20 # pciconf -lv iwlwifi0 iwlwifi0@pci0:0:12:0: class=3D0x028000 rev=3D0x06 hdr=3D0x00 vendor=3D0x= 8086 device=3D0x31dc subvendor=3D0x8086 subdevice=3D0x0264 vendor =3D 'Intel Corporation' device =3D 'Gemini Lake PCH CNVi WiFi' class =3D network=20 (should be Intel 9461). --=20 You are receiving this mail because: You are on the CC list for the bug.=