Hello,
I'm trying to improve the pvgrub2 support for archlinux, but after updating my system and the kernel, I cannot successfully boot with pvgrub2 anymore.
The system boot successfully, but all qubes related components are failing starting with the error message:
[ 1.177436] qubesdb-daemon[306]: Cannot get own domid
[ 1.177608] qubesdb-daemon[306]: FATAL: vchan initialization
failed
Any idea why it boots with a standard qubes fedora kernel, but fails with pvgrub2 ?
The first relevant messages in the kernel console are :
[.[0;32m OK .[0m] Reached target Local File Systems (Pre). Mounting /tmp... [.[0;32m OK .[0m] Mounted /tmp. [.[0;32m OK .[0m] Started udev Coldplug all Devices. [.[0;32m OK .[0m] Started udev Kernel Device Manager. [.[0;32m OK .[0m] Started Journal Service. [ 0.963731] systemd-modules-load[169]: Inserted module 'xen_evtchn' Starting Flush Journal to Persistent Storage... [ 0.967448] systemd-modules-load[169]: Inserted module 'xen_blkback' [ 0.967622] systemd-modules-load[169]: Inserted module 'u2mfn' [ 0.967731] systemd-modules-load[169]: Inserted module 'xen_gntalloc' [ 0.967842] systemd-modules-load[169]: Inserted module 'xen_gntdev' [ 0.967951] systemd-modules-load[169]: Inserted module 'dummy_hcd' [.[0;32m OK .[0m] Found device /dev/hvc0. [.[0;32m OK .[0m] Found device /dev/xvdb. Starting File System Check on /dev/xvdb... [.[0;32m OK .[0m] Started Flush Journal to Persistent Storage. [ 1.101323] systemd-fsck[266]: /dev/xvdb: recovering journal [ 1.122903] systemd-fsck[266]: /dev/xvdb: clean, 53723/966656 files, 678174/3852288 blocks [.[0;32m OK .[0m] Started File System Check on /dev/xvdb. rw.mount: Directory /rw to mount over is not empty, mounting anyway. Mounting /rw... [.[0;32m OK .[0m] Mounted /rw.
Then I have an error with tinyproxy (which I think could be
safely ignored):
[.[0;32m OK .[0m] Reached target Local File Systems.
Starting Create Volatile Files and Directories...
[ 1.154212] systemd-tmpfiles[298]:
[/usr/lib/tmpfiles.d/qubes-core-agent-linux.conf:1] Unknown user
'tinyproxy'.
Starting Qubes DB agent...
systemd-tmpfiles-setup.service: Main process exited, code=exited,
status=1/FAILURE
[.[0;1;31mFAILED.[0m] Failed to start Create Volatile Files and
Directories.
See 'systemctl status systemd-tmpfiles-setup.service' for
details.
systemd-tmpfiles-setup.service: Unit entered failed state.
systemd-tmpfiles-setup.service: Failed with result 'exit-code'.
Finally, the real error is qubes-db failing, which causes
everything depending on vchan failing:
Starting Update UTMP about System Boot/Shutdown...
Starting Network Time Synchronization...
qubes-db.service: Main process exited, code=exited,
status=1/FAILURE
[.[0;1;31mFAILED.[0m] Failed to start Qubes DB agent.
See 'systemctl status qubes-db.service' for details.
qubes-db.service: Unit entered failed state.
qubes-db.service: Failed with result 'exit-code'.
Starting Init Qubes Services settings...
[ 1.177436] qubesdb-daemon[306]: Cannot get own domid
[ 1.177608] qubesdb-daemon[306]: FATAL: vchan initialization
failed
Starting Qubes Random Seed...
[.[0;32m OK .[0m] Started Update UTMP about System
Boot/Shutdown.
[ 1.189617] qubes-random-seed.sh[316]: Failed connect to local
daemon
qubes-random-seed.service: Main process exited, code=exited,
status=1/FAILURE
[.[0;1;31mFAILED.[0m] Failed to start Qubes Random Seed.
See 'systemctl status qubes-random-seed.service' for details.
qubes-random-seed.service: Unit entered failed state.
qubes-random-seed.service: Failed with result 'exit-code'.
Reexecuting.
systemd 231 running in system mode. (+PAM -AUDIT -SELINUX -IMA
-APPARMOR +SMACK -SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS
+ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN)
Detected virtualization xen.
Detected architecture x86-64.
[ 1.297687] qubes-sysinit.sh[315]: Failed connect to local
daemon
[ 1.308254] qubes-sysinit.sh[315]:
/usr/lib/qubes/init/qubes-sysinit.sh: line 105: [: : integer
expression expected
[.[0;32m OK .[0m] Started Init Qubes Services settings.
[.[0;32m OK .[0m] Started Network Time Synchronization.
[.[0;32m OK .[0m] Reached target System Time Synchronized.
[.[0;32m OK .[0m] Found device /dev/xvdc1.
Activating swap /dev/xvdc1...
[.[0;32m OK .[0m] Activated swap /dev/xvdc1.
[.[0;32m OK .[0m] Reached target Swap.
[.[0;32m OK .[0m] Reached target System Initialization.
[.[0;32m OK .[0m] Listening on D-Bus System Message Bus Socket.
[.[0;32m OK .[0m] Reached target Sockets.
[.[0;32m OK .[0m] Started Daily rotation of log files.
[.[0;32m OK .[0m] Started Daily locate database update.
[.[0;32m OK .[0m] Reached target Paths.
[.[0;32m OK .[0m] Reached target Basic System.
Starting Qubes memory information reporter...
[.[0;32m OK .[0m] Reached target Containers.
[.[0;32m OK .[0m] Started D-Bus System Message Bus.
Starting Network Service...
Starting Initialize and mount /rw and /home...
Starting Qubes base firewall settings...
Starting Login Service...
[.[0;32m OK .[0m] Started Daily verification of password and
group files.
Starting Qubes remote exec agent...
[.[0;32m OK .[0m] Started Daily man-db cache update.
[.[0;32m OK .[0m] Started Daily Cleanup of Temporary
Directories.
[.[0;32m OK .[0m] Reached target Timers.
[.[0;32m OK .[0m] Started Entropy Harvesting Daemon.
[.[0;32m OK .[0m] Started Qubes memory information reporter.
[.[0;32m OK .[0m] Started Qubes remote exec agent.
[ 4.181114] systemd-networkd[377]: Enumeration completed
[.[0;32m OK .[0m] Started Network Service.
[ 4.186496] qrexec-agent[392]: Error while vchan server_init,
exiting
qubes-qrexec-agent.service: Main process exited, code=exited,
status=1/FAILURE
qubes-qrexec-agent.service: Unit entered failed state.
qubes-qrexec-agent.service: Failed with result 'exit-code'.
[.[0;32m OK .[0m] Started Login Service.
[ 4.195638] systemd-logind[380]: New seat seat0.
[ 4.205274] mount-dirs.sh[378]: tune2fs 1.43.1 (08-Jun-2016)
[ 4.205759] mount-dirs.sh[378]: Setting reserved blocks
percentage to 0% (0 blocks)
[ 4.209024] mount-dirs.sh[378]: mount: /dev/xvdb is already
mounted or /rw busy
[ 4.209367] mount-dirs.sh[378]: /dev/xvdb is already
mounted on /rw
[ 4.246177] qubes-iptables[379]: iptables: Applying firewall
rules: OK
[.[0;32m OK .[0m] Started Initialize and mount /rw and /home.
Starting Qubes GUI Agent...
Starting Qubes misc post-boot actions...
[.[0;32m OK .[0m] Started Qubes GUI Agent.
[ 4.277814] qubes-gui[448]: vchan initialization failed
qubes-gui-agent.service: Main process exited, code=exited,
status=1/FAILURE
[.[0;32m OK .[0m] Started Qubes base firewall settings.
[.[0;32m OK .[0m] Reached target Network.
Starting Network Name Resolution...
[ 4.289943] qubes-iptables[379]: ip6tables: Applying firewall
rules: OK
Starting Permit User Sessions...
qubes-gui-agent.service: Unit entered failed state.
qubes-gui-agent.service: Failed with result 'exit-code'.
[.[0;32m OK .[0m] Started Permit User Sessions.
[.[0;32m OK .[0m] Started Getty on tty1.
[.[0;32m OK .[0m] Started Serial Getty on hvc0.
[.[0;32m OK .[0m] Reached target Login Prompts.
[ 4.338130] login[466]: pam_unix(login:session): session opened
for user user by LOGIN(uid=0)
[ 4.343453] systemd-resolved[457]: Positive Trust Anchors:
[ 4.343957] systemd-resolved[457]: . IN DS 19036 8 2
49aac11d7b6f6446702e54a1607371607a1a41855200fd2ce1cdde32f24e8fb5
[ 4.344197] systemd-resolved[457]: Negative trust anchors:
10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa
18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa
21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa
24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa
27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa
30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa corp
home internal intranet lan local private test
[ 4.344430] systemd-resolved[457]: Defaulting to hostname
'linux'.
[ 4.344684] systemd-resolved[457]: Switching to system DNS
server 10.137.2.1.
[.[0;32m OK .[0m] Created slice User Slice of user.
[.[0;32m OK .[0m] Started Network Name Resolution.
Starting User Manager for UID 1000...
[.[0;32m OK .[0m] Started Session c1 of user user.
[ 4.354997] systemd-logind[380]: New session c1 of user user.
[ 4.357798] systemd[480]: pam_unix(systemd-user:session):
session opened for user user by (uid=0)
[.[0;32m OK .[0m] Started Qubes misc post-boot actions.
[.[0;32m OK .[0m] Reached target Multi-User System.
[ 4.386519] systemd[480]: Startup finished in 20ms.
[.[0;32m OK .[0m] Started User Manager for UID 1000.
Startup finished in 643ms (kernel) + 3.743s (userspace) = 4.387s.
[ 4.400567] login[466]: LOGIN ON tty1 BY user
[ 4.504396] haveged[385]: haveged: ver: 1.9.1; arch: x86; vend:
GenuineIntel; build: (gcc 4.9.2 ITV); collect: 128K
[ 4.505139] haveged[385]: haveged: cpu: (L4 VC); data: 32K (L4
V); inst: 32K (L4 V); idx: 21/40; sz: 31910/59039
[ 4.505343] haveged[385]: haveged: tot tests(BA8): A:1/1 B:1/1
continuous tests(B): last entropy estimate 8.00172
[ 4.505520] haveged[385]: haveged: fills: 0, generated: 0
Arch Linux 4.4.19-1-lts (hvc0)
localhost login: