Bug 204959
Summary: | iwlwifi: AX200: TOF_RANGE_REQ command is called, crash on firmware. | ||
---|---|---|---|
Product: | Drivers | Reporter: | chany kim (chanykim) |
Component: | network-wireless-intel | Assignee: | DO NOT USE - assign "network-wireless-intel" component instead (linuxwifi) |
Status: | ASSIGNED --- | ||
Severity: | high | CC: | chanykim, linuxwifi, luca, wei.liu1011 |
Priority: | P1 | ||
Hardware: | Intel | ||
OS: | Linux | ||
Kernel Version: | 5.1.0-050100-generic | Subsystem: | |
Regression: | No | Bisected commit-id: |
Description
chany kim
2019-09-23 02:07:25 UTC
This doesn't seem to be a backports bug per se. Can you try it with a mainline kernel without backports? In any case, reassigning. Yes, This issue doesn't seem to be a backports bug. I confirmed same issue on kernel 5.3.4 I think that it is a problem on iwlwifi firmware. Could yo check whether ToF support on ax200 or not? There is a log file on kernel 5.3.4 ------------------------------------ [ 5.152126] iwlwifi 0000:01:00.0: Applying debug destination EXTERNAL_DRAM [ 5.301110] iwlwifi 0000:01:00.0: FW already configured (0) - re-configuring [ 13.561197] e1000e: enp0s31f6 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: None [ 13.561244] IPv6: ADDRCONF(NETDEV_CHANGE): enp0s31f6: link becomes ready [ 14.646537] logitech-hidpp-device 0003:046D:406B.0005: HID++ 4.5 device connected. [ 146.895750] iwlwifi 0000:01:00.0: Microcode SW error detected. Restarting 0x0. [ 146.895768] iwlwifi 0000:01:00.0: FW error in SYNC CMD TOF_RANGE_REQ_CMD [ 146.895773] CPU: 1 PID: 2160 Comm: iw Not tainted 5.3.4-050304-generic #201910051526 [ 146.895774] Hardware name: GIGABYTE GB-BSi5-6200/MKLP5AP-00, BIOS F6 05/16/2016 [ 146.895774] Call Trace: [ 146.895780] dump_stack+0x63/0x8a [ 146.895791] iwl_pcie_gen2_send_hcmd_sync+0x435/0x450 [iwlwifi] [ 146.895794] ? wait_woken+0x80/0x80 [ 146.895801] iwl_trans_pcie_gen2_send_hcmd+0x5c/0xd0 [iwlwifi] [ 146.895807] iwl_trans_send_cmd+0x5d/0xc0 [iwlwifi] [ 146.895815] iwl_mvm_send_cmd_status+0x3c/0xc0 [iwlmvm] [ 146.895822] iwl_mvm_ftm_start+0x3d5/0x4a0 [iwlmvm] [ 146.895825] ? __nla_validate+0xb/0x10 [ 146.895827] ? validate_nla+0x366/0x640 [ 146.895829] ? __nla_validate_parse+0x116/0x140 [ 146.895831] ? __nla_validate_parse+0x42/0x140 [ 146.895837] iwl_mvm_start_pmsr+0x3a/0x60 [iwlmvm] [ 146.895837] iwlwifi 0000:01:00.0: Start IWL Error Log Dump: [ 146.895839] iwlwifi 0000:01:00.0: Status: 0x00000080, count: 6 [ 146.895840] iwlwifi 0000:01:00.0: Loaded firmware version: 48.4fa0041f.0 [ 146.895842] iwlwifi 0000:01:00.0: 0x00000071 | NMI_INTERRUPT_UMAC_FATAL [ 146.895861] ieee80211_start_pmsr+0x74/0x150 [mac80211] [ 146.895881] nl80211_pmsr_start+0x23b/0x710 [cfg80211] [ 146.895881] iwlwifi 0000:01:00.0: 0x000022F0 | trm_hw_status0 [ 146.895882] iwlwifi 0000:01:00.0: 0x00000000 | trm_hw_status1 [ 146.895883] iwlwifi 0000:01:00.0: 0x004F8E3C | branchlink2 [ 146.895884] iwlwifi 0000:01:00.0: 0x004E4FF4 | interruptlink1 [ 146.895885] iwlwifi 0000:01:00.0: 0x004E4FF4 | interruptlink2 [ 146.895886] iwlwifi 0000:01:00.0: 0x000067F4 | data1 [ 146.895887] iwlwifi 0000:01:00.0: 0x00001000 | data2 [ 146.895888] iwlwifi 0000:01:00.0: 0xF0000008 | data3 [ 146.895889] iwlwifi 0000:01:00.0: 0x00000000 | beacon time [ 146.895891] iwlwifi 0000:01:00.0: 0x08727281 | tsf low [ 146.895893] genl_family_rcv_msg+0x1b9/0x470 [ 146.895895] iwlwifi 0000:01:00.0: 0x00000000 | tsf hi [ 146.895896] genl_rcv_msg+0x4c/0xa0 [ 146.895898] ? _cond_resched+0x19/0x30 [ 146.895900] ? genl_family_rcv_msg+0x470/0x470 [ 146.895901] iwlwifi 0000:01:00.0: 0x00000000 | time gp1 [ 146.895902] netlink_rcv_skb+0x50/0x120 [ 146.895904] genl_rcv+0x29/0x40 [ 146.895905] iwlwifi 0000:01:00.0: 0x0872D3D2 | time gp2 [ 146.895906] netlink_unicast+0x187/0x220 [ 146.895908] netlink_sendmsg+0x20a/0x3c0 [ 146.895909] iwlwifi 0000:01:00.0: 0x00000001 | uCode revision type [ 146.895911] sock_sendmsg+0x65/0x70 [ 146.895913] ___sys_sendmsg+0x2ae/0x330 [ 146.895914] iwlwifi 0000:01:00.0: 0x00000030 | uCode version major [ 146.895917] ? generic_file_read_iter+0xdc/0x140 [ 146.895919] ? ext4_file_read_iter+0x4f/0x100 [ 146.895920] iwlwifi 0000:01:00.0: 0x4FA0041F | uCode version minor [ 146.895922] ? new_sync_read+0x122/0x1b0 [ 146.895924] __sys_sendmsg+0x5c/0xa0 [ 146.895925] iwlwifi 0000:01:00.0: 0x00000340 | hw version [ 146.895926] iwlwifi 0000:01:00.0: 0x18C89000 | board version [ 146.895927] iwlwifi 0000:01:00.0: 0x805EFC04 | hcmd [ 146.895928] iwlwifi 0000:01:00.0: 0x00020000 | isr0 [ 146.895929] iwlwifi 0000:01:00.0: 0x00000000 | isr1 [ 146.895930] iwlwifi 0000:01:00.0: 0x18F00002 | isr2 [ 146.895931] iwlwifi 0000:01:00.0: 0x00C1FFCC | isr3 [ 146.895932] iwlwifi 0000:01:00.0: 0x00000000 | isr4 [ 146.895933] iwlwifi 0000:01:00.0: 0x0040019C | last cmd Id [ 146.895934] iwlwifi 0000:01:00.0: 0x000067F4 | wait_event [ 146.895935] iwlwifi 0000:01:00.0: 0x00000094 | l2p_control [ 146.895936] iwlwifi 0000:01:00.0: 0x00000020 | l2p_duration [ 146.895937] iwlwifi 0000:01:00.0: 0x0000000F | l2p_mhvalid [ 146.895938] iwlwifi 0000:01:00.0: 0x00000000 | l2p_addr_match [ 146.895939] iwlwifi 0000:01:00.0: 0x00000009 | lmpm_pmg_sel [ 146.895940] iwlwifi 0000:01:00.0: 0x00000000 | timestamp [ 146.895941] iwlwifi 0000:01:00.0: 0x0000A8F4 | flow_handler [ 146.895947] __x64_sys_sendmsg+0x1f/0x30 [ 146.895959] do_syscall_64+0x5a/0x130 [ 146.895962] entry_SYSCALL_64_after_hwframe+0x44/0xa9 Hi, I have experienced the same behavior when trying to use ftm_request measurement, I have newer kernel version, and newer firmware, details see below, any update on this bug? demo@nuc5:~$ ethtool -i wlp2s0 driver: iwlwifi version: 5.4.6-050406-generic firmware-version: 48.4fa0041f.0 The log file of dmesg is very similar [4148941.759014] iwlwifi 0000:02:00.0: Collecting data: trigger 2 fired. [4148941.759020] ieee80211 phy0: Hardware restart was requested [4148941.759032] iwlwifi 0000:02:00.0: FW error in SYNC CMD TOF_RANGE_REQ_CMD [4148941.759039] CPU: 1 PID: 29476 Comm: iw Tainted: G W 5.4.6-050406-generic #201912211140 [4148941.759040] Hardware name: Intel(R) Client Systems NUC6CAYH/NUC6CAYB, BIOS AYAPLCEL.86A.0064.2019.0910.1422 09/10/2019 [4148941.759041] Call Trace: [4148941.759053] dump_stack+0x6d/0x9a [4148941.759069] iwl_trans_pcie_gen2_send_hcmd+0x3de/0x3f0 [iwlwifi] [4148941.759077] ? wait_woken+0x80/0x80 [4148941.759091] iwl_trans_send_cmd+0x55/0xc0 [iwlwifi] [4148941.759108] iwl_mvm_send_cmd_status+0x38/0xd0 [iwlmvm] [4148941.759121] iwl_mvm_ftm_start+0x3de/0x4a0 [iwlmvm] [4148941.759126] ? ring_buffer_unlock_commit+0x27/0x120 [4148941.759163] ? ieee80211_start_pmsr+0x74/0x150 [mac80211] [4148941.759165] ? trace_function+0xa4/0x120 [4148941.759174] ? iwl_mvm_configure_filter+0x90/0x90 [iwlmvm] [4148941.759176] ? function_trace_call+0x102/0x150 [4148941.759183] ? 0xffffffffc0da3077 [4148941.759185] ? 0xffffffffc0da3077 [4148941.759193] ? iwl_mvm_configure_filter+0x90/0x90 [iwlmvm] [4148941.759202] iwl_mvm_start_pmsr+0x3a/0x60 [iwlmvm] [4148941.759226] ieee80211_start_pmsr+0x74/0x150 [mac80211] [4148941.759260] nl80211_pmsr_start+0x23e/0x740 [cfg80211] [4148941.759265] genl_family_rcv_msg+0x1b9/0x470 [4148941.759268] genl_rcv_msg+0x4c/0xa0 [4148941.759270] ? _cond_resched+0x19/0x30 [4148941.759272] ? genl_family_rcv_msg+0x470/0x470 [4148941.759274] netlink_rcv_skb+0x50/0x120 [4148941.759276] genl_rcv+0x29/0x40 [4148941.759277] netlink_unicast+0x187/0x220 [4148941.759279] netlink_sendmsg+0x20a/0x3c0 [4148941.759283] sock_sendmsg+0x65/0x70 [4148941.759285] ____sys_sendmsg+0x212/0x280 [4148941.759287] ___sys_sendmsg+0x88/0xd0 [4148941.759290] ? __wake_up_common_lock+0x8a/0xc0 [4148941.759292] ? __wake_up+0x13/0x20 [4148941.759296] ? tty_write_unlock+0x31/0x40 [4148941.759299] __sys_sendmsg+0x5c/0xa0 [4148941.759302] __x64_sys_sendmsg+0x1f/0x30 [4148941.759305] do_syscall_64+0x57/0x190 [4148941.759308] entry_SYSCALL_64_after_hwframe+0x44/0xa9 [4148941.759310] RIP: 0033:0x7f89b8de0d04 [4148941.759314] Code: 00 f7 d8 64 89 02 48 c7 c0 ff ff ff ff eb b5 0f 1f 80 00 00 00 00 48 8d 05 01 dc 2c 00 8b 00 85 c0 75 13 b8 2e 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 54 f3 c3 66 90 41 54 55 41 89 d4 53 48 89 f5 [4148941.759315] RSP: 002b:00007ffcad5df6f8 EFLAGS: 00000246 ORIG_RAX: 000000000000002e [4148941.759317] RAX: ffffffffffffffda RBX: 00005605e4944990 RCX: 00007f89b8de0d04 [4148941.759318] RDX: 0000000000000000 RSI: 00007ffcad5df730 RDI: 0000000000000003 [4148941.759319] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000008 [4148941.759320] R10: 00000000fffffff8 R11: 0000000000000246 R12: 00005605e4949ef0 [4148941.759321] R13: 00007ffcad5df730 R14: 00005605e4949e00 R15: 00005605e4949d98 [4148942.316342] iwlwifi 0000:02:00.0: Applying debug destination EXTERNAL_DRAM [4148942.464035] iwlwifi 0000:02:00.0: FW already configured (0) - re-configuring [4153234.245705] wlp2s0: deauthenticating from dc:fb:48:00:25:49 by local choice (Reason: 3=DEAUTH_LEAVING) [4211280.121977] wlp2s0: authenticate with dc:fb:48:00:25:49 [4211280.129188] wlp2s0: send auth to dc:fb:48:00:25:49 (try 1/3) [4211280.132371] wlp2s0: authenticated [4211280.133894] wlp2s0: associate with dc:fb:48:00:25:49 (try 1/3) [4211280.137245] wlp2s0: RX AssocResp from dc:fb:48:00:25:49 (capab=0x401 status=0 aid=1) [4211280.139146] wlp2s0: associated [4212330.020749] iwlwifi 0000:02:00.0: Microcode SW error detected. Restarting 0x0. [4212330.020837] iwlwifi 0000:02:00.0: Start IWL Error Log Dump: [4212330.020841] iwlwifi 0000:02:00.0: Status: 0x00000040, count: 6 [4212330.020846] iwlwifi 0000:02:00.0: Loaded firmware version: 48.4fa0041f.0 [4212330.020850] iwlwifi 0000:02:00.0: 0x00000071 | NMI_INTERRUPT_UMAC_FATAL [4212330.020854] iwlwifi 0000:02:00.0: 0x000022F0 | trm_hw_status0 [4212330.020858] iwlwifi 0000:02:00.0: 0x00000000 | trm_hw_status1 [4212330.020862] iwlwifi 0000:02:00.0: 0x004F8E3C | branchlink2 [4212330.020865] iwlwifi 0000:02:00.0: 0x004E4FF4 | interruptlink1 [4212330.020869] iwlwifi 0000:02:00.0: 0x004E4FF4 | interruptlink2 [4212330.020873] iwlwifi 0000:02:00.0: 0x004F4AD2 | data1 [4212330.020876] iwlwifi 0000:02:00.0: 0x00001000 | data2 [4212330.020880] iwlwifi 0000:02:00.0: 0xF0000008 | data3 [4212330.020883] iwlwifi 0000:02:00.0: 0x0340AFD4 | beacon time [4212330.020887] iwlwifi 0000:02:00.0: 0x94E030B0 | tsf low [4212330.020890] iwlwifi 0000:02:00.0: 0x00000013 | tsf hi [4212330.020893] iwlwifi 0000:02:00.0: 0x00000000 | time gp1 [4212330.020897] iwlwifi 0000:02:00.0: 0xC23BB193 | time gp2 [4212330.020900] iwlwifi 0000:02:00.0: 0x00000001 | uCode revision type [4212330.020904] iwlwifi 0000:02:00.0: 0x00000030 | uCode version major [4212330.020908] iwlwifi 0000:02:00.0: 0x4FA0041F | uCode version minor [4212330.020911] iwlwifi 0000:02:00.0: 0x00000340 | hw version [4212330.020915] iwlwifi 0000:02:00.0: 0x00C89000 | board version [4212330.020919] iwlwifi 0000:02:00.0: 0x8060FC19 | hcmd [4212330.020922] iwlwifi 0000:02:00.0: 0x00020000 | isr0 [4212330.020925] iwlwifi 0000:02:00.0: 0x00000000 | isr1 [4212330.020929] iwlwifi 0000:02:00.0: 0x18F04802 | isr2 [4212330.020932] iwlwifi 0000:02:00.0: 0x04C1FFCC | isr3 [4212330.020936] iwlwifi 0000:02:00.0: 0x00000000 | isr4 [4212330.020939] iwlwifi 0000:02:00.0: 0x004E019C | last cmd Id [4212330.020942] iwlwifi 0000:02:00.0: 0x004F4AD2 | wait_event [4212330.020946] iwlwifi 0000:02:00.0: 0x00000000 | l2p_control [4212330.020949] iwlwifi 0000:02:00.0: 0x00000000 | l2p_duration [4212330.020953] iwlwifi 0000:02:00.0: 0x0000003F | l2p_mhvalid [4212330.020956] iwlwifi 0000:02:00.0: 0x000000CE | l2p_addr_match [4212330.020960] iwlwifi 0000:02:00.0: 0x00000008 | lmpm_pmg_sel [4212330.020963] iwlwifi 0000:02:00.0: 0x00000000 | timestamp [4212330.020967] iwlwifi 0000:02:00.0: 0x00003050 | flow_handler [4212330.021000] iwlwifi 0000:02:00.0: Start IWL Error Log Dump: [4212330.021004] iwlwifi 0000:02:00.0: Status: 0x00000040, count: 7 [4212330.021008] iwlwifi 0000:02:00.0: 0x2010104C | ADVANCED_SYSASSERT [4212330.021011] iwlwifi 0000:02:00.0: 0x00000000 | umac branchlink1 [4212330.021015] iwlwifi 0000:02:00.0: 0xC008CC3C | umac branchlink2 [4212330.021019] iwlwifi 0000:02:00.0: 0xC0087698 | umac interruptlink1 [4212330.021022] iwlwifi 0000:02:00.0: 0x00000000 | umac interruptlink2 [4212330.021026] iwlwifi 0000:02:00.0: 0xFBDC010D | umac data1 [4212330.021029] iwlwifi 0000:02:00.0: 0x00100080 | umac data2 [4212330.021033] iwlwifi 0000:02:00.0: 0xDEADBEEF | umac data3 [4212330.021036] iwlwifi 0000:02:00.0: 0x00000030 | umac major [4212330.021040] iwlwifi 0000:02:00.0: 0x4FA0041F | umac minor [4212330.021043] iwlwifi 0000:02:00.0: 0xC23BB185 | frame pointer [4212330.021047] iwlwifi 0000:02:00.0: 0xC0887F3C | stack pointer [4212330.021050] iwlwifi 0000:02:00.0: 0xFBDC010D | last host cmd [4212330.021054] iwlwifi 0000:02:00.0: 0x00020040 | isr status reg [4212330.021064] iwlwifi 0000:02:00.0: Fseq Registers: [4212330.021070] iwlwifi 0000:02:00.0: 0xE0000000 | FSEQ_ERROR_CODE [4212330.021077] iwlwifi 0000:02:00.0: 0x80290001 | FSEQ_TOP_INIT_VERSION [4212330.021083] iwlwifi 0000:02:00.0: 0x80050008 | FSEQ_CNVIO_INIT_VERSION [4212330.021090] iwlwifi 0000:02:00.0: 0x0000A503 | FSEQ_OTP_VERSION [4212330.021096] iwlwifi 0000:02:00.0: 0x80000003 | FSEQ_TOP_CONTENT_VERSION [4212330.021103] iwlwifi 0000:02:00.0: 0x4552414E | FSEQ_ALIVE_TOKEN [4212330.021109] iwlwifi 0000:02:00.0: 0x00100530 | FSEQ_CNVI_ID [4212330.021115] iwlwifi 0000:02:00.0: 0x00000532 | FSEQ_CNVR_ID [4212330.021122] iwlwifi 0000:02:00.0: 0x00100530 | CNVI_AUX_MISC_CHIP [4212330.021130] iwlwifi 0000:02:00.0: 0x00000532 | CNVR_AUX_MISC_CHIP [4212330.021139] iwlwifi 0000:02:00.0: 0x05B0905B | CNVR_SCU_SD_REGS_SD_REG_DIG_DCDC_VTRIM [4212330.021148] iwlwifi 0000:02:00.0: 0x0000025B | CNVR_SCU_SD_REGS_SD_REG_ACTIVE_VDIG_MIRROR [4212330.021266] iwlwifi 0000:02:00.0: Collecting data: trigger 2 fired. [4212330.021272] ieee80211 phy0: Hardware restart was requested [4212330.577452] iwlwifi 0000:02:00.0: Applying debug destination EXTERNAL_DRAM [4212330.725691] iwlwifi 0000:02:00.0: FW already configured (0) - re-configuring |