VFIO Woes - still no output on VM, need help

Hi!

I’m having troubles with my VFIO setup.
I’ve managed to passthrough my RX 480 card successfully, had a display output on my monitor then the VM just froze and had to kill the VM. Thinking this was the known gpu reset bug and I was aware of the @gnif 's vendor-reset project, I followed the instructions and installed the vendor-reset module. After a system reboot, starting VM results in no image and no shutdown response.

I don’t know if this is useful information, but I hope it helps:
When I passed the guest card on the initial setup, the card fans were at fixed ~50% when launched the OS. That was a sign that my passthrough was doing something. Before adding the vendor-reset module, when I started up the VM, the card fans would ramp down and that’s when I knew the VM settings were correct. After all, I had a displaying UEFI image. After installing the module, when starting the VM, the fans didn’t ramp down and were still stuck at ~50%.

I’ve never gone as far into troubleshooting overall, first time messing with modules, so I’m as level1 as it can get regarding this stuff.
I’m at a dead-end on troubleshooting steps, so any kind of help would be appreciated :slight_smile:

I will add all the logs and information I can think of, if anything else is needed please let me know.

System: AMD 3600, Mem 24 GB, MSI B450-A PRO MAX
Host Card: GTX 650 Ti
Guest Card: Sapphire Nitro RX 480

OS: 5.10.9-arch1-1
All packages updated
Using OVMF-based guest VM

GRUB Config

GRUB_CMDLINE_LINUX_DEFAULT="loglevel=3 quiet vfio-pci.ids=1002:67df,1002:aaf0"
GRUB_CMDLINE_LINUX="amd_iommu=on iommu=pt"

mkinitcpio.conf

(...)
MODULES=(vfio_pci vfio vfio_iommu_type1 vfio_virqfd vendor-reset)
(...)
HOOKS=(base udev autodetect modconf block filesystems keyboard fsck)

IOMMU groups (some of them)

IOMMU Group 13:
        03:00.0 USB controller [0c03]: Advanced Micro Devices, Inc. [AMD] 400 Series Chipset USB 3.1 XHCI Controller [1022:43d5] (rev 01)
        03:00.1 SATA controller [0106]: Advanced Micro Devices, Inc. [AMD] 400 Series Chipset SATA Controller [1022:43c8] (rev 01)
        03:00.2 PCI bridge [0604]: Advanced Micro Devices, Inc. [AMD] 400 Series Chipset PCIe Bridge [1022:43c6] (rev 01)
        20:00.0 PCI bridge [0604]: Advanced Micro Devices, Inc. [AMD] 400 Series Chipset PCIe Port [1022:43c7] (rev 01)
        20:01.0 PCI bridge [0604]: Advanced Micro Devices, Inc. [AMD] 400 Series Chipset PCIe Port [1022:43c7] (rev 01)
        20:04.0 PCI bridge [0604]: Advanced Micro Devices, Inc. [AMD] 400 Series Chipset PCIe Port [1022:43c7] (rev 01)
        22:00.0 Ethernet controller [0200]: Realtek Semiconductor Co., Ltd. RTL8111/8168/8411 PCI Express Gigabit Ethernet Controller [10ec:8168] (rev 15)
        25:00.0 VGA compatible controller [0300]: NVIDIA Corporation GK106 [GeForce GTX 650 Ti] [10de:11c6] (rev a1)
        25:00.1 Audio device [0403]: NVIDIA Corporation GK106 HDMI Audio Controller [10de:0e0b] (rev a1)
IOMMU Group 14:
        26:00.0 VGA compatible controller [0300]: Advanced Micro Devices, Inc. [AMD/ATI] Ellesmere [Radeon RX 470/480/570/570X/580/580X/590] [1002:67df] (rev c7)
        26:00.1 Audio device [0403]: Advanced Micro Devices, Inc. [AMD/ATI] Ellesmere HDMI Audio [Radeon RX 470/480 / 570/580/590] [1002:aaf0]

Module vendor-reset is installed (correctly, I think)

$ lsmod | grep vendor_reset 
vendor_reset          110592  0
$ modinfo vendor_reset
filename:       /lib/modules/5.10.9-arch1-1/kernel/drivers/misc/vendor-reset.ko.xz
version:        0.1.0
author:         Adam Madsen <[email protected]>
author:         Geoffrey McRae <[email protected]>
license:        GPL v2
srcversion:     B3555632CE313411DE80CCF
depends:        
retpoline:      Y
name:           vendor_reset
vermagic:       5.10.9-arch1-1 SMP preempt mod_unload 
parm:           install_hook:bool

dmesg log

[Mon Jan 25 13:04:18 2021] audit: type=1130 audit(1611579859.348:323): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=libvirtd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[Mon Jan 25 13:04:18 2021] audit: type=1325 audit(1611579859.384:324): table=mangle family=2 entries=10 op=xt_replace pid=3005 comm="iptables"
[Mon Jan 25 13:04:18 2021] audit: type=1325 audit(1611579859.424:325): table=nat family=2 entries=25 op=xt_replace pid=3006 comm="iptables"
[Mon Jan 25 13:04:18 2021] audit: type=1325 audit(1611579859.478:326): table=nat family=2 entries=24 op=xt_replace pid=3007 comm="iptables"
[Mon Jan 25 13:04:18 2021] audit: type=1325 audit(1611579859.511:327): table=nat family=2 entries=23 op=xt_replace pid=3008 comm="iptables"
[Mon Jan 25 13:04:18 2021] audit: type=1325 audit(1611579859.568:328): table=nat family=2 entries=22 op=xt_replace pid=3009 comm="iptables"
[Mon Jan 25 13:04:18 2021] audit: type=1325 audit(1611579859.591:329): table=nat family=2 entries=21 op=xt_replace pid=3010 comm="iptables"
[Mon Jan 25 13:04:18 2021] audit: type=1325 audit(1611579859.634:330): table=filter family=2 entries=59 op=xt_replace pid=3011 comm="iptables"
[Mon Jan 25 13:04:18 2021] audit: type=1325 audit(1611579859.671:331): table=filter family=2 entries=58 op=xt_replace pid=3012 comm="iptables"
[Mon Jan 25 13:04:18 2021] audit: type=1325 audit(1611579859.694:332): table=filter family=2 entries=57 op=xt_replace pid=3013 comm="iptables"
[Mon Jan 25 13:04:29 2021] kauditd_printk_skb: 31 callbacks suppressed
[Mon Jan 25 13:04:29 2021] audit: type=1100 audit(1611579869.881:364): pid=3052 uid=1000 auid=1000 ses=1 msg='op=PAM:authentication grantors=pam_faillock,pam_permit,pam_faillock acct="root" exe="/usr/lib/polkit-1/polkit-agent-helper-1" hostname=luis-desktop-arch addr=? terminal=tty1 res=success'
[Mon Jan 25 13:04:29 2021] audit: type=1101 audit(1611579869.881:365): pid=3052 uid=1000 auid=1000 ses=1 msg='op=PAM:accounting grantors=pam_unix,pam_permit,pam_time acct="root" exe="/usr/lib/polkit-1/polkit-agent-helper-1" hostname=? addr=? terminal=? res=success'
[Mon Jan 25 13:04:37 2021] audit: type=1325 audit(1611579877.928:366): table=filter family=7 entries=0 op=xt_unregister pid=187 comm="kworker/u64:9"
[Mon Jan 25 13:04:37 2021] audit: type=1325 audit(1611579878.168:367): table=filter family=7 entries=0 op=xt_unregister pid=187 comm="kworker/u64:9"
[Mon Jan 25 13:04:37 2021] audit: type=1325 audit(1611579878.168:368): table=filter family=7 entries=0 op=xt_unregister pid=187 comm="kworker/u64:9"
[Mon Jan 25 13:04:42 2021] audit: type=1101 audit(1611579883.034:369): pid=3076 uid=1000 auid=1000 ses=1 msg='op=PAM:accounting grantors=pam_unix,pam_permit,pam_time acct="luis" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
[Mon Jan 25 13:04:42 2021] audit: type=1110 audit(1611579883.034:370): pid=3076 uid=1000 auid=1000 ses=1 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_env,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
[Mon Jan 25 13:04:42 2021] audit: type=1105 audit(1611579883.038:371): pid=3076 uid=1000 auid=1000 ses=1 msg='op=PAM:session_open grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
[Mon Jan 25 13:04:42 2021] audit: type=1106 audit(1611579883.044:372): pid=3076 uid=1000 auid=1000 ses=1 msg='op=PAM:session_close grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
[Mon Jan 25 13:04:42 2021] audit: type=1104 audit(1611579883.044:373): pid=3076 uid=1000 auid=1000 ses=1 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_env,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
[Mon Jan 25 13:04:45 2021] audit: type=2502 audit(1611579886.591:374): pid=2971 uid=0 auid=4294967295 ses=4294967295 msg='virt=kvm vm="win10" uuid=13a28e8d-c55c-452e-8f5b-34e58db816c3 vm-ctx=+65534:+992 img-ctx=+65534:+992 model=dac exe="/usr/bin/libvirtd" hostname=? addr=? terminal=? res=success'
[Mon Jan 25 13:04:45 2021] virbr0: port 2(vnet0) entered blocking state
[Mon Jan 25 13:04:45 2021] virbr0: port 2(vnet0) entered disabled state
[Mon Jan 25 13:04:45 2021] device vnet0 entered promiscuous mode
[Mon Jan 25 13:04:45 2021] audit: type=1700 audit(1611579886.608:375): dev=vnet0 prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295
[Mon Jan 25 13:04:45 2021] virbr0: port 2(vnet0) entered blocking state
[Mon Jan 25 13:04:45 2021] virbr0: port 2(vnet0) entered listening state
[Mon Jan 25 13:04:45 2021] audit: type=2501 audit(1611579886.608:376): pid=2971 uid=0 auid=4294967295 ses=4294967295 msg='virt=kvm resrc=net reason=open vm="win10" uuid=13a28e8d-c55c-452e-8f5b-34e58db816c3 net=52:54:00:71:07:72 path="/dev/net/tun" rdev=0A:C8 exe="/usr/bin/libvirtd" hostname=? addr=? terminal=? res=success'
[Mon Jan 25 13:04:45 2021] audit: type=1130 audit(1611579886.624:377): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[Mon Jan 25 13:04:45 2021] audit: type=2501 audit(1611579886.661:378): pid=2971 uid=0 auid=4294967295 ses=4294967295 msg='virt=kvm resrc=cgroup reason=deny vm="win10" uuid=13a28e8d-c55c-452e-8f5b-34e58db816c3 cgroup="/sys/fs/cgroup/devices/machine.slice/machine-qemu\x2d1\x2dwin10.scope/" class=all exe="/usr/bin/libvirtd" hostname=? addr=? terminal=? res=success'
[Mon Jan 25 13:04:47 2021] vfio-pci 0000:26:00.0: enabling device (0400 -> 0403)
[Mon Jan 25 13:04:47 2021] vfio-pci 0000:26:00.0: AMD_POLARIS10: version 1.1
[Mon Jan 25 13:04:47 2021] vfio-pci 0000:26:00.0: AMD_POLARIS10: performing pre-reset
[Mon Jan 25 13:04:47 2021] vfio-pci 0000:26:00.0: AMD_POLARIS10: performing reset
[Mon Jan 25 13:04:47 2021] vfio-pci 0000:26:00.0: AMD_POLARIS10: CLOCK_CNTL: 0x0, PC: 0x2a3c
[Mon Jan 25 13:04:47 2021] vfio-pci 0000:26:00.0: AMD_POLARIS10: performing post-reset
[Mon Jan 25 13:04:47 2021] vfio-pci 0000:26:00.0: AMD_POLARIS10: reset result = 0
[Mon Jan 25 13:04:47 2021] vfio-pci 0000:26:00.0: vfio_ecap_init: hiding ecap 0x19@0x270
[Mon Jan 25 13:04:47 2021] vfio-pci 0000:26:00.0: vfio_ecap_init: hiding ecap 0x1b@0x2d0
[Mon Jan 25 13:04:47 2021] vfio-pci 0000:26:00.0: vfio_ecap_init: hiding ecap 0x1e@0x370
[Mon Jan 25 13:04:47 2021] vfio-pci 0000:26:00.0: AMD_POLARIS10: version 1.1
[Mon Jan 25 13:04:47 2021] vfio-pci 0000:26:00.0: AMD_POLARIS10: performing pre-reset
[Mon Jan 25 13:04:47 2021] vfio-pci 0000:26:00.0: AMD_POLARIS10: performing reset
[Mon Jan 25 13:04:47 2021] vfio-pci 0000:26:00.0: AMD_POLARIS10: CLOCK_CNTL: 0x0, PC: 0x2a38
[Mon Jan 25 13:04:47 2021] vfio-pci 0000:26:00.0: AMD_POLARIS10: performing post-reset
[Mon Jan 25 13:04:47 2021] vfio-pci 0000:26:00.0: AMD_POLARIS10: reset result = 0
[Mon Jan 25 13:04:47 2021] virbr0: port 2(vnet0) entered learning state
[Mon Jan 25 13:04:48 2021] kauditd_printk_skb: 13 callbacks suppressed
[Mon Jan 25 13:04:48 2021] audit: type=2501 audit(1611579888.934:392): pid=2971 uid=0 auid=4294967295 ses=4294967295 msg='virt=kvm resrc=disk reason=start vm="win10" uuid=13a28e8d-c55c-452e-8f5b-34e58db816c3 old-disk="?" new-disk="/mnt/256gb/win10.qcow2" exe="/usr/bin/libvirtd" hostname=? addr=? terminal=? res=success'
[Mon Jan 25 13:04:48 2021] audit: type=2501 audit(1611579888.934:393): pid=2971 uid=0 auid=4294967295 ses=4294967295 msg='virt=kvm resrc=disk reason=start vm="win10" uuid=13a28e8d-c55c-452e-8f5b-34e58db816c3 old-disk="?" new-disk="/mnt/256gb/Win10_20H2_v2_English_x64.iso" exe="/usr/bin/libvirtd" hostname=? addr=? terminal=? res=success'
[Mon Jan 25 13:04:48 2021] audit: type=2501 audit(1611579888.934:394): pid=2971 uid=0 auid=4294967295 ses=4294967295 msg='virt=kvm resrc=net reason=start vm="win10" uuid=13a28e8d-c55c-452e-8f5b-34e58db816c3 old-net="?" new-net="52:54:00:71:07:72" exe="/usr/bin/libvirtd" hostname=? addr=? terminal=? res=success'
[Mon Jan 25 13:04:48 2021] audit: type=2501 audit(1611579888.934:395): pid=2971 uid=0 auid=4294967295 ses=4294967295 msg='virt=kvm resrc=dev reason=start vm="win10" uuid=13a28e8d-c55c-452e-8f5b-34e58db816c3 bus=pci device="0000:26:00.0" exe="/usr/bin/libvirtd" hostname=? addr=? terminal=? res=success'
[Mon Jan 25 13:04:48 2021] audit: type=2501 audit(1611579888.934:396): pid=2971 uid=0 auid=4294967295 ses=4294967295 msg='virt=kvm resrc=dev reason=start vm="win10" uuid=13a28e8d-c55c-452e-8f5b-34e58db816c3 bus=pci device="0000:26:00.1" exe="/usr/bin/libvirtd" hostname=? addr=? terminal=? res=success'
[Mon Jan 25 13:04:48 2021] audit: type=2501 audit(1611579888.934:397): pid=2971 uid=0 auid=4294967295 ses=4294967295 msg='virt=kvm resrc=dev reason=start vm="win10" uuid=13a28e8d-c55c-452e-8f5b-34e58db816c3 bus=usb device="001.003" exe="/usr/bin/libvirtd" hostname=? addr=? terminal=? res=success'
[Mon Jan 25 13:04:48 2021] audit: type=2501 audit(1611579888.934:398): pid=2971 uid=0 auid=4294967295 ses=4294967295 msg='virt=kvm resrc=dev reason=start vm="win10" uuid=13a28e8d-c55c-452e-8f5b-34e58db816c3 bus=usb device=555342207265646972646576 exe="/usr/bin/libvirtd" hostname=? addr=? terminal=? res=success'
[Mon Jan 25 13:04:48 2021] audit: type=2501 audit(1611579888.934:399): pid=2971 uid=0 auid=4294967295 ses=4294967295 msg='virt=kvm resrc=dev reason=start vm="win10" uuid=13a28e8d-c55c-452e-8f5b-34e58db816c3 bus=usb device=555342207265646972646576 exe="/usr/bin/libvirtd" hostname=? addr=? terminal=? res=success'
[Mon Jan 25 13:04:48 2021] audit: type=2501 audit(1611579888.934:400): pid=2971 uid=0 auid=4294967295 ses=4294967295 msg='virt=kvm resrc=mem reason=start vm="win10" uuid=13a28e8d-c55c-452e-8f5b-34e58db816c3 old-mem=0 new-mem=8192000 exe="/usr/bin/libvirtd" hostname=? addr=? terminal=? res=success'
[Mon Jan 25 13:04:48 2021] audit: type=2501 audit(1611579888.934:401): pid=2971 uid=0 auid=4294967295 ses=4294967295 msg='virt=kvm resrc=vcpu reason=start vm="win10" uuid=13a28e8d-c55c-452e-8f5b-34e58db816c3 old-vcpu=0 new-vcpu=6 exe="/usr/bin/libvirtd" hostname=? addr=? terminal=? res=success'
[Mon Jan 25 13:04:48 2021] vfio-pci 0000:26:00.0: Invalid PCI ROM header signature: expecting 0xaa55, got 0xffff
[Mon Jan 25 13:04:50 2021] virbr0: port 2(vnet0) entered forwarding state
[Mon Jan 25 13:04:50 2021] virbr0: topology change detected, propagating
[Mon Jan 25 13:04:55 2021] kauditd_printk_skb: 1 callbacks suppressed
[Mon Jan 25 13:04:55 2021] audit: type=1131 audit(1611579896.711:403): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[Mon Jan 25 13:05:35 2021] audit: type=1101 audit(1611579936.044:404): pid=3132 uid=1000 auid=1000 ses=1 msg='op=PAM:accounting grantors=pam_unix,pam_permit,pam_time acct="luis" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
[Mon Jan 25 13:05:35 2021] audit: type=1110 audit(1611579936.044:405): pid=3132 uid=1000 auid=1000 ses=1 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_env,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
[Mon Jan 25 13:05:35 2021] audit: type=1105 audit(1611579936.048:406): pid=3132 uid=1000 auid=1000 ses=1 msg='op=PAM:session_open grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'

Thanks!

Edit: Added dmesg logs

Your gonna have to look through dmesg and post relevant output.

I had my RX 460 would reset fine with vendor-reset but occasionally have a freeze that required rebooting the whole system to fix. Dmesg had intermittent gpu errors and after a lot of googling I finally fixed it by disabling pcie power management in bios and systemd options.

Well, after learning what dmesg is and does I found this:

[ 7073.723921] vfio-pci 0000:26:00.0: Invalid PCI ROM header signature: expecting 0xaa55, got 0xffff

This leads me to an archived thread on redhat that explains that this may be a power state issue, probability similar to what you were saying.
I will look into it and follow up, thanks :smiley:

did you modprobe the module?

Yes, can also confirm that vendor_reset is loaded with lsmod.
I’ll edit the title of this post, probably it’s some other type of misconfiguration.

Please show your complete dmesg output

[Mon Jan 25 13:04:18 2021] audit: type=1130 audit(1611579859.348:323): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=libvirtd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[Mon Jan 25 13:04:18 2021] audit: type=1325 audit(1611579859.384:324): table=mangle family=2 entries=10 op=xt_replace pid=3005 comm="iptables"
[Mon Jan 25 13:04:18 2021] audit: type=1325 audit(1611579859.424:325): table=nat family=2 entries=25 op=xt_replace pid=3006 comm="iptables"
[Mon Jan 25 13:04:18 2021] audit: type=1325 audit(1611579859.478:326): table=nat family=2 entries=24 op=xt_replace pid=3007 comm="iptables"
[Mon Jan 25 13:04:18 2021] audit: type=1325 audit(1611579859.511:327): table=nat family=2 entries=23 op=xt_replace pid=3008 comm="iptables"
[Mon Jan 25 13:04:18 2021] audit: type=1325 audit(1611579859.568:328): table=nat family=2 entries=22 op=xt_replace pid=3009 comm="iptables"
[Mon Jan 25 13:04:18 2021] audit: type=1325 audit(1611579859.591:329): table=nat family=2 entries=21 op=xt_replace pid=3010 comm="iptables"
[Mon Jan 25 13:04:18 2021] audit: type=1325 audit(1611579859.634:330): table=filter family=2 entries=59 op=xt_replace pid=3011 comm="iptables"
[Mon Jan 25 13:04:18 2021] audit: type=1325 audit(1611579859.671:331): table=filter family=2 entries=58 op=xt_replace pid=3012 comm="iptables"
[Mon Jan 25 13:04:18 2021] audit: type=1325 audit(1611579859.694:332): table=filter family=2 entries=57 op=xt_replace pid=3013 comm="iptables"
[Mon Jan 25 13:04:29 2021] kauditd_printk_skb: 31 callbacks suppressed
[Mon Jan 25 13:04:29 2021] audit: type=1100 audit(1611579869.881:364): pid=3052 uid=1000 auid=1000 ses=1 msg='op=PAM:authentication grantors=pam_faillock,pam_permit,pam_faillock acct="root" exe="/usr/lib/polkit-1/polkit-agent-helper-1" hostname=luis-desktop-arch addr=? terminal=tty1 res=success'
[Mon Jan 25 13:04:29 2021] audit: type=1101 audit(1611579869.881:365): pid=3052 uid=1000 auid=1000 ses=1 msg='op=PAM:accounting grantors=pam_unix,pam_permit,pam_time acct="root" exe="/usr/lib/polkit-1/polkit-agent-helper-1" hostname=? addr=? terminal=? res=success'
[Mon Jan 25 13:04:37 2021] audit: type=1325 audit(1611579877.928:366): table=filter family=7 entries=0 op=xt_unregister pid=187 comm="kworker/u64:9"
[Mon Jan 25 13:04:37 2021] audit: type=1325 audit(1611579878.168:367): table=filter family=7 entries=0 op=xt_unregister pid=187 comm="kworker/u64:9"
[Mon Jan 25 13:04:37 2021] audit: type=1325 audit(1611579878.168:368): table=filter family=7 entries=0 op=xt_unregister pid=187 comm="kworker/u64:9"
[Mon Jan 25 13:04:42 2021] audit: type=1101 audit(1611579883.034:369): pid=3076 uid=1000 auid=1000 ses=1 msg='op=PAM:accounting grantors=pam_unix,pam_permit,pam_time acct="luis" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
[Mon Jan 25 13:04:42 2021] audit: type=1110 audit(1611579883.034:370): pid=3076 uid=1000 auid=1000 ses=1 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_env,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
[Mon Jan 25 13:04:42 2021] audit: type=1105 audit(1611579883.038:371): pid=3076 uid=1000 auid=1000 ses=1 msg='op=PAM:session_open grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
[Mon Jan 25 13:04:42 2021] audit: type=1106 audit(1611579883.044:372): pid=3076 uid=1000 auid=1000 ses=1 msg='op=PAM:session_close grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
[Mon Jan 25 13:04:42 2021] audit: type=1104 audit(1611579883.044:373): pid=3076 uid=1000 auid=1000 ses=1 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_env,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
[Mon Jan 25 13:04:45 2021] audit: type=2502 audit(1611579886.591:374): pid=2971 uid=0 auid=4294967295 ses=4294967295 msg='virt=kvm vm="win10" uuid=13a28e8d-c55c-452e-8f5b-34e58db816c3 vm-ctx=+65534:+992 img-ctx=+65534:+992 model=dac exe="/usr/bin/libvirtd" hostname=? addr=? terminal=? res=success'
[Mon Jan 25 13:04:45 2021] virbr0: port 2(vnet0) entered blocking state
[Mon Jan 25 13:04:45 2021] virbr0: port 2(vnet0) entered disabled state
[Mon Jan 25 13:04:45 2021] device vnet0 entered promiscuous mode
[Mon Jan 25 13:04:45 2021] audit: type=1700 audit(1611579886.608:375): dev=vnet0 prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295
[Mon Jan 25 13:04:45 2021] virbr0: port 2(vnet0) entered blocking state
[Mon Jan 25 13:04:45 2021] virbr0: port 2(vnet0) entered listening state
[Mon Jan 25 13:04:45 2021] audit: type=2501 audit(1611579886.608:376): pid=2971 uid=0 auid=4294967295 ses=4294967295 msg='virt=kvm resrc=net reason=open vm="win10" uuid=13a28e8d-c55c-452e-8f5b-34e58db816c3 net=52:54:00:71:07:72 path="/dev/net/tun" rdev=0A:C8 exe="/usr/bin/libvirtd" hostname=? addr=? terminal=? res=success'
[Mon Jan 25 13:04:45 2021] audit: type=1130 audit(1611579886.624:377): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[Mon Jan 25 13:04:45 2021] audit: type=2501 audit(1611579886.661:378): pid=2971 uid=0 auid=4294967295 ses=4294967295 msg='virt=kvm resrc=cgroup reason=deny vm="win10" uuid=13a28e8d-c55c-452e-8f5b-34e58db816c3 cgroup="/sys/fs/cgroup/devices/machine.slice/machine-qemu\x2d1\x2dwin10.scope/" class=all exe="/usr/bin/libvirtd" hostname=? addr=? terminal=? res=success'
[Mon Jan 25 13:04:47 2021] vfio-pci 0000:26:00.0: enabling device (0400 -> 0403)
[Mon Jan 25 13:04:47 2021] vfio-pci 0000:26:00.0: AMD_POLARIS10: version 1.1
[Mon Jan 25 13:04:47 2021] vfio-pci 0000:26:00.0: AMD_POLARIS10: performing pre-reset
[Mon Jan 25 13:04:47 2021] vfio-pci 0000:26:00.0: AMD_POLARIS10: performing reset
[Mon Jan 25 13:04:47 2021] vfio-pci 0000:26:00.0: AMD_POLARIS10: CLOCK_CNTL: 0x0, PC: 0x2a3c
[Mon Jan 25 13:04:47 2021] vfio-pci 0000:26:00.0: AMD_POLARIS10: performing post-reset
[Mon Jan 25 13:04:47 2021] vfio-pci 0000:26:00.0: AMD_POLARIS10: reset result = 0
[Mon Jan 25 13:04:47 2021] vfio-pci 0000:26:00.0: vfio_ecap_init: hiding ecap 0x19@0x270
[Mon Jan 25 13:04:47 2021] vfio-pci 0000:26:00.0: vfio_ecap_init: hiding ecap 0x1b@0x2d0
[Mon Jan 25 13:04:47 2021] vfio-pci 0000:26:00.0: vfio_ecap_init: hiding ecap 0x1e@0x370
[Mon Jan 25 13:04:47 2021] vfio-pci 0000:26:00.0: AMD_POLARIS10: version 1.1
[Mon Jan 25 13:04:47 2021] vfio-pci 0000:26:00.0: AMD_POLARIS10: performing pre-reset
[Mon Jan 25 13:04:47 2021] vfio-pci 0000:26:00.0: AMD_POLARIS10: performing reset
[Mon Jan 25 13:04:47 2021] vfio-pci 0000:26:00.0: AMD_POLARIS10: CLOCK_CNTL: 0x0, PC: 0x2a38
[Mon Jan 25 13:04:47 2021] vfio-pci 0000:26:00.0: AMD_POLARIS10: performing post-reset
[Mon Jan 25 13:04:47 2021] vfio-pci 0000:26:00.0: AMD_POLARIS10: reset result = 0
[Mon Jan 25 13:04:47 2021] virbr0: port 2(vnet0) entered learning state
[Mon Jan 25 13:04:48 2021] kauditd_printk_skb: 13 callbacks suppressed
[Mon Jan 25 13:04:48 2021] audit: type=2501 audit(1611579888.934:392): pid=2971 uid=0 auid=4294967295 ses=4294967295 msg='virt=kvm resrc=disk reason=start vm="win10" uuid=13a28e8d-c55c-452e-8f5b-34e58db816c3 old-disk="?" new-disk="/mnt/256gb/win10.qcow2" exe="/usr/bin/libvirtd" hostname=? addr=? terminal=? res=success'
[Mon Jan 25 13:04:48 2021] audit: type=2501 audit(1611579888.934:393): pid=2971 uid=0 auid=4294967295 ses=4294967295 msg='virt=kvm resrc=disk reason=start vm="win10" uuid=13a28e8d-c55c-452e-8f5b-34e58db816c3 old-disk="?" new-disk="/mnt/256gb/Win10_20H2_v2_English_x64.iso" exe="/usr/bin/libvirtd" hostname=? addr=? terminal=? res=success'
[Mon Jan 25 13:04:48 2021] audit: type=2501 audit(1611579888.934:394): pid=2971 uid=0 auid=4294967295 ses=4294967295 msg='virt=kvm resrc=net reason=start vm="win10" uuid=13a28e8d-c55c-452e-8f5b-34e58db816c3 old-net="?" new-net="52:54:00:71:07:72" exe="/usr/bin/libvirtd" hostname=? addr=? terminal=? res=success'
[Mon Jan 25 13:04:48 2021] audit: type=2501 audit(1611579888.934:395): pid=2971 uid=0 auid=4294967295 ses=4294967295 msg='virt=kvm resrc=dev reason=start vm="win10" uuid=13a28e8d-c55c-452e-8f5b-34e58db816c3 bus=pci device="0000:26:00.0" exe="/usr/bin/libvirtd" hostname=? addr=? terminal=? res=success'
[Mon Jan 25 13:04:48 2021] audit: type=2501 audit(1611579888.934:396): pid=2971 uid=0 auid=4294967295 ses=4294967295 msg='virt=kvm resrc=dev reason=start vm="win10" uuid=13a28e8d-c55c-452e-8f5b-34e58db816c3 bus=pci device="0000:26:00.1" exe="/usr/bin/libvirtd" hostname=? addr=? terminal=? res=success'
[Mon Jan 25 13:04:48 2021] audit: type=2501 audit(1611579888.934:397): pid=2971 uid=0 auid=4294967295 ses=4294967295 msg='virt=kvm resrc=dev reason=start vm="win10" uuid=13a28e8d-c55c-452e-8f5b-34e58db816c3 bus=usb device="001.003" exe="/usr/bin/libvirtd" hostname=? addr=? terminal=? res=success'
[Mon Jan 25 13:04:48 2021] audit: type=2501 audit(1611579888.934:398): pid=2971 uid=0 auid=4294967295 ses=4294967295 msg='virt=kvm resrc=dev reason=start vm="win10" uuid=13a28e8d-c55c-452e-8f5b-34e58db816c3 bus=usb device=555342207265646972646576 exe="/usr/bin/libvirtd" hostname=? addr=? terminal=? res=success'
[Mon Jan 25 13:04:48 2021] audit: type=2501 audit(1611579888.934:399): pid=2971 uid=0 auid=4294967295 ses=4294967295 msg='virt=kvm resrc=dev reason=start vm="win10" uuid=13a28e8d-c55c-452e-8f5b-34e58db816c3 bus=usb device=555342207265646972646576 exe="/usr/bin/libvirtd" hostname=? addr=? terminal=? res=success'
[Mon Jan 25 13:04:48 2021] audit: type=2501 audit(1611579888.934:400): pid=2971 uid=0 auid=4294967295 ses=4294967295 msg='virt=kvm resrc=mem reason=start vm="win10" uuid=13a28e8d-c55c-452e-8f5b-34e58db816c3 old-mem=0 new-mem=8192000 exe="/usr/bin/libvirtd" hostname=? addr=? terminal=? res=success'
[Mon Jan 25 13:04:48 2021] audit: type=2501 audit(1611579888.934:401): pid=2971 uid=0 auid=4294967295 ses=4294967295 msg='virt=kvm resrc=vcpu reason=start vm="win10" uuid=13a28e8d-c55c-452e-8f5b-34e58db816c3 old-vcpu=0 new-vcpu=6 exe="/usr/bin/libvirtd" hostname=? addr=? terminal=? res=success'
[Mon Jan 25 13:04:48 2021] vfio-pci 0000:26:00.0: Invalid PCI ROM header signature: expecting 0xaa55, got 0xffff
[Mon Jan 25 13:04:50 2021] virbr0: port 2(vnet0) entered forwarding state
[Mon Jan 25 13:04:50 2021] virbr0: topology change detected, propagating
[Mon Jan 25 13:04:55 2021] kauditd_printk_skb: 1 callbacks suppressed
[Mon Jan 25 13:04:55 2021] audit: type=1131 audit(1611579896.711:403): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[Mon Jan 25 13:05:35 2021] audit: type=1101 audit(1611579936.044:404): pid=3132 uid=1000 auid=1000 ses=1 msg='op=PAM:accounting grantors=pam_unix,pam_permit,pam_time acct="luis" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
[Mon Jan 25 13:05:35 2021] audit: type=1110 audit(1611579936.044:405): pid=3132 uid=1000 auid=1000 ses=1 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_env,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
[Mon Jan 25 13:05:35 2021] audit: type=1105 audit(1611579936.048:406): pid=3132 uid=1000 auid=1000 ses=1 msg='op=PAM:session_open grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'

Complete being the operative word there

complete_dmesg_logs.txt (150.3 KB)