Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

iwlwifi fails to load with model ax200-series #5615

Closed
raedah opened this issue Feb 1, 2020 · 48 comments
Closed

iwlwifi fails to load with model ax200-series #5615

raedah opened this issue Feb 1, 2020 · 48 comments
Labels
C: kernel C: Xen diagnosed Technical diagnosis has been performed (see issue comments). hardware support P: major Priority: major. Between "default" and "critical" in severity. r4.0-dom0-stable r4.1-dom0-stable r4.2-host-stable T: bug Type: bug report. A problem or defect resulting in unintended behavior in something that exists.

Comments

@raedah
Copy link

raedah commented Feb 1, 2020

Qubes OS version
4.0.3 Fresh install, Fedora 30 Template

Affected component(s) or functionality
iwlwifi for AX200 based cards

Brief summary
AX200 is only supported on kernel 5.1+, so kernel-latest-qubes-vm is needed for sys-net. At that point numerous errors repeat in the dmesg.

Relevant documentation you've consulted
https://wireless.wiki.kernel.org/en/users/drivers/iwlwifi/debugging#firmware_debugging
https://wireless.wiki.kernel.org/en/users/drivers/iwlwifi/core_release
https://www.intel.com/content/www/us/en/support/articles/000005511/network-and-i-o/wireless-networking.html
https://git.kernel.org/pub/scm/linux/kernel/git/firmware/linux-firmware.git/tree/
https://git.kernel.org/pub/scm/linux/kernel/git/iwlwifi/backport-iwlwifi.git
https://github.com/QubesOS/qubes-builder/blob/master/example-configs/kernel.conf
xaki23/qubes-linux-kernel@45a0a90

dmesg error output

[ 40.119114] iwlwifi 0000:00:06.0: Direct firmware load for iwlwifi-cc-a0-50.ucode failed with error -2
[ 40.119154] iwlwifi 0000:00:06.0: Direct firmware load for iwlwifi-cc-a0-49.ucode failed with error -2
[ 40.120647] iwlwifi 0000:00:06.0: TLV_FW_FSEQ_VERSION: FSEQ Version: 43.2.23.17
[ 40.120664] iwlwifi 0000:00:06.0: Found debug destination: EXTERNAL_DRAM
[ 40.120677] iwlwifi 0000:00:06.0: Found debug configuration: 0
[ 40.120830] iwlwifi 0000:00:06.0: loaded firmware version 48.4fa0041f.0 op_mode iwlmvm
[ 40.120858] iwlwifi 0000:00:06.0: Detected Intel(R) Wi-Fi 6 AX200 160MHz, REV=0x340
[ 40.131203] iwlwifi 0000:00:06.0: Applying debug destination EXTERNAL_DRAM
[ 40.131352] iwlwifi 0000:00:06.0: Allocated 0x00200000 bytes for firmware monitor.
[ 40.131369] iwlwifi 0000:00:06.0: Sorry - debug buffer is only 2048K while you requested 4096K
[ 40.299811] iwlwifi 0000:00:06.0: base HW address: 50:eb:71:10:fa:b8
[ 40.314443] thermal thermal_zone0: failed to read out thermal zone (-61)
[ 40.319640] iwlwifi 0000:00:06.0 wls6: renamed from wlan0
[ 40.341323] iwlwifi 0000:00:06.0: Applying debug destination EXTERNAL_DRAM
[ 40.509066] iwlwifi 0000:00:06.0: FW already configured (0) - re-configuring
[ 40.709758] iwlwifi 0000:00:06.0: Microcode SW error detected. Restarting 0x2000000.
[ 40.709912] iwlwifi 0000:00:06.0: Start IWL Error Log Dump:
[ 40.709925] iwlwifi 0000:00:06.0: Status: 0x00000040, count: 6
[ 40.709940] iwlwifi 0000:00:06.0: Loaded firmware version: 48.4fa0041f.0
[ 40.709957] iwlwifi 0000:00:06.0: 0x00000071 | NMI_INTERRUPT_UMAC_FATAL
[ 40.709974] iwlwifi 0000:00:06.0: 0x000022F0 | trm_hw_status0
[ 40.709990] iwlwifi 0000:00:06.0: 0x00000000 | trm_hw_status1
[ 40.710013] iwlwifi 0000:00:06.0: 0x004F8E3C | branchlink2
[ 40.710028] iwlwifi 0000:00:06.0: 0x004E4FF4 | interruptlink1
[ 40.710044] iwlwifi 0000:00:06.0: 0x004E4FF4 | interruptlink2
[ 40.710060] iwlwifi 0000:00:06.0: 0x00016FF4 | data1
[ 40.710074] iwlwifi 0000:00:06.0: 0x00001000 | data2
[ 40.710088] iwlwifi 0000:00:06.0: 0xF0000008 | data3
[ 40.710101] iwlwifi 0000:00:06.0: 0x00000000 | beacon time
[ 40.710117] iwlwifi 0000:00:06.0: 0x00053D45 | tsf low
[ 40.710131] iwlwifi 0000:00:06.0: 0x00000000 | tsf hi
[ 40.710145] iwlwifi 0000:00:06.0: 0x00000000 | time gp1
[ 40.710160] iwlwifi 0000:00:06.0: 0x00059EE7 | time gp2
[ 40.710175] iwlwifi 0000:00:06.0: 0x00000001 | uCode revision type
[ 40.710191] iwlwifi 0000:00:06.0: 0x00000030 | uCode version major
[ 40.710207] iwlwifi 0000:00:06.0: 0x4FA0041F | uCode version minor
[ 40.710223] iwlwifi 0000:00:06.0: 0x00000340 | hw version
[ 40.710238] iwlwifi 0000:00:06.0: 0x18C89000 | board version
[ 40.710253] iwlwifi 0000:00:06.0: 0x801FF500 | hcmd
[ 40.710267] iwlwifi 0000:00:06.0: 0x00020000 | isr0
[ 40.710285] iwlwifi 0000:00:06.0: 0x00000000 | isr1
[ 40.710299] iwlwifi 0000:00:06.0: 0x08F00002 | isr2
[ 40.710313] iwlwifi 0000:00:06.0: 0x00C1FFCC | isr3
[ 40.710327] iwlwifi 0000:00:06.0: 0x00000000 | isr4
[ 40.710341] iwlwifi 0000:00:06.0: 0x000301D1 | last cmd Id
[ 40.710352] iwlwifi 0000:00:06.0: 0x00016FF4 | wait_event
[ 40.710363] iwlwifi 0000:00:06.0: 0x00000000 | l2p_control
[ 40.710374] iwlwifi 0000:00:06.0: 0x00000000 | l2p_duration
[ 40.710385] iwlwifi 0000:00:06.0: 0x00000000 | l2p_mhvalid
[ 40.710396] iwlwifi 0000:00:06.0: 0x00000000 | l2p_addr_match
[ 40.710408] iwlwifi 0000:00:06.0: 0x00000009 | lmpm_pmg_sel
[ 40.710418] iwlwifi 0000:00:06.0: 0x00000000 | timestamp
[ 40.710429] iwlwifi 0000:00:06.0: 0x00000828 | flow_handler
[ 40.710492] iwlwifi 0000:00:06.0: Start IWL Error Log Dump:
[ 40.710503] iwlwifi 0000:00:06.0: Status: 0x00000040, count: 7
[ 40.710515] iwlwifi 0000:00:06.0: 0x20000034 | NMI_INTERRUPT_WDG
[ 40.710527] iwlwifi 0000:00:06.0: 0x00000000 | umac branchlink1
[ 40.710540] iwlwifi 0000:00:06.0: 0xC008CC3C | umac branchlink2
[ 40.710552] iwlwifi 0000:00:06.0: 0x8048E6D8 | umac interruptlink1
[ 40.710565] iwlwifi 0000:00:06.0: 0xC00869FC | umac interruptlink2
[ 40.710577] iwlwifi 0000:00:06.0: 0x00000004 | umac data1
[ 40.710588] iwlwifi 0000:00:06.0: 0xC00869FC | umac data2
[ 40.710599] iwlwifi 0000:00:06.0: 0x00000000 | umac data3
[ 40.710609] iwlwifi 0000:00:06.0: 0x00000030 | umac major
[ 40.710620] iwlwifi 0000:00:06.0: 0x4FA0041F | umac minor
[ 40.710631] iwlwifi 0000:00:06.0: 0x00059EDA | frame pointer
[ 40.710643] iwlwifi 0000:00:06.0: 0xC08869A8 | stack pointer
[ 40.710655] iwlwifi 0000:00:06.0: 0x000A050D | last host cmd
[ 40.710667] iwlwifi 0000:00:06.0: 0x00000000 | isr status reg
[ 40.710687] iwlwifi 0000:00:06.0: Fseq Registers:
[ 40.710700] iwlwifi 0000:00:06.0: 0xE0000101 | FSEQ_ERROR_CODE
[ 40.710716] iwlwifi 0000:00:06.0: 0x00290006 | FSEQ_TOP_INIT_VERSION
[ 40.710733] iwlwifi 0000:00:06.0: 0x80050008 | FSEQ_CNVIO_INIT_VERSION
[ 40.710750] iwlwifi 0000:00:06.0: 0x0000A503 | FSEQ_OTP_VERSION
[ 40.710767] iwlwifi 0000:00:06.0: 0x80000003 | FSEQ_TOP_CONTENT_VERSION
[ 40.710783] iwlwifi 0000:00:06.0: 0x4552414E | FSEQ_ALIVE_TOKEN
[ 40.909590] iwlwifi 0000:00:06.0: 0x00100530 | FSEQ_CNVI_ID
[ 40.909604] iwlwifi 0000:00:06.0: 0x00000532 | FSEQ_CNVR_ID
[ 40.909619] iwlwifi 0000:00:06.0: 0x00100530 | CNVI_AUX_MISC_CHIP
[ 40.909637] iwlwifi 0000:00:06.0: 0x00000532 | CNVR_AUX_MISC_CHIP
[ 40.909656] iwlwifi 0000:00:06.0: 0x05B0905B | CNVR_SCU_SD_REGS_SD_REG_DIG_DCDC_VTRIM
[ 40.909678] iwlwifi 0000:00:06.0: 0x0000025B | CNVR_SCU_SD_REGS_SD_REG_ACTIVE_VDIG_MIRROR
[ 40.909939] iwlwifi 0000:00:06.0: Collecting data: trigger 2 fired.
[ 40.909954] ieee80211 phy24: Hardware restart was requested
[ 40.911245] iwlwifi 0000:00:06.0: FW error in SYNC CMD RFH_QUEUE_CONFIG_CMD
[ 40.911264] CPU: 0 PID: 542 Comm: NetworkManager Tainted: G W O 5.4.14-1.qubes.x86_64 #1
[ 40.911284] Hardware name: Xen HVM domU, BIOS 4.8.5-14.fc25 12/11/2019
[ 40.911299] Call Trace:
[ 40.911309] dump_stack+0x66/0x8b
[ 40.911323] iwl_trans_pcie_gen2_send_hcmd+0x35b/0x400 [iwlwifi]
[ 40.911338] ? remove_wait_queue+0x60/0x60
[ 40.911350] iwl_trans_send_cmd+0x51/0xd0 [iwlwifi]
[ 40.911367] iwl_mvm_send_cmd+0x1b/0x40 [iwlmvm]
[ 40.911381] iwl_configure_rxq+0x151/0x1c0 [iwlmvm]
[ 40.911394] ? iwl_mvm_up+0x5e5/0xbb0 [iwlmvm]
[ 40.911406] iwl_mvm_up+0x5e5/0xbb0 [iwlmvm]
[ 40.911421] __iwl_mvm_mac_start+0x26/0x1d0 [iwlmvm]
[ 40.911434] iwl_mvm_mac_start+0x23/0x40 [iwlmvm]
[ 40.911453] drv_start+0x43/0xf0 [mac80211]
[ 40.911470] ieee80211_do_open+0x190/0x940 [mac80211]
[ 40.911488] ? ieee80211_check_concurrent_iface+0x11a/0x1f0 [mac80211]
[ 40.911502] __dev_open+0xe8/0x170
[ 40.911511] ? inet6_set_link_af+0x6a/0x2f0
[ 40.911520] __dev_change_flags+0x1b4/0x200
[ 40.911529] dev_change_flags+0x23/0x60
[ 40.911538] do_setlink+0x3c3/0xe40
[ 40.911548] ? cpumask_next+0x17/0x20
[ 40.911556] ? __snmp6_fill_stats64.isra.49+0x6d/0x120
[ 40.911568] __rtnl_newlink+0x5ba/0x8c0
[ 40.911578] rtnl_newlink+0x47/0x70
[ 40.911587] ? ns_capable_common+0x27/0x50
[ 40.911595] rtnetlink_rcv_msg+0x2ab/0x3a0
[ 40.911605] ? rtnl_calcit.isra.27+0x120/0x120
[ 40.911615] netlink_rcv_skb+0xfd/0x130
[ 40.911624] netlink_unicast+0x1c3/0x250
[ 40.911633] netlink_sendmsg+0x2f9/0x3f0
[ 40.911642] sock_sendmsg+0x5b/0x60
[ 40.911650] ____sys_sendmsg+0x273/0x2a0
[ 40.911658] ? copy_msghdr_from_user+0xe5/0x160
[ 40.911668] ___sys_sendmsg+0xa6/0xf0
[ 40.911676] ? __do_proc_dointvec+0x310/0x410
[ 40.911687] ? __sys_sendmsg+0x8a/0xd0
[ 40.911695] __sys_sendmsg+0x8a/0xd0
[ 40.911704] do_syscall_64+0x5b/0x1c0
[ 40.911713] entry_SYSCALL_64_after_hwframe+0x44/0xa9
[ 40.911724] RIP: 0033:0x7e16e228796d
[ 40.911732] Code: 28 89 54 24 1c 48 89 74 24 10 89 7c 24 08 e8 ea ec ff ff 8b 54 24 1c 48 8b 74 24 10 41 89 c0 8b 7c 24 08 b8 2e 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 2f 44 89 c7 48 89 44 24 08 e8 1e ed ff ff 48
[ 40.911767] RSP: 002b:00007fffb271a2d0 EFLAGS: 00000293 ORIG_RAX: 000000000000002e
[ 40.911783] RAX: ffffffffffffffda RBX: 0000582df00e6c00 RCX: 00007e16e228796d
[ 40.911798] RDX: 0000000000000000 RSI: 00007fffb271a320 RDI: 000000000000000a
[ 40.911813] RBP: 00007fffb271a320 R08: 0000000000000000 R09: 0000000000000000
[ 40.911827] R10: 0000000000000000 R11: 0000000000000293 R12: 0000582df00e6c00
[ 40.911842] R13: 00007fffb271a4d8 R14: 00007fffb271a4cc R15: 0000000000000000
[ 40.911861] iwlwifi 0000:00:06.0: Failed to configure RX queues: -5
[ 40.911874] iwlwifi 0000:00:06.0: Firmware not running - cannot dump error
[ 41.134016] iwlwifi 0000:00:06.0: Applying debug destination EXTERNAL_DRAM
[ 41.303294] iwlwifi 0000:00:06.0: FW already configured (0) - re-configuring
[ 41.503775] iwlwifi 0000:00:06.0: Microcode SW error detected. Restarting 0x2000000.
[ 41.503934] iwlwifi 0000:00:06.0: Start IWL Error Log Dump:
[ 41.503946] iwlwifi 0000:00:06.0: Status: 0x00000050, count: 6
[ 41.503965] iwlwifi 0000:00:06.0: Loaded firmware version: 48.4fa0041f.0
[ 41.503983] iwlwifi 0000:00:06.0: 0x00000071 | NMI_INTERRUPT_UMAC_FATAL
[ 41.504013] iwlwifi 0000:00:06.0: 0x000022F0 | trm_hw_status0
[ 41.504029] iwlwifi 0000:00:06.0: 0x00000000 | trm_hw_status1
[ 41.504048] iwlwifi 0000:00:06.0: 0x004F8E3C | branchlink2
[ 41.504064] iwlwifi 0000:00:06.0: 0x004E4FF4 | interruptlink1
[ 41.504080] iwlwifi 0000:00:06.0: 0x004E4FF4 | interruptlink2
[ 41.504096] iwlwifi 0000:00:06.0: 0x00016FF4 | data1
[ 41.504110] iwlwifi 0000:00:06.0: 0x00001000 | data2
[ 41.504124] iwlwifi 0000:00:06.0: 0xF0000008 | data3
[ 41.504141] iwlwifi 0000:00:06.0: 0x00000000 | beacon time
[ 41.504158] iwlwifi 0000:00:06.0: 0x00054273 | tsf low
[ 41.504172] iwlwifi 0000:00:06.0: 0x00000000 | tsf hi
[ 41.504185] iwlwifi 0000:00:06.0: 0x00000000 | time gp1
[ 41.504199] iwlwifi 0000:00:06.0: 0x0005A407 | time gp2
[ 41.504213] iwlwifi 0000:00:06.0: 0x00000001 | uCode revision type
[ 41.504228] iwlwifi 0000:00:06.0: 0x00000030 | uCode version major
[ 41.504244] iwlwifi 0000:00:06.0: 0x4FA0041F | uCode version minor
[ 41.504261] iwlwifi 0000:00:06.0: 0x00000340 | hw version
[ 41.504275] iwlwifi 0000:00:06.0: 0x18C89000 | board version
[ 41.504290] iwlwifi 0000:00:06.0: 0x801EF500 | hcmd
[ 41.504305] iwlwifi 0000:00:06.0: 0x00020000 | isr0
[ 41.504321] iwlwifi 0000:00:06.0: 0x00000000 | isr1
[ 41.504332] iwlwifi 0000:00:06.0: 0x08F00002 | isr2
[ 41.504341] iwlwifi 0000:00:06.0: 0x00C1FFCC | isr3
[ 41.504351] iwlwifi 0000:00:06.0: 0x00000000 | isr4
[ 41.504361] iwlwifi 0000:00:06.0: 0x00000000 | last cmd Id
[ 41.504372] iwlwifi 0000:00:06.0: 0x00016FF4 | wait_event
[ 41.504383] iwlwifi 0000:00:06.0: 0x00000000 | l2p_control
[ 41.504393] iwlwifi 0000:00:06.0: 0x00000000 | l2p_duration
[ 41.504404] iwlwifi 0000:00:06.0: 0x00000000 | l2p_mhvalid
[ 41.504415] iwlwifi 0000:00:06.0: 0x00000000 | l2p_addr_match
[ 41.504427] iwlwifi 0000:00:06.0: 0x00000009 | lmpm_pmg_sel
[ 41.504437] iwlwifi 0000:00:06.0: 0x00000000 | timestamp
[ 41.504448] iwlwifi 0000:00:06.0: 0x00000828 | flow_handler
[ 41.504511] iwlwifi 0000:00:06.0: Start IWL Error Log Dump:
[ 41.504522] iwlwifi 0000:00:06.0: Status: 0x00000050, count: 7
[ 41.504534] iwlwifi 0000:00:06.0: 0x20000034 | NMI_INTERRUPT_WDG
[ 41.504546] iwlwifi 0000:00:06.0: 0x00000000 | umac branchlink1
[ 41.504559] iwlwifi 0000:00:06.0: 0xC008CC3C | umac branchlink2
[ 41.504571] iwlwifi 0000:00:06.0: 0x8048E6D8 | umac interruptlink1
[ 41.504584] iwlwifi 0000:00:06.0: 0xC00869FC | umac interruptlink2
[ 41.504597] iwlwifi 0000:00:06.0: 0x00000004 | umac data1
[ 41.504607] iwlwifi 0000:00:06.0: 0xC00869FC | umac data2
[ 41.504618] iwlwifi 0000:00:06.0: 0x00000000 | umac data3
[ 41.504628] iwlwifi 0000:00:06.0: 0x00000030 | umac major
[ 41.504639] iwlwifi 0000:00:06.0: 0x4FA0041F | umac minor
[ 41.504649] iwlwifi 0000:00:06.0: 0x0005A3FB | frame pointer
[ 41.504662] iwlwifi 0000:00:06.0: 0xC08869A8 | stack pointer
[ 41.504674] iwlwifi 0000:00:06.0: 0x0009050D | last host cmd
[ 41.504686] iwlwifi 0000:00:06.0: 0x00000000 | isr status reg
[ 41.504706] iwlwifi 0000:00:06.0: Fseq Registers:
[ 41.504719] iwlwifi 0000:00:06.0: 0xE0000101 | FSEQ_ERROR_CODE
[ 41.504735] iwlwifi 0000:00:06.0: 0x00290006 | FSEQ_TOP_INIT_VERSION
[ 41.504751] iwlwifi 0000:00:06.0: 0x80050008 | FSEQ_CNVIO_INIT_VERSION
[ 41.703575] iwlwifi 0000:00:06.0: 0x0000A503 | FSEQ_OTP_VERSION
[ 41.703590] iwlwifi 0000:00:06.0: 0x80000003 | FSEQ_TOP_CONTENT_VERSION
[ 41.703608] iwlwifi 0000:00:06.0: 0x4552414E | FSEQ_ALIVE_TOKEN
[ 41.703625] iwlwifi 0000:00:06.0: 0x00100530 | FSEQ_CNVI_ID
[ 41.703640] iwlwifi 0000:00:06.0: 0x00000532 | FSEQ_CNVR_ID
[ 41.703654] iwlwifi 0000:00:06.0: 0x00100530 | CNVI_AUX_MISC_CHIP
[ 41.703672] iwlwifi 0000:00:06.0: 0x00000532 | CNVR_AUX_MISC_CHIP
[ 41.703692] iwlwifi 0000:00:06.0: 0x05B0905B | CNVR_SCU_SD_REGS_SD_REG_DIG_DCDC_VTRIM
[ 41.703713] iwlwifi 0000:00:06.0: 0x0000025B | CNVR_SCU_SD_REGS_SD_REG_ACTIVE_VDIG_MIRROR
[ 41.703760] iwlwifi 0000:00:06.0: Firmware error during reconfiguration - reprobe!
[ 41.703801] iwlwifi 0000:00:06.0: LED command failed: -5
[ 41.703828] iwlwifi 0000:00:06.0: LED command failed: -5
[ 41.704053] iwlwifi 0000:00:06.0: FW error in SYNC CMD RFH_QUEUE_CONFIG_CMD
[ 41.704069] CPU: 1 PID: 542 Comm: NetworkManager Tainted: G W O 5.4.14-1.qubes.x86_64 #1
[ 41.704089] Hardware name: Xen HVM domU, BIOS 4.8.5-14.fc25 12/11/2019
[ 41.704104] Call Trace:
[ 41.704114] dump_stack+0x66/0x8b
[ 41.704128] iwl_trans_pcie_gen2_send_hcmd+0x35b/0x400 [iwlwifi]
[ 41.704143] ? remove_wait_queue+0x60/0x60
[ 41.704156] iwl_trans_send_cmd+0x51/0xd0 [iwlwifi]
[ 41.704172] iwl_mvm_send_cmd+0x1b/0x40 [iwlmvm]
[ 41.704186] iwl_configure_rxq+0x151/0x1c0 [iwlmvm]
[ 41.704200] ? iwl_mvm_up+0x5e5/0xbb0 [iwlmvm]
[ 41.704214] iwl_mvm_up+0x5e5/0xbb0 [iwlmvm]
[ 41.704226] ? iwl_mvm_channel_switch+0x30/0x30 [iwlmvm]
[ 41.704248] ? ieee80211_wake_queues_by_reason+0xa3/0xd0 [mac80211]
[ 41.704265] __iwl_mvm_mac_start+0x26/0x1d0 [iwlmvm]
[ 41.704278] iwl_mvm_mac_start+0x23/0x40 [iwlmvm]
[ 41.704294] drv_start+0x43/0xf0 [mac80211]
[ 41.704309] ieee80211_do_open+0x190/0x940 [mac80211]
[ 41.704326] ? ieee80211_check_concurrent_iface+0x11a/0x1f0 [mac80211]
[ 41.704341] __dev_open+0xe8/0x170
[ 41.704350] __dev_change_flags+0x1b4/0x200
[ 41.704359] dev_change_flags+0x23/0x60
[ 41.704368] do_setlink+0x3c3/0xe40
[ 41.704378] ? cpumask_next+0x17/0x20
[ 41.704386] ? __snmp6_fill_stats64.isra.49+0x6d/0x120
[ 41.704398] __rtnl_newlink+0x5ba/0x8c0
[ 41.704408] rtnl_newlink+0x47/0x70
[ 41.704416] ? ns_capable_common+0x27/0x50
[ 41.704425] rtnetlink_rcv_msg+0x2ab/0x3a0
[ 41.704435] ? rtnl_calcit.isra.27+0x120/0x120
[ 41.704445] netlink_rcv_skb+0xfd/0x130
[ 41.704455] netlink_unicast+0x1c3/0x250
[ 41.704463] netlink_sendmsg+0x2f9/0x3f0
[ 41.704472] sock_sendmsg+0x5b/0x60
[ 41.704480] ____sys_sendmsg+0x273/0x2a0
[ 41.704489] ? copy_msghdr_from_user+0xe5/0x160
[ 41.704499] ___sys_sendmsg+0xa6/0xf0
[ 41.704508] ? __switch_to_asm+0x34/0x70
[ 41.704517] ? __sys_sendmsg+0x8a/0xd0
[ 41.704525] __sys_sendmsg+0x8a/0xd0
[ 41.704534] do_syscall_64+0x5b/0x1c0
[ 41.704542] entry_SYSCALL_64_after_hwframe+0x44/0xa9
[ 41.704554] RIP: 0033:0x7e16e228796d
[ 41.704562] Code: 28 89 54 24 1c 48 89 74 24 10 89 7c 24 08 e8 ea ec ff ff 8b 54 24 1c 48 8b 74 24 10 41 89 c0 8b 7c 24 08 b8 2e 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 2f 44 89 c7 48 89 44 24 08 e8 1e ed ff ff 48
[ 41.704597] RSP: 002b:00007fffb271a090 EFLAGS: 00000293 ORIG_RAX: 000000000000002e
[ 41.704613] RAX: ffffffffffffffda RBX: 0000582df00e6c00 RCX: 00007e16e228796d
[ 41.704628] RDX: 0000000000000000 RSI: 00007fffb271a0e0 RDI: 000000000000000a
[ 41.904457] RBP: 00007fffb271a0e0 R08: 0000000000000000 R09: 0000000000000000
[ 41.904472] R10: 0000000000000000 R11: 0000000000000293 R12: 0000582df00e6c00
[ 41.904488] R13: 00007fffb271a298 R14: 00007fffb271a28c R15: 0000000000000000
[ 41.904520] iwlwifi 0000:00:06.0: Failed to configure RX queues: -5

@raedah raedah added P: default Priority: default. Default priority for new issues, to be replaced given sufficient information. T: bug Type: bug report. A problem or defect resulting in unintended behavior in something that exists. labels Feb 1, 2020
@raedah
Copy link
Author

raedah commented Feb 1, 2020

Appears this may be a problem in Fedora directly.
https://bugzilla.redhat.com/show_bug.cgi?id=1790231

@zeroqn
Copy link

zeroqn commented Feb 1, 2020

AX200 doesn't work in debian template.
Related bugs: https://bugzilla.kernel.org/show_bug.cgi?id=204731

@ejose19
Copy link

ejose19 commented Feb 2, 2020

Can confirm this is an issue, doesn't work with any template (debian, fedora, archlinux) however if running any of those distros directly the wifi is working properly.

@raedah
Copy link
Author

raedah commented Feb 2, 2020

Can anyone confirm a vm kernel version that works without these crashes?

@fmeef
Copy link

fmeef commented Feb 3, 2020

Can confirm I have this problem too with an intel wireless-ac 9462 on a thinkpad x1 carbon 7th gen with an intel 10th generation i7. The issue is present both on 4.0.3 stable and 4.1 (built from master branch using qubes-builder).

/var/log/xen/console/hypervisor.log complains about [VT-D]DMAR" reason 06 - PTE Read access is not set when attempting to assign the device

@fmeef
Copy link

fmeef commented Feb 3, 2020

Interestingly, dom0 seems to have the iwlwifi kernel module loaded. Could this mean the wireless card was attached to dom0 before the netvm had a chance to grab it? If this is a problem, is there a way to prevent this from happening?
edit: I tried blacklisting the driver and adding the pci device to the rd.qubes.hide_pci kernel option and the original problem still persists

@andrewdavidwong andrewdavidwong added C: other hardware support P: major Priority: major. Between "default" and "critical" in severity. and removed P: default Priority: default. Default priority for new issues, to be replaced given sufficient information. labels Feb 3, 2020
@andrewdavidwong andrewdavidwong added this to the Release 4.0 updates milestone Feb 3, 2020
@v6ak
Copy link

v6ak commented Mar 19, 2020

I have an issue with similar symptoms: https://groups.google.com/forum/#!topic/qubes-users/7VcN8kkKop0

Noteworthy parts:

  • Bare-metal Fedora 31 Live works, the same image in a HVM with attached Wi-Fi card does not work.
  • I see some suspicious lines in stubdom log. Maybe it is a stubdom incompatibility.
  • I don't have iwlwifi loaded in dom0, though.
  • You need kernel 5.2+ or 5.1+ (sources vary on minimum version). So, you probably need to use HVM for sys-net and configure no kernel in order to use the kernel from VM template.

@marmarek
Copy link
Member

@pwmarcz is the same as with your new laptop?

@pwmarcz
Copy link

pwmarcz commented Mar 19, 2020

My card is Intel Wireless-AC 9462. This happens after trying enable the interface (ip link set wls7f0 up):

[  156.323756] iwlwifi 0000:00:07.0: Microcode SW error detected.  Restarting 0x2000000.
[  156.323897] iwlwifi 0000:00:07.0: Start IWL Error Log Dump:
[  156.323908] iwlwifi 0000:00:07.0: Status: 0x00000050, count: 6
[  156.323926] iwlwifi 0000:00:07.0: Loaded firmware version: 48.4fa0041f.0
[  156.323940] iwlwifi 0000:00:07.0: 0x00000071 | NMI_INTERRUPT_UMAC_FATAL    
[  156.323980] iwlwifi 0000:00:07.0: 0x000022F0 | trm_hw_status0
[  156.323992] iwlwifi 0000:00:07.0: 0x00000000 | trm_hw_status1
[  156.324028] iwlwifi 0000:00:07.0: 0x004C2214 | branchlink2
[  156.324038] iwlwifi 0000:00:07.0: 0x004B0CE8 | interruptlink1
[  156.324050] iwlwifi 0000:00:07.0: 0x004B0CE8 | interruptlink2
[  156.324067] iwlwifi 0000:00:07.0: 0x0001541C | data1
[  156.324077] iwlwifi 0000:00:07.0: 0x00001000 | data2
[  156.324092] iwlwifi 0000:00:07.0: 0xF0000008 | data3
[  156.324102] iwlwifi 0000:00:07.0: 0x00000000 | beacon time
[  156.324112] iwlwifi 0000:00:07.0: 0x000512DA | tsf low
[  156.324185] iwlwifi 0000:00:07.0: 0x00000000 | tsf hi
[  156.324196] iwlwifi 0000:00:07.0: 0x00000000 | time gp1
[  156.324212] iwlwifi 0000:00:07.0: 0x00056FBD | time gp2
[  156.324222] iwlwifi 0000:00:07.0: 0x00000001 | uCode revision type
[  156.324241] iwlwifi 0000:00:07.0: 0x00000030 | uCode version major
[  156.324271] iwlwifi 0000:00:07.0: 0x4FA0041F | uCode version minor
[  156.324304] iwlwifi 0000:00:07.0: 0x00000351 | hw version
[  156.324320] iwlwifi 0000:00:07.0: 0x18489004 | board version
[  156.324350] iwlwifi 0000:00:07.0: 0x801AF500 | hcmd
[  156.324365] iwlwifi 0000:00:07.0: 0x00020000 | isr0
[  156.324375] iwlwifi 0000:00:07.0: 0x00000000 | isr1
[  156.324385] iwlwifi 0000:00:07.0: 0x08F00002 | isr2
[  156.324400] iwlwifi 0000:00:07.0: 0x00C1FFCC | isr3
[  156.324410] iwlwifi 0000:00:07.0: 0x00000000 | isr4
[  156.324420] iwlwifi 0000:00:07.0: 0x00000000 | last cmd Id
[  156.324430] iwlwifi 0000:00:07.0: 0x0001541C | wait_event
[  156.324440] iwlwifi 0000:00:07.0: 0x00000000 | l2p_control
[  156.324450] iwlwifi 0000:00:07.0: 0x00000000 | l2p_duration
[  156.324465] iwlwifi 0000:00:07.0: 0x00000000 | l2p_mhvalid
[  156.324475] iwlwifi 0000:00:07.0: 0x00000000 | l2p_addr_match
[  156.324487] iwlwifi 0000:00:07.0: 0x00000009 | lmpm_pmg_sel
[  156.324497] iwlwifi 0000:00:07.0: 0x00000000 | timestamp
[  156.324507] iwlwifi 0000:00:07.0: 0x00000024 | flow_handler
[  156.324575] iwlwifi 0000:00:07.0: Start IWL Error Log Dump:
[  156.324586] iwlwifi 0000:00:07.0: Status: 0x00000050, count: 7
[  156.324603] iwlwifi 0000:00:07.0: 0x20000034 | NMI_INTERRUPT_WDG
[  156.324616] iwlwifi 0000:00:07.0: 0x00000000 | umac branchlink1
[  156.324633] iwlwifi 0000:00:07.0: 0xC008CF5C | umac branchlink2
[  156.324645] iwlwifi 0000:00:07.0: 0x8048BD7C | umac interruptlink1
[  156.324662] iwlwifi 0000:00:07.0: 0xC0086704 | umac interruptlink2
[  156.324674] iwlwifi 0000:00:07.0: 0x00000004 | umac data1
[  156.324685] iwlwifi 0000:00:07.0: 0xC0086704 | umac data2
[  156.324700] iwlwifi 0000:00:07.0: 0x00000000 | umac data3
[  156.324710] iwlwifi 0000:00:07.0: 0x00000030 | umac major
[  156.324725] iwlwifi 0000:00:07.0: 0x4FA0041F | umac minor
[  156.324735] iwlwifi 0000:00:07.0: 0x00056FB1 | frame pointer
[  156.324747] iwlwifi 0000:00:07.0: 0xC08869A8 | stack pointer
[  156.324763] iwlwifi 0000:00:07.0: 0x0005050D | last host cmd
[  156.324775] iwlwifi 0000:00:07.0: 0x00000000 | isr status reg
[  156.324817] iwlwifi 0000:00:07.0: Fseq Registers:
[  156.324839] iwlwifi 0000:00:07.0: 0xE0000021 | FSEQ_ERROR_CODE
[  156.324885] iwlwifi 0000:00:07.0: 0x00260000 | FSEQ_TOP_INIT_VERSION
[  156.324909] iwlwifi 0000:00:07.0: 0x80020006 | FSEQ_CNVIO_INIT_VERSION
[  156.324937] iwlwifi 0000:00:07.0: 0x0000A384 | FSEQ_OTP_VERSION
[  156.325013] iwlwifi 0000:00:07.0: 0x6A76E268 | FSEQ_TOP_CONTENT_VERSION
[  156.325038] iwlwifi 0000:00:07.0: 0x4552414E | FSEQ_ALIVE_TOKEN
[  156.325061] iwlwifi 0000:00:07.0: 0x20000302 | FSEQ_CNVI_ID
[  156.325084] iwlwifi 0000:00:07.0: 0x01300202 | FSEQ_CNVR_ID
[  156.325107] iwlwifi 0000:00:07.0: 0x20000302 | CNVI_AUX_MISC_CHIP
[  156.325134] iwlwifi 0000:00:07.0: 0x01300202 | CNVR_AUX_MISC_CHIP
[  156.325161] iwlwifi 0000:00:07.0: 0x0000485B | CNVR_SCU_SD_REGS_SD_REG_DIG_DCDC_VTRIM
[  156.325220] iwlwifi 0000:00:07.0: 0xA5A5A5A2 | CNVR_SCU_SD_REGS_SD_REG_ACTIVE_VDIG_MIRROR
[  156.325265] iwlwifi 0000:00:07.0: Firmware error during reconfiguration - reprobe!
[  156.325320] iwlwifi 0000:00:07.0: FW error in SYNC CMD RFH_QUEUE_CONFIG_CMD
[  156.325326] iwlwifi 0000:00:07.0: Hardware error detected.  Restarting.
[  156.325354] iwlwifi 0000:00:07.0: LED command failed: -5
[  156.325388] iwlwifi 0000:00:07.0: LED command failed: -5
[  156.325412]  iwl_trans_pcie_gen2_send_hcmd+0x405/0x410 [iwlwifi]
[  156.325438]  iwl_trans_send_cmd+0x51/0xb0 [iwlwifi]

That's on kernel 5.4.24, but I get the same on 5.5.9.

This is a System76 laptop, and the manufacturer's own PopOS (with stock Ubuntu kernel 5.3.0-7625) loads wifi when booted directly, but fails in a similar manner when run under Qubes as an HVM doman.

@process0
Copy link

process0 commented Apr 12, 2020

Experiencing this as well using an AX200 (rev 1a).

QubesOS: 4.0.3 fresh install
dom0 kernel: 4.19.94-1.pvops.qubes.x86_64
sys-net kernel: 5.5.7-1.qubes.x86_64

I've tried this under a Fedora 30 VM and a Debian 10 VM. With microcodes iwlwifi-cc-a0-46 and iwlwifi-cc-a0-48, both giving the same error.

Also tested a a combination of dom0 with kernel-latest and sys-net on kernel and kernel-latest both with Fedora 30 and Debian 10 with no success.

I previously had NixOS (unstable) installed on this laptop baremetal with wifi working fine on a 5.1+ kernel, which leads me to believe this might have something to do with the firmware and xen.

@process0
Copy link

process0 commented Apr 13, 2020

Following the suggestions from https://groups.google.com/forum/#!topic/qubes-users/7VcN8kkKop0:

1. Some config space access is filtered and driver doesn't cope with it.

For the first hypothesis, I'd try enabling permissive option (AFAIR
didn't helped in itself), but then enable verbose logging in pciback
driver:
echo 1 > /sys/module/xen_pciback/parameters/verbose_request
before starting sys-net.

I forgot to set the device to permissive. This device is set for no strict reset though. After enabling verbosity, I ran dmesg -w | tee -a out in dom0, then modprobe -a iwlwifi in sys-net. Output:

[ 1245.547439] xen-pciback: 0000:52:00.0: read 2 bytes at 0xcc
[ 1245.547877] xen-pciback: 0000:52:00.0: read 2 bytes at 0xcc = 8
[ 1245.548304] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4
[ 1245.548746] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4 = 2
[ 1245.549186] xen-pciback: 0000:52:00.0: read 1 bytes at 0x3d
[ 1245.549528] xen-pciback: 0000:52:00.0: read 1 bytes at 0x3d = 1
[ 1245.549702] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4
[ 1245.549941] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4 = 2
[ 1245.550144] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4
[ 1245.550589] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4 = 2
[ 1245.550726] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4
[ 1245.551195] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4 = 2
[ 1245.551486] xen-pciback: 0000:52:00.0: write request 2 bytes at 0x4 = 6
[ 1245.551673] xen-pciback: 0000:52:00.0: set bus master
[ 1245.551934] xen-pciback: 0000:52:00.0: read 1 bytes at 0x41
[ 1245.552182] xen-pciback: 0000:52:00.0: read 1 bytes at 0x41 = 80
[ 1245.555583] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2
[ 1245.555776] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2 = 80
[ 1245.555936] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2
[ 1245.556243] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2 = 80
[ 1245.556341] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2
[ 1245.556534] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2 = 80
[ 1245.556551] xen-pciback: 0000:52:00.0: write request 2 bytes at 0xd2 = 80
[ 1245.556893] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2
[ 1245.557189] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2 = 80
[ 1245.557274] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2
[ 1245.557467] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2 = 80
[ 1245.557552] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2
[ 1245.557743] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2 = 80
[ 1245.557759] xen-pciback: 0000:52:00.0: write request 2 bytes at 0xd2 = 80
[ 1245.558159] xen-pciback: 0000:52:00.0: read 4 bytes at 0xd4
[ 1245.558303] xen-pciback: 0000:52:00.0: read 4 bytes at 0xd4 = fee00798
[ 1245.558398] xen-pciback: 0000:52:00.0: read 4 bytes at 0xd8
[ 1245.558540] xen-pciback: 0000:52:00.0: read 4 bytes at 0xd8 = 0
[ 1245.558631] xen-pciback: 0000:52:00.0: read 2 bytes at 0xdc
[ 1245.558773] xen-pciback: 0000:52:00.0: read 2 bytes at 0xdc = 0
[ 1245.558894] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4
[ 1245.559205] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4 = 6
[ 1245.559297] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4
[ 1245.559490] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4 = 6
[ 1245.559540] xen-pciback: 0000:52:00.0: write request 2 bytes at 0x4 = 406
[ 1245.559774] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2
[ 1245.559965] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2 = 80
[ 1245.560060] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2
[ 1245.560358] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2 = 80
[ 1245.560996] xen-pciback: 0000:52:00.0: write request 2 bytes at 0xd2 = 81
[ 1245.578947] xen-pciback: 0000:52:00.0: read 2 bytes at 0x50
[ 1245.579182] xen-pciback: 0000:52:00.0: read 2 bytes at 0x50 = 142
[ 1245.579418] xen-pciback: 0000:52:00.0: read 2 bytes at 0x68
[ 1245.579607] xen-pciback: 0000:52:00.0: read 2 bytes at 0x68 = 405
[ 1245.582838] xen-pciback: 0000:52:00.0: read 2 bytes at 0x50
[ 1245.582982] xen-pciback: 0000:52:00.0: read 2 bytes at 0x50 = 142
[ 1245.583130] xen-pciback: 0000:52:00.0: read 2 bytes at 0x68
[ 1245.583273] xen-pciback: 0000:52:00.0: read 2 bytes at 0x68 = 405
[ 1245.811502] xen-pciback: 0000:52:00.0: read 4 bytes at 0x0
[ 1245.811712] xen-pciback: 0000:52:00.0: read 4 bytes at 0x0 = 27238086
[ 1245.811946] xen-pciback: 0000:52:00.0: read 4 bytes at 0x4
[ 1245.812457] xen-pciback: 0000:52:00.0: read 4 bytes at 0x4 = 100406
[ 1245.812689] xen-pciback: 0000:52:00.0: read 4 bytes at 0x8
[ 1245.812893] xen-pciback: 0000:52:00.0: read 4 bytes at 0x8 = 280001a
[ 1245.813083] xen-pciback: 0000:52:00.0: read 4 bytes at 0xc
[ 1245.813986] xen-pciback: 0000:52:00.0: read 4 bytes at 0xc = 0
[ 1245.814126] xen-pciback: 0000:52:00.0: read 4 bytes at 0x10
[ 1245.814330] xen-pciback: 0000:52:00.0: read 4 bytes at 0x10 = ce100004
[ 1245.814496] xen-pciback: 0000:52:00.0: read 4 bytes at 0x14
[ 1245.814699] xen-pciback: 0000:52:00.0: read 4 bytes at 0x14 = 0
[ 1245.814796] xen-pciback: 0000:52:00.0: read 4 bytes at 0x18
[ 1245.814984] xen-pciback: 0000:52:00.0: read 4 bytes at 0x18 = 0
[ 1245.815089] xen-pciback: 0000:52:00.0: read 4 bytes at 0x1c
[ 1245.815277] xen-pciback: 0000:52:00.0: read 4 bytes at 0x1c = 0
[ 1245.815383] xen-pciback: 0000:52:00.0: read 4 bytes at 0x20
[ 1245.815570] xen-pciback: 0000:52:00.0: read 4 bytes at 0x20 = 0
[ 1245.815665] xen-pciback: 0000:52:00.0: read 4 bytes at 0x24
[ 1245.815851] xen-pciback: 0000:52:00.0: read 4 bytes at 0x24 = 0
[ 1245.815948] xen-pciback: 0000:52:00.0: read 4 bytes at 0x28
[ 1245.816146] xen-pciback: 0000:52:00.0: read 4 bytes at 0x28 = 0
[ 1245.816346] xen-pciback: 0000:52:00.0: read 4 bytes at 0x2c
[ 1245.816534] xen-pciback: 0000:52:00.0: read 4 bytes at 0x2c = 808086
[ 1245.816638] xen-pciback: 0000:52:00.0: read 4 bytes at 0x30
[ 1245.816825] xen-pciback: 0000:52:00.0: read 4 bytes at 0x30 = 0
[ 1245.816921] xen-pciback: 0000:52:00.0: read 4 bytes at 0x34
[ 1245.817123] xen-pciback: 0000:52:00.0: read 4 bytes at 0x34 = c8
[ 1245.817233] xen-pciback: 0000:52:00.0: read 4 bytes at 0x38
[ 1245.817421] xen-pciback: 0000:52:00.0: read 4 bytes at 0x38 = 0
[ 1245.817538] xen-pciback: 0000:52:00.0: read 4 bytes at 0x3c
[ 1245.817777] xen-pciback: 0000:52:00.0: read 4 bytes at 0x3c = 112
[ 1245.817974] xen-pciback: 0000:52:00.0: read 4 bytes at 0x0
[ 1245.818180] xen-pciback: 0000:52:00.0: read 4 bytes at 0x0 = 27238086
[ 1245.818289] xen-pciback: 0000:52:00.0: read 4 bytes at 0x4
[ 1245.818532] xen-pciback: 0000:52:00.0: read 4 bytes at 0x4 = 100406
[ 1245.818647] xen-pciback: 0000:52:00.0: read 4 bytes at 0x8
[ 1245.818836] xen-pciback: 0000:52:00.0: read 4 bytes at 0x8 = 280001a
[ 1245.818945] xen-pciback: 0000:52:00.0: read 4 bytes at 0xc
[ 1245.819655] xen-pciback: 0000:52:00.0: read 4 bytes at 0xc = 0
[ 1245.819791] xen-pciback: 0000:52:00.0: read 4 bytes at 0x10
[ 1245.819979] xen-pciback: 0000:52:00.0: read 4 bytes at 0x10 = ce100004
[ 1245.820079] xen-pciback: 0000:52:00.0: read 4 bytes at 0x14
[ 1245.820269] xen-pciback: 0000:52:00.0: read 4 bytes at 0x14 = 0
[ 1245.820808] xen-pciback: 0000:52:00.0: read 4 bytes at 0x18
[ 1245.821013] xen-pciback: 0000:52:00.0: read 4 bytes at 0x18 = 0
[ 1245.821122] xen-pciback: 0000:52:00.0: read 4 bytes at 0x1c
[ 1245.821314] xen-pciback: 0000:52:00.0: read 4 bytes at 0x1c = 0
[ 1245.821425] xen-pciback: 0000:52:00.0: read 4 bytes at 0x20
[ 1245.821613] xen-pciback: 0000:52:00.0: read 4 bytes at 0x20 = 0
[ 1245.821709] xen-pciback: 0000:52:00.0: read 4 bytes at 0x24
[ 1245.821897] xen-pciback: 0000:52:00.0: read 4 bytes at 0x24 = 0
[ 1245.821991] xen-pciback: 0000:52:00.0: read 4 bytes at 0x28
[ 1245.822192] xen-pciback: 0000:52:00.0: read 4 bytes at 0x28 = 0
[ 1245.822342] xen-pciback: 0000:52:00.0: read 4 bytes at 0x2c
[ 1245.822530] xen-pciback: 0000:52:00.0: read 4 bytes at 0x2c = 808086
[ 1245.822641] xen-pciback: 0000:52:00.0: read 4 bytes at 0x30
[ 1245.822828] xen-pciback: 0000:52:00.0: read 4 bytes at 0x30 = 0
[ 1245.822929] xen-pciback: 0000:52:00.0: read 4 bytes at 0x34
[ 1245.823130] xen-pciback: 0000:52:00.0: read 4 bytes at 0x34 = c8
[ 1245.823234] xen-pciback: 0000:52:00.0: read 4 bytes at 0x38
[ 1245.823437] xen-pciback: 0000:52:00.0: read 4 bytes at 0x38 = 0
[ 1245.823549] xen-pciback: 0000:52:00.0: read 4 bytes at 0x3c
[ 1245.823787] xen-pciback: 0000:52:00.0: read 4 bytes at 0x3c = 112
[ 1245.845895] xen-pciback: 0000:52:00.0: read 2 bytes at 0x50
[ 1245.846112] xen-pciback: 0000:52:00.0: read 2 bytes at 0x50 = 142
[ 1245.846259] xen-pciback: 0000:52:00.0: read 2 bytes at 0x68
[ 1245.846448] xen-pciback: 0000:52:00.0: read 2 bytes at 0x68 = 405
[ 1245.849949] xen-pciback: 0000:52:00.0: read 2 bytes at 0x50
[ 1245.850122] xen-pciback: 0000:52:00.0: read 2 bytes at 0x50 = 142
[ 1245.850233] xen-pciback: 0000:52:00.0: read 2 bytes at 0x68
[ 1245.850376] xen-pciback: 0000:52:00.0: read 2 bytes at 0x68 = 405
[ 1246.464934] xen-pciback: 0000:52:00.0: read 4 bytes at 0x0
[ 1246.465159] xen-pciback: 0000:52:00.0: read 4 bytes at 0x0 = 27238086
[ 1246.465410] xen-pciback: 0000:52:00.0: read 4 bytes at 0x4
[ 1246.465834] xen-pciback: 0000:52:00.0: read 4 bytes at 0x4 = 100406
[ 1246.466402] xen-pciback: 0000:52:00.0: read 4 bytes at 0x8
[ 1246.466622] xen-pciback: 0000:52:00.0: read 4 bytes at 0x8 = 280001a
[ 1246.466795] xen-pciback: 0000:52:00.0: read 4 bytes at 0xc
[ 1246.467342] xen-pciback: 0000:52:00.0: read 4 bytes at 0xc = 0
[ 1246.467605] xen-pciback: 0000:52:00.0: read 4 bytes at 0x10
[ 1246.467613] xen-pciback: 0000:52:00.0: read 4 bytes at 0x10 = ce100004
[ 1246.467797] xen-pciback: 0000:52:00.0: read 4 bytes at 0x14
[ 1246.467938] xen-pciback: 0000:52:00.0: read 4 bytes at 0x14 = 0
[ 1246.468083] xen-pciback: 0000:52:00.0: read 4 bytes at 0x18
[ 1246.468257] xen-pciback: 0000:52:00.0: read 4 bytes at 0x18 = 0
[ 1246.468441] xen-pciback: 0000:52:00.0: read 4 bytes at 0x1c
[ 1246.468666] xen-pciback: 0000:52:00.0: read 4 bytes at 0x1c = 0
[ 1246.468820] xen-pciback: 0000:52:00.0: read 4 bytes at 0x20
[ 1246.469020] xen-pciback: 0000:52:00.0: read 4 bytes at 0x20 = 0
[ 1246.469177] xen-pciback: 0000:52:00.0: read 4 bytes at 0x24
[ 1246.469366] xen-pciback: 0000:52:00.0: read 4 bytes at 0x24 = 0
[ 1246.469477] xen-pciback: 0000:52:00.0: read 4 bytes at 0x28
[ 1246.469665] xen-pciback: 0000:52:00.0: read 4 bytes at 0x28 = 0
[ 1246.469766] xen-pciback: 0000:52:00.0: read 4 bytes at 0x2c
[ 1246.469967] xen-pciback: 0000:52:00.0: read 4 bytes at 0x2c = 808086
[ 1246.470090] xen-pciback: 0000:52:00.0: read 4 bytes at 0x30
[ 1246.470279] xen-pciback: 0000:52:00.0: read 4 bytes at 0x30 = 0
[ 1246.470380] xen-pciback: 0000:52:00.0: read 4 bytes at 0x34
[ 1246.470566] xen-pciback: 0000:52:00.0: read 4 bytes at 0x34 = c8
[ 1246.470658] xen-pciback: 0000:52:00.0: read 4 bytes at 0x38
[ 1246.470844] xen-pciback: 0000:52:00.0: read 4 bytes at 0x38 = 0
[ 1246.470951] xen-pciback: 0000:52:00.0: read 4 bytes at 0x3c
[ 1246.471389] xen-pciback: 0000:52:00.0: read 4 bytes at 0x3c = 112
[ 1246.471625] xen-pciback: 0000:52:00.0: read 4 bytes at 0x0
[ 1246.471813] xen-pciback: 0000:52:00.0: read 4 bytes at 0x0 = 27238086
[ 1246.471912] xen-pciback: 0000:52:00.0: read 4 bytes at 0x4
[ 1246.472340] xen-pciback: 0000:52:00.0: read 4 bytes at 0x4 = 100406
[ 1246.472447] xen-pciback: 0000:52:00.0: read 2 bytes at 0x50
[ 1246.472635] xen-pciback: 0000:52:00.0: read 2 bytes at 0x50 = 142
[ 1246.472735] xen-pciback: 0000:52:00.0: read 4 bytes at 0x8
[ 1246.472937] xen-pciback: 0000:52:00.0: read 4 bytes at 0x8 = 280001a
[ 1246.473047] xen-pciback: 0000:52:00.0: read 2 bytes at 0x68
[ 1246.473248] xen-pciback: 0000:52:00.0: read 2 bytes at 0x68 = 405
[ 1246.473380] xen-pciback: 0000:52:00.0: read 4 bytes at 0xc
[ 1246.474284] xen-pciback: 0000:52:00.0: read 4 bytes at 0xc = 0
[ 1246.474389] xen-pciback: 0000:52:00.0: read 4 bytes at 0x10
[ 1246.474576] xen-pciback: 0000:52:00.0: read 4 bytes at 0x10 = ce100004
[ 1246.474671] xen-pciback: 0000:52:00.0: read 4 bytes at 0x14
[ 1246.474859] xen-pciback: 0000:52:00.0: read 4 bytes at 0x14 = 0
[ 1246.474956] xen-pciback: 0000:52:00.0: read 4 bytes at 0x18
[ 1246.475158] xen-pciback: 0000:52:00.0: read 4 bytes at 0x18 = 0
[ 1246.475350] xen-pciback: 0000:52:00.0: read 4 bytes at 0x1c
[ 1246.475469] xen-pciback: 0000:52:00.0: read 4 bytes at 0x1c = 0
[ 1246.475576] xen-pciback: 0000:52:00.0: read 4 bytes at 0x20
[ 1246.475754] xen-pciback: 0000:52:00.0: read 4 bytes at 0x20 = 0
[ 1246.475847] xen-pciback: 0000:52:00.0: read 4 bytes at 0x24
[ 1246.475907] xen-pciback: 0000:52:00.0: read 4 bytes at 0x24 = 0
[ 1246.475997] xen-pciback: 0000:52:00.0: read 4 bytes at 0x28
[ 1246.476160] xen-pciback: 0000:52:00.0: read 4 bytes at 0x28 = 0
[ 1246.476471] xen-pciback: 0000:52:00.0: read 4 bytes at 0x2c
[ 1246.476596] xen-pciback: 0000:52:00.0: read 4 bytes at 0x2c = 808086
[ 1246.476702] xen-pciback: 0000:52:00.0: read 4 bytes at 0x30
[ 1246.476845] xen-pciback: 0000:52:00.0: read 4 bytes at 0x30 = 0
[ 1246.476945] xen-pciback: 0000:52:00.0: read 2 bytes at 0x50
[ 1246.477108] xen-pciback: 0000:52:00.0: read 2 bytes at 0x50 = 142
[ 1246.477237] xen-pciback: 0000:52:00.0: read 4 bytes at 0x34
[ 1246.477385] xen-pciback: 0000:52:00.0: read 4 bytes at 0x34 = c8
[ 1246.477502] xen-pciback: 0000:52:00.0: read 2 bytes at 0x68
[ 1246.477645] xen-pciback: 0000:52:00.0: read 2 bytes at 0x68 = 405
[ 1246.477786] xen-pciback: 0000:52:00.0: read 4 bytes at 0x38
[ 1246.477928] xen-pciback: 0000:52:00.0: read 4 bytes at 0x38 = 0
[ 1246.478051] xen-pciback: 0000:52:00.0: read 4 bytes at 0x3c
[ 1246.478349] xen-pciback: 0000:52:00.0: read 4 bytes at 0x3c = 112
[ 1247.103874] xen-pciback: 0000:52:00.0: read 2 bytes at 0x50
[ 1247.104114] xen-pciback: 0000:52:00.0: read 2 bytes at 0x50 = 142
[ 1247.104360] xen-pciback: 0000:52:00.0: read 2 bytes at 0x68
[ 1247.104561] xen-pciback: 0000:52:00.0: read 2 bytes at 0x68 = 405
[ 1247.125733] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2
[ 1247.125978] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2 = 81
[ 1247.126299] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2
[ 1247.126746] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2 = 81
[ 1247.126846] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2
[ 1247.127291] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2 = 81
[ 1247.127352] xen-pciback: 0000:52:00.0: write request 2 bytes at 0xd2 = 80
[ 1247.131481] xen-pciback: 0000:52:00.0: write request 2 bytes at 0xd2 = 80
[ 1247.132412] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4
[ 1247.132840] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4 = 406
[ 1247.132976] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4
[ 1247.133421] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4 = 406
[ 1247.133467] xen-pciback: 0000:52:00.0: write request 2 bytes at 0x4 = 6
[ 1247.133797] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4
[ 1247.134089] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4 = 6
[ 1247.134193] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4
[ 1247.134431] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4 = 6
[ 1247.134525] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4
[ 1247.134763] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4 = 6
[ 1247.135001] xen-pciback: 0000:52:00.0: write request 2 bytes at 0x4 = 2
[ 1247.135221] xen-pciback: 0000:52:00.0: clear bus master
[ 1247.136311] xen-pciback: 0000:52:00.0: read 2 bytes at 0xcc
[ 1247.136736] xen-pciback: 0000:52:00.0: read 2 bytes at 0xcc = 8
[ 1247.136857] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4
[ 1247.137284] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4 = 2
[ 1247.137601] xen-pciback: 0000:52:00.0: read 1 bytes at 0x3d
[ 1247.137839] xen-pciback: 0000:52:00.0: read 1 bytes at 0x3d = 1
[ 1247.137939] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4
[ 1247.138365] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4 = 2
[ 1247.138498] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4
[ 1247.138737] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4 = 2
[ 1247.138844] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4
[ 1247.139271] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4 = 2
[ 1247.139503] xen-pciback: 0000:52:00.0: write request 2 bytes at 0x4 = 6
[ 1247.139689] xen-pciback: 0000:52:00.0: set bus master
[ 1247.139910] xen-pciback: 0000:52:00.0: read 1 bytes at 0x41
[ 1247.140143] xen-pciback: 0000:52:00.0: read 1 bytes at 0x41 = 80
[ 1247.143671] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2
[ 1247.143863] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2 = 80
[ 1247.143958] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2
[ 1247.144285] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2 = 80
[ 1247.144393] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2
[ 1247.144585] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2 = 80
[ 1247.144601] xen-pciback: 0000:52:00.0: write request 2 bytes at 0xd2 = 80
[ 1247.144947] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2
[ 1247.145240] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2 = 80
[ 1247.145341] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2
[ 1247.145535] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2 = 80
[ 1247.145625] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2
[ 1247.145816] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2 = 80
[ 1247.145832] xen-pciback: 0000:52:00.0: write request 2 bytes at 0xd2 = 80
[ 1247.146392] xen-pciback: 0000:52:00.0: read 4 bytes at 0xd4
[ 1247.146536] xen-pciback: 0000:52:00.0: read 4 bytes at 0xd4 = fee00798
[ 1247.146633] xen-pciback: 0000:52:00.0: read 4 bytes at 0xd8
[ 1247.146776] xen-pciback: 0000:52:00.0: read 4 bytes at 0xd8 = 0
[ 1247.146889] xen-pciback: 0000:52:00.0: read 2 bytes at 0xdc
[ 1247.147058] xen-pciback: 0000:52:00.0: read 2 bytes at 0xdc = 0
[ 1247.147175] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4
[ 1247.147383] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4 = 6
[ 1247.147492] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4
[ 1247.147684] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4 = 6
[ 1247.147738] xen-pciback: 0000:52:00.0: write request 2 bytes at 0x4 = 406
[ 1247.148018] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2
[ 1247.148330] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2 = 80
[ 1247.148456] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2
[ 1247.148650] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2 = 80
[ 1247.149184] xen-pciback: 0000:52:00.0: write request 2 bytes at 0xd2 = 81
[ 1247.157957] xen-pciback: 0000:52:00.0: read 2 bytes at 0x50
[ 1247.158186] xen-pciback: 0000:52:00.0: read 2 bytes at 0x50 = 142
[ 1247.158388] xen-pciback: 0000:52:00.0: read 2 bytes at 0x68
[ 1247.158591] xen-pciback: 0000:52:00.0: read 2 bytes at 0x68 = 405
[ 1247.161910] xen-pciback: 0000:52:00.0: read 2 bytes at 0x50
[ 1247.162081] xen-pciback: 0000:52:00.0: read 2 bytes at 0x50 = 142
[ 1247.162285] xen-pciback: 0000:52:00.0: read 2 bytes at 0x68
[ 1247.162441] xen-pciback: 0000:52:00.0: read 2 bytes at 0x68 = 405
[ 1247.378726] xen-pciback: 0000:52:00.0: read 4 bytes at 0x0
[ 1247.378931] xen-pciback: 0000:52:00.0: read 4 bytes at 0x0 = 27238086
[ 1247.379478] xen-pciback: 0000:52:00.0: read 4 bytes at 0x4
[ 1247.379919] xen-pciback: 0000:52:00.0: read 4 bytes at 0x4 = 100406
[ 1247.380439] xen-pciback: 0000:52:00.0: read 4 bytes at 0x8
[ 1247.380642] xen-pciback: 0000:52:00.0: read 4 bytes at 0x8 = 280001a
[ 1247.382961] xen-pciback: 0000:52:00.0: read 4 bytes at 0xc
[ 1247.383891] xen-pciback: 0000:52:00.0: read 4 bytes at 0xc = 0
[ 1247.384430] xen-pciback: 0000:52:00.0: read 4 bytes at 0x10
[ 1247.384631] xen-pciback: 0000:52:00.0: read 4 bytes at 0x10 = ce100004
[ 1247.385104] xen-pciback: 0000:52:00.0: read 4 bytes at 0x14
[ 1247.385305] xen-pciback: 0000:52:00.0: read 4 bytes at 0x14 = 0
[ 1247.385772] xen-pciback: 0000:52:00.0: read 4 bytes at 0x18
[ 1247.385980] xen-pciback: 0000:52:00.0: read 4 bytes at 0x18 = 0
[ 1247.386432] xen-pciback: 0000:52:00.0: read 4 bytes at 0x1c
[ 1247.386636] xen-pciback: 0000:52:00.0: read 4 bytes at 0x1c = 0
[ 1247.387095] xen-pciback: 0000:52:00.0: read 4 bytes at 0x20
[ 1247.387292] xen-pciback: 0000:52:00.0: read 4 bytes at 0x20 = 0
[ 1247.387763] xen-pciback: 0000:52:00.0: read 4 bytes at 0x24
[ 1247.387963] xen-pciback: 0000:52:00.0: read 4 bytes at 0x24 = 0
[ 1247.388451] xen-pciback: 0000:52:00.0: read 4 bytes at 0x28
[ 1247.388650] xen-pciback: 0000:52:00.0: read 4 bytes at 0x28 = 0
[ 1247.389121] xen-pciback: 0000:52:00.0: read 4 bytes at 0x2c
[ 1247.389331] xen-pciback: 0000:52:00.0: read 4 bytes at 0x2c = 808086
[ 1247.389970] xen-pciback: 0000:52:00.0: read 4 bytes at 0x30
[ 1247.390245] xen-pciback: 0000:52:00.0: read 4 bytes at 0x30 = 0
[ 1247.391125] xen-pciback: 0000:52:00.0: read 4 bytes at 0x34
[ 1247.391329] xen-pciback: 0000:52:00.0: read 4 bytes at 0x34 = c8
[ 1247.391836] xen-pciback: 0000:52:00.0: read 4 bytes at 0x38
[ 1247.392111] xen-pciback: 0000:52:00.0: read 4 bytes at 0x38 = 0
[ 1247.392524] xen-pciback: 0000:52:00.0: read 4 bytes at 0x3c
[ 1247.392961] xen-pciback: 0000:52:00.0: read 4 bytes at 0x3c = 112
[ 1247.396070] xen-pciback: 0000:52:00.0: read 4 bytes at 0x0
[ 1247.396272] xen-pciback: 0000:52:00.0: read 4 bytes at 0x0 = 27238086
[ 1247.396677] xen-pciback: 0000:52:00.0: read 4 bytes at 0x4
[ 1247.397177] xen-pciback: 0000:52:00.0: read 4 bytes at 0x4 = 100406
[ 1247.397812] xen-pciback: 0000:52:00.0: read 4 bytes at 0x8
[ 1247.398011] xen-pciback: 0000:52:00.0: read 4 bytes at 0x8 = 280001a
[ 1247.398785] xen-pciback: 0000:52:00.0: read 4 bytes at 0xc
[ 1247.399711] xen-pciback: 0000:52:00.0: read 4 bytes at 0xc = 0
[ 1247.400186] xen-pciback: 0000:52:00.0: read 4 bytes at 0x10
[ 1247.400390] xen-pciback: 0000:52:00.0: read 4 bytes at 0x10 = ce100004
[ 1247.401363] xen-pciback: 0000:52:00.0: read 4 bytes at 0x14
[ 1247.401570] xen-pciback: 0000:52:00.0: read 4 bytes at 0x14 = 0
[ 1247.402232] xen-pciback: 0000:52:00.0: read 4 bytes at 0x18
[ 1247.402454] xen-pciback: 0000:52:00.0: read 4 bytes at 0x18 = 0
[ 1247.403131] xen-pciback: 0000:52:00.0: read 4 bytes at 0x1c
[ 1247.403334] xen-pciback: 0000:52:00.0: read 4 bytes at 0x1c = 0
[ 1247.404008] xen-pciback: 0000:52:00.0: read 4 bytes at 0x20
[ 1247.404270] xen-pciback: 0000:52:00.0: read 4 bytes at 0x20 = 0
[ 1247.404890] xen-pciback: 0000:52:00.0: read 4 bytes at 0x24
[ 1247.405180] xen-pciback: 0000:52:00.0: read 4 bytes at 0x24 = 0
[ 1247.408062] xen-pciback: 0000:52:00.0: read 4 bytes at 0x28
[ 1247.408249] xen-pciback: 0000:52:00.0: read 4 bytes at 0x28 = 0
[ 1247.408366] xen-pciback: 0000:52:00.0: read 4 bytes at 0x2c
[ 1247.408576] xen-pciback: 0000:52:00.0: read 4 bytes at 0x2c = 808086
[ 1247.408708] xen-pciback: 0000:52:00.0: read 4 bytes at 0x30
[ 1247.408909] xen-pciback: 0000:52:00.0: read 4 bytes at 0x30 = 0
[ 1247.409022] xen-pciback: 0000:52:00.0: read 4 bytes at 0x34
[ 1247.409276] xen-pciback: 0000:52:00.0: read 4 bytes at 0x34 = c8
[ 1247.409487] xen-pciback: 0000:52:00.0: read 4 bytes at 0x38
[ 1247.409677] xen-pciback: 0000:52:00.0: read 4 bytes at 0x38 = 0
[ 1247.409798] xen-pciback: 0000:52:00.0: read 4 bytes at 0x3c
[ 1247.410073] xen-pciback: 0000:52:00.0: read 4 bytes at 0x3c = 112
[ 1247.427613] xen-pciback: 0000:52:00.0: read 2 bytes at 0x50
[ 1247.427803] xen-pciback: 0000:52:00.0: read 2 bytes at 0x50 = 142
[ 1247.427931] xen-pciback: 0000:52:00.0: read 2 bytes at 0x68
[ 1247.428172] xen-pciback: 0000:52:00.0: read 2 bytes at 0x68 = 405
[ 1247.431682] xen-pciback: 0000:52:00.0: read 2 bytes at 0x50
[ 1247.431842] xen-pciback: 0000:52:00.0: read 2 bytes at 0x50 = 142
[ 1247.431989] xen-pciback: 0000:52:00.0: read 2 bytes at 0x68
[ 1247.432192] xen-pciback: 0000:52:00.0: read 2 bytes at 0x68 = 405
[ 1248.246635] xen-pciback: 0000:52:00.0: read 2 bytes at 0x50
[ 1248.246844] xen-pciback: 0000:52:00.0: read 2 bytes at 0x50 = 142
[ 1248.247022] xen-pciback: 0000:52:00.0: read 2 bytes at 0x68
[ 1248.247259] xen-pciback: 0000:52:00.0: read 2 bytes at 0x68 = 405
[ 1248.250589] xen-pciback: 0000:52:00.0: read 2 bytes at 0x50
[ 1248.250751] xen-pciback: 0000:52:00.0: read 2 bytes at 0x50 = 142
[ 1248.250979] xen-pciback: 0000:52:00.0: read 2 bytes at 0x68
[ 1248.251143] xen-pciback: 0000:52:00.0: read 2 bytes at 0x68 = 405
[ 1249.059964] xen-pciback: 0000:52:00.0: read 4 bytes at 0x0
[ 1249.060217] xen-pciback: 0000:52:00.0: read 4 bytes at 0x0 = 27238086
[ 1249.060626] xen-pciback: 0000:52:00.0: read 4 bytes at 0x4
[ 1249.061074] xen-pciback: 0000:52:00.0: read 4 bytes at 0x4 = 100406
[ 1249.061280] xen-pciback: 0000:52:00.0: read 4 bytes at 0x8
[ 1249.061482] xen-pciback: 0000:52:00.0: read 4 bytes at 0x8 = 280001a
[ 1249.061667] xen-pciback: 0000:52:00.0: read 4 bytes at 0xc
[ 1249.062009] xen-pciback: 0000:52:00.0: read 4 bytes at 0xc = 0
[ 1249.062160] xen-pciback: 0000:52:00.0: read 4 bytes at 0x10
[ 1249.062350] xen-pciback: 0000:52:00.0: read 4 bytes at 0x10 = ce100004
[ 1249.062511] xen-pciback: 0000:52:00.0: read 4 bytes at 0x14
[ 1249.062700] xen-pciback: 0000:52:00.0: read 4 bytes at 0x14 = 0
[ 1249.062851] xen-pciback: 0000:52:00.0: read 4 bytes at 0x18
[ 1249.063085] xen-pciback: 0000:52:00.0: read 4 bytes at 0x18 = 0
[ 1249.063317] xen-pciback: 0000:52:00.0: read 4 bytes at 0x1c
[ 1249.063506] xen-pciback: 0000:52:00.0: read 4 bytes at 0x1c = 0
[ 1249.063624] xen-pciback: 0000:52:00.0: read 4 bytes at 0x20
[ 1249.063813] xen-pciback: 0000:52:00.0: read 4 bytes at 0x20 = 0
[ 1249.063916] xen-pciback: 0000:52:00.0: read 4 bytes at 0x24
[ 1249.064121] xen-pciback: 0000:52:00.0: read 4 bytes at 0x24 = 0
[ 1249.064235] xen-pciback: 0000:52:00.0: read 4 bytes at 0x28
[ 1249.064423] xen-pciback: 0000:52:00.0: read 4 bytes at 0x28 = 0
[ 1249.064529] xen-pciback: 0000:52:00.0: read 4 bytes at 0x2c
[ 1249.064717] xen-pciback: 0000:52:00.0: read 4 bytes at 0x2c = 808086
[ 1249.064818] xen-pciback: 0000:52:00.0: read 4 bytes at 0x30
[ 1249.065007] xen-pciback: 0000:52:00.0: read 4 bytes at 0x30 = 0
[ 1249.065127] xen-pciback: 0000:52:00.0: read 4 bytes at 0x34
[ 1249.065317] xen-pciback: 0000:52:00.0: read 4 bytes at 0x34 = c8
[ 1249.065432] xen-pciback: 0000:52:00.0: read 4 bytes at 0x38
[ 1249.065620] xen-pciback: 0000:52:00.0: read 4 bytes at 0x38 = 0
[ 1249.065722] xen-pciback: 0000:52:00.0: read 4 bytes at 0x3c
[ 1249.066065] xen-pciback: 0000:52:00.0: read 4 bytes at 0x3c = 112
[ 1249.066366] xen-pciback: 0000:52:00.0: read 4 bytes at 0x0
[ 1249.066555] xen-pciback: 0000:52:00.0: read 4 bytes at 0x0 = 27238086
[ 1249.066661] xen-pciback: 0000:52:00.0: read 4 bytes at 0x4
[ 1249.066899] xen-pciback: 0000:52:00.0: read 4 bytes at 0x4 = 100406
[ 1249.067003] xen-pciback: 0000:52:00.0: read 4 bytes at 0x8
[ 1249.067207] xen-pciback: 0000:52:00.0: read 4 bytes at 0x8 = 280001a
[ 1249.067311] xen-pciback: 0000:52:00.0: read 4 bytes at 0xc
[ 1249.067656] xen-pciback: 0000:52:00.0: read 4 bytes at 0xc = 0
[ 1249.067760] xen-pciback: 0000:52:00.0: read 4 bytes at 0x10
[ 1249.067948] xen-pciback: 0000:52:00.0: read 4 bytes at 0x10 = ce100004
[ 1249.068055] xen-pciback: 0000:52:00.0: read 4 bytes at 0x14
[ 1249.068243] xen-pciback: 0000:52:00.0: read 4 bytes at 0x14 = 0
[ 1249.068354] xen-pciback: 0000:52:00.0: read 4 bytes at 0x18
[ 1249.068541] xen-pciback: 0000:52:00.0: read 4 bytes at 0x18 = 0
[ 1249.068638] xen-pciback: 0000:52:00.0: read 4 bytes at 0x1c
[ 1249.068826] xen-pciback: 0000:52:00.0: read 4 bytes at 0x1c = 0
[ 1249.068940] xen-pciback: 0000:52:00.0: read 4 bytes at 0x20
[ 1249.069152] xen-pciback: 0000:52:00.0: read 4 bytes at 0x20 = 0
[ 1249.069275] xen-pciback: 0000:52:00.0: read 4 bytes at 0x24
[ 1249.069467] xen-pciback: 0000:52:00.0: read 4 bytes at 0x24 = 0
[ 1249.069563] xen-pciback: 0000:52:00.0: read 4 bytes at 0x28
[ 1249.069750] xen-pciback: 0000:52:00.0: read 4 bytes at 0x28 = 0
[ 1249.069845] xen-pciback: 0000:52:00.0: read 4 bytes at 0x2c
[ 1249.070050] xen-pciback: 0000:52:00.0: read 4 bytes at 0x2c = 808086
[ 1249.070153] xen-pciback: 0000:52:00.0: read 4 bytes at 0x30
[ 1249.070342] xen-pciback: 0000:52:00.0: read 4 bytes at 0x30 = 0
[ 1249.070437] xen-pciback: 0000:52:00.0: read 4 bytes at 0x34
[ 1249.070621] xen-pciback: 0000:52:00.0: read 4 bytes at 0x34 = c8
[ 1249.070718] xen-pciback: 0000:52:00.0: read 4 bytes at 0x38
[ 1249.070900] xen-pciback: 0000:52:00.0: read 4 bytes at 0x38 = 0
[ 1249.070998] xen-pciback: 0000:52:00.0: read 4 bytes at 0x3c
[ 1249.071419] xen-pciback: 0000:52:00.0: read 4 bytes at 0x3c = 112
[ 1249.082701] xen-pciback: 0000:52:00.0: read 2 bytes at 0x50
[ 1249.082889] xen-pciback: 0000:52:00.0: read 2 bytes at 0x50 = 142
[ 1249.082988] xen-pciback: 0000:52:00.0: read 2 bytes at 0x68
[ 1249.083217] xen-pciback: 0000:52:00.0: read 2 bytes at 0x68 = 405
[ 1252.032137] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2
[ 1252.032577] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2 = 81
[ 1252.033215] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2
[ 1252.033656] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2 = 81
[ 1252.033953] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2
[ 1252.034446] xen-pciback: 0000:52:00.0: read 2 bytes at 0xd2 = 81
[ 1252.034758] xen-pciback: 0000:52:00.0: write request 2 bytes at 0xd2 = 80
[ 1252.036186] xen-pciback: 0000:52:00.0: write request 2 bytes at 0xd2 = 80
[ 1252.037476] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4
[ 1252.037918] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4 = 406
[ 1252.038398] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4
[ 1252.038839] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4 = 406
[ 1252.039014] xen-pciback: 0000:52:00.0: write request 2 bytes at 0x4 = 6
[ 1252.039802] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4
[ 1252.040310] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4 = 6
[ 1252.040835] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4
[ 1252.041294] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4 = 6
[ 1252.041744] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4
[ 1252.042278] xen-pciback: 0000:52:00.0: read 2 bytes at 0x4 = 6
[ 1252.043208] xen-pciback: 0000:52:00.0: write request 2 bytes at 0x4 = 2
[ 1252.043408] xen-pciback: 0000:52:00.0: clear bus master

Ran rmmod iwlmvm && rmmod iwlwifi which outputted the last line.

2. Some extended PCIe feature that driver/firmware assumes present is not implemented in PCI passthrough.

For the second hypothesis, I'd take lspci -vv of the device from both
sys-net and dom0 (preferably in exact the same time, during enabling the
interface, but that's unrealistic). And compare.

In dom0:

52:00.0 Network controller: Intel Corporation Device 2723 (rev 1a)
        Subsystem: Intel Corporation Device 0080
        Control: I/O- Mem+ BusMaster- SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
        Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
        Interrupt: pin A routed to IRQ 18
        Region 0: Memory at ce100000 (64-bit, non-prefetchable) [size=16K]
        Capabilities: [c8] Power Management version 3
                Flags: PMEClk- DSI+ D1- D2- AuxCurrent=0mA PME(D0+,D1-,D2-,D3hot+,D3cold+)
                Status: D0 NoSoftRst+ PME-Enable- DSel=0 DScale=0 PME-
        Capabilities: [d0] MSI: Enable- Count=1/1 Maskable- 64bit+
                Address: 00000000fee00758  Data: 0000
        Capabilities: [40] Express (v2) Endpoint, MSI 00
                DevCap: MaxPayload 128 bytes, PhantFunc 0, Latency L0s <512ns, L1 unlimited
                        ExtTag- AttnBtn- AttnInd- PwrInd- RBE+ FLReset+ SlotPowerLimit 0.000W
                DevCtl: Report errors: Correctable- Non-Fatal- Fatal- Unsupported-
                        RlxdOrd+ ExtTag- PhantFunc- AuxPwr+ NoSnoop+ FLReset-
                        MaxPayload 128 bytes, MaxReadReq 128 bytes
                DevSta: CorrErr- UncorrErr- FatalErr- UnsuppReq- AuxPwr+ TransPend-
                LnkCap: Port #0, Speed 5GT/s, Width x1, ASPM L1, Exit Latency L1 <8us
                        ClockPM+ Surprise- LLActRep- BwNot- ASPMOptComp+
                LnkCtl: ASPM L1 Enabled; RCB 64 bytes Disabled- CommClk+
                        ExtSynch- ClockPM+ AutWidDis- BWInt- AutBWInt-
                LnkSta: Speed 5GT/s, Width x1, TrErr- Train- SlotClk+ DLActive- BWMgmt- ABWMgmt-
                DevCap2: Completion Timeout: Range B, TimeoutDis+, LTR+, OBFF Via WAKE#
                         AtomicOpsCap: 32bit- 64bit- 128bitCAS-
                DevCtl2: Completion Timeout: 16ms to 55ms, TimeoutDis-, LTR+, OBFF Disabled
                         AtomicOpsCtl: ReqEn-
                LnkCtl2: Target Link Speed: 5GT/s, EnterCompliance- SpeedDis-
                         Transmit Margin: Normal Operating Range, EnterModifiedCompliance- ComplianceSOS-
                         Compliance De-emphasis: -6dB
                LnkSta2: Current De-emphasis Level: -6dB, EqualizationComplete-, EqualizationPhase1-
                         EqualizationPhase2-, EqualizationPhase3-, LinkEqualizationRequest-
        Capabilities: [80] MSI-X: Enable- Count=16 Masked-
                Vector table: BAR=0 offset=00002000
                PBA: BAR=0 offset=00003000
        Capabilities: [100 v1] Advanced Error Reporting
                UESta:  DLP- SDES- TLP- FCP- CmpltTO- CmpltAbrt- UnxCmplt- RxOF- MalfTLP- ECRC- UnsupReq- ACSViol-
                UEMsk:  DLP- SDES- TLP- FCP- CmpltTO- CmpltAbrt- UnxCmplt- RxOF- MalfTLP- ECRC- UnsupReq- ACSViol-
                UESvrt: DLP+ SDES+ TLP- FCP+ CmpltTO- CmpltAbrt- UnxCmplt- RxOF+ MalfTLP+ ECRC- UnsupReq- ACSViol-
                CESta:  RxErr- BadTLP- BadDLLP- Rollover- Timeout- NonFatalErr-
                CEMsk:  RxErr- BadTLP- BadDLLP- Rollover- Timeout- NonFatalErr+
                AERCap: First Error Pointer: 00, ECRCGenCap- ECRCGenEn- ECRCChkCap- ECRCChkEn-
                        MultHdrRecCap- MultHdrRecEn- TLPPfxPres- HdrLogCap-
                HeaderLog: 00000000 00000000 00000000 00000000
        Capabilities: [14c v1] Latency Tolerance Reporting
                Max snoop latency: 0ns
                Max no snoop latency: 0ns
        Capabilities: [154 v1] L1 PM Substates
                L1SubCap: PCI-PM_L1.2+ PCI-PM_L1.1+ ASPM_L1.2+ ASPM_L1.1+ L1_PM_Substates+
                          PortCommonModeRestoreTime=30us PortTPowerOnTime=18us
                L1SubCtl1: PCI-PM_L1.2+ PCI-PM_L1.1+ ASPM_L1.2+ ASPM_L1.1+
                           T_CommonMode=0us LTR1.2_Threshold=81920ns
                L1SubCtl2: T_PwrOn=60us
        Kernel driver in use: pciback

In sys-net:

00:07.0 Network controller: Intel Corporation Device 2723 (rev 1a)
	Subsystem: Intel Corporation Device 0080
	Physical Slot: 7
	Control: I/O- Mem+ BusMaster- SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
	Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
	Interrupt: pin A routed to IRQ 44
	Region 0: Memory at f2044000 (64-bit, non-prefetchable) [size=16K]
	Capabilities: [c8] Power Management version 3
		Flags: PMEClk- DSI+ D1- D2- AuxCurrent=0mA PME(D0-,D1-,D2-,D3hot-,D3cold-)
		Status: D0 NoSoftRst+ PME-Enable- DSel=0 DScale=0 PME-
	Capabilities: [d0] MSI: Enable- Count=1/1 Maskable- 64bit+
		Address: 00000000fee96000  Data: 4300
	Capabilities: [40] Express (v2) Endpoint, MSI 00
		DevCap:	MaxPayload 128 bytes, PhantFunc 0, Latency L0s <512ns, L1 unlimited
			ExtTag- AttnBtn- AttnInd- PwrInd- RBE+ FLReset- SlotPowerLimit 0.000W
		DevCtl:	Report errors: Correctable- Non-Fatal- Fatal- Unsupported-
			RlxdOrd+ ExtTag- PhantFunc- AuxPwr- NoSnoop+
			MaxPayload 128 bytes, MaxReadReq 512 bytes
		DevSta:	CorrErr- UncorrErr- FatalErr- UnsuppReq- AuxPwr+ TransPend-
		LnkCap:	Port #0, Speed 5GT/s, Width x1, ASPM L1, Exit Latency L0s <4us, L1 <8us
			ClockPM+ Surprise- LLActRep- BwNot- ASPMOptComp+
		LnkCtl:	ASPM Disabled; RCB 64 bytes Disabled- CommClk-
			ExtSynch- ClockPM- AutWidDis- BWInt- AutBWInt-
		LnkSta:	Speed 5GT/s, Width x1, TrErr- Train- SlotClk+ DLActive- BWMgmt- ABWMgmt-
		DevCap2: Completion Timeout: Range B, TimeoutDis+, LTR+, OBFF Via WAKE#
		DevCtl2: Completion Timeout: 50us to 50ms, TimeoutDis-, LTR-, OBFF Disabled
		LnkSta2: Current De-emphasis Level: -6dB, EqualizationComplete-, EqualizationPhase1-
			 EqualizationPhase2-, EqualizationPhase3-, LinkEqualizationRequest-
	Kernel modules: iwlwifi

I'll rerun 1 with permissive and also test 3 with sudo trace-cmd record -e iwlwifi -e mac80211 -e cfg80211 -e iwlwifi_msg as per https://wireless.wiki.kernel.org/en/users/drivers/iwlwifi/debugging

@marmarek
Copy link
Member

marmarek commented Apr 13, 2020

Thanks for the logs @boundless-thread !

@pwmarcz recently did some debugging here and identified a workaround. Mail to linux-wireless mailing list is here: https://lore.kernel.org/linux-wireless/3cab5072-17a2-4d9a-2077-93788971c6c4@invisiblethingslab.com/T/#u

@v6ak
Copy link

v6ak commented Apr 14, 2020 via email

@process0
Copy link

process0 commented Apr 20, 2020

@marmarek & @pwmarcz : Have you tried testing it against https://lore.kernel.org/linux-wireless/20200323131925.208376-1-luca@coelho.fi/ to see if it resolves this issue?

It might be useful to CC them.

Edit: I've tried the latest mainline (5.7.0 rc2) on fedora 30 and still get the same errors, so the link above hasn't fixed anything.

@adubois
Copy link

adubois commented May 20, 2020

Thanks for the logs @boundless-thread !

@pwmarcz recently did some debugging here and identified a workaround. Mail to linux-wireless mailing list is here: https://lore.kernel.org/linux-wireless/3cab5072-17a2-4d9a-2077-93788971c6c4@invisiblethingslab.com/T/#u

I confirm this work around makes the AX200 WiFi operational on a Lenovo P53 with kernel 5.6.13-1. Bluetooth is not working as not initialized AFAIU.

The code bypassed seems to be used to initialize each (but the default?) queues:
{q_num, DMA address of free BD cyclic buffer, DMA address of urbd_stts_wrptr, DMA address of used BD cyclic buffer, Initial write index of the free BD cyclic buffer} if it helps someone who investigates on the Xen side.

@prdn
Copy link

prdn commented Aug 25, 2020

I'm having the same issue.
Did any new kernel solve the issue?
Otherwise would it be ok to create a branch (ie. stable-5.4-ax200) in the qubes-linux-kernel project that patches the kernel automatically?

@andrewdavidwong andrewdavidwong added the needs diagnosis Requires technical diagnosis from developer. Replace with "diagnosed" or remove if otherwise closed. label Aug 25, 2020
pwmarcz added a commit to pwmarcz/qubes-linux-kernel that referenced this issue Sep 23, 2020
See QubesOS/qubes-issues#5615.

To enable, add `iwlwifi.disable_rxq=1` to kernel options:

    opt=$(qvm-prefs --get sys-net kernelopts)
    qvm-prefs --set sys-net kernelopts "$opt iwlwifi.disable_rxq=1"
@pwmarcz
Copy link

pwmarcz commented Oct 1, 2020

There is a workaround in the newest Qubes kernel (see QubesOS/qubes-linux-kernel#275) that basically enables the bypass mentioned above. To enable:

  • install kernel-latest-qubes-vm version 5.8.11-3 in dom0 (available via the current-testing repository)
  • shut down sys-net
  • go to qube settings for sys-net, use 5.8.11-3 kernel
  • add iwlwifi.disable_rxq=1 to kernel options for sys-net:
opt=$(qvm-prefs --get sys-net kernelopts)
qvm-prefs --set sys-net kernelopts "$opt iwlwifi.disable_rxq=1"
  • start sys-net again

@ejose19
Copy link

ejose19 commented Oct 1, 2020

@pwmarcz Thanks for making such a simple and practical solution, as WiFi 6 is becoming more common in newer mobos I'm sure new users will appreciate it as well. Confirmed it works following those steps.

@aslfv
Copy link

aslfv commented Sep 3, 2022

I am happy to confirm that my wifi (AX211) is now working after performing the patch as described by @scsich. I am happy not only because I can get rid of my usb adaptors, but also because of the joyful/interesting experience and gained ability to patch my system :) @marmarek I still look forward to the new kernel. Many thanks to all again, including @jandryuk !

@andrewdavidwong andrewdavidwong added diagnosed Technical diagnosis has been performed (see issue comments). and removed needs diagnosis Requires technical diagnosis from developer. Replace with "diagnosed" or remove if otherwise closed. labels Sep 3, 2022
marmarek added a commit to QubesOS/qubes-linux-kernel that referenced this issue Sep 3, 2022
@qubesos-bot
Copy link

Automated announcement from builder-github

The component linux-kernel-latest (including package kernel-latest-5.19.6-1.fc32.qubes) has been pushed to the r4.1 testing repository for dom0.
To test this update, please install it with the following command:

sudo qubes-dom0-update --enablerepo=qubes-dom0-current-testing

Changes included in this update

@qubesos-bot
Copy link

Automated announcement from builder-github

The component linux-kernel (including package kernel-5.15.64-1.fc32.qubes) has been pushed to the r4.1 testing repository for dom0.
To test this update, please install it with the following command:

sudo qubes-dom0-update --enablerepo=qubes-dom0-current-testing

Changes included in this update

@qubesos-bot
Copy link

Automated announcement from builder-github

The component linux-kernel (including package kernel-5.15.64-1.fc32.qubes) has been pushed to the r4.1 stable repository for dom0.
To install this update, please use the standard update command:

sudo qubes-dom0-update

Or update dom0 via Qubes Manager.

Changes included in this update

@qubesos-bot
Copy link

Automated announcement from builder-github

The component linux-kernel-latest (including package kernel-latest-6.0.2-2.fc32.qubes) has been pushed to the r4.1 stable repository for dom0.
To install this update, please use the standard update command:

sudo qubes-dom0-update

Or update dom0 via Qubes Manager.

Changes included in this update

@qubesos-bot
Copy link

Automated announcement from builder-github

The component linux-kernel (including package kernel-6.1.26-1.qubes.fc32) has been pushed to the r4.1 testing repository for dom0.
To test this update, please install it with the following command:

sudo qubes-dom0-update --enablerepo=qubes-dom0-current-testing

Changes included in this update

@qubesos-bot
Copy link

Automated announcement from builder-github

The component linux-kernel (including package kernel-6.1.35-1.qubes.fc32) has been pushed to the r4.1 stable repository for dom0.
To install this update, please use the standard update command:

sudo qubes-dom0-update

Or update dom0 via Qubes Manager.

Changes included in this update

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
C: kernel C: Xen diagnosed Technical diagnosis has been performed (see issue comments). hardware support P: major Priority: major. Between "default" and "critical" in severity. r4.0-dom0-stable r4.1-dom0-stable r4.2-host-stable T: bug Type: bug report. A problem or defect resulting in unintended behavior in something that exists.
Projects
None yet
Development

No branches or pull requests