Thanks for the help!
clocksource=tsc
is indeed default
I also noticed that the wireless card shows up as a device in sys-usb in the Devices Widget:
Sys-usb:4-3 – Mediatek_Inc._Wireless_Device_000000000
That seems to be the Bluetooth part of the wireless card, as it does not appear when Bluetooth is disabled in Bios.
It looks like my WiFi pci device doesn’t support strict reset. Anyway, I created a separate sys-net-wifi for the WiFi in addition to sys-net for the ethernet. I enabled no-strict-reset for both of them. That does not make a difference. I then also enabled permissive mode for the WiFi pci device, but that does not also make a difference.
I also noticed that in addition to the
pciback 0000:06:00.0: not ready X ms after FLR: waiting
messages after Grub and before the password screen, these same messages also appear later in the boot process during VM start jobs. This was the case before and is still the case now.
I copied the messages from journalctl and included more messages after those:
Jan 12 19:30:45 dom0 systemd[1]: Started Qubes memory information reporter.
Jan 12 19:30:45 dom0 systemd[1]: Starting Start Qubes VM sys-firewall...
Jan 12 19:30:45 dom0 systemd[1]: Starting Start Qubes VM sys-net-wifi...
Jan 12 19:30:45 dom0 systemd[1]: Starting Start Qubes VM sys-net...
Jan 12 19:30:45 dom0 systemd[1]: Starting Start Qubes VM sys-usb...
Jan 12 19:30:45 dom0 systemd[1]: Starting Start Qubes VM sys-whonix...
Jan 12 19:30:45 dom0 qubesd[3021]: vm.sys-firewall: Starting sys-firewall
Jan 12 19:30:45 dom0 qubesd[3021]: vm.sys-net-wifi: Starting sys-net-wifi
Jan 12 19:30:45 dom0 qubesd[3021]: vm.sys-usb: Starting sys-usb
Jan 12 19:30:45 dom0 qubesd[3021]: vm.sys-whonix: Starting sys-whonix
Jan 12 19:30:45 dom0 qubesd[3021]: vm.sys-net: Starting sys-net
Jan 12 19:30:45 dom0 lvm[2002]: No longer monitoring thin pool qubes_dom0-vm--pool-tpool.
Jan 12 19:30:45 dom0 lvm[2002]: Monitoring thin pool qubes_dom0-vm--pool-tpool.
Jan 12 19:30:45 dom0 lvm[2002]: No longer monitoring thin pool qubes_dom0-vm--pool-tpool.
Jan 12 19:30:45 dom0 lvm[2002]: Monitoring thin pool qubes_dom0-vm--pool-tpool.
Jan 12 19:30:46 dom0 kernel: memmap_init_zone_device initialised 32768 pages in 0ms
Jan 12 19:30:46 dom0 root[3914]: /etc/xen/scripts/block: add XENBUS_PATH=backend/vbd/1/51728
Jan 12 19:30:46 dom0 root[3913]: /etc/xen/scripts/block: add XENBUS_PATH=backend/vbd/1/51712
Jan 12 19:30:46 dom0 root[3921]: /etc/xen/scripts/block: add XENBUS_PATH=backend/vbd/1/51744
Jan 12 19:30:46 dom0 root[3924]: /etc/xen/scripts/block: add XENBUS_PATH=backend/vbd/1/51760
Jan 12 19:30:46 dom0 root[4036]: /etc/xen/scripts/block: Writing backend/vbd/1/51712/physical-device fd:67 to xenstore.
Jan 12 19:30:46 dom0 root[4038]: /etc/xen/scripts/block: Writing backend/vbd/1/51712/physical-device-path /dev/dm-103 to xenstore.
Jan 12 19:30:46 dom0 root[4040]: /etc/xen/scripts/block: Writing backend/vbd/1/51712/hotplug-status connected to xenstore.
Jan 12 19:30:46 dom0 root[4088]: /etc/xen/scripts/block: Writing backend/vbd/1/51728/physical-device fd:66 to xenstore.
Jan 12 19:30:46 dom0 root[4090]: /etc/xen/scripts/block: Writing backend/vbd/1/51728/physical-device-path /dev/dm-102 to xenstore.
Jan 12 19:30:46 dom0 root[4092]: /etc/xen/scripts/block: Writing backend/vbd/1/51728/hotplug-status connected to xenstore.
Jan 12 19:30:46 dom0 root[4137]: /etc/xen/scripts/block: Writing backend/vbd/1/51744/physical-device fd:68 to xenstore.
Jan 12 19:30:46 dom0 root[4139]: /etc/xen/scripts/block: Writing backend/vbd/1/51744/physical-device-path /dev/dm-104 to xenstore.
Jan 12 19:30:46 dom0 root[4141]: /etc/xen/scripts/block: Writing backend/vbd/1/51744/hotplug-status connected to xenstore.
Jan 12 19:30:46 dom0 kernel: loop: module loaded
Jan 12 19:30:46 dom0 kernel: loop0: detected capacity change from 0 to 1071840
Jan 12 19:30:46 dom0 root[4167]: /etc/xen/scripts/block: Writing backend/vbd/1/51760/node /dev/loop0 to xenstore.
Jan 12 19:30:46 dom0 root[4171]: /etc/xen/scripts/block: Writing backend/vbd/1/51760/physical-device 7:0 to xenstore.
Jan 12 19:30:46 dom0 root[4173]: /etc/xen/scripts/block: Writing backend/vbd/1/51760/physical-device-path /dev/loop0 to xenstore.
Jan 12 19:30:46 dom0 root[4175]: /etc/xen/scripts/block: Writing backend/vbd/1/51760/hotplug-status connected to xenstore.
Jan 12 19:30:46 dom0 root[4202]: /etc/xen/scripts/block: add XENBUS_PATH=backend/vbd/2/51712
Jan 12 19:30:46 dom0 root[4207]: /etc/xen/scripts/block: add XENBUS_PATH=backend/vbd/2/51728
Jan 12 19:30:46 dom0 root[4213]: /etc/xen/scripts/block: add XENBUS_PATH=backend/vbd/2/51744
Jan 12 19:30:46 dom0 root[4217]: /etc/xen/scripts/block: add XENBUS_PATH=backend/vbd/2/51760
Jan 12 19:30:46 dom0 root[4346]: /etc/xen/scripts/block: Writing backend/vbd/2/51712/physical-device fd:67 to xenstore.
Jan 12 19:30:46 dom0 root[4348]: /etc/xen/scripts/block: Writing backend/vbd/2/51712/physical-device-path /dev/dm-103 to xenstore.
Jan 12 19:30:46 dom0 root[4350]: /etc/xen/scripts/block: Writing backend/vbd/2/51712/hotplug-status connected to xenstore.
Jan 12 19:30:46 dom0 root[4417]: /etc/xen/scripts/block: Writing backend/vbd/2/51728/physical-device fd:66 to xenstore.
Jan 12 19:30:46 dom0 root[4419]: /etc/xen/scripts/block: Writing backend/vbd/2/51728/physical-device-path /dev/dm-102 to xenstore.
Jan 12 19:30:46 dom0 root[4421]: /etc/xen/scripts/block: Writing backend/vbd/2/51728/hotplug-status connected to xenstore.
Jan 12 19:30:46 dom0 root[4485]: /etc/xen/scripts/block: Writing backend/vbd/2/51744/physical-device fd:68 to xenstore.
Jan 12 19:30:46 dom0 root[4487]: /etc/xen/scripts/block: Writing backend/vbd/2/51744/physical-device-path /dev/dm-104 to xenstore.
Jan 12 19:30:46 dom0 root[4489]: /etc/xen/scripts/block: Writing backend/vbd/2/51744/hotplug-status connected to xenstore.
Jan 12 19:30:46 dom0 kernel: loop1: detected capacity change from 0 to 1071840
Jan 12 19:30:46 dom0 root[4514]: /etc/xen/scripts/block: Writing backend/vbd/2/51760/node /dev/loop1 to xenstore.
Jan 12 19:30:46 dom0 root[4518]: /etc/xen/scripts/block: Writing backend/vbd/2/51760/physical-device 7:1 to xenstore.
Jan 12 19:30:46 dom0 root[4520]: /etc/xen/scripts/block: Writing backend/vbd/2/51760/physical-device-path /dev/loop1 to xenstore.
Jan 12 19:30:46 dom0 root[4522]: /etc/xen/scripts/block: Writing backend/vbd/2/51760/hotplug-status connected to xenstore.
Jan 12 19:30:48 dom0 kernel: pciback 0000:06:00.0: not ready 1023ms after FLR; waiting
Jan 12 19:30:49 dom0 kernel: pciback 0000:06:00.0: not ready 2047ms after FLR; waiting
Jan 12 19:30:51 dom0 kernel: pciback 0000:06:00.0: not ready 4095ms after FLR; waiting
Jan 12 19:30:55 dom0 kernel: pciback 0000:06:00.0: not ready 8191ms after FLR; waiting
Jan 12 19:31:04 dom0 kernel: pciback 0000:06:00.0: not ready 16383ms after FLR; waiting
Jan 12 19:31:21 dom0 kernel: pciback 0000:06:00.0: not ready 32767ms after FLR; waiting
Jan 12 19:31:54 dom0 kernel: pciback 0000:06:00.0: not ready 65535ms after FLR; giving up
Jan 12 19:31:55 dom0 kernel: pciback 0000:06:00.0: xen_pciback: vpci: assign to virtual slot 0
Jan 12 19:31:55 dom0 kernel: pciback 0000:06:00.0: registering for 2
Jan 12 19:31:55 dom0 kernel: xen-blkback: backend/vbd/2/51712: using 1 queues, protocol 1 (x86_64-abi) persistent grants
Jan 12 19:31:55 dom0 kernel: xen-blkback: backend/vbd/2/51728: using 1 queues, protocol 1 (x86_64-abi) persistent grants
Jan 12 19:31:55 dom0 kernel: xen-blkback: backend/vbd/2/51744: using 1 queues, protocol 1 (x86_64-abi) persistent grants
Jan 12 19:31:55 dom0 kernel: xen-blkback: backend/vbd/2/51760: using 1 queues, protocol 1 (x86_64-abi) persistent grants
Jan 12 19:31:56 dom0 kernel: xen: registering gsi 40 triggering 0 polarity 1
Jan 12 19:31:56 dom0 kernel: Already setup the GSI :40
Jan 12 19:31:56 dom0 qubesd[3021]: vm.sys-net-wifi: Setting Qubes DB info for the VM
Jan 12 19:31:56 dom0 qubesd[3021]: vm.sys-net-wifi: Starting Qubes DB
Jan 12 19:31:56 dom0 audit[4534]: CRED_ACQ pid=4534 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_rootok acct="User" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Jan 12 19:31:56 dom0 kernel: kauditd_printk_skb: 25 callbacks suppressed
Jan 12 19:31:56 dom0 kernel: audit: type=1103 audit(1673548316.401:137): pid=4534 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_rootok acct="User" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Jan 12 19:31:56 dom0 runuser[4534]: pam_unix(runuser:session): session opened for user User by (uid=0)
Jan 12 19:31:56 dom0 audit[4534]: USER_START pid=4534 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_open grantors=pam_keyinit,pam_limits,pam_unix acct="User" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Jan 12 19:31:56 dom0 kernel: audit: type=1105 audit(1673548316.403:138): pid=4534 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_open grantors=pam_keyinit,pam_limits,pam_unix acct="User" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Jan 12 19:31:56 dom0 runuser[4534]: pam_unix(runuser:session): session closed for user User
Jan 12 19:31:56 dom0 audit[4534]: USER_END pid=4534 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_close grantors=pam_keyinit,pam_limits,pam_unix acct="User" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Jan 12 19:31:56 dom0 audit[4534]: CRED_DISP pid=4534 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_rootok acct="User" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Jan 12 19:31:56 dom0 kernel: audit: type=1106 audit(1673548316.409:139): pid=4534 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_close grantors=pam_keyinit,pam_limits,pam_unix acct="User" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Jan 12 19:31:56 dom0 kernel: audit: type=1104 audit(1673548316.409:140): pid=4534 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_rootok acct="User" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Jan 12 19:31:56 dom0 qubesd[3021]: vm.sys-net-wifi: Activating the sys-net-wifi VM
Jan 12 19:31:56 dom0 audit[4544]: CRED_ACQ pid=4544 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_rootok acct="User" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Jan 12 19:31:56 dom0 runuser[4544]: pam_unix(runuser:session): session opened for user User by (uid=0)
Jan 12 19:31:56 dom0 audit[4544]: USER_START pid=4544 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_open grantors=pam_keyinit,pam_limits,pam_unix acct="User" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Jan 12 19:31:56 dom0 kernel: audit: type=1103 audit(1673548316.426:141): pid=4544 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_rootok acct="User" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Jan 12 19:31:56 dom0 kernel: audit: type=1105 audit(1673548316.426:142): pid=4544 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_open grantors=pam_keyinit,pam_limits,pam_unix acct="User" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Jan 12 19:31:56 dom0 lvm[2002]: No longer monitoring thin pool qubes_dom0-vm--pool-tpool.
Jan 12 19:31:56 dom0 lvm[2002]: Monitoring thin pool qubes_dom0-vm--pool-tpool.
Jan 12 19:31:56 dom0 lvm[2002]: No longer monitoring thin pool qubes_dom0-vm--pool-tpool.
Jan 12 19:31:56 dom0 lvm[2002]: Monitoring thin pool qubes_dom0-vm--pool-tpool.
Jan 12 19:31:56 dom0 root[4732]: /etc/xen/scripts/block: add XENBUS_PATH=backend/vbd/3/51712
Jan 12 19:31:56 dom0 root[4742]: /etc/xen/scripts/block: add XENBUS_PATH=backend/vbd/3/51728
Jan 12 19:31:56 dom0 root[4746]: /etc/xen/scripts/block: add XENBUS_PATH=backend/vbd/3/51744
Jan 12 19:31:56 dom0 root[4749]: /etc/xen/scripts/block: add XENBUS_PATH=backend/vbd/3/51760
Jan 12 19:31:56 dom0 root[4878]: /etc/xen/scripts/block: Writing backend/vbd/3/51712/physical-device fd:6b to xenstore.
Jan 12 19:31:57 dom0 root[4880]: /etc/xen/scripts/block: Writing backend/vbd/3/51712/physical-device-path /dev/dm-107 to xenstore.
Jan 12 19:31:57 dom0 root[4882]: /etc/xen/scripts/block: Writing backend/vbd/3/51712/hotplug-status connected to xenstore.
Jan 12 19:31:57 dom0 root[4948]: /etc/xen/scripts/block: Writing backend/vbd/3/51728/physical-device fd:69 to xenstore.
Jan 12 19:31:57 dom0 root[4950]: /etc/xen/scripts/block: Writing backend/vbd/3/51728/physical-device-path /dev/dm-105 to xenstore.
Jan 12 19:31:57 dom0 root[4952]: /etc/xen/scripts/block: Writing backend/vbd/3/51728/hotplug-status connected to xenstore.
Jan 12 19:31:57 dom0 root[5015]: /etc/xen/scripts/block: Writing backend/vbd/3/51744/physical-device fd:6a to xenstore.
Jan 12 19:31:57 dom0 root[5017]: /etc/xen/scripts/block: Writing backend/vbd/3/51744/physical-device-path /dev/dm-106 to xenstore.
Jan 12 19:31:57 dom0 root[5019]: /etc/xen/scripts/block: Writing backend/vbd/3/51744/hotplug-status connected to xenstore.
Jan 12 19:31:57 dom0 kernel: loop2: detected capacity change from 0 to 957384
Jan 12 19:31:57 dom0 root[5044]: /etc/xen/scripts/block: Writing backend/vbd/3/51760/node /dev/loop2 to xenstore.
Jan 12 19:31:57 dom0 root[5048]: /etc/xen/scripts/block: Writing backend/vbd/3/51760/physical-device 7:2 to xenstore.
Jan 12 19:31:57 dom0 root[5050]: /etc/xen/scripts/block: Writing backend/vbd/3/51760/physical-device-path /dev/loop2 to xenstore.
Jan 12 19:31:57 dom0 root[5052]: /etc/xen/scripts/block: Writing backend/vbd/3/51760/hotplug-status connected to xenstore.
Jan 12 19:31:57 dom0 root[5076]: /etc/xen/scripts/block: add XENBUS_PATH=backend/vbd/4/51712
Jan 12 19:31:57 dom0 root[5081]: /etc/xen/scripts/block: add XENBUS_PATH=backend/vbd/4/51728
Jan 12 19:31:57 dom0 root[5088]: /etc/xen/scripts/block: add XENBUS_PATH=backend/vbd/4/51744
Jan 12 19:31:57 dom0 root[5097]: /etc/xen/scripts/block: add XENBUS_PATH=backend/vbd/4/51760
Jan 12 19:31:57 dom0 root[5241]: /etc/xen/scripts/block: Writing backend/vbd/4/51712/physical-device fd:6b to xenstore.
Jan 12 19:31:57 dom0 root[5243]: /etc/xen/scripts/block: Writing backend/vbd/4/51712/physical-device-path /dev/dm-107 to xenstore.
Jan 12 19:31:57 dom0 root[5245]: /etc/xen/scripts/block: Writing backend/vbd/4/51712/hotplug-status connected to xenstore.
Jan 12 19:31:57 dom0 root[5330]: /etc/xen/scripts/block: Writing backend/vbd/4/51728/physical-device fd:69 to xenstore.
Jan 12 19:31:57 dom0 root[5332]: /etc/xen/scripts/block: Writing backend/vbd/4/51728/physical-device-path /dev/dm-105 to xenstore.
Jan 12 19:31:57 dom0 root[5334]: /etc/xen/scripts/block: Writing backend/vbd/4/51728/hotplug-status connected to xenstore.
Jan 12 19:31:57 dom0 root[5416]: /etc/xen/scripts/block: Writing backend/vbd/4/51744/physical-device fd:6a to xenstore.
Jan 12 19:31:57 dom0 root[5418]: /etc/xen/scripts/block: Writing backend/vbd/4/51744/physical-device-path /dev/dm-106 to xenstore.
Jan 12 19:31:57 dom0 root[5420]: /etc/xen/scripts/block: Writing backend/vbd/4/51744/hotplug-status connected to xenstore.
Jan 12 19:31:57 dom0 kernel: loop3: detected capacity change from 0 to 957384
Jan 12 19:31:57 dom0 root[5445]: /etc/xen/scripts/block: Writing backend/vbd/4/51760/node /dev/loop3 to xenstore.
Jan 12 19:31:57 dom0 root[5449]: /etc/xen/scripts/block: Writing backend/vbd/4/51760/physical-device 7:3 to xenstore.
Jan 12 19:31:57 dom0 root[5451]: /etc/xen/scripts/block: Writing backend/vbd/4/51760/physical-device-path /dev/loop3 to xenstore.
Jan 12 19:31:57 dom0 root[5453]: /etc/xen/scripts/block: Writing backend/vbd/4/51760/hotplug-status connected to xenstore.
Jan 12 19:31:57 dom0 kernel: pciback 0000:06:00.0: xen-pciback: Driver tried to write to a read-only configuration space field at offset 0x6, size 2. This may be harmless, but if you have problems with your device:
1) see permissive attribute in sysfs
2) report problems to the xen-devel mailing list along with details of your device obtained from lspci.
Jan 12 19:31:57 dom0 kernel: pciback 0000:07:00.3: xen_pciback: vpci: assign to virtual slot 0
Jan 12 19:31:57 dom0 kernel: pciback 0000:07:00.3: registering for 4
Jan 12 19:31:57 dom0 kernel: pciback 0000:07:00.4: xen_pciback: vpci: assign to virtual slot 0 func 4
Jan 12 19:31:57 dom0 kernel: pciback 0000:07:00.4: registering for 4
I had to shorten the journalctl messages, as I exceeded the maximum character limit of 32000 for a post by 19275
I posted the full length here:
When I disable WiFi in Bios, going from Grub to disk password screen only takes a few seconds. No messages about the pci device. The VM start jobs at the end of the boot process are then also a lot faster.
However, of course WiFi won’t work then.
Since it is a WiFi+Bluetooth card, I was thinking I might enable WiFi in bios but disable Bluethooth in bios.
Then the pci device is visible again, but with the same problems as before.
I also tried hiding it from dom0 with:
rd.qubes.hide_pci=06:00.0
The pciback 0000:06:00.0: not ready X ms after FLR: waiting
messages will then still appear. After some messages, the “waiting” will change to “giving up” like usual, however it will just start trying again then. After a while the "waiting for device /dev/diskby-uuid/*id/ will time out and the depencies for Cryptography Setup for luks-id and Local Encrypted Volumes will fail. The messages “not ready X ms after FLR: Waiting” will continue. After some time, the boot screen finally appears, however there is no possibility to enter the password due to the failed dependencies.
If I can supply more logs please let me know. I’m not an expert user.