bug#44872: GuixSD 1.2.0 installer fails with exception when formatting drive

bug#44872: GuixSD 1.2.0 installer fails with exception when formatting drive

[Tim Magee]

I attempted to intall GNU Guix using the 1.2.0 GuixSD installation image. The install failed with an exception when formatting the hard drive. Here follows the backtrace:

----------------------------------------------------------------------------------------------------------------------------------------

The installer has encountered an unexpected problem. The backtrace is displayed below. Please report it by email to <bug-guix@gnu.org>.

In ./gnu/installer/parted.scm:
  1258:19 19 (user-partition->file-system _)
In gnu/system/uuid.scm:
    317:2 18 (uuid->string . _)
In ice-9/boot-9.scm:
  1669:16 17 (raise-exception _ #:continuable? _)
  1667:16 16 (raise-exception _ #:continuable? _)
  1667:16 15 (raise-exception _ #:continuable? _)
  1667:16 14 (raise-exception _ #:continuable? _)
  1667:16 13 (raise-exception _ #:continuable? _)
  1667:16 12 (raise-exception _ #:continuable? _)
  1667:16 11 (raise-exception _ #:continuable? _)
  1667:16 10 (raise-exception _ #:continuable? _)
  1667:16  9 (raise-exception _ #:continuable? _)
  1667:16  8 (raise-exception _ #:continuable? _)
  1669:16  7 (raise-exception _ #:continuable? _)
  1764:13  6 (_ #<&compound-exception components: (#<&error> #<&origin: "match"> #<&message message: "no matching pattern"> #<&irritants irritants: (#f ext4)> #<&exception-with-kind-and-arg...>)
In ice-9/eval.scm:
    619:8  5 (_ #(#(#<directory (guile-user) 7f10d0fb6f00> #<<installer> name: newt init: #<procedure init ()> exit: #<procedure exit ()> exit-error: #<procedure exit-error (file key args)> f...>) ...))
    619:8  4 (_ #(#(#(#<directory (guile-user) 7f10d0fb6f00> #<<installer> name: newt init: #<procedure init ()> exit: #<procedure exit ()> exit-error: #<procedure exit-error (file key args)> f...>) ...))
In ice-9/ports.scm:
   463:17  3 (call-with-output-file _ _ #:binary _ #:encoding _)
In ice-9/eval.scm:
    691:9  2 (_ #(#(#<directory (guile-user) 7f10d0fbf00> match-error ("match" "no matching pattern" (#f ext4))) #<output: /tmp/last-installer-error 19>))
    159:9  1 (_ #(#(#<directory (guile-user) 7f10d0fbf00> match-error ("match" "no matching pattern" (#f ext4))) #<output: /tmp/last-installer-error 19>))
In unknown file:
           0 (make-stack #t)
ice-9/eval.scm:159:9: Throw to key `match-error' with args `("match" "no matching pattern" (#f ext4))'.

----------------------------------------------------------------------------------------------------------------------------------------

@nckx on the IRC recommend I include the output of fdisk -l in my bug report. So here goes:

Disk /dev/sda: 931.53GiB, 100020104886016 bytes, 1953525168 sectors
Disk model: WDC WDS100T2B0A-
Units: sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disklabel type: gpt
Disk identifier: 425B5102-51E0-4994-BC78-FBE09F62D9E3

Device       Start        End    Sectors   Size Type
/dev/sda1     2048    1126399    1124352   549M EFI System
/dev/sda2  1126400    1132543       6144     3M BIOS boot
/dev/sda3  1132544    8943615    7811072   3.7G Linux swap
/dev/sda4  8943616 1953523711 1944580096 927.3G Linux filesystem

bug#44872: GuixSD 1.2.0 installer fails with exception when formatting drive

[Mathieu Othacehe]

Hello,

Thanks for the bug report and sorry for the inconvenience.

> In gnu/system/uuid.scm:
>     317:2 18 (uuid->string . _)

It looks like the UUID extraction of an "ext4" partition failed. Did you
use automatic or manual partitioning?

Thanks,

Mathieu

bug#44872: Fw: bug#44872: GuixSD 1.2.0 installer fails with exception when formatting drive

[Tim Magee]

> It looks like the UUID extraction of an "ext4" partition failed. Did you
use automatic or manual partitioning?


I used automatic partitioning this time. But it failed with an exception when I had previously tried manual partitioning as well. Though I believe it was a different error then.

bug#44872: GuixSD 1.2.0 installer fails with exception when formatting drive

[Tim Magee]

I successfully installed GNU Guix.

The work around I used for this bug is I simply ran `sudo dd
if=/dev/zero of=/dev/sda`. Basically, I think the existence of an old
GPT partition table is causing problems on a new install though I'm not
sure why.

Unfortunately, this computer is my daily driver and I need it working so
I can't run any more tests at this time.

bug#44872: GuixSD 1.2.0 installer fails with exception when formatting drive

[andi]

I did run into a similar issue and it turned out to be me selecting
/dev/sr0 in the device selection dialog instead of the actual VM disk.

bug#44872: GuixSD 1.2.0 installer fails with exception when formatting drive

[Mathieu Othacehe]

Hey Tim,

> I successfully installed GNU Guix.
>
> The work around I used for this bug is I simply ran `sudo dd
> if=/dev/zero of=/dev/sda`. Basically, I think the existence of an old
> GPT partition table is causing problems on a new install though I'm not
> sure why.

Nice you found a work-around. I think I already hit this issue myself
without being able to reproduce it. Do you remember what was the
previous GPT layout of your drive? Maybe the distribution previously
installed?

Thanks,

Mathieu

bug#44872: GuixSD 1.2.0 installer fails with exception when formatting drive

[Tim Magee via web]

I previously had GNU Guix installed with an encrypted home partition but the main partition unencrypted.

bug#44872: GuixSD 1.2.0 installer fails with exception when formatting drive

[Ludovic Courtès]

Hi,

Tim Magee <timothy@eastlincoln.net> skribis:

> In ./gnu/installer/parted.scm:
>   1258:19 19 (user-partition->file-system _)
> In gnu/system/uuid.scm:
>     317:2 18 (uuid->string . _)
> In ice-9/boot-9.scm:
>   1669:16 17 (raise-exception _ #:continuable? _)
>   1667:16 16 (raise-exception _ #:continuable? _)
>   1667:16 15 (raise-exception _ #:continuable? _)
>   1667:16 14 (raise-exception _ #:continuable? _)
>   1667:16 13 (raise-exception _ #:continuable? _)
>   1667:16 12 (raise-exception _ #:continuable? _)
>   1667:16 11 (raise-exception _ #:continuable? _)
>   1667:16 10 (raise-exception _ #:continuable? _)
>   1667:16  9 (raise-exception _ #:continuable? _)
>   1667:16  8 (raise-exception _ #:continuable? _)
>   1669:16  7 (raise-exception _ #:continuable? _)
>   1764:13  6 (_ #<&compound-exception components: (#<&error> #<&origin: "match"> #<&message message: "no matching pattern"> #<&irritants irritants: (#f ext4)> #<&exception-with-kind-and-arg...>)
> In ice-9/eval.scm:
>     619:8  5 (_ #(#(#<directory (guile-user) 7f10d0fb6f00> #<<installer> name: newt init: #<procedure init ()> exit: #<procedure exit ()> exit-error: #<procedure exit-error (file key args)> f...>) ...))
>     619:8  4 (_ #(#(#(#<directory (guile-user) 7f10d0fb6f00> #<<installer> name: newt init: #<procedure init ()> exit: #<procedure exit ()> exit-error: #<procedure exit-error (file key args)> f...>) ...))
> In ice-9/ports.scm:
>    463:17  3 (call-with-output-file _ _ #:binary _ #:encoding _)
> In ice-9/eval.scm:
>     691:9  2 (_ #(#(#<directory (guile-user) 7f10d0fbf00> match-error ("match" "no matching pattern" (#f ext4))) #<output: /tmp/last-installer-error 19>))
>     159:9  1 (_ #(#(#<directory (guile-user) 7f10d0fbf00> match-error ("match" "no matching pattern" (#f ext4))) #<output: /tmp/last-installer-error 19>))
> In unknown file:
>            0 (make-stack #t)
> ice-9/eval.scm:159:9: Throw to key `match-error' with args `("match" "no matching pattern" (#f ext4))'.

Apparently what happens here is that ‘read-partition-uuid’, called by
‘user-partition->file-system’, returns #f, and that value is then passed
to ‘uuid->string’.

There are two ways ‘read-partition-uuid’ can return #f:

  1. the partition doesn’t have one of the types listed in
     ‘%partition-uuid-readers’;

  2. the partition does not exist or is EIO (see use of ‘ENOENT-safe’ in
     ‘partition-field-reader’).

So most likely the problem is #2.

Now, when we reach this code (from ‘user-partitions->configuration’),
partitions have been created.  I wonder if there’s a possibility that
the kernel hasn’t yet updated /dev by the time we reach this?

‘free-parted’ is supposed to avoid that though.

WDYT, Mathieu?

Ludo’.

bug#44872: Installer crash: 'uuid->string' is passed #f in lieu of a UUID

[Ludovic Courtès]

Ludovic Courtès <ludo@gnu.org> skribis:

> Apparently what happens here is that ‘read-partition-uuid’, called by
> ‘user-partition->file-system’, returns #f, and that value is then passed
> to ‘uuid->string’.
>
> There are two ways ‘read-partition-uuid’ can return #f:
>
>   1. the partition doesn’t have one of the types listed in
>      ‘%partition-uuid-readers’;
>
>   2. the partition does not exist or is EIO (see use of ‘ENOENT-safe’ in
>      ‘partition-field-reader’).
>
> So most likely the problem is #2.

I pushed a change so that ‘read-partition-uuid’ & co. will no longer
swallow ENOENT, so we at least get more accurate error reports:

  https://git.savannah.gnu.org/cgit/guix.git/commit/?id=09ce4568f2cc1f87c5a5e0aa1643780c39a73088

Ludo’.

bug#44872: Installer crash: 'uuid->string' is passed #f in lieu of a UUID

[David Wilson]

Hi Guix!

I also just hit this issue while using the graphical installer on a
machine that previously had Guix installed on it via the shell-driven
manual installation flow.  I'm working on a video to show how to install
Guix using the graphical installer so it's a big blocker for me hit this
seemingly impassable error.

Is there any other workaround you can think of that might help the code
resolve the UUID of the partition correctly?  I'm not sure how these
things are supposed to work but I'm happy to try any ideas you may have!
I'd also be happy to make any speculative tweaks to the source that you
recommend.

On my side, I've tried deleting and recreating the target partition a
few times using both the graphical installer and `cfdisk', nothing seems
to help.

Let me know what I can do to help diagnose and fix this issue!

Thanks,

David

bug#44872: Installer crash: 'uuid->string' is passed #f in lieu of a UUID

[Mathieu Othacehe]

[-- Attachment #1: Type: text/plain, Size: 732 bytes --]


Hello David,

> I also just hit this issue while using the graphical installer on a
> machine that previously had Guix installed on it via the shell-driven
> manual installation flow.  I'm working on a video to show how to install
> Guix using the graphical installer so it's a big blocker for me hit this
> seemingly impassable error.

It's great that you are making a video about the installer!

> Let me know what I can do to help diagnose and fix this issue!

The problem here is probably caused by the "free-parted" procedure
returning before all the partitions are properly created by the kernel.

You could maybe try to run a custom installer image with the hideous
attached patch to confirm this theory.

Thanks,

Mathieu


[-- Attachment #2: diff --]
[-- Type: application/octet-stream, Size: 375 bytes --]

diff --git a/gnu/installer/newt/partition.scm b/gnu/installer/newt/partition.scm
index 2bb9b16945..2bb23b6506 100644
--- a/gnu/installer/newt/partition.scm
+++ b/gnu/installer/newt/partition.scm
@@ -791,4 +791,5 @@ by pressing the Exit button.~%~%")))
             (length user-partitions-with-pass))
 
     (destroy-form-and-pop form)
+    (sleep 10)
     user-partitions))

bug#44872: Installer crash: 'uuid->string' is passed #f in lieu of a UUID

[David Wilson]

Hi Mathieu!

Mathieu Othacehe <othacehe@gnu.org> writes:

> The problem here is probably caused by the "free-parted" procedure
> returning before all the partitions are properly created by the kernel.
>
> You could maybe try to run a custom installer image with the hideous
> attached patch to confirm this theory.

I built a new installer image with the fix you provided and it
unfortunately didn't resolve the issue.  I did see the sleep occur after
I confirmed the partitioning dialog, so the patch is definitely in
place.

I spent a lot of time on Sunday investigating this and I'm pretty
confused as to why it's happening.  It seems that for some reason
`read-partition-uuid' is returning `#f' for one of my partitions in the
installer but I'm not sure which one it is.  Is it the case that it
should only be looking at the partitions that I'm mounting?

Take a look at these logs from a `guix repl' session (sorry for the
image, couldn't copy text from that machine):

https://0x0.st/-_no.jpg

The two partitions that I'm setting as mount points in the graphical
installer are:

- /dev/nvme0n1p1: An existing vfat EFI partition created for the
  original Windows install on this machine
- /dev/nvme0n1p6: A fresh ext4 partition that I created and formatted
  myself in the shell with `cfdisk' and `mkfs.ext4'.

As you can see from the logs, both `read-partition-uuid' and
`uuid->string' are both returning the expected outputs.  I've
double-checked the UUIDs with `cfdisk' and they match up perfectly.

The reason why I think this isn't related to `free-parted' is because
I'm not actually creating or changing any of the partitions in the
partitioning page; the only setting I change is to set the mount point
of /dev/nvme0n1p6 to `/'.  The EFI partition is already detected and
set to mount at /boot/efi.

Any thoughts on what else I can try?  I'm happy to try anything since I
have a very reliable repro in front of me :)

Thanks!

David

bug#44872: Installer crash: 'uuid->string' is passed #f in lieu of a UUID

[Mathieu Othacehe]

[-- Attachment #1: Type: text/plain, Size: 1494 bytes --]


Hey David,

> Take a look at these logs from a `guix repl' session (sorry for the
> image, couldn't copy text from that machine):
>
> https://0x0.st/-_no.jpg

Thanks for the very valuable information here! I managed to reproduce a
very similar crash in Qemu by simulating your hard drive layout:

--8<---------------cut here---------------start------------->8---
mathieu@meije ~$ fdisk -l guix-system.img 
Disk guix-system.img: 10 GiB, 10737418240 bytes, 20971520 sectors
Units: sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disklabel type: gpt
Disk identifier: 7477884D-53F7-484F-BE53-D09EFE1DA63F

Device              Start      End  Sectors  Size Type
guix-system.img1     2048   534527   532480  260M EFI System
guix-system.img2   534528   567295    32768   16M Microsoft reserved
guix-system.img3   567296 11053055 10485760    5G Microsoft basic data
guix-system.img4 11053056 13150207  2097152    1G Windows recovery environment
guix-system.img5 13150208 15247359  2097152    1G Linux filesystem
guix-system.img6 15247360 20971486  5724127  2.7G Linux filesystem
--8<---------------cut here---------------end--------------->8---

I'm using manual partitioning with the first partition mounted as the
ESP partition and the sixth partition as the root directory.

The backtrace and the messages log file are attached. I'll try to find
some spare time to understand what's going on.

Thanks,

Mathieu

[-- Attachment #2: last-installer-error --]
[-- Type: application/octet-stream, Size: 1870 bytes --]

In gnu/system/uuid.scm:
    319:2 19 (uuid->string . _)
In ice-9/boot-9.scm:
  1685:16 18 (raise-exception _ #:continuable? _)
  1683:16 17 (raise-exception _ #:continuable? _)
  1683:16 16 (raise-exception _ #:continuable? _)
  1683:16 15 (raise-exception _ #:continuable? _)
  1683:16 14 (raise-exception _ #:continuable? _)
  1683:16 13 (raise-exception _ #:continuable? _)
  1683:16 12 (raise-exception _ #:continuable? _)
  1683:16 11 (raise-exception _ #:continuable? _)
  1683:16 10 (raise-exception _ #:continuable? _)
  1683:16  9 (raise-exception _ #:continuable? _)
  1683:16  8 (raise-exception _ #:continuable? _)
  1683:16  7 (raise-exception _ #:continuable? _)
  1780:13  6 (_ #<&compound-exception components: (#<&error> #<&origin origin: "match"> #<&message message: "no matching pattern"> #<&irritants irritants: (#f ext4)> #<&exception-with-kind-and-arg…>)
In ice-9/eval.scm:
    619:8  5 (_ #(#(#<directory (guile-user) 7f3890d19c80> #<<installer> name: newt init: #<procedure init ()> exit: #<procedure exit ()> exit-error: #<procedure exit-error (file key args)> f…>) …))
    619:8  4 (_ #(#(#(#<directory (guile-user) 7f3890d19c80> #<<installer> name: newt init: #<procedure init ()> exit: #<procedure exit ()> exit-error: #<procedure exit-error (file key arg…>) …) #))
In ice-9/ports.scm:
   433:17  3 (call-with-output-file _ _ #:binary _ #:encoding _)
In ice-9/eval.scm:
    619:8  2 (_ #(#(#<directory (guile-user) 7f3890d19c80> match-error ("match" "no matching pattern" (#f ext4))) #<output: /tmp/last-installer-error 19>))
    159:9  1 (_ #(#(#<directory (guile-user) 7f3890d19c80> match-error ("match" "no matching pattern" (#f ext4))) #<output: /tmp/last-installer-error 19>))
In unknown file:
           0 (make-stack #t)
ice-9/eval.scm:159:9: Throw to key `match-error' with args `("match" "no matching pattern" (#f ext4))'.

[-- Attachment #3: messages --]
[-- Type: application/octet-stream, Size: 63905 bytes --]

Jun  9 17:47:15 localhost syslogd (GNU inetutils 1.9.4): restart
Jun  9 17:47:15 localhost vmunix: [    0.000000] Linux version 5.12.9-gnu (nixbld@localhost) (gcc (GCC) 7.5.0, GNU ld (GNU Binutils) 2.34) #1 SMP 1
Jun  9 17:47:15 localhost vmunix: [    0.000000] Command line: BOOT_IMAGE=/gnu/store/9wzwagq79npc6mr7ccmf9crq7dpiyi2y-linux-libre-5.12.9/bzImage --root=31393730-3031-3031-3139-343934363833 --system=/gnu/store/qfh9daps3zvhqc4wzmmx2m94j3qig2f5-system --load=/gnu/store/qfh9daps3zvhqc4wzmmx2m94j3qig2f5-system/boot quiet modprobe.blacklist=radeon
Jun  9 17:47:15 localhost vmunix: [    0.000000] KERNEL supported cpus:
Jun  9 17:47:15 localhost vmunix: [    0.000000]   Intel GenuineIntel
Jun  9 17:47:15 localhost vmunix: [    0.000000]   AMD AuthenticAMD
Jun  9 17:47:15 localhost vmunix: [    0.000000]   Hygon HygonGenuine
Jun  9 17:47:15 localhost vmunix: [    0.000000]   Centaur CentaurHauls
Jun  9 17:47:15 localhost vmunix: [    0.000000]   zhaoxin   Shanghai  
Jun  9 17:47:15 localhost vmunix: [    0.000000] x86/fpu: x87 FPU will use FXSAVE
Jun  9 17:47:15 localhost vmunix: [    0.000000] BIOS-provided physical RAM map:
Jun  9 17:47:15 localhost vmunix: [    0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable
Jun  9 17:47:15 localhost vmunix: [    0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000007fffff] usable
Jun  9 17:47:15 localhost vmunix: [    0.000000] BIOS-e820: [mem 0x0000000000800000-0x0000000000807fff] ACPI NVS
Jun  9 17:47:15 localhost vmunix: [    0.000000] BIOS-e820: [mem 0x0000000000808000-0x000000000080ffff] usable
Jun  9 17:47:15 localhost vmunix: [    0.000000] BIOS-e820: [mem 0x0000000000810000-0x0000000000817fff] ACPI NVS
Jun  9 17:47:15 localhost vmunix: [    0.000000] BIOS-e820: [mem 0x0000000000818000-0x000000000081ffff] usable
Jun  9 17:47:15 localhost vmunix: [    0.000000] BIOS-e820: [mem 0x0000000000820000-0x00000000008fffff] ACPI NVS
Jun  9 17:47:15 localhost vmunix: [    0.000000] BIOS-e820: [mem 0x0000000000900000-0x000000003ecc0fff] usable
Jun  9 17:47:15 localhost vmunix: [    0.000000] BIOS-e820: [mem 0x000000003ecc1000-0x000000003ecc1fff] ACPI data
Jun  9 17:47:15 localhost vmunix: [    0.000000] BIOS-e820: [mem 0x000000003ecc2000-0x000000003ed69fff] usable
Jun  9 17:47:15 localhost vmunix: [    0.000000] BIOS-e820: [mem 0x000000003ed6a000-0x000000003ed76fff] ACPI NVS
Jun  9 17:47:15 localhost vmunix: [    0.000000] BIOS-e820: [mem 0x000000003ed77000-0x000000003ed8dfff] reserved
Jun  9 17:47:15 localhost vmunix: [    0.000000] BIOS-e820: [mem 0x000000003ed8e000-0x000000003eda7fff] usable
Jun  9 17:47:15 localhost vmunix: [    0.000000] BIOS-e820: [mem 0x000000003eda8000-0x000000003edc7fff] reserved
Jun  9 17:47:15 localhost vmunix: [    0.000000] BIOS-e820: [mem 0x000000003edc8000-0x000000003fe47fff] usable
Jun  9 17:47:15 localhost vmunix: [    0.000000] BIOS-e820: [mem 0x000000003fe48000-0x000000003fe77fff] type 20
Jun  9 17:47:15 localhost vmunix: [    0.000000] BIOS-e820: [mem 0x000000003fe78000-0x000000003fe9ffff] reserved
Jun  9 17:47:15 localhost vmunix: [    0.000000] BIOS-e820: [mem 0x000000003fea0000-0x000000003fea7fff] ACPI data
Jun  9 17:47:15 localhost vmunix: [    0.000000] BIOS-e820: [mem 0x000000003fea8000-0x000000003feabfff] ACPI NVS
Jun  9 17:47:15 localhost vmunix: [    0.000000] BIOS-e820: [mem 0x000000003feac000-0x000000003ff4ffff] usable
Jun  9 17:47:15 localhost vmunix: [    0.000000] BIOS-e820: [mem 0x000000003ff50000-0x000000003ff6ffff] reserved
Jun  9 17:47:15 localhost vmunix: [    0.000000] BIOS-e820: [mem 0x000000003ff70000-0x000000003ff77fff] usable
Jun  9 17:47:15 localhost vmunix: [    0.000000] BIOS-e820: [mem 0x000000003ff78000-0x000000003fffffff] ACPI NVS
Jun  9 17:47:15 localhost vmunix: [    0.000000] NX (Execute Disable) protection: active
Jun  9 17:47:15 localhost vmunix: [    0.000000] efi: EFI v2.60 by EDK II
Jun  9 17:47:15 localhost vmunix: [    0.000000] efi: SMBIOS=0x3fe79000 ACPI=0x3fea7000 ACPI 2.0=0x3fea7014 MEMATTR=0x3f383518 
Jun  9 17:47:15 localhost vmunix: [    0.000000] SMBIOS 2.8 present.
Jun  9 17:47:15 localhost vmunix: [    0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 0.0.0 02/06/2015
Jun  9 17:47:15 localhost vmunix: [    0.000000] Hypervisor detected: KVM
Jun  9 17:47:15 localhost vmunix: [    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
Jun  9 17:47:15 localhost vmunix: [    0.000000] kvm-clock: cpu 0, msr babc001, primary cpu clock
Jun  9 17:47:15 localhost vmunix: [    0.000000] kvm-clock: using sched offset of 13866089233 cycles
Jun  9 17:47:15 localhost vmunix: [    0.000001] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
Jun  9 17:47:15 localhost vmunix: [    0.000005] tsc: Detected 1804.800 MHz processor
Jun  9 17:47:15 localhost vmunix: [    0.000087] last_pfn = 0x3ff78 max_arch_pfn = 0x400000000
Jun  9 17:47:15 localhost vmunix: [    0.000127] x86/PAT: PAT not supported by the CPU.
Jun  9 17:47:15 localhost vmunix: [    0.000133] x86/PAT: Configuration [0-7]: WB  WT  UC- UC  WB  WT  UC- UC  
Jun  9 17:47:15 localhost vmunix: [    0.009163] check: Scanning 1 areas for low memory corruption
Jun  9 17:47:15 localhost vmunix: [    0.009486] Secure boot could not be determined
Jun  9 17:47:15 localhost vmunix: [    0.009487] RAMDISK: [mem 0x365ef000-0x372eefff]
Jun  9 17:47:15 localhost vmunix: [    0.009494] ACPI: Early table checksum verification disabled
Jun  9 17:47:15 localhost vmunix: [    0.009506] ACPI: RSDP 0x000000003FEA7014 000024 (v02 BOCHS )
Jun  9 17:47:15 localhost vmunix: [    0.009513] ACPI: XSDT 0x000000003FEA60E8 00004C (v01 BOCHS  BXPCFACP 00000001      01000013)
Jun  9 17:47:15 localhost vmunix: [    0.009519] ACPI: FACP 0x000000003FEA3000 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
Jun  9 17:47:15 localhost vmunix: [    0.009526] ACPI: DSDT 0x000000003FEA4000 0013C4 (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
Jun  9 17:47:15 localhost vmunix: [    0.009529] ACPI: FACS 0x000000003FEAA000 000040
Jun  9 17:47:15 localhost vmunix: [    0.009531] ACPI: APIC 0x000000003FEA2000 000078 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
Jun  9 17:47:15 localhost vmunix: [    0.009534] ACPI: HPET 0x000000003FEA1000 000038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
Jun  9 17:47:15 localhost vmunix: [    0.009536] ACPI: WAET 0x000000003FEA0000 000028 (v01 BOCHS  BXPCWAET 00000001 BXPC 00000001)
Jun  9 17:47:15 localhost vmunix: [    0.009541] ACPI: BGRT 0x000000003ECC1000 000038 (v01 INTEL  EDK2     00000002      01000013)
Jun  9 17:47:15 localhost vmunix: [    0.009543] ACPI: Reserving FACP table memory at [mem 0x3fea3000-0x3fea3073]
Jun  9 17:47:15 localhost vmunix: [    0.009544] ACPI: Reserving DSDT table memory at [mem 0x3fea4000-0x3fea53c3]
Jun  9 17:47:15 localhost vmunix: [    0.009545] ACPI: Reserving FACS table memory at [mem 0x3feaa000-0x3feaa03f]
Jun  9 17:47:15 localhost vmunix: [    0.009546] ACPI: Reserving APIC table memory at [mem 0x3fea2000-0x3fea2077]
Jun  9 17:47:15 localhost vmunix: [    0.009546] ACPI: Reserving HPET table memory at [mem 0x3fea1000-0x3fea1037]
Jun  9 17:47:15 localhost vmunix: [    0.009547] ACPI: Reserving WAET table memory at [mem 0x3fea0000-0x3fea0027]
Jun  9 17:47:15 localhost vmunix: [    0.009547] ACPI: Reserving BGRT table memory at [mem 0x3ecc1000-0x3ecc1037]
Jun  9 17:47:15 localhost vmunix: [    0.009727] No NUMA configuration found
Jun  9 17:47:15 localhost vmunix: [    0.009728] Faking a node at [mem 0x0000000000000000-0x000000003ff77fff]
Jun  9 17:47:15 localhost vmunix: [    0.009733] NODE_DATA(0) allocated [mem 0x3ff74000-0x3ff77fff]
Jun  9 17:47:15 localhost vmunix: [    0.009752] Zone ranges:
Jun  9 17:47:15 localhost vmunix: [    0.009757]   DMA32    [mem 0x0000000000001000-0x000000003ff77fff]
Jun  9 17:47:15 localhost vmunix: [    0.009758]   Normal   empty
Jun  9 17:47:15 localhost vmunix: [    0.009759]   Device   empty
Jun  9 17:47:15 localhost vmunix: [    0.009760] Movable zone start for each node
Jun  9 17:47:15 localhost vmunix: [    0.009761] Early memory node ranges
Jun  9 17:47:15 localhost vmunix: [    0.009761]   node   0: [mem 0x0000000000001000-0x000000000009ffff]
Jun  9 17:47:15 localhost vmunix: [    0.009764]   node   0: [mem 0x0000000000100000-0x00000000007fffff]
Jun  9 17:47:15 localhost vmunix: [    0.009765]   node   0: [mem 0x0000000000808000-0x000000000080ffff]
Jun  9 17:47:15 localhost vmunix: [    0.009766]   node   0: [mem 0x0000000000818000-0x000000000081ffff]
Jun  9 17:47:15 localhost vmunix: [    0.009766]   node   0: [mem 0x0000000000900000-0x000000003ecc0fff]
Jun  9 17:47:15 localhost vmunix: [    0.009767]   node   0: [mem 0x000000003ecc2000-0x000000003ed69fff]
Jun  9 17:47:15 localhost vmunix: [    0.009767]   node   0: [mem 0x000000003ed8e000-0x000000003eda7fff]
Jun  9 17:47:15 localhost vmunix: [    0.009768]   node   0: [mem 0x000000003edc8000-0x000000003fe47fff]
Jun  9 17:47:15 localhost vmunix: [    0.009768]   node   0: [mem 0x000000003feac000-0x000000003ff4ffff]
Jun  9 17:47:15 localhost vmunix: [    0.009769]   node   0: [mem 0x000000003ff70000-0x000000003ff77fff]
Jun  9 17:47:15 localhost vmunix: [    0.009770] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ff77fff]
Jun  9 17:47:15 localhost vmunix: [    0.017109]   DMA32 zone: 674 pages in unavailable ranges
Jun  9 17:47:15 localhost vmunix: [    0.017325] ACPI: PM-Timer IO Port: 0xb008
Jun  9 17:47:15 localhost vmunix: [    0.017336] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
Jun  9 17:47:15 localhost vmunix: [    0.017363] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
Jun  9 17:47:15 localhost vmunix: [    0.017366] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
Jun  9 17:47:15 localhost vmunix: [    0.017367] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
Jun  9 17:47:15 localhost vmunix: [    0.017371] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
Jun  9 17:47:15 localhost vmunix: [    0.017375] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
Jun  9 17:47:15 localhost vmunix: [    0.017376] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
Jun  9 17:47:15 localhost vmunix: [    0.017382] Using ACPI (MADT) for SMP configuration information
Jun  9 17:47:15 localhost vmunix: [    0.017383] ACPI: HPET id: 0x8086a201 base: 0xfed00000
Jun  9 17:47:15 localhost vmunix: [    0.017400] smpboot: Allowing 1 CPUs, 0 hotplug CPUs
Jun  9 17:47:15 localhost vmunix: [    0.017425] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]
Jun  9 17:47:15 localhost vmunix: [    0.017427] PM: hibernation: Registered nosave memory: [mem 0x000a0000-0x000fffff]
Jun  9 17:47:15 localhost vmunix: [    0.017428] PM: hibernation: Registered nosave memory: [mem 0x00800000-0x00807fff]
Jun  9 17:47:15 localhost vmunix: [    0.017429] PM: hibernation: Registered nosave memory: [mem 0x00810000-0x00817fff]
Jun  9 17:47:15 localhost vmunix: [    0.017430] PM: hibernation: Registered nosave memory: [mem 0x00820000-0x008fffff]
Jun  9 17:47:15 localhost vmunix: [    0.017431] PM: hibernation: Registered nosave memory: [mem 0x3ecc1000-0x3ecc1fff]
Jun  9 17:47:15 localhost vmunix: [    0.017432] PM: hibernation: Registered nosave memory: [mem 0x3ed6a000-0x3ed76fff]
Jun  9 17:47:15 localhost vmunix: [    0.017433] PM: hibernation: Registered nosave memory: [mem 0x3ed77000-0x3ed8dfff]
Jun  9 17:47:15 localhost vmunix: [    0.017434] PM: hibernation: Registered nosave memory: [mem 0x3eda8000-0x3edc7fff]
Jun  9 17:47:15 localhost vmunix: [    0.017435] PM: hibernation: Registered nosave memory: [mem 0x3f2a4000-0x3f2acfff]
Jun  9 17:47:15 localhost vmunix: [    0.017436] PM: hibernation: Registered nosave memory: [mem 0x3fe48000-0x3fe77fff]
Jun  9 17:47:15 localhost vmunix: [    0.017437] PM: hibernation: Registered nosave memory: [mem 0x3fe78000-0x3fe9ffff]
Jun  9 17:47:15 localhost vmunix: [    0.017437] PM: hibernation: Registered nosave memory: [mem 0x3fea0000-0x3fea7fff]
Jun  9 17:47:15 localhost vmunix: [    0.017438] PM: hibernation: Registered nosave memory: [mem 0x3fea8000-0x3feabfff]
Jun  9 17:47:15 localhost vmunix: [    0.017439] PM: hibernation: Registered nosave memory: [mem 0x3ff50000-0x3ff6ffff]
Jun  9 17:47:15 localhost vmunix: [    0.017440] [mem 0x40000000-0xffffffff] available for PCI devices
Jun  9 17:47:15 localhost vmunix: [    0.017441] Booting paravirtualized kernel on KVM
Jun  9 17:47:15 localhost vmunix: [    0.017443] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns
Jun  9 17:47:15 localhost vmunix: [    0.017451] setup_percpu: NR_CPUS:256 nr_cpumask_bits:256 nr_cpu_ids:1 nr_node_ids:1
Jun  9 17:47:15 localhost vmunix: [    0.017524] percpu: Embedded 55 pages/cpu s186200 r8192 d30888 u2097152
Jun  9 17:47:15 localhost vmunix: [    0.017558] kvm-guest: stealtime: cpu 0, msr 3fa18080
Jun  9 17:47:15 localhost vmunix: [    0.017561] kvm-guest: PV spinlocks disabled, no host support
Jun  9 17:47:15 localhost vmunix: [    0.017563] Built 1 zonelists, mobility grouping on.  Total pages: 255561
Jun  9 17:47:15 localhost vmunix: [    0.017567] Policy zone: DMA32
Jun  9 17:47:15 localhost vmunix: [    0.017568] Kernel command line: BOOT_IMAGE=/gnu/store/9wzwagq79npc6mr7ccmf9crq7dpiyi2y-linux-libre-5.12.9/bzImage --root=31393730-3031-3031-3139-343934363833 --system=/gnu/store/qfh9daps3zvhqc4wzmmx2m94j3qig2f5-system --load=/gnu/store/qfh9daps3zvhqc4wzmmx2m94j3qig2f5-system/boot quiet modprobe.blacklist=radeon
Jun  9 17:47:15 localhost vmunix: [    0.017755] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
Jun  9 17:47:15 localhost vmunix: [    0.017775] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)
Jun  9 17:47:15 localhost vmunix: [    0.017817] mem auto-init: stack:off, heap alloc:on, heap free:off
Jun  9 17:47:15 localhost vmunix: [    0.019170] Memory: 969884K/1045880K available (14345K kernel code, 2685K rwdata, 4804K rodata, 1892K init, 3476K bss, 75736K reserved, 0K cma-reserved)
Jun  9 17:47:15 localhost vmunix: [    0.019177] random: get_random_u64 called from __kmem_cache_create+0x2c/0x470 with crng_init=0
Jun  9 17:47:15 localhost vmunix: [    0.019266] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
Jun  9 17:47:15 localhost vmunix: [    0.019281] Kernel/User page tables isolation: enabled
Jun  9 17:47:15 localhost vmunix: [    0.019299] ftrace: allocating 45366 entries in 178 pages
Jun  9 17:47:15 localhost vmunix: [    0.032084] ftrace: allocated 178 pages with 4 groups
Jun  9 17:47:15 localhost vmunix: [    0.033082] rcu: Hierarchical RCU implementation.
Jun  9 17:47:15 localhost vmunix: [    0.033083] rcu: 	RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=1.
Jun  9 17:47:15 localhost vmunix: [    0.033085] 	Rude variant of Tasks RCU enabled.
Jun  9 17:47:15 localhost vmunix: [    0.033085] 	Tracing variant of Tasks RCU enabled.
Jun  9 17:47:15 localhost vmunix: [    0.033086] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
Jun  9 17:47:15 localhost vmunix: [    0.033087] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
Jun  9 17:47:15 localhost vmunix: [    0.035591] NR_IRQS: 16640, nr_irqs: 256, preallocated irqs: 16
Jun  9 17:47:15 localhost vmunix: [    0.035722] Console: colour dummy device 80x25
Jun  9 17:47:15 localhost vmunix: [    0.035734] printk: console [tty0] enabled
Jun  9 17:47:15 localhost vmunix: [    0.035747] ACPI: Core revision 20210105
Jun  9 17:47:15 localhost vmunix: [    0.035837] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
Jun  9 17:47:15 localhost vmunix: [    0.035903] APIC: Switch to symmetric I/O mode setup
Jun  9 17:47:15 localhost vmunix: [    0.036039] x2apic enabled
Jun  9 17:47:15 localhost vmunix: [    0.036190] Switched APIC routing to physical x2apic.
Jun  9 17:47:15 localhost vmunix: [    0.036993] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
Jun  9 17:47:15 localhost vmunix: [    0.037008] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x1a03dfe73b3, max_idle_ns: 440795256845 ns
Jun  9 17:47:15 localhost vmunix: [    0.037012] Calibrating delay loop (skipped) preset value.. 3609.60 BogoMIPS (lpj=7219200)
Jun  9 17:47:15 localhost vmunix: [    0.037015] pid_max: default: 32768 minimum: 301
Jun  9 17:47:15 localhost vmunix: [    0.037354] LSM: Security Framework initializing
Jun  9 17:47:15 localhost vmunix: [    0.037358] Yama: becoming mindful.
Jun  9 17:47:15 localhost vmunix: [    0.037374] AppArmor: AppArmor initialized
Jun  9 17:47:15 localhost vmunix: [    0.037376] TOMOYO Linux initialized
Jun  9 17:47:15 localhost vmunix: [    0.037403] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
Jun  9 17:47:15 localhost vmunix: [    0.037406] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
Jun  9 17:47:15 localhost vmunix: [    0.037670] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
Jun  9 17:47:15 localhost vmunix: [    0.037671] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
Jun  9 17:47:15 localhost vmunix: [    0.037673] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
Jun  9 17:47:15 localhost vmunix: [    0.037674] Spectre V2 : Mitigation: Full generic retpoline
Jun  9 17:47:15 localhost vmunix: [    0.037674] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
Jun  9 17:47:15 localhost vmunix: [    0.037675] Speculative Store Bypass: Vulnerable
Jun  9 17:47:15 localhost vmunix: [    0.037677] MDS: Vulnerable: Clear CPU buffers attempted, no microcode
Jun  9 17:47:15 localhost vmunix: [    0.044822] Freeing SMP alternatives memory: 40K
Jun  9 17:47:15 localhost vmunix: [    0.154782] smpboot: CPU0: Intel QEMU Virtual CPU version 2.5+ (family: 0x6, model: 0x6, stepping: 0x3)
Jun  9 17:47:15 localhost vmunix: [    0.154863] Performance Events: PMU not available due to virtualization, using software events only.
Jun  9 17:47:15 localhost vmunix: [    0.154897] rcu: Hierarchical SRCU implementation.
Jun  9 17:47:15 localhost vmunix: [    0.155202] NMI watchdog: Perf NMI watchdog permanently disabled
Jun  9 17:47:15 localhost vmunix: [    0.155226] smp: Bringing up secondary CPUs ...
Jun  9 17:47:15 localhost vmunix: [    0.155226] smp: Brought up 1 node, 1 CPU
Jun  9 17:47:15 localhost vmunix: [    0.155228] smpboot: Max logical packages: 1
Jun  9 17:47:15 localhost vmunix: [    0.155228] smpboot: Total of 1 processors activated (3609.60 BogoMIPS)
Jun  9 17:47:15 localhost vmunix: [    0.155364] devtmpfs: initialized
Jun  9 17:47:15 localhost vmunix: [    0.155399] x86/mm: Memory block size: 128MB
Jun  9 17:47:15 localhost vmunix: [    0.155547] PM: Registering ACPI NVS region [mem 0x00800000-0x00807fff] (32768 bytes)
Jun  9 17:47:15 localhost vmunix: [    0.155549] PM: Registering ACPI NVS region [mem 0x00810000-0x00817fff] (32768 bytes)
Jun  9 17:47:15 localhost vmunix: [    0.155550] PM: Registering ACPI NVS region [mem 0x00820000-0x008fffff] (917504 bytes)
Jun  9 17:47:15 localhost vmunix: [    0.155561] PM: Registering ACPI NVS region [mem 0x3ed6a000-0x3ed76fff] (53248 bytes)
Jun  9 17:47:15 localhost vmunix: [    0.155562] PM: Registering ACPI NVS region [mem 0x3fea8000-0x3feabfff] (16384 bytes)
Jun  9 17:47:15 localhost vmunix: [    0.155563] PM: Registering ACPI NVS region [mem 0x3ff78000-0x3fffffff] (557056 bytes)
Jun  9 17:47:15 localhost vmunix: [    0.155592] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
Jun  9 17:47:15 localhost vmunix: [    0.155596] futex hash table entries: 256 (order: 2, 16384 bytes, linear)
Jun  9 17:47:15 localhost vmunix: [    0.155629] pinctrl core: initialized pinctrl subsystem
Jun  9 17:47:15 localhost vmunix: [    0.155724] PM: RTC time: 15:47:08, date: 2021-06-09
Jun  9 17:47:15 localhost vmunix: [    0.155786] NET: Registered protocol family 16
Jun  9 17:47:15 localhost vmunix: [    0.155839] audit: initializing netlink subsys (disabled)
Jun  9 17:47:15 localhost vmunix: [    0.155934] thermal_sys: Registered thermal governor 'fair_share'
Jun  9 17:47:15 localhost vmunix: [    0.155935] thermal_sys: Registered thermal governor 'bang_bang'
Jun  9 17:47:15 localhost vmunix: [    0.155935] thermal_sys: Registered thermal governor 'step_wise'
Jun  9 17:47:15 localhost vmunix: [    0.155936] thermal_sys: Registered thermal governor 'user_space'
Jun  9 17:47:15 localhost vmunix: [    0.155938] cpuidle: using governor ladder
Jun  9 17:47:15 localhost vmunix: [    0.155939] cpuidle: using governor menu
Jun  9 17:47:15 localhost vmunix: [    0.155971] ACPI: bus type PCI registered
Jun  9 17:47:15 localhost vmunix: [    0.155973] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
Jun  9 17:47:15 localhost vmunix: [    0.156052] PCI: Using configuration type 1 for base access
Jun  9 17:47:15 localhost vmunix: [    0.156778] Kprobes globally optimized
Jun  9 17:47:15 localhost vmunix: [    0.156788] audit: type=2000 audit(1623253628.937:1): state=initialized audit_enabled=0 res=1
Jun  9 17:47:15 localhost vmunix: [    0.156828] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
Jun  9 17:47:15 localhost vmunix: [    0.157011] ACPI: Added _OSI(Module Device)
Jun  9 17:47:15 localhost vmunix: [    0.157011] ACPI: Added _OSI(Processor Device)
Jun  9 17:47:15 localhost vmunix: [    0.157011] ACPI: Added _OSI(3.0 _SCP Extensions)
Jun  9 17:47:15 localhost vmunix: [    0.157011] ACPI: Added _OSI(Processor Aggregator Device)
Jun  9 17:47:15 localhost vmunix: [    0.157011] ACPI: Added _OSI(Linux-Dell-Video)
Jun  9 17:47:15 localhost vmunix: [    0.157011] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
Jun  9 17:47:15 localhost vmunix: [    0.157011] ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics)
Jun  9 17:47:15 localhost vmunix: [    0.157011] ACPI: 1 ACPI AML tables successfully acquired and loaded
Jun  9 17:47:15 localhost vmunix: [    0.157011] ACPI: Interpreter enabled
Jun  9 17:47:15 localhost vmunix: [    0.157011] ACPI: (supports S0 S3 S4 S5)
Jun  9 17:47:15 localhost vmunix: [    0.157011] ACPI: Using IOAPIC for interrupt routing
Jun  9 17:47:15 localhost vmunix: [    0.157011] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
Jun  9 17:47:15 localhost vmunix: [    0.157011] ACPI: Enabled 2 GPEs in block 00 to 0F
Jun  9 17:47:15 localhost vmunix: [    0.157691] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
Jun  9 17:47:15 localhost vmunix: [    0.157696] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3]
Jun  9 17:47:15 localhost vmunix: [    0.157702] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
Jun  9 17:47:15 localhost vmunix: [    0.157846] acpiphp: Slot [3] registered
Jun  9 17:47:15 localhost vmunix: [    0.157855] acpiphp: Slot [4] registered
Jun  9 17:47:15 localhost vmunix: [    0.157864] acpiphp: Slot [5] registered
Jun  9 17:47:15 localhost vmunix: [    0.157872] acpiphp: Slot [6] registered
Jun  9 17:47:15 localhost vmunix: [    0.157880] acpiphp: Slot [7] registered
Jun  9 17:47:15 localhost vmunix: [    0.157889] acpiphp: Slot [8] registered
Jun  9 17:47:15 localhost vmunix: [    0.157897] acpiphp: Slot [9] registered
Jun  9 17:47:15 localhost vmunix: [    0.157905] acpiphp: Slot [10] registered
Jun  9 17:47:15 localhost vmunix: [    0.157913] acpiphp: Slot [11] registered
Jun  9 17:47:15 localhost vmunix: [    0.157921] acpiphp: Slot [12] registered
Jun  9 17:47:15 localhost vmunix: [    0.157930] acpiphp: Slot [13] registered
Jun  9 17:47:15 localhost vmunix: [    0.157938] acpiphp: Slot [14] registered
Jun  9 17:47:15 localhost vmunix: [    0.157947] acpiphp: Slot [15] registered
Jun  9 17:47:15 localhost vmunix: [    0.157956] acpiphp: Slot [16] registered
Jun  9 17:47:15 localhost vmunix: [    0.157965] acpiphp: Slot [17] registered
Jun  9 17:47:15 localhost vmunix: [    0.157973] acpiphp: Slot [18] registered
Jun  9 17:47:15 localhost vmunix: [    0.157981] acpiphp: Slot [19] registered
Jun  9 17:47:15 localhost vmunix: [    0.157990] acpiphp: Slot [20] registered
Jun  9 17:47:15 localhost vmunix: [    0.157998] acpiphp: Slot [21] registered
Jun  9 17:47:15 localhost vmunix: [    0.158006] acpiphp: Slot [22] registered
Jun  9 17:47:15 localhost vmunix: [    0.158014] acpiphp: Slot [23] registered
Jun  9 17:47:15 localhost vmunix: [    0.158023] acpiphp: Slot [24] registered
Jun  9 17:47:15 localhost vmunix: [    0.158031] acpiphp: Slot [25] registered
Jun  9 17:47:15 localhost vmunix: [    0.158040] acpiphp: Slot [26] registered
Jun  9 17:47:15 localhost vmunix: [    0.158048] acpiphp: Slot [27] registered
Jun  9 17:47:15 localhost vmunix: [    0.158056] acpiphp: Slot [28] registered
Jun  9 17:47:15 localhost vmunix: [    0.158065] acpiphp: Slot [29] registered
Jun  9 17:47:15 localhost vmunix: [    0.158074] acpiphp: Slot [30] registered
Jun  9 17:47:15 localhost vmunix: [    0.158082] acpiphp: Slot [31] registered
Jun  9 17:47:15 localhost vmunix: [    0.158088] PCI host bridge to bus 0000:00
Jun  9 17:47:15 localhost vmunix: [    0.158089] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
Jun  9 17:47:15 localhost vmunix: [    0.158090] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
Jun  9 17:47:15 localhost vmunix: [    0.158091] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
Jun  9 17:47:15 localhost vmunix: [    0.158092] pci_bus 0000:00: root bus resource [mem 0x40000000-0xfebfffff window]
Jun  9 17:47:15 localhost vmunix: [    0.158093] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window]
Jun  9 17:47:15 localhost vmunix: [    0.158094] pci_bus 0000:00: root bus resource [bus 00-ff]
Jun  9 17:47:15 localhost vmunix: [    0.158119] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
Jun  9 17:47:15 localhost vmunix: [    0.158586] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
Jun  9 17:47:15 localhost vmunix: [    0.158890] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
Jun  9 17:47:15 localhost vmunix: [    0.159882] pci 0000:00:01.1: reg 0x20: [io  0xc040-0xc04f]
Jun  9 17:47:15 localhost vmunix: [    0.160258] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
Jun  9 17:47:15 localhost vmunix: [    0.160260] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
Jun  9 17:47:15 localhost vmunix: [    0.160261] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
Jun  9 17:47:15 localhost vmunix: [    0.160262] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
Jun  9 17:47:15 localhost vmunix: [    0.160372] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
Jun  9 17:47:15 localhost vmunix: [    0.160599] pci 0000:00:01.3: quirk: [io  0xb000-0xb03f] claimed by PIIX4 ACPI
Jun  9 17:47:15 localhost vmunix: [    0.160604] pci 0000:00:01.3: quirk: [io  0xb100-0xb10f] claimed by PIIX4 SMB
Jun  9 17:47:15 localhost vmunix: [    0.160948] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000
Jun  9 17:47:15 localhost vmunix: [    0.161261] pci 0000:00:02.0: reg 0x10: [mem 0x80000000-0x80ffffff pref]
Jun  9 17:47:15 localhost vmunix: [    0.162077] pci 0000:00:02.0: reg 0x18: [mem 0x81020000-0x81020fff]
Jun  9 17:47:15 localhost vmunix: [    0.165999] pci 0000:00:02.0: reg 0x30: [mem 0xffff0000-0xffffffff pref]
Jun  9 17:47:15 localhost vmunix: [    0.166161] pci 0000:00:02.0: BAR 0: assigned to efifb
Jun  9 17:47:15 localhost vmunix: [    0.166811] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000
Jun  9 17:47:15 localhost vmunix: [    0.167196] pci 0000:00:03.0: reg 0x10: [mem 0x81000000-0x8101ffff]
Jun  9 17:47:15 localhost vmunix: [    0.167559] pci 0000:00:03.0: reg 0x14: [io  0xc000-0xc03f]
Jun  9 17:47:15 localhost vmunix: [    0.169331] pci 0000:00:03.0: reg 0x30: [mem 0xfffc0000-0xffffffff pref]
Jun  9 17:47:15 localhost vmunix: [    0.178272] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 10 *11)
Jun  9 17:47:15 localhost vmunix: [    0.178335] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 10 *11)
Jun  9 17:47:15 localhost vmunix: [    0.178383] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 *10 11)
Jun  9 17:47:15 localhost vmunix: [    0.178430] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 *10 11)
Jun  9 17:47:15 localhost vmunix: [    0.178457] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
Jun  9 17:47:15 localhost vmunix: [    0.178697] iommu: Default domain type: Translated 
Jun  9 17:47:15 localhost vmunix: [    0.178748] pci 0000:00:02.0: vgaarb: setting as boot VGA device
Jun  9 17:47:15 localhost vmunix: [    0.178750] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
Jun  9 17:47:15 localhost vmunix: [    0.178752] pci 0000:00:02.0: vgaarb: bridge control possible
Jun  9 17:47:15 localhost vmunix: [    0.178752] vgaarb: loaded
Jun  9 17:47:15 localhost vmunix: [    0.178936] SCSI subsystem initialized
Jun  9 17:47:15 localhost vmunix: [    0.178985] ACPI: bus type USB registered
Jun  9 17:47:15 localhost vmunix: [    0.179003] usbcore: registered new interface driver usbfs
Jun  9 17:47:15 localhost vmunix: [    0.179011] usbcore: registered new interface driver hub
Jun  9 17:47:15 localhost vmunix: [    0.179014] usbcore: registered new device driver usb
Jun  9 17:47:15 localhost vmunix: [    0.179056] EDAC MC: Ver: 3.0.0
Jun  9 17:47:15 localhost vmunix: [    0.179201] Registered efivars operations
Jun  9 17:47:15 localhost vmunix: [    0.179455] NetLabel: Initializing
Jun  9 17:47:15 localhost vmunix: [    0.179457] NetLabel:  domain hash size = 128
Jun  9 17:47:15 localhost vmunix: [    0.179458] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
Jun  9 17:47:15 localhost vmunix: [    0.179475] NetLabel:  unlabeled traffic allowed by default
Jun  9 17:47:15 localhost vmunix: [    0.179497] PCI: Using ACPI for IRQ routing
Jun  9 17:47:15 localhost vmunix: [    0.179669] hpet: 3 channels of 0 reserved for per-cpu timers
Jun  9 17:47:15 localhost vmunix: [    0.179678] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
Jun  9 17:47:15 localhost vmunix: [    0.179681] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
Jun  9 17:47:15 localhost vmunix: [    0.183074] clocksource: Switched to clocksource kvm-clock
Jun  9 17:47:15 localhost vmunix: [    0.189434] VFS: Disk quotas dquot_6.6.0
Jun  9 17:47:15 localhost vmunix: [    0.189454] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
Jun  9 17:47:15 localhost vmunix: [    0.189565] AppArmor: AppArmor Filesystem Enabled
Jun  9 17:47:15 localhost vmunix: [    0.189592] pnp: PnP ACPI init
Jun  9 17:47:15 localhost vmunix: [    0.189872] pnp: PnP ACPI: found 6 devices
Jun  9 17:47:15 localhost vmunix: [    0.195056] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
Jun  9 17:47:15 localhost vmunix: [    0.195103] NET: Registered protocol family 2
Jun  9 17:47:15 localhost vmunix: [    0.195130] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
Jun  9 17:47:15 localhost vmunix: [    0.197884] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)
Jun  9 17:47:15 localhost vmunix: [    0.197890] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)
Jun  9 17:47:15 localhost vmunix: [    0.197902] TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear)
Jun  9 17:47:15 localhost vmunix: [    0.197907] TCP: Hash tables configured (established 8192 bind 8192)
Jun  9 17:47:15 localhost vmunix: [    0.197944] MPTCP token hash table entries: 1024 (order: 2, 24576 bytes, linear)
Jun  9 17:47:15 localhost vmunix: [    0.197952] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
Jun  9 17:47:15 localhost vmunix: [    0.197955] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
Jun  9 17:47:15 localhost vmunix: [    0.197987] NET: Registered protocol family 1
Jun  9 17:47:15 localhost vmunix: [    0.197995] NET: Registered protocol family 44
Jun  9 17:47:15 localhost vmunix: [    0.198001] pci 0000:00:02.0: can't claim BAR 6 [mem 0xffff0000-0xffffffff pref]: no compatible bridge window
Jun  9 17:47:15 localhost vmunix: [    0.198004] pci 0000:00:03.0: can't claim BAR 6 [mem 0xfffc0000-0xffffffff pref]: no compatible bridge window
Jun  9 17:47:15 localhost vmunix: [    0.198011] pci 0000:00:03.0: BAR 6: assigned [mem 0x40000000-0x4003ffff pref]
Jun  9 17:47:15 localhost vmunix: [    0.198012] pci 0000:00:02.0: BAR 6: assigned [mem 0x40040000-0x4004ffff pref]
Jun  9 17:47:15 localhost vmunix: [    0.198014] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
Jun  9 17:47:15 localhost vmunix: [    0.198016] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
Jun  9 17:47:15 localhost vmunix: [    0.198017] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
Jun  9 17:47:15 localhost vmunix: [    0.198018] pci_bus 0000:00: resource 7 [mem 0x40000000-0xfebfffff window]
Jun  9 17:47:15 localhost vmunix: [    0.198019] pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window]
Jun  9 17:47:15 localhost vmunix: [    0.198048] pci 0000:00:01.0: PIIX3: Enabling Passive Release
Jun  9 17:47:15 localhost vmunix: [    0.198057] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
Jun  9 17:47:15 localhost vmunix: [    0.198065] pci 0000:00:01.0: Activating ISA DMA hang workarounds
Jun  9 17:47:15 localhost vmunix: [    0.198097] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
Jun  9 17:47:15 localhost vmunix: [    0.198107] PCI: CLS 0 bytes, default 64
Jun  9 17:47:15 localhost vmunix: [    0.198140] Trying to unpack rootfs image as initramfs...
Jun  9 17:47:15 localhost vmunix: [    0.357529] Freeing initrd memory: 13312K
Jun  9 17:47:15 localhost vmunix: [    0.357586] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x1a03dfe73b3, max_idle_ns: 440795256845 ns
Jun  9 17:47:15 localhost vmunix: [    0.357639] check: Scanning for low memory corruption every 60 seconds
Jun  9 17:47:15 localhost vmunix: [    0.357826] Initialise system trusted keyrings
Jun  9 17:47:15 localhost vmunix: [    0.357858] workingset: timestamp_bits=40 max_order=18 bucket_order=0
Jun  9 17:47:15 localhost vmunix: [    0.358366] zbud: loaded
Jun  9 17:47:15 localhost vmunix: [    0.358967] Allocating IMA blacklist keyring.
Jun  9 17:47:15 localhost vmunix: [    0.365599] Key type asymmetric registered
Jun  9 17:47:15 localhost vmunix: [    0.365600] Asymmetric key parser 'x509' registered
Jun  9 17:47:15 localhost vmunix: [    0.365607] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
Jun  9 17:47:15 localhost vmunix: [    0.365662] io scheduler mq-deadline registered
Jun  9 17:47:15 localhost vmunix: [    0.365735] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
Jun  9 17:47:15 localhost vmunix: [    0.365758] efifb: probing for efifb
Jun  9 17:47:15 localhost vmunix: [    0.365771] efifb: framebuffer at 0x80000000, using 1408k, total 1408k
Jun  9 17:47:15 localhost vmunix: [    0.365772] efifb: mode is 800x600x24, linelength=2400, pages=1
Jun  9 17:47:15 localhost vmunix: [    0.365773] efifb: scrolling: redraw
Jun  9 17:47:15 localhost vmunix: [    0.365773] efifb: Truecolor: size=0:8:8:8, shift=0:16:8:0
Jun  9 17:47:15 localhost vmunix: [    0.365846] Console: switching to colour frame buffer device 100x37
Jun  9 17:47:15 localhost vmunix: [    0.367029] fb0: EFI VGA frame buffer device
Jun  9 17:47:15 localhost vmunix: [    0.367100] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
Jun  9 17:47:15 localhost vmunix: [    0.367126] ACPI: button: Power Button [PWRF]
Jun  9 17:47:15 localhost vmunix: [    0.367287] Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled
Jun  9 17:47:15 localhost vmunix: [    0.367389] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
Jun  9 17:47:15 localhost vmunix: [    0.368180] Linux agpgart interface v0.103
Jun  9 17:47:15 localhost vmunix: [    0.369106] brd: module loaded
Jun  9 17:47:15 localhost vmunix: [    0.369889] loop: module loaded
Jun  9 17:47:15 localhost vmunix: [    0.370278] scsi host0: ata_piix
Jun  9 17:47:15 localhost vmunix: [    0.370335] scsi host1: ata_piix
Jun  9 17:47:15 localhost vmunix: [    0.370352] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc040 irq 14
Jun  9 17:47:15 localhost vmunix: [    0.370354] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc048 irq 15
Jun  9 17:47:15 localhost vmunix: [    0.370434] libphy: Fixed MDIO Bus: probed
Jun  9 17:47:15 localhost vmunix: [    0.370434] tun: Universal TUN/TAP device driver, 1.6
Jun  9 17:47:15 localhost vmunix: [    0.370603] PPP generic driver version 2.4.2
Jun  9 17:47:15 localhost vmunix: [    0.370626] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
Jun  9 17:47:15 localhost vmunix: [    0.370628] ehci-pci: EHCI PCI platform driver
Jun  9 17:47:15 localhost vmunix: [    0.370636] ehci-platform: EHCI generic platform driver
Jun  9 17:47:15 localhost vmunix: [    0.370639] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
Jun  9 17:47:15 localhost vmunix: [    0.370640] ohci-pci: OHCI PCI platform driver
Jun  9 17:47:15 localhost vmunix: [    0.370646] ohci-platform: OHCI generic platform driver
Jun  9 17:47:15 localhost vmunix: [    0.370651] uhci_hcd: USB Universal Host Controller Interface driver
Jun  9 17:47:15 localhost vmunix: [    0.370682] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
Jun  9 17:47:15 localhost vmunix: [    0.371110] serio: i8042 KBD port at 0x60,0x64 irq 1
Jun  9 17:47:15 localhost vmunix: [    0.371113] serio: i8042 AUX port at 0x60,0x64 irq 12
Jun  9 17:47:15 localhost vmunix: [    0.371167] mousedev: PS/2 mouse device common for all mice
Jun  9 17:47:15 localhost vmunix: [    0.371311] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1
Jun  9 17:47:15 localhost vmunix: [    0.371450] rtc_cmos 00:05: RTC can wake from S4
Jun  9 17:47:15 localhost vmunix: [    0.371687] rtc_cmos 00:05: registered as rtc0
Jun  9 17:47:15 localhost vmunix: [    0.371726] rtc_cmos 00:05: setting system clock to 2021-06-09T15:47:08 UTC (1623253628)
Jun  9 17:47:15 localhost vmunix: [    0.371739] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs
Jun  9 17:47:15 localhost vmunix: [    0.371743] i2c /dev entries driver
Jun  9 17:47:15 localhost vmunix: [    0.371764] device-mapper: uevent: version 1.0.3
Jun  9 17:47:15 localhost vmunix: [    0.371791] device-mapper: ioctl: 4.44.0-ioctl (2021-02-01) initialised: dm-devel@redhat.com
Jun  9 17:47:15 localhost vmunix: [    0.371793] intel_pstate: CPU model not supported
Jun  9 17:47:15 localhost vmunix: [    0.371799] ledtrig-cpu: registered to indicate activity on CPUs
Jun  9 17:47:15 localhost vmunix: [    0.371800] EFI Variables Facility v0.08 2004-May-17
Jun  9 17:47:15 localhost vmunix: [    0.373132] NET: Registered protocol family 10
Jun  9 17:47:15 localhost vmunix: [    0.373289] Segment Routing with IPv6
Jun  9 17:47:15 localhost vmunix: [    0.373300] NET: Registered protocol family 17
Jun  9 17:47:15 localhost vmunix: [    0.373321] Key type dns_resolver registered
Jun  9 17:47:15 localhost vmunix: [    0.373389] IPI shorthand broadcast: enabled
Jun  9 17:47:15 localhost vmunix: [    0.373395] sched_clock: Marking stable (375695545, -2680160)->(376439131, -3423746)
Jun  9 17:47:15 localhost vmunix: [    0.373436] registered taskstats version 1
Jun  9 17:47:15 localhost vmunix: [    0.373441] Loading compiled-in X.509 certificates
Jun  9 17:47:15 localhost vmunix: [    0.373467] zswap: loaded using pool lzo/zbud
Jun  9 17:47:15 localhost vmunix: [    0.373638] Key type encrypted registered
Jun  9 17:47:15 localhost vmunix: [    0.373640] AppArmor: AppArmor sha1 policy hashing enabled
Jun  9 17:47:15 localhost vmunix: [    0.373645] ima: No TPM chip found, activating TPM-bypass!
Jun  9 17:47:15 localhost vmunix: [    0.373647] ima: Allocated hash algorithm: sha1
Jun  9 17:47:15 localhost vmunix: [    0.373654] ima: No architecture policies found
Jun  9 17:47:15 localhost vmunix: [    0.373661] evm: Initialising EVM extended attributes:
Jun  9 17:47:15 localhost vmunix: [    0.373661] evm: security.selinux
Jun  9 17:47:15 localhost vmunix: [    0.373661] evm: security.SMACK64
Jun  9 17:47:15 localhost vmunix: [    0.373662] evm: security.SMACK64EXEC
Jun  9 17:47:15 localhost vmunix: [    0.373662] evm: security.SMACK64TRANSMUTE
Jun  9 17:47:15 localhost vmunix: [    0.373663] evm: security.SMACK64MMAP
Jun  9 17:47:15 localhost vmunix: [    0.373663] evm: security.apparmor
Jun  9 17:47:15 localhost vmunix: [    0.373664] evm: security.ima
Jun  9 17:47:15 localhost vmunix: [    0.373664] evm: security.capability
Jun  9 17:47:15 localhost vmunix: [    0.373664] evm: HMAC attrs: 0x1
Jun  9 17:47:15 localhost vmunix: [    0.373859] PM:   Magic number: 9:502:790
Jun  9 17:47:15 localhost vmunix: [    0.529928] ata1.00: ATA-7: QEMU HARDDISK, 2.5+, max UDMA/100
Jun  9 17:47:15 localhost vmunix: [    0.529930] ata1.00: 20971520 sectors, multi 16: LBA48 
Jun  9 17:47:15 localhost vmunix: [    0.530073] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
Jun  9 17:47:15 localhost vmunix: [    0.530580] scsi 0:0:0:0: Direct-Access     ATA      QEMU HARDDISK    2.5+ PQ: 0 ANSI: 5
Jun  9 17:47:15 localhost vmunix: [    0.530730] sd 0:0:0:0: [sda] 20971520 512-byte logical blocks: (10.7 GB/10.0 GiB)
Jun  9 17:47:15 localhost vmunix: [    0.530735] sd 0:0:0:0: [sda] Write Protect is off
Jun  9 17:47:15 localhost vmunix: [    0.530744] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Jun  9 17:47:15 localhost vmunix: [    0.530915] sd 0:0:0:0: Attached scsi generic sg0 type 0
Jun  9 17:47:15 localhost vmunix: [    0.531375] scsi 1:0:0:0: CD-ROM            QEMU     QEMU DVD-ROM     2.5+ PQ: 0 ANSI: 5
Jun  9 17:47:15 localhost vmunix: [    0.532815] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
Jun  9 17:47:15 localhost vmunix: [    0.532817] cdrom: Uniform CD-ROM driver Revision: 3.20
Jun  9 17:47:15 localhost vmunix: [    0.533163]  sda: sda1 sda2 sda3 sda4 sda5 sda6
Jun  9 17:47:15 localhost vmunix: [    0.545172] sd 0:0:0:0: [sda] Attached SCSI disk
Jun  9 17:47:15 localhost vmunix: [    0.557734] sr 1:0:0:0: Attached scsi generic sg1 type 5
Jun  9 17:47:15 localhost vmunix: [    0.558740] Freeing unused kernel image (initmem) memory: 1892K
Jun  9 17:47:15 localhost vmunix: [    0.558751] Write protecting the kernel read-only data: 22528k
Jun  9 17:47:15 localhost vmunix: [    0.559620] Freeing unused kernel image (text/rodata gap) memory: 2036K
Jun  9 17:47:15 localhost vmunix: [    0.560170] Freeing unused kernel image (rodata/data gap) memory: 1340K
Jun  9 17:47:15 localhost vmunix: [    0.560183] Run /init as init process
Jun  9 17:47:15 localhost vmunix: [    0.600020] usbcore: registered new interface driver usb-storage
Jun  9 17:47:15 localhost vmunix: [    0.602881] usbcore: registered new interface driver uas
Jun  9 17:47:15 localhost vmunix: [    0.605054] hid: raw HID events driver (C) Jiri Kosina
Jun  9 17:47:15 localhost vmunix: [    0.605390] usbcore: registered new interface driver usbhid
Jun  9 17:47:15 localhost vmunix: [    0.605391] usbhid: USB HID core driver
Jun  9 17:47:15 localhost vmunix: [    0.614888] isci: Intel(R) C600 SAS Controller Driver - version 1.2.0
Jun  9 17:47:15 localhost vmunix: [    0.670278] random: fast init done
Jun  9 17:47:15 localhost vmunix: [    0.757383] random: dbus-uuidgen: uninitialized urandom read (12 bytes read)
Jun  9 17:47:15 localhost vmunix: [    0.757398] random: dbus-uuidgen: uninitialized urandom read (8 bytes read)
Jun  9 17:47:15 localhost vmunix: [    3.289021] random: crng init done
Jun  9 17:47:15 localhost vmunix: [    3.855855] shepherd[1]: Service root has been started.
Jun  9 17:47:15 localhost vmunix: [    3.946887] shepherd[1]: starting services...
Jun  9 17:47:15 localhost vmunix: [    3.947242] shepherd[1]: Service root-file-system has been started.
Jun  9 17:47:15 localhost vmunix: [    3.948757] shepherd[1]: Service user-file-systems has been started.
Jun  9 17:47:15 localhost vmunix: [    3.952687] shepherd[1]: waiting for udevd...
Jun  9 17:47:15 localhost vmunix: [    3.966561] udevd[117]: starting version 3.2.9
Jun  9 17:47:15 localhost vmunix: [    3.979778] udevd[117]: starting eudev-3.2.9
Jun  9 17:47:15 localhost vmunix: [    4.454073] udevd[117]: no sender credentials received, message ignored
Jun  9 17:47:15 localhost vmunix: [    4.494297] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0xb100, revision 0
Jun  9 17:47:15 localhost vmunix: [    4.528433] e1000: Intel(R) PRO/1000 Network Driver
Jun  9 17:47:15 localhost vmunix: [    4.528437] e1000: Copyright (c) 1999-2006 Intel Corporation.
Jun  9 17:47:15 localhost vmunix: [    4.570193] Floppy drive(s): fd0 is 2.88M AMI BIOS
Jun  9 17:47:15 localhost vmunix: [    4.570922] parport_pc 00:03: reported by Plug and Play ACPI
Jun  9 17:47:15 localhost vmunix: [    4.571001] parport0: PC-style at 0x378, irq 7 [PCSPP,TRISTATE]
Jun  9 17:47:15 localhost vmunix: [    4.589254] FDC 0 is a S82078B
Jun  9 17:47:15 localhost vmunix: [    4.635119] PCI Interrupt Link [LNKC] enabled at IRQ 10
Jun  9 17:47:15 localhost vmunix: [    4.650826] input: PC Speaker as /devices/platform/pcspkr/input/input3
Jun  9 17:47:15 localhost vmunix: [    4.669094] parport_pc parport_pc.956: Unable to set coherent dma mask: disabling DMA
Jun  9 17:47:15 localhost vmunix: [    4.669199] parport_pc parport_pc.888: Unable to set coherent dma mask: disabling DMA
Jun  9 17:47:15 localhost vmunix: [    4.669252] parport_pc parport_pc.632: Unable to set coherent dma mask: disabling DMA
Jun  9 17:47:15 localhost vmunix: [    4.672969] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5
Jun  9 17:47:15 localhost vmunix: [    4.673104] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4
Jun  9 17:47:15 localhost vmunix: [    4.679856] fb0: switching to bochsdrmfb from EFI VGA
Jun  9 17:47:15 localhost vmunix: [    4.680063] Console: switching to colour dummy device 80x25
Jun  9 17:47:15 localhost vmunix: [    4.682190] pstore: Using crash dump compression: deflate
Jun  9 17:47:15 localhost vmunix: [    4.682193] pstore: Registered efi as persistent store backend
Jun  9 17:47:15 localhost vmunix: [    4.682428] bochs-drm 0000:00:02.0: vgaarb: deactivate vga console
Jun  9 17:47:15 localhost vmunix: [    4.682485] [drm] Found bochs VGA, ID 0xb0c5.
Jun  9 17:47:15 localhost vmunix: [    4.682486] [drm] Framebuffer size 16384 kB @ 0x80000000, mmio @ 0x81020000.
Jun  9 17:47:15 localhost vmunix: [    4.685098] [TTM] Zone  kernel: Available graphics memory: 501036 KiB
Jun  9 17:47:15 localhost vmunix: [    4.685719] [drm] Found EDID data blob.
Jun  9 17:47:15 localhost vmunix: [    4.686262] [drm] Initialized bochs-drm 1.0.0 20130925 for 0000:00:02.0 on minor 0
Jun  9 17:47:15 localhost vmunix: [    4.692068] fbcon: bochs-drmdrmfb (fb0) is primary device
Jun  9 17:47:15 localhost vmunix: [    4.694432] Console: switching to colour frame buffer device 128x48
Jun  9 17:47:15 localhost vmunix: [    4.699180] Error: Driver 'pcspkr' is already registered, aborting...
Jun  9 17:47:15 localhost vmunix: [    4.720962] bochs-drm 0000:00:02.0: [drm] fb0: bochs-drmdrmfb frame buffer device
Jun  9 17:47:15 localhost vmunix: [    4.740188] ppdev: user-space parallel port driver
Jun  9 17:47:15 localhost vmunix: [    4.811758] blk_update_request: I/O error, dev fd0, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 0
Jun  9 17:47:15 localhost vmunix: [    4.811791] floppy: error 10 while reading block 0
Jun  9 17:47:15 localhost vmunix: [    5.015653] e1000 0000:00:03.0 eth0: (PCI:33MHz:32-bit) 52:54:00:12:34:56
Jun  9 17:47:15 localhost vmunix: [    5.015660] e1000 0000:00:03.0 eth0: Intel(R) PRO/1000 Network Connection
Jun  9 17:47:15 localhost vmunix: [    5.016914] e1000 0000:00:03.0 ens3: renamed from eth0
Jun  9 17:47:15 localhost vmunix: [    7.738012] shepherd[1]: Service udev has been started.
Jun  9 17:47:15 localhost vmunix: [    7.738639] shepherd[1]: Service file-system-/tmp has been started.
Jun  9 17:47:15 localhost vmunix: [    7.739070] shepherd[1]: Service file-system-/dev/pts has been started.
Jun  9 17:47:15 localhost vmunix: [    7.739558] shepherd[1]: Service file-system-/dev/shm has been started.
Jun  9 17:47:15 localhost vmunix: [    7.740306] shepherd[1]: Service file-system-/sys/firmware/efi/efivars has been started.
Jun  9 17:47:15 localhost vmunix: [    7.740766] shepherd[1]: Service file-system-/gnu/store has been started.
Jun  9 17:47:15 localhost vmunix: [    7.741076] shepherd[1]: Service file-systems has been started.
Jun  9 17:47:15 localhost vmunix: [    7.746483] shepherd[1]: Service user-homes has been started.
Jun  9 17:47:15 localhost vmunix: [    7.746745] shepherd[1]: Service user-processes has been started.
Jun  9 17:47:15 localhost vmunix: [    7.747067] shepherd[1]: Service host-name has been started.
Jun  9 17:47:15 localhost vmunix: [    7.747358] shepherd[1]: Service user-homes has been started.
Jun  9 17:47:15 localhost vmunix: [    7.747689] shepherd[1]: Service maybe-uvesafb has been started.
Jun  9 17:47:16 localhost shepherd[1]: Service syslogd has been started. 
Jun  9 17:47:17 localhost shepherd[1]: Service dbus-system has been started. 
Jun  9 17:47:17 localhost shepherd[1]: Service loopback has been started. 
Jun  9 17:47:17 localhost wpa_supplicant[146]: Successfully initialized wpa_supplicant
Jun  9 17:47:18 localhost shepherd[1]: Service wpa-supplicant has been started. 
Jun  9 17:47:18 localhost shepherd[1]: Service networking has been started. 
Jun  9 17:47:18 localhost nscd: 149 monitoring file `/etc/hosts` (1)
Jun  9 17:47:18 localhost nscd: 149 monitoring directory `/etc` (2)
Jun  9 17:47:18 localhost nscd: 149 monitoring file `/etc/resolv.conf` (3)
Jun  9 17:47:18 localhost nscd: 149 monitoring directory `/etc` (2)
Jun  9 17:47:18 localhost connmand[148]: Connection Manager version 1.39
Jun  9 17:47:18 localhost connmand[148]: Excluding VPN plugin
Jun  9 17:47:18 localhost connmand[148]: Checking loopback interface settings
Jun  9 17:47:18 localhost connmand[148]: System hostname is gnu
Jun  9 17:47:18 localhost connmand[148]: lo {newlink} index 1 address 00:00:00:00:00:00 mtu 65536
Jun  9 17:47:18 localhost connmand[148]: lo {newlink} index 1 operstate 0 <UNKNOWN>
Jun  9 17:47:18 localhost connmand[148]: ens3 {create} index 2 type 1 <ETHER>
Jun  9 17:47:18 localhost connmand[148]: ens3 {update} flags 4098 <DOWN>
Jun  9 17:47:18 localhost connmand[148]: ens3 {newlink} index 2 address 52:54:00:12:34:56 mtu 1500
Jun  9 17:47:18 localhost connmand[148]: ens3 {newlink} index 2 operstate 2 <DOWN>
Jun  9 17:47:18 localhost connmand[148]: Adding interface ens3 [ ethernet ]
Jun  9 17:47:18 localhost connmand[148]: ens3 {update} flags 36867 <UP>
Jun  9 17:47:18 localhost connmand[148]: ens3 {newlink} index 2 address 52:54:00:12:34:56 mtu 1500
Jun  9 17:47:18 localhost connmand[148]: ens3 {newlink} index 2 operstate 2 <DOWN>
Jun  9 17:47:18 localhost vmunix: [   10.805472] e1000: ens3 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX
Jun  9 17:47:18 localhost vmunix: [   10.805745] IPv6: ADDRCONF(NETDEV_CHANGE): ens3: link becomes ready
Jun  9 17:47:18 localhost connmand[148]: ens3 {add} route fe80:: gw :: scope 0 <UNIVERSE>
Jun  9 17:47:18 localhost connmand[148]: ens3 {update} flags 102467 <UP,RUNNING,LOWER_UP>
Jun  9 17:47:18 localhost connmand[148]: ens3 {newlink} index 2 address 52:54:00:12:34:56 mtu 1500
Jun  9 17:47:18 localhost connmand[148]: ens3 {newlink} index 2 operstate 6 <UP>
Jun  9 17:47:18 localhost vmunix: [   10.826500] 8021q: 802.1Q VLAN Support v1.8
Jun  9 17:47:18 localhost vmunix: [   10.826517] 8021q: adding VLAN 0 to HW filter on device ens3
Jun  9 17:47:19 localhost shepherd[1]: Service nscd has been started. 
Jun  9 17:47:19 localhost /gnu/store/a6dbsapqfs15wa0z1xswnz2vjvz558gf-gpm-1.20.7/sbin/gpm[159]: *** info [daemon/startup.c(136)]: 
Jun  9 17:47:19 localhost /gnu/store/a6dbsapqfs15wa0z1xswnz2vjvz558gf-gpm-1.20.7/sbin/gpm[159]: Started gpm successfully. Entered daemon mode.
Jun  9 17:47:19 localhost connmand[148]: Cannot create /var/run/connman/resolv.conf falling back to /etc/resolv.conf
Jun  9 17:47:19 localhost connmand[148]: ens3 {add} address 10.0.2.15/24 label ens3 family 2
Jun  9 17:47:19 localhost connmand[148]: ens3 {add} route 10.0.2.0 gw 0.0.0.0 scope 253 <LINK>
Jun  9 17:47:19 localhost connmand[148]: ens3 {add} route 10.0.2.2 gw 0.0.0.0 scope 253 <LINK>
Jun  9 17:47:19 localhost connmand[148]: ens3 {add} route 0.0.0.0 gw 10.0.2.2 scope 0 <UNIVERSE>
Jun  9 17:47:19 localhost nscd: 149 monitored file `/etc/resolv.conf` was written to
Jun  9 17:47:20 localhost connmand[148]: ens3 {add} route 82.165.8.211 gw 10.0.2.2 scope 0 <UNIVERSE>
Jun  9 17:47:20 localhost connmand[148]: ens3 {del} route 82.165.8.211 gw 10.0.2.2 scope 0 <UNIVERSE>
Jun  9 17:47:20 localhost connmand[148]: ens3 {add} route fec0:: gw :: scope 0 <UNIVERSE>
Jun  9 17:47:20 localhost nscd: 149 monitored file `/etc/resolv.conf` was written to
Jun  9 17:47:20 localhost shepherd[1]: Service gpm has been started. 
Jun  9 17:47:20 localhost shepherd[1]: Service term-tty1 has been started. 
Jun  9 17:47:20 localhost shepherd[1]: Service console-font-tty1 has been started. 
Jun  9 17:47:20 localhost shepherd[1]: Service host-name has been started. 
Jun  9 17:47:20 localhost shepherd[1]: Service virtual-terminal has been started. 
Jun  9 17:47:20 localhost shepherd[1]: Service term-tty2 has been started. 
Jun  9 17:47:20 localhost shepherd[1]: Service console-font-tty2 has been started. 
Jun  9 17:47:20 localhost shepherd[1]: Service host-name has been started. 
Jun  9 17:47:20 localhost shepherd[1]: Service term-tty3 has been started. 
Jun  9 17:47:20 localhost shepherd[1]: Service console-font-tty3 has been started. 
Jun  9 17:47:20 localhost shepherd[1]: Service host-name has been started. 
Jun  9 17:47:20 localhost shepherd[1]: Service term-tty4 has been started. 
Jun  9 17:47:21 localhost shepherd[1]: Service console-font-tty4 has been started. 
Jun  9 17:47:21 localhost shepherd[1]: Service host-name has been started. 
Jun  9 17:47:21 localhost shepherd[1]: Service term-tty5 has been started. 
Jun  9 17:47:21 localhost shepherd[1]: Service console-font-tty5 has been started. 
Jun  9 17:47:21 localhost shepherd[1]: Service host-name has been started. 
Jun  9 17:47:21 localhost shepherd[1]: Service term-tty6 has been started. 
Jun  9 17:47:21 localhost shepherd[1]: Service console-font-tty6 has been started. 
Jun  9 17:47:21 localhost shepherd[1]: Service guix-daemon has been started. 
Jun  9 17:47:21 localhost avahi-daemon[187]: Found user 'avahi' (UID 987) and group 'avahi' (GID 981).
Jun  9 17:47:21 localhost avahi-daemon[187]: Successfully dropped root privileges.
Jun  9 17:47:21 localhost avahi-daemon[187]: avahi-daemon 0.8 starting up.
Jun  9 17:47:21 localhost avahi-daemon[187]: WARNING: No NSS support for mDNS detected, consider installing nss-mdns!
Jun  9 17:47:21 localhost avahi-daemon[187]: Successfully called chroot().
Jun  9 17:47:21 localhost avahi-daemon[187]: Successfully dropped remaining capabilities.
Jun  9 17:47:21 localhost avahi-daemon[187]: Loading service file /services/sftp-ssh.service.
Jun  9 17:47:21 localhost avahi-daemon[187]: Loading service file /services/ssh.service.
Jun  9 17:47:21 localhost avahi-daemon[187]: Joining mDNS multicast group on interface ens3.IPv6 with address fec0::5054:ff:fe12:3456.
Jun  9 17:47:21 localhost avahi-daemon[187]: New relevant interface ens3.IPv6 for mDNS.
Jun  9 17:47:21 localhost avahi-daemon[187]: Joining mDNS multicast group on interface ens3.IPv4 with address 10.0.2.15.
Jun  9 17:47:21 localhost avahi-daemon[187]: New relevant interface ens3.IPv4 for mDNS.
Jun  9 17:47:21 localhost avahi-daemon[187]: Joining mDNS multicast group on interface lo.IPv6 with address ::1.
Jun  9 17:47:21 localhost avahi-daemon[187]: New relevant interface lo.IPv6 for mDNS.
Jun  9 17:47:21 localhost avahi-daemon[187]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1.
Jun  9 17:47:21 localhost avahi-daemon[187]: New relevant interface lo.IPv4 for mDNS.
Jun  9 17:47:21 localhost avahi-daemon[187]: Network interface enumeration completed.
Jun  9 17:47:21 localhost avahi-daemon[187]: Registering new address record for fec0::5054:ff:fe12:3456 on ens3.*.
Jun  9 17:47:21 localhost avahi-daemon[187]: Registering new address record for 10.0.2.15 on ens3.IPv4.
Jun  9 17:47:21 localhost avahi-daemon[187]: Registering new address record for ::1 on lo.*.
Jun  9 17:47:21 localhost avahi-daemon[187]: Registering new address record for 127.0.0.1 on lo.IPv4.
Jun  9 17:47:21 localhost connmand[148]: ens3 {add} address fec0::5054:ff:fe12:3456/64 label (null) family 10
Jun  9 17:47:22 localhost installer[185]: Display is 102x40. 
Jun  9 17:47:22 localhost shepherd[1]: Service avahi-daemon has been started. 
Jun  9 17:47:22 localhost installer[185]: running step 'locale' 
Jun  9 17:47:22 localhost installer[185]: running step 'language' 
Jun  9 17:47:22 localhost installer[185]: running form #<newt-form 83c810> ("Locale language") with 0 clients 
Jun  9 17:47:22 localhost avahi-daemon[187]: Server startup complete. Host name is gnu.local. Local service cookie is 3580933774.
Jun  9 17:47:23 localhost avahi-daemon[187]: Service "gnu" (/services/ssh.service) successfully established.
Jun  9 17:47:23 localhost avahi-daemon[187]: Service "gnu" (/services/sftp-ssh.service) successfully established.
Jun  9 17:47:43 localhost installer[185]: running step 'territory' 
Jun  9 17:47:43 localhost installer[185]: running form #<newt-form 7a47c0> ("Locale location") with 0 clients 
Jun  9 17:47:43 localhost installer[185]: running step 'codeset' 
Jun  9 17:47:43 localhost shepherd[1]: Service console-font-tty2 has been stopped. 
Jun  9 17:47:43 localhost shepherd[1]: Service term-tty2 has been stopped. 
Jun  9 17:47:43 localhost shepherd[1]: Service host-name has been started. 
Jun  9 17:47:43 localhost shepherd[1]: Service term-tty2 has been started. 
Jun  9 17:47:43 localhost installer[185]: running step 'welcome' 
Jun  9 17:47:43 localhost installer[185]: running form #<newt-form 895370> ("GNU Guix install") with 0 clients 
Jun  9 17:47:44 localhost installer[185]: running step 'timezone' 
Jun  9 17:47:44 localhost installer[185]: running form #<newt-form 8954d0> ("Timezone") with 0 clients 
Jun  9 17:47:44 localhost installer[185]: running form #<newt-form 890800> ("Timezone") with 0 clients 
Jun  9 17:47:44 localhost installer[185]: running step 'keymap' 
Jun  9 17:47:45 localhost installer[185]: running step 'layout' 
Jun  9 17:47:45 localhost installer[185]: running form #<newt-form 88f8f0> ("Layout") with 0 clients 
Jun  9 17:47:45 localhost installer[185]: running step 'variant' 
Jun  9 17:47:45 localhost installer[185]: running step 'hostname' 
Jun  9 17:47:45 localhost installer[185]: running form #<newt-form 891f80> ("Hostname") with 0 clients 
Jun  9 17:47:46 localhost installer[185]: running step 'network' 
Jun  9 17:47:46 localhost installer[185]: running step 'select-technology' 
Jun  9 17:47:46 localhost installer[185]: running step 'power-technology' 
Jun  9 17:47:47 localhost installer[185]: running step 'connect-service' 
Jun  9 17:47:48 localhost installer[185]: running step 'wait-online' 
Jun  9 17:47:49 localhost installer[185]: running step 'substitutes' 
Jun  9 17:47:49 localhost shepherd[1]: enable substitute servers discovery 
Jun  9 17:47:49 localhost shepherd[1]: Service guix-daemon has been stopped. 
Jun  9 17:47:49 localhost shepherd[1]: Service guix-daemon has been started. 
Jun  9 17:47:49 localhost installer[185]: running step 'user' 
Jun  9 17:47:49 localhost installer[185]: running form #<newt-form 8e45c0> ("System administrator password") with 0 clients 
Jun  9 17:47:51 localhost installer[185]: running form #<newt-form 8e9010> ("Password confirmation required") with 0 clients 
Jun  9 17:47:52 localhost installer[185]: running form #<newt-form 8e4a20> (add-users) with 0 clients 
Jun  9 17:47:55 localhost installer[185]: running form #<newt-form 8e5f30> ("Password confirmation required") with 0 clients 
Jun  9 17:47:56 localhost installer[185]: running form #<newt-form 8e4f00> (add-users) with 0 clients 
Jun  9 17:47:57 localhost installer[185]: running step 'services' 
Jun  9 17:47:57 localhost installer[185]: running form #<newt-form 8e5ce0> ("Desktop environment") with 0 clients 
Jun  9 17:47:57 localhost installer[185]: running form #<newt-form 8e5a00> ("Network service") with 0 clients 
Jun  9 17:47:59 localhost installer[185]: running form #<newt-form 8e4f20> ("Network management") with 0 clients 
Jun  9 17:48:00 localhost installer[185]: running step 'partition' 
Jun  9 17:48:00 localhost installer[185]: running form #<newt-form 8e59b0> ("Partitioning method") with 0 clients 
Jun  9 17:48:00 localhost vmunix: [   52.427368]  sda: sda1 sda2 sda3 sda4 sda5 sda6
Jun  9 17:48:00 localhost installer[185]: running form #<newt-form 8e5cc0> ("Disk") with 0 clients 
Jun  9 17:48:04 localhost installer[185]: running form #<newt-form 8e4f20> ("Installation menu") with 0 clients 
Jun  9 17:48:06 localhost installer[185]: running step 'partition' 
Jun  9 17:48:06 localhost installer[185]: running form #<newt-form 8a8e50> ("Partitioning method") with 0 clients 
Jun  9 17:48:07 localhost installer[185]: running form #<newt-form 8e5cc0> ("Manual partitioning") with 0 clients 
Jun  9 17:48:12 localhost installer[185]: running form #<newt-form 8e4440> ("Partition edit") with 0 clients 
Jun  9 17:48:14 localhost installer[185]: running form #<newt-form 8ec800> ("Mounting point") with 0 clients 
Jun  9 17:48:16 localhost installer[185]: running form #<newt-form 8ea750> ("Partition edit") with 0 clients 
Jun  9 17:48:19 localhost installer[185]: running form #<newt-form 8ec920> ("Manual partitioning") with 0 clients 
Jun  9 17:48:21 localhost installer[185]: running form #<newt-form 8eae00> ("Format disk?") with 0 clients 
Jun  9 17:48:23 localhost vmunix: [   75.023670]  sda: sda1 sda2 sda3 sda4 sda5 sda6
Jun  9 17:48:23 localhost installer[185]: Syncing /dev/sda took 0 seconds. 
Jun  9 17:48:23 localhost installer[185]: Syncing /dev/sr0 took 0 seconds. 
Jun  9 17:48:23 localhost installer[185]: formatted 2 user partitions 
Jun  9 17:48:23 localhost installer[185]: (#<<user-partition> name: #f type: normal file-name: "/dev/sda6" disk-file-name: "/dev/sda" crypt-label: #f crypt-password: #f fs-type: ext4 bootable?: #f esp?: #f bios-grub?: #f size: #f start: "15247360s" end: "20971486s" mount-point: "/" need-formatting?: #f parted-object: #<<partition> bytestructure: #<bytestructure 0x7f388e5c16c0>>> #<<user-partition> name: #f type: normal file-name: "/dev/sda1" disk-file-name: "/dev/sda" crypt-label: #f crypt-password: #f fs-type: ext4 bootable?: #t esp?: #t bios-grub?: #f size: #f start: #f end: #f mount-point: "/boot/efi" need-formatting?: #f parted-object: #<<partition> bytestructure: #<bytestructure 0x7f388e5b4120>>>) 
Jun  9 17:48:23 localhost installer[185]: running step 'final' 
Jun  9 17:48:23 localhost installer[185]: proceeding with final step 
Jun  9 17:48:23 localhost installer[185]: crashing due to uncaught exception: match-error ("match" "no matching pattern" (#f ext4)) 
Jun  9 17:48:23 localhost installer[185]: running form #<newt-form 8e4f20> ("Unexpected problem") with 0 clients 

bug#44872: Installer crash: 'uuid->string' is passed #f in lieu of a UUID

[David Wilson]

Hey Mathieu,

Mathieu Othacehe <othacehe@gnu.org> writes:

> I'm using manual partitioning with the first partition mounted as the
> ESP partition and the sixth partition as the root directory.
>
> The backtrace and the messages log file are attached. I'll try to find
> some spare time to understand what's going on.
>

Awesome, glad you were able to reproduce it!  The stack trace you
attached is consistent with what I've seen.  I'm curious to learn
what you discover when you have a chance to look into it!


Thanks,

David

bug#44872: Installer crash: 'uuid->string' is passed #f in lieu of a UUID

[Mathieu Othacehe]

Hello David,

>> I'm using manual partitioning with the first partition mounted as the
>> ESP partition and the sixth partition as the root directory.

My issue here is that those partitions were never formatted. The
read-partition-uuid method returns #f on unformatted partitions, causing
the installer crash.

I fixed this issue with f5d9d6ec68f78f5651bd5a698f489ab57bf77d5d. The
rationale is that any partition that will be mounted and not formatted
("Format the partition? No) must have a valid UUID. The installer
now prevents going further if this is not the case.

That said, I think you are experiencing a different issue. The REPL
commands you ran show that your nvme0n1p1 and nvme0n1p6 partitions have
valid UUIDs.

That's why I also pushed this commit:
1a0a18d0ccc6cb6c7f4e42a0d659340f13b206c5 that prints in the syslog the
internal <user-partitions> list, hoping that it will help us understand
what's going on.

It would be great if you could build a new installer image on top of
master, try reproducing the crash and attach the content of the
"/var/log/messages" file. You can for instance scp it to another machine
of your local network running an SSH server.

I'm not sure if you are aware of this feature, but generating
uncompressed ISO9660 image is a serious time saver when debugging the
installer:

--8<---------------cut here---------------start------------->8---
guix system image -t uncompressed-iso9660 gnu/system/install.scm
--8<---------------cut here---------------end--------------->8---

Thanks,

Mathieu

bug#44872: Installer crash: 'uuid->string' is passed #f in lieu of a UUID

[David Wilson]

Hey Mathieu, thanks for looking into this!

Mathieu Othacehe <othacehe@gnu.org> writes:

> I fixed this issue with f5d9d6ec68f78f5651bd5a698f489ab57bf77d5d. The
> rationale is that any partition that will be mounted and not formatted
> ("Format the partition? No) must have a valid UUID. The installer
> now prevents going further if this is not the case.
>
> That said, I think you are experiencing a different issue. The REPL
> commands you ran show that your nvme0n1p1 and nvme0n1p6 partitions have
> valid UUIDs.

Your change seems to have exposed the real (and totally unexpected)
problem!  For some reason, the installer is trying to get the UUID of
a partition on my USB stick that contains the installer.

It seems that for some reason the installer has automatically picked a
mount point of `/boot/efi' for `/dev/sda2' in addition to the mount
points on my actual hard drive.  I now see an error dialog saying that
it can't retrieve the UUID of /dev/sda2 when I try to proceed past the
partitioning page.  If I clear the mount point for /dev/sda2, I am now
able to proceed to the page to with the generated system configuration!

At this point I think I should be able to proceed with making the
installation video I was working on, I'll just let viewers know that
they may need to clear that mount point if the same thing happens for
them.

> I'm not sure if you are aware of this feature, but generating
> uncompressed ISO9660 image is a serious time saver when debugging the
> installer:
>
> --8<---------------cut here---------------start------------->8---
> guix system image -t uncompressed-iso9660 gnu/system/install.scm
> --8<---------------cut here---------------end--------------->8---

I wasn't aware, thanks for letting me know!  Creating the image was a
lot faster this time :)

David

bug#44872: Installer crash: 'uuid->string' is passed #f in lieu of a UUID

[Mathieu Othacehe]

Hey,

> It seems that for some reason the installer has automatically picked a
> mount point of `/boot/efi' for `/dev/sda2' in addition to the mount
> points on my actual hard drive.  I now see an error dialog saying that
> it can't retrieve the UUID of /dev/sda2 when I try to proceed past the
> partitioning page.  If I clear the mount point for /dev/sda2, I am now
> able to proceed to the page to with the generated system configuration!

Oh, I understand! The installation device is not detected by the
non-install-devices procedure, as it often happens. Hence, the
create-special-user-partitions finds two ESP partitions. The one on your
main hard drive and the one on the installation ISO.

Reading the UUID of the ESP partition of the installation ISO returns
false, not sure why, but this is not the central issue.

I improved the install device detection with
154a4e046281c28e39b5016e965d3d937a2ea4a1 by removing the device with the
default Guix System image ISO label.

This is quite fragile and if someone has a better idea, please feel free
to share it :). In the meantime, it should completely fix your issue.

Thanks a lot for your help,

Mathieu

bug#44872: Installer crash: 'uuid->string' is passed #f in lieu of a UUID

[David Wilson]

Hey Mathieu!

Mathieu Othacehe <othacehe@gnu.org> writes:

> I improved the install device detection with
> 154a4e046281c28e39b5016e965d3d937a2ea4a1 by removing the device with the
> default Guix System image ISO label.

Works perfectly for me now, the USB installation device doesn't show up
in the device list anymore!

I've installed Guix about 5 times after building an image from the
latest changes, works perfectly for me both with encrypted and
unencrypted root partitions.

Thanks a lot for your help!

David

bug#44872: Installer crash: 'uuid->string' is passed #f in lieu of a UUID

[Mathieu Othacehe]

Hey,

> I've installed Guix about 5 times after building an image from the
> latest changes, works perfectly for me both with encrypted and
> unencrypted root partitions.

Great, I improved the installation device detection again with:
e12be802e02b3345a753e7ec1287852a7337a0a5.

I think we can close that one.

Thanks,

Mathieu