Debian Templates: pbgrub kernel broken

adrelanos commented 8 years ago

Qubes OS version (e.g., `R3.1`):

R3.1 with testing repository

Affected TemplateVMs (e.g., `fedora-23`, if applicable):

debian-8

Expected behavior:

/////

Actual behavior:

  Booting `GNU/Linux'

Loading Linux 3.16.0-4-amd64 ...
Loading initial ramdisk ...
[    0.000000] Initializing cgroup subsys cpuset4-  16.04MiB  100%  1.38MiB/s ]
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Initializing cgroup subsys cpuacct
[    0.000000] Linux version 3.16.0-4-amd64 (debian-kernel@lists.debian.org) (gcc version 4.8.4 (Debian 4.8.4-1) ) #1 SMP Debian 3.16.7-ckt25-2+deb8u3 (2016-07-02)
[    0.000000] Command line: root=/dev/mapper/dmroot ro
[    0.000000] ACPI in unprivileged domain disabled
[    0.000000] e820: BIOS-provided physical RAM map:
[    0.000000] Xen: [mem 0x0000000000000000-0x000000000009ffff] usable
[    0.000000] Xen: [mem 0x00000000000a0000-0x00000000000fffff] reserved
[    0.000000] Xen: [mem 0x0000000000100000-0x00000000f9ffffff] usable
[    0.000000] NX (Execute Disable) protection: active
[    0.000000] DMI not present or invalid.
[    0.000000] AGP: No AGP bridge found
[    0.000000] e820: last_pfn = 0xfa000 max_arch_pfn = 0x400000000
[    0.000000] init_memory_mapping: [mem 0x00000000-0x000fffff]
[    0.000000] init_memory_mapping: [mem 0x18e00000-0x18ffffff]
[    0.000000] init_memory_mapping: [mem 0x18000000-0x18dfffff]
[    0.000000] init_memory_mapping: [mem 0x00100000-0x17ffffff]
[    0.000000] init_memory_mapping: [mem 0x19000000-0xf9ffffff]
[    0.000000] RAMDISK: [mem 0x01f18000-0x02f22fff]
[    0.000000] NUMA turned off
[    0.000000] Faking a node at [mem 0x0000000000000000-0x00000000f9ffffff]
[    0.000000] Initmem setup node 0 [mem 0x00000000-0xf9ffffff]
[    0.000000]   NODE_DATA [mem 0x18848000-0x1884cfff]
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x00001000-0x00ffffff]
[    0.000000]   DMA32    [mem 0x01000000-0xffffffff]
[    0.000000]   Normal   empty
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x00001000-0x0009ffff]
[    0.000000]   node   0: [mem 0x00100000-0xf9ffffff]
[    0.000000] SFI: Simple Firmware Interface v0.81 http://simplefirmware.org
[    0.000000] smpboot: Allowing 8 CPUs, 0 hotplug CPUs
[    0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000fffff]
[    0.000000] e820: [mem 0xfa000000-0xffffffff] available for PCI devices
[    0.000000] Booting paravirtualized kernel on Xen
[    0.000000] Xen version: 4.6.1 (preserve-AD)
[    0.000000] setup_percpu: NR_CPUS:512 nr_cpumask_bits:512 nr_cpu_ids:8 nr_node_ids:1
[    0.000000] PERCPU: Embedded 27 pages/cpu @ffff880018200000 s80896 r8192 d21504 u262144
[    0.000000] Built 1 zonelists in Node order, mobility grouping on.  Total pages: 1009882
[    0.000000] Policy zone: DMA32
[    0.000000] Kernel command line: root=/dev/mapper/dmroot ro
[    0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes)
[    0.000000] xsave: enabled xstate_bv 0x7, cntxt size 0x340
[    0.000000] AGP: Checking aperture...
[    0.000000] AGP: No AGP bridge found
[    0.000000] Memory: 313976K/4095612K available (5220K kernel code, 947K rwdata, 1836K rodata, 1204K init, 840K bss, 3781636K reserved)
[    0.000000] Hierarchical RCU implementation.
[    0.000000]  RCU dyntick-idle grace-period acceleration is enabled.
[    0.000000]  RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=8.
[    0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=8
[    0.000000] NR_IRQS:33024 nr_irqs:336 16
[    0.000000] xen:events: Using FIFO-based ABI
[    0.000000] Console: colour dummy device 80x25
[    0.000000] console [tty0] enabled
[    0.000000] console [hvc0] enabled
[    0.000000] installing Xen timer for CPU 0
[    0.000000] tsc: Detected 2194.978 MHz processor
[    0.004000] Calibrating delay loop (skipped), value calculated using timer frequency.. 4389.95 BogoMIPS (lpj=8779912)
[    0.004000] pid_max: default: 32768 minimum: 301
[    0.004000] Security Framework initialized
[    0.004000] AppArmor: AppArmor disabled by boot time parameter
[    0.004000] Yama: disabled by default; enable with sysctl kernel.yama.*
[    0.004000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes)
[    0.004000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes)
[    0.004000] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes)
[    0.004000] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes)
[    0.004000] Initializing cgroup subsys memory
[    0.004000] Initializing cgroup subsys devices
[    0.004000] Initializing cgroup subsys freezer
[    0.004000] Initializing cgroup subsys net_cls
[    0.004000] Initializing cgroup subsys blkio
[    0.004000] Initializing cgroup subsys perf_event
[    0.004000] Initializing cgroup subsys net_prio
[    0.004000] ENERGY_PERF_BIAS: Set to 'normal', was 'performance'
[    0.004000] ENERGY_PERF_BIAS: View and update with x86_energy_perf_policy(8)
[    0.004000] CPU: Physical Processor ID: 0
[    0.004000] CPU: Processor Core ID: 1
[    0.004000] Last level iTLB entries: 4KB 1024, 2MB 1024, 4MB 1024
[    0.004000] Last level dTLB entries: 4KB 1024, 2MB 1024, 4MB 1024, 1GB 4
[    0.004000] tlb_flushall_shift: 6
[    0.019492] ftrace: allocating 21683 entries in 85 pages
[    0.024078] Performance Events: unsupported p6 CPU model 70 no PMU driver, software events only.
[    0.025081] NMI watchdog: disabled (cpu0): hardware events not enabled
[    0.025147] installing Xen timer for CPU 1
[    0.025191] SMP alternatives: switching to SMP code
[    0.039995] installing Xen timer for CPU 2
[    0.040979] installing Xen timer for CPU 3
[    0.041902] installing Xen timer for CPU 4
[    0.042830] installing Xen timer for CPU 5
[    0.043828] installing Xen timer for CPU 6
[    0.044788] installing Xen timer for CPU 7
[    0.045666] x86: Booted up 1 node, 8 CPUs
[    0.045725] devtmpfs: initialized
[    0.048628] pinctrl core: initialized pinctrl subsystem
[    0.048711] NET: Registered protocol family 16
[    0.048722] xen:grant_table: Grant tables using version 1 layout
[    0.048731] Grant table initialized
[    0.048772] PCI: setting up Xen PCI frontend stub
[    0.060170] ACPI: Interpreter disabled.
[    0.060170] xen:balloon: Initialising balloon driver
[    0.068028] xen_balloon: Initialising balloon driver
[    0.068044] vgaarb: loaded
[    0.068077] PCI: System does not support PCI
[    0.068079] PCI: System does not support PCI
[    0.068249] Switched to clocksource xen
[    0.072194] pnp: PnP ACPI: disabled
[    0.073882] NET: Registered protocol family 2
[    0.074117] TCP established hash table entries: 32768 (order: 6, 262144 bytes)
[    0.074204] TCP bind hash table entries: 32768 (order: 7, 524288 bytes)
[    0.074248] TCP: Hash tables configured (established 32768 bind 32768)
[    0.074258] TCP: reno registered
[    0.074269] UDP hash table entries: 2048 (order: 4, 65536 bytes)
[    0.074285] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes)
[    0.074350] NET: Registered protocol family 1
[    0.074390] Unpacking initramfs...
[    0.276208] Freeing initrd memory: 16428K (ffff880001f18000 - ffff880002f23000)
[    0.276379] platform rtc_cmos: registered platform RTC device (no PNP device found)
[    0.276874] microcode: CPU0 sig=0x40661, pf=0x20, revision=0xa
[    0.276904] microcode: CPU1 sig=0x40661, pf=0x20, revision=0xa
[    0.276916] microcode: CPU2 sig=0x40661, pf=0x20, revision=0xa
[    0.276935] microcode: CPU3 sig=0x40661, pf=0x20, revision=0xa
[    0.276948] microcode: CPU4 sig=0x40661, pf=0x20, revision=0xa
[    0.276969] microcode: CPU5 sig=0x40661, pf=0x20, revision=0xa
[    0.276978] microcode: CPU6 sig=0x40661, pf=0x20, revision=0xa
[    0.277021] microcode: CPU7 sig=0x40661, pf=0x20, revision=0xa
[    0.277121] microcode: Microcode Update Driver: v2.00 <tigran@aivazian.fsnet.co.uk>, Peter Oruba
[    0.277411] futex hash table entries: 2048 (order: 5, 131072 bytes)
[    0.277457] audit: initializing netlink subsys (disabled)
[    0.277472] audit: type=2000 audit(1468975184.613:1): initialized
[    0.277784] zbud: loaded
[    0.277997] VFS: Disk quotas dquot_6.5.2
[    0.278022] Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.278051] hugetlbfs: disabling because there are no supported hugepage sizes
[    0.278067] msgmni has been set to 645
[    0.278339] alg: No test for stdrng (krng)
[    0.278372] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[    0.278435] io scheduler noop registered
[    0.278440] io scheduler deadline registered
[    0.278494] io scheduler cfq registered (default)
[    0.278559] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[    0.278578] pciehp: PCI Express Hot Plug Controller Driver version: 0.4
[    0.278876] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[    0.279100] Linux agpgart interface v0.103
[    0.279185] i8042: PNP: No PS/2 controller found. Probing ports directly.
[    1.281785] i8042: No controller found
[    1.281973] mousedev: PS/2 mouse device common for all mice
[    1.341791] rtc_cmos rtc_cmos: rtc core: registered rtc_cmos as rtc0
[    1.341916] rtc_cmos: probe of rtc_cmos failed with error -38
[    1.341952] ledtrig-cpu: registered to indicate activity on CPUs
[    1.341975] AMD IOMMUv2 driver by Joerg Roedel <joerg.roedel@amd.com>
[    1.341977] AMD IOMMUv2 functionality not available on this system
[    1.342070] TCP: cubic registered
[    1.342215] NET: Registered protocol family 10
[    1.342440] mip6: Mobile IPv6
[    1.342446] NET: Registered protocol family 17
[    1.342452] mpls_gso: MPLS GSO support
[    1.342457] mce: Unable to init device /dev/mcelog (rc: -5)
[    1.342599] registered taskstats version 1
[    1.342649] xenbus_probe_frontend: Device with no driver: device/vbd/51712
[    1.342652] xenbus_probe_frontend: Device with no driver: device/vbd/51728
[    1.342654] xenbus_probe_frontend: Device with no driver: device/vbd/51744
[    1.342656] xenbus_probe_frontend: Device with no driver: device/vif/0
[    1.342758] rtc_hctosys: unable to open rtc device (rtc0)
[    1.343268] Freeing unused kernel memory: 1204K (ffffffff818ee000 - ffffffff81a1b000)
[    1.343273] Write protecting the kernel read-only data: 8192k
[    1.345852] Freeing unused kernel memory: 912K (ffff88000151c000 - ffff880001600000)
[    1.345956] Freeing unused kernel memory: 212K (ffff8800017cb000 - ffff880001800000)
Loading, please wait...
[    1.388453] systemd-udevd[100]: starting version 215
[    1.388861] random: systemd-udevd urandom read with 38 bits of entropy available
[    1.397832] xen_netfront: Initialising Xen virtual ethernet driver
[    1.407736] blkfront: xvda: flush diskcache: enabled; persistent grants: enabled; indirect descriptors: enabled;
[    1.409132]  xvda: unknown partition table
[    1.414430] blkfront: xvdb: flush diskcache: enabled; persistent grants: enabled; indirect descriptors: enabled;
[    1.415299]  xvdb: unknown partition table
[    1.472058] Setting capacity to 4194304
[    1.472069] xvdb: detected capacity change from 0 to 2147483648
[    1.472953] blkfront: xvdc: flush diskcache: enabled; persistent grants: enabled; indirect descriptors: enabled;
[    1.473707]  xvdc: unknown partition table
[    1.473896] Setting capacity to 24117248
[    1.473901] xvdc: detected capacity change from 0 to 12348030976
Begin: Loading essential drivers ... done.
Begin: Running /scripts/init-premount ... done.
Begin: Mounting root file system ... Begin: Running /scripts/local-top ... [    1.566680] device-mapper: uevent: version 1.0.3
[    1.567066] device-mapper: ioctl: 4.27.0-ioctl (2013-10-30) initialised: dm-devel@redhat.com
done.
Begin: Running /scripts/local-premount ... done.
Begin: Waiting for root file system ... Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
[    4.652173] random: nonblocking pool is initialized
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
done.
Gave up waiting for root device.  Common problems:
 - Boot args (cat /proc/cmdline)
   - Check rootdelay= (did the system wait long enough?)
   - Check root= (did the system wait for the right device?)
 - Missing modules (cat /proc/modules; ls /dev)
ALERT!  /dev/mapper/dmroot does not exist.  Dropping to a shell!
[   32.311561] usbcore: registered new interface driver usbfs
[   32.311573] usbcore: registered new interface driver hub
[   32.311635] usbcore: registered new device driver usb
[   32.312390] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[   32.312746] ehci-pci: EHCI PCI platform driver
modprobe: module ehci-orion not found in modules.dep
[   32.317823] uhci_hcd: USB Universal Host Controller Interface driver
[   32.319635] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[   32.321645] hidraw: raw HID events driver (C) Jiri Kosina
[   32.322203] usbcore: registered new interface driver usbhid
[   32.322208] usbhid: USB HID core driver

BusyBox v1.22.1 (Debian 1:1.22.0-9+deb8u1) built-in shell (ash)
Enter 'help' for a list of built-in commands.

/bin/sh: can't access tty; job control turned off
(initramfs)

Steps to reproduce the behavior:

set up as per https://www.qubes-os.org/doc/managing-vm-kernel/

marmarek commented 8 years ago

Looks like missing qubes initramfs script. Are you sure you installed qubes-kernel-vm-support before generating it?

adrelanos commented 8 years ago

Marek Marczykowski-Górecki:

Looks like missing qubes initramfs script. Are you sure you installed qubes-kernel-vm-support before generating it?

Yes.

I also tried reinstalling it to no avail.

user@debian-8:~$ sudo apt-get purge linux-image-amd64
Reading package lists... Done
Building dependency tree
Reading state information... Done
The following packages will be REMOVED:
  linux-image-amd64*
0 upgraded, 0 newly installed, 1 to remove and 9 not upgraded.
After this operation, 6,144 B disk space will be freed.
Do you want to continue? [Y/n]
(Reading database ... 158902 files and directories currently installed.)
Removing linux-image-amd64 (3.16+63) ...
user@debian-8:~$
user@debian-8:~$ sudo apt-get install linux-image-amd64
Reading package lists... Done
Building dependency tree
Reading state information... Done
The following NEW packages will be installed:
  linux-image-amd64
0 upgraded, 1 newly installed, 0 to remove and 9 not upgraded.
Need to get 0 B/5,824 B of archives.
After this operation, 6,144 B of additional disk space will be used.
Selecting previously unselected package linux-image-amd64.
(Reading database ... 158896 files and directories currently installed.)
Preparing to unpack .../linux-image-amd64_3.16+63_amd64.deb ...
Unpacking linux-image-amd64 (3.16+63) ...
Setting up linux-image-amd64 (3.16+63) ...
user@debian-8:~$

marmarek commented 8 years ago

I think you're not reinstalling the right package - linux-image-amd64 is just a metapackage pulling in the actual kernel package (linux-image-3.16.0-4-amd64 for Debian 8). You should get something like this (just tried):

$ sudo apt-get install -V linux-image-amd64
Reading package lists... Done
Building dependency tree       
Reading state information... Done
The following extra packages will be installed:
   irqbalance (1.0.6-3)
   libnuma1 (2.0.10-1)
   libuuid-perl (0.05-1+b1)
   linux-base (3.5)
   linux-image-3.16.0-4-amd64 (3.16.7-ckt25-2+deb8u3)
Suggested packages:
   linux-doc-3.16 (3.16.7-ckt25-2+deb8u3)
   debian-kernel-handbook (1.0.15)
   grub-pc (2.02~beta2-22+deb8u1)
   grub-efi (2.02~beta2-22+deb8u1)
   extlinux (6.03+dfsg-5+deb8u1)
The following NEW packages will be installed:
   irqbalance (1.0.6-3)
   libnuma1 (2.0.10-1)
   libuuid-perl (0.05-1+b1)
   linux-base (3.5)
   linux-image-3.16.0-4-amd64 (3.16.7-ckt25-2+deb8u3)
   linux-image-amd64 (3.16+63)
0 upgraded, 6 newly installed, 0 to remove and 3 not upgraded.
Need to get 34.0 MB of archives.
After this operation, 164 MB of additional disk space will be used.
Do you want to continue? [Y/n] 
Get:1 http://security.debian.org/ jessie/updates/main linux-image-3.16.0-4-amd64 amd64 3.16.7-ckt25-2+deb8u3 [33.9 MB]
Get:2 http://http.debian.net/debian/ jessie/main libnuma1 amd64 2.0.10-1 [32.5 kB]  
Get:3 http://http.debian.net/debian/ jessie/main libuuid-perl amd64 0.05-1+b1 [10.4 kB]                   
Get:4 http://http.debian.net/debian/ jessie/main linux-base all 3.5 [34.3 kB]             
Get:5 http://http.debian.net/debian/ jessie/main linux-image-amd64 amd64 3.16+63 [5,824 B]
Get:6 http://http.debian.net/debian/ jessie/main irqbalance amd64 1.0.6-3 [31.1 kB]            
Fetched 34.0 MB in 2s (11.9 MB/s)                                                       
Preconfiguring packages ...
Selecting previously unselected package libnuma1:amd64.
(Reading database ... 106223 files and directories currently installed.)
Preparing to unpack .../libnuma1_2.0.10-1_amd64.deb ...
Unpacking libnuma1:amd64 (2.0.10-1) ...
Selecting previously unselected package libuuid-perl.
Preparing to unpack .../libuuid-perl_0.05-1+b1_amd64.deb ...
Unpacking libuuid-perl (0.05-1+b1) ...
Selecting previously unselected package linux-base.
Preparing to unpack .../linux-base_3.5_all.deb ...
Unpacking linux-base (3.5) ...
Selecting previously unselected package linux-image-3.16.0-4-amd64.
Preparing to unpack .../linux-image-3.16.0-4-amd64_3.16.7-ckt25-2+deb8u3_amd64.deb ...
Unpacking linux-image-3.16.0-4-amd64 (3.16.7-ckt25-2+deb8u3) ...
Selecting previously unselected package linux-image-amd64.
Preparing to unpack .../linux-image-amd64_3.16+63_amd64.deb ...
Unpacking linux-image-amd64 (3.16+63) ...
Selecting previously unselected package irqbalance.
Preparing to unpack .../irqbalance_1.0.6-3_amd64.deb ...
Unpacking irqbalance (1.0.6-3) ...
Processing triggers for man-db (2.7.0.2-5) ...
Processing triggers for systemd (215-17+deb8u4) ...
Setting up libnuma1:amd64 (2.0.10-1) ...
Setting up libuuid-perl (0.05-1+b1) ...
Setting up linux-base (3.5) ...
Setting up linux-image-3.16.0-4-amd64 (3.16.7-ckt25-2+deb8u3) ...
/etc/kernel/postinst.d/initramfs-tools:
update-initramfs: Generating /boot/initrd.img-3.16.0-4-amd64
Setting up linux-image-amd64 (3.16+63) ...
Setting up irqbalance (1.0.6-3) ...
Processing triggers for libc-bin (2.19-18+deb8u4) ...
Processing triggers for systemd (215-17+deb8u4) ...

The most important part:

update-initramfs: Generating /boot/initrd.img-3.16.0-4-amd64

Anyway, instead of reinstall, you can trigger it manually: update-initramfs -u

adrelanos commented 8 years ago

Marek Marczykowski-Górecki:

Anyway, instead of reinstall, you can trigger it manually: update-initramfs -u

That helped. We should add that to documentation. (Troubleshooting or always just in case?)

One or two more issues. VM stays yellow, qrexec apparently does start yet (symptom: cannot start gui applications). Probably a follow up issue.

[ SKIP ] Ordering cycle found, skipping LSB: RPC portmapper replacement

Unrelated?

systemd-modules-load failed. Related?

Full boot log log:

  Booting `GNU/Linux'

Loading Linux 3.16.0-4-amd64 ...
Loading initial ramdisk ...
[    0.000000] Initializing cgroup subsys cpuset4-  16.08MiB  100%
1.38MiB/s ]
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Initializing cgroup subsys cpuacct
[    0.000000] Linux version 3.16.0-4-amd64
(debian-kernel@lists.debian.org) (gcc version 4.8.4 (Debian 4.8.4-1) )
#1 SMP Debian 3.16.7-ckt25-2+deb8u3 (2016-07-02)
[    0.000000] Command line: root=/dev/mapper/dmroot ro
[    0.000000] ACPI in unprivileged domain disabled
[    0.000000] e820: BIOS-provided physical RAM map:
[    0.000000] Xen: [mem 0x0000000000000000-0x000000000009ffff] usable
[    0.000000] Xen: [mem 0x00000000000a0000-0x00000000000fffff] reserved
[    0.000000] Xen: [mem 0x0000000000100000-0x00000000f9ffffff] usable
[    0.000000] NX (Execute Disable) protection: active
[    0.000000] DMI not present or invalid.
[    0.000000] AGP: No AGP bridge found
[    0.000000] e820: last_pfn = 0xfa000 max_arch_pfn = 0x400000000
[    0.000000] init_memory_mapping: [mem 0x00000000-0x000fffff]
[    0.000000] init_memory_mapping: [mem 0x18e00000-0x18ffffff]
[    0.000000] init_memory_mapping: [mem 0x18000000-0x18dfffff]
[    0.000000] init_memory_mapping: [mem 0x00100000-0x17ffffff]
[    0.000000] init_memory_mapping: [mem 0x19000000-0xf9ffffff]
[    0.000000] RAMDISK: [mem 0x01f18000-0x02f2cfff]
[    0.000000] NUMA turned off
[    0.000000] Faking a node at [mem 0x0000000000000000-0x00000000f9ffffff]
[    0.000000] Initmem setup node 0 [mem 0x00000000-0xf9ffffff]
[    0.000000]   NODE_DATA [mem 0x18848000-0x1884cfff]
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x00001000-0x00ffffff]
[    0.000000]   DMA32    [mem 0x01000000-0xffffffff]
[    0.000000]   Normal   empty
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x00001000-0x0009ffff]
[    0.000000]   node   0: [mem 0x00100000-0xf9ffffff]
[    0.000000] SFI: Simple Firmware Interface v0.81
http://simplefirmware.org
[    0.000000] smpboot: Allowing 8 CPUs, 0 hotplug CPUs
[    0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000fffff]
[    0.000000] e820: [mem 0xfa000000-0xffffffff] available for PCI devices
[    0.000000] Booting paravirtualized kernel on Xen
[    0.000000] Xen version: 4.6.1 (preserve-AD)
[    0.000000] setup_percpu: NR_CPUS:512 nr_cpumask_bits:512
nr_cpu_ids:8 nr_node_ids:1
[    0.000000] PERCPU: Embedded 27 pages/cpu @ffff880018200000 s80896
r8192 d21504 u262144
[    0.000000] Built 1 zonelists in Node order, mobility grouping on.
Total pages: 1009882
[    0.000000] Policy zone: DMA32
[    0.000000] Kernel command line: root=/dev/mapper/dmroot ro
[    0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes)
[    0.000000] xsave: enabled xstate_bv 0x7, cntxt size 0x340
[    0.000000] AGP: Checking aperture...
[    0.000000] AGP: No AGP bridge found
[    0.000000] Memory: 313936K/4095612K available (5220K kernel code,
947K rwdata, 1836K rodata, 1204K init, 840K bss, 3781676K reserved)
[    0.000000] Hierarchical RCU implementation.
[    0.000000]  RCU dyntick-idle grace-period acceleration is enabled.
[    0.000000]  RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=8.
[    0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=8
[    0.000000] NR_IRQS:33024 nr_irqs:336 16
[    0.000000] xen:events: Using FIFO-based ABI
[    0.000000] Console: colour dummy device 80x25
[    0.000000] console [tty0] enabled
[    0.000000] console [hvc0] enabled
[    0.000000] installing Xen timer for CPU 0
[    0.000000] tsc: Detected 2194.978 MHz processor
[    0.004000] Calibrating delay loop (skipped), value calculated using
timer frequency.. 4389.95 BogoMIPS (lpj=8779912)
[    0.004000] pid_max: default: 32768 minimum: 301
[    0.004000] Security Framework initialized
[    0.004000] AppArmor: AppArmor disabled by boot time parameter
[    0.004000] Yama: disabled by default; enable with sysctl kernel.yama.*
[    0.004000] Dentry cache hash table entries: 524288 (order: 10,
4194304 bytes)
[    0.004000] Inode-cache hash table entries: 262144 (order: 9, 2097152
bytes)
[    0.004000] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes)
[    0.004000] Mountpoint-cache hash table entries: 8192 (order: 4,
65536 bytes)
[    0.004000] Initializing cgroup subsys memory
[    0.004000] Initializing cgroup subsys devices
[    0.004000] Initializing cgroup subsys freezer
[    0.004000] Initializing cgroup subsys net_cls
[    0.004000] Initializing cgroup subsys blkio
[    0.004000] Initializing cgroup subsys perf_event
[    0.004000] Initializing cgroup subsys net_prio
[    0.004000] ENERGY_PERF_BIAS: Set to 'normal', was 'performance'
[    0.004000] ENERGY_PERF_BIAS: View and update with
x86_energy_perf_policy(8)
[    0.004000] CPU: Physical Processor ID: 0
[    0.004000] CPU: Processor Core ID: 3
[    0.004000] Last level iTLB entries: 4KB 1024, 2MB 1024, 4MB 1024
[    0.004000] Last level dTLB entries: 4KB 1024, 2MB 1024, 4MB 1024, 1GB 4
[    0.004000] tlb_flushall_shift: 6
[    0.019000] ftrace: allocating 21683 entries in 85 pages
[    0.024068] Performance Events: unsupported p6 CPU model 70 no PMU
driver, software events only.
[    0.025042] NMI watchdog: disabled (cpu0): hardware events not enabled
[    0.025107] installing Xen timer for CPU 1
[    0.025163] SMP alternatives: switching to SMP code
[    0.039691] installing Xen timer for CPU 2
[    0.040718] installing Xen timer for CPU 3
[    0.041741] installing Xen timer for CPU 4
[    0.042703] installing Xen timer for CPU 5
[    0.043701] installing Xen timer for CPU 6
[    0.044641] installing Xen timer for CPU 7
[    0.045512] x86: Booted up 1 node, 8 CPUs
[    0.045595] devtmpfs: initialized
[    0.048158] pinctrl core: initialized pinctrl subsystem
[    0.048258] NET: Registered protocol family 16
[    0.048270] xen:grant_table: Grant tables using version 1 layout
[    0.048278] Grant table initialized
[    0.048368] PCI: setting up Xen PCI frontend stub
[    0.060087] ACPI: Interpreter disabled.
[    0.060087] xen:balloon: Initialising balloon driver
[    0.064036] xen_balloon: Initialising balloon driver
[    0.068018] vgaarb: loaded
[    0.068074] PCI: System does not support PCI
[    0.068076] PCI: System does not support PCI
[    0.068188] Switched to clocksource xen
[    0.071598] pnp: PnP ACPI: disabled
[    0.073130] NET: Registered protocol family 2
[    0.073360] TCP established hash table entries: 32768 (order: 6,
262144 bytes)
[    0.073447] TCP bind hash table entries: 32768 (order: 7, 524288 bytes)
[    0.073490] TCP: Hash tables configured (established 32768 bind 32768)
[    0.073500] TCP: reno registered
[    0.073511] UDP hash table entries: 2048 (order: 4, 65536 bytes)
[    0.073526] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes)
[    0.073592] NET: Registered protocol family 1
[    0.073632] Unpacking initramfs...
[    0.285763] Freeing initrd memory: 16468K (ffff880001f18000 -
ffff880002f2d000)
[    0.285889] platform rtc_cmos: registered platform RTC device (no PNP
device found)
[    0.286395] microcode: CPU0 sig=0x40661, pf=0x20, revision=0xa
[    0.286423] microcode: CPU1 sig=0x40661, pf=0x20, revision=0xa
[    0.286432] microcode: CPU2 sig=0x40661, pf=0x20, revision=0xa
[    0.286458] microcode: CPU3 sig=0x40661, pf=0x20, revision=0xa
[    0.286480] microcode: CPU4 sig=0x40661, pf=0x20, revision=0xa
[    0.286502] microcode: CPU5 sig=0x40661, pf=0x20, revision=0xa
[    0.286514] microcode: CPU6 sig=0x40661, pf=0x20, revision=0xa
[    0.286534] microcode: CPU7 sig=0x40661, pf=0x20, revision=0xa
[    0.286628] microcode: Microcode Update Driver: v2.00
<tigran@aivazian.fsnet.co.uk>, Peter Oruba
[    0.287003] futex hash table entries: 2048 (order: 5, 131072 bytes)
[    0.287077] audit: initializing netlink subsys (disabled)
[    0.287107] audit: type=2000 audit(1468987213.276:1): initialized
[    0.287512] zbud: loaded
[    0.287726] VFS: Disk quotas dquot_6.5.2
[    0.287750] Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.287770] hugetlbfs: disabling because there are no supported
hugepage sizes
[    0.287783] msgmni has been set to 645
[    0.288067] alg: No test for stdrng (krng)
[    0.288101] Block layer SCSI generic (bsg) driver version 0.4 loaded
(major 252)
[    0.288164] io scheduler noop registered
[    0.288168] io scheduler deadline registered
[    0.288212] io scheduler cfq registered (default)
[    0.288271] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[    0.288283] pciehp: PCI Express Hot Plug Controller Driver version: 0.4
[    0.288575] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[    0.288778] Linux agpgart interface v0.103
[    0.288845] i8042: PNP: No PS/2 controller found. Probing ports directly.
[    1.294050] i8042: No controller found
[    1.294275] mousedev: PS/2 mouse device common for all mice
[    1.354013] rtc_cmos rtc_cmos: rtc core: registered rtc_cmos as rtc0
[    1.354116] rtc_cmos: probe of rtc_cmos failed with error -38
[    1.354142] ledtrig-cpu: registered to indicate activity on CPUs
[    1.354167] AMD IOMMUv2 driver by Joerg Roedel <joerg.roedel@amd.com>
[    1.354170] AMD IOMMUv2 functionality not available on this system
[    1.354281] TCP: cubic registered
[    1.354410] NET: Registered protocol family 10
[    1.354657] mip6: Mobile IPv6
[    1.354663] NET: Registered protocol family 17
[    1.354669] mpls_gso: MPLS GSO support
[    1.354674] mce: Unable to init device /dev/mcelog (rc: -5)
[    1.354798] registered taskstats version 1
[    1.354849] xenbus_probe_frontend: Device with no driver:
device/vbd/51712
[    1.354852] xenbus_probe_frontend: Device with no driver:
device/vbd/51728
[    1.354854] xenbus_probe_frontend: Device with no driver:
device/vbd/51744
[    1.354856] xenbus_probe_frontend: Device with no driver: device/vif/0
[    1.354947] rtc_hctosys: unable to open rtc device (rtc0)
[    1.355473] Freeing unused kernel memory: 1204K (ffffffff818ee000 -
ffffffff81a1b000)
[    1.355477] Write protecting the kernel read-only data: 8192k
[    1.358091] Freeing unused kernel memory: 912K (ffff88000151c000 -
ffff880001600000)
[    1.358204] Freeing unused kernel memory: 212K (ffff8800017cb000 -
ffff880001800000)
Loading, please wait...
[    1.393993] systemd-udevd[101]: starting version 215
[    1.394395] random: systemd-udevd urandom read with 38 bits of
entropy available
[    1.402701] xen_netfront: Initialising Xen virtual ethernet driver
[    1.498566] blkfront: xvda: flush diskcache: enabled; persistent
grants: enabled; indirect descriptors: enabled;
[    1.499297]  xvda: unknown partition table
[    1.500283] blkfront: xvdb: flush diskcache: enabled; persistent
grants: enabled; indirect descriptors: enabled;
[    1.500995]  xvdb: unknown partition table
[    1.502276] blkfront: xvdc: flush diskcache: enabled; persistent
grants: enabled; indirect descriptors: enabled;
[    1.502926]  xvdc: unknown partition table
[    1.503066] Setting capacity to 4194304
[    1.503072] xvdb: detected capacity change from 0 to 2147483648
[    1.503177] Setting capacity to 24117248
[    1.503183] xvdc: detected capacity change from 0 to 12348030976
Begin: Loading essential drivers ... [    1.522100] device-mapper:
uevent: version 1.0.3
[    1.522182] device-mapper: ioctl: 4.27.0-ioctl (2013-10-30)
initialised: dm-devel@redhat.com
done.
Begin: Running /scripts/init-premount ... done.
Begin: Mounting root file system ... Begin: Running /scripts/local-top
... Begin: Waiting for /dev/xvda* devices... ... done.
Begin: Qubes: Doing COW setup for AppVM... ... sfdisk: Checking that
no-one is using this disk right now ...
[    1.541279]  xvdc: unknown partition table
sfdisk: OK
sfdisk:  /dev/xvdc: unrecognized partition table type
sfdisk: No partitions found
sfdisk: Warning: partition 1 does not end at a cylinder boundary
sfdisk: Warning: partition 2 does not start at a cylinder boundary
sfdisk: Warning: partition 2 does not end at a cylinder boundary
sfdisk: Warning: no primary partition is marked bootable (active)
This does not matter for LILO, but the DOS MBR will not boot this disk.
[    1.553918]  xvdc: xvdc1 xvdc2
sfdisk: If you created or changed a DOS partition, /dev/foo7, say, then
use dd(1)
to zero the first 512 bytes:  dd if=/dev/zero of=/dev/foo7 bs=512 count=1
(See fdisk(8).)
Setting up swapspace version 1, size = 1073737728 bytes
UUID=31416c6a-ebca-4784-bf66-588bff08bf9c
done.
done.
Begin: Running /scripts/local-premount ... done.
Begin: Will now check root file system ... fsck from util-linux 2.25.2
[    1.612029] random: nonblocking pool is initialized
[/sbin/fsck.ext4 (1) -- /dev/mapper/dmroot] fsck.ext4 -a -C0
/dev/mapper/dmroot
/dev/mapper/dmroot: clean, 176763/655360 files, 1678037/2621440 blocks
done.
[    1.634393] EXT4-fs (dm-0): mounted filesystem with ordered data
mode. Opts: (null)
done.
Begin: Running /scripts/local-bottom ... done.
Begin: Running /scripts/init-bottom ... done.
[    1.686755] systemd[1]: systemd 215 running in system mode. (+PAM
+AUDIT +SELINUX +IMA +SYSVINIT +LIBCRYPTSETUP +GCRYPT +ACL +XZ -SECCOMP
-APPARMOR)
[    1.686788] systemd[1]: Detected virtualization 'xen'.
[    1.686793] systemd[1]: Detected architecture 'x86-64'.

Welcome to Debian GNU/Linux 8 (jessie)!

[    1.690181] systemd[1]: Inserted module 'autofs4'
[    1.690305] systemd[1]: No hostname configured.
[    1.690310] systemd[1]: Set hostname to <localhost>.
[    1.719026] systemd[1]: Found ordering cycle on basic.target/start
[    1.719034] systemd[1]: Found dependency on sysinit.target/start
[    1.719038] systemd[1]: Found dependency on rpcbind.service/start
[    1.719041] systemd[1]: Found dependency on network-online.target/start
[    1.719044] systemd[1]: Found dependency on
NetworkManager-wait-online.service/start
[    1.719047] systemd[1]: Found dependency on basic.target/start
[    1.719051] systemd[1]: Breaking ordering cycle by deleting job
rpcbind.service/start
[    1.719054] systemd[1]: Job rpcbind.service/start deleted to break
ordering cycle starting with basic.target/start
[ SKIP ] Ordering cycle found, skipping LSB: RPC portmapper replacement
[    1.719415] systemd[1]: Expecting device dev-hvc0.device...
         Expecting device dev-hvc0.device...
[    1.719459] systemd[1]: Starting Forward Password Requests to Wall
Directory Watch.
[    1.719505] systemd[1]: Started Forward Password Requests to Wall
Directory Watch.
[    1.719516] systemd[1]: Starting Remote File Systems (Pre).
[  OK  ] Reached target Remote File Systems (Pre).
[    1.719554] systemd[1]: Reached target Remote File Systems (Pre).
[    1.719574] systemd[1]: Starting Arbitrary Executable File Formats
File System Automount Point.
[  OK  ] Set up automount Arbitrary Executable File Formats F...utomount
Point.
[    1.719659] systemd[1]: Set up automount Arbitrary Executable File
Formats File System Automount Point.
[    1.719673] systemd[1]: Starting Dispatch Password Requests to
Console Directory Watch.
[    1.719698] systemd[1]: Started Dispatch Password Requests to Console
Directory Watch.
[    1.719708] systemd[1]: Expecting device dev-xvdc1.device...
         Expecting device dev-xvdc1.device...
[    1.719744] systemd[1]: Starting Root Slice.
[  OK  ] Created slice Root Slice.
[    1.719791] systemd[1]: Created slice Root Slice.
[    1.719800] systemd[1]: Starting User and Session Slice.
[  OK  ] Created slice User and Session Slice.
[    1.719896] systemd[1]: Created slice User and Session Slice.
[    1.719905] systemd[1]: Starting Delayed Shutdown Socket.
[  OK  ] Listening on Delayed Shutdown Socket.
[    1.719953] systemd[1]: Listening on Delayed Shutdown Socket.
[    1.719962] systemd[1]: Starting /dev/initctl Compatibility Named Pipe.
[  OK  ] Listening on /dev/initctl Compatibility Named Pipe.
[    1.720020] systemd[1]: Listening on /dev/initctl Compatibility Named
Pipe.
[    1.720029] systemd[1]: Starting Journal Socket (/dev/log).
[  OK  ] Listening on Journal Socket (/dev/log).
[    1.720079] systemd[1]: Listening on Journal Socket (/dev/log).
[    1.720087] systemd[1]: Starting LVM2 metadata daemon socket.
[  OK  ] Listening on LVM2 metadata daemon socket.
[    1.720132] systemd[1]: Listening on LVM2 metadata daemon socket.
[    1.720140] systemd[1]: Starting Device-mapper event daemon FIFOs.
[  OK  ] Listening on Device-mapper event daemon FIFOs.
[    1.720184] systemd[1]: Listening on Device-mapper event daemon FIFOs.
[    1.720194] systemd[1]: Starting udev Control Socket.
[  OK  ] Listening on udev Control Socket.
[    1.720233] systemd[1]: Listening on udev Control Socket.
[    1.720243] systemd[1]: Starting udev Kernel Socket.
[  OK  ] Listening on udev Kernel Socket.
[    1.720280] systemd[1]: Listening on udev Kernel Socket.
[    1.720290] systemd[1]: Starting Journal Socket.
[  OK  ] Listening on Journal Socket.
[    1.720338] systemd[1]: Listening on Journal Socket.
[    1.720352] systemd[1]: Starting System Slice.
[  OK  ] Created slice System Slice.
[    1.720436] systemd[1]: Created slice System Slice.
[    1.720454] systemd[1]: Mounting Temporary Directory...
         Mounting Temporary Directory...
[    1.720545] systemd[1]: tmp.mount: Directory /tmp to mount over is
not empty, mounting anyway.
[    1.721281] systemd[1]: Starting system-getty.slice.
[  OK  ] Created slice system-getty.slice.
[    1.721391] systemd[1]: Created slice system-getty.slice.
[    1.895236] systemd[1]: Starting system-serial\x2dgetty.slice.
[  OK  ] Created slice system-serial\x2dgetty.slice.
[    1.895458] systemd[1]: Created slice system-serial\x2dgetty.slice.
[    1.895484] systemd[1]: Started Collect Read-Ahead Data.
[    1.895501] systemd[1]: Started Replay Read-Ahead Data.
[    1.895521] systemd[1]: Started File System Check on Root Device.
[    1.895556] systemd[1]: Starting Increase datagram queue length...
         Starting Increase datagram queue length...
[    1.896424] systemd[1]: Starting Device-mapper event daemon...
         Starting Device-mapper event daemon...
[  OK  ] Started Device-mapper event daemon.
[    1.897363] systemd[1]: Started Device-mapper event daemon.
[    1.897729] systemd[1]: Started Set Up Additional Binary Formats.
[    1.897849] systemd[1]: Starting Create list of required static
device nodes for the current kernel...
         Starting Create list of required static device nodes...rrent
kernel...
[    1.898740] systemd[1]: Mounting POSIX Message Queue File System...
         Mounting POSIX Message Queue File System...
[    1.900162] systemd[1]: Starting Load Kernel Modules...
         Starting Load Kernel Modules...
[    1.901230] systemd[1]: Mounted Huge Pages File System.
[    1.901298] systemd[1]: Mounting Debug File System...
         Mounting Debug File System...
[    1.902254] systemd[1]: Starting udev Coldplug all Devices...
         Starting udev Coldplug all Devices...
[    1.903447] systemd[1]: Starting Slices.
[  OK  ] Reached target Slices.
[    1.903504] systemd[1]: Reached target Slices.
[  OK  ] Mounted POSIX Message Queue File System.
[    1.904163] systemd[1]: Mounted POSIX Message Queue File System.
[  OK  ] Mounted Temporary Directory.
[    1.904257] systemd[1]: Mounted Temporary Directory.
[  OK  ] Started Increase datagram queue length.
[    1.904645] systemd[1]: Started Increase datagram queue length.
[  OK  ] Started Create list of required static device nodes ...current
kernel.
[    1.905009] systemd[1]: Started Create list of required static device
nodes for the current kernel.
[  OK  ] Mounted Debug File System.
[    1.905207] systemd[1]: Mounted Debug File System.
[    1.907161] lp: driver loaded but no devices found
[    1.908534] ppdev: user-space parallel port driver
[    1.911831] systemd[1]: Starting Create Static Device Nodes in /dev...
         Starting Create Static Device Nodes in /dev...
[    1.912773] systemd[1]: Starting Syslog Socket.
[  OK  ] Listening on Syslog Socket.
[    1.912839] systemd[1]: Listening on Syslog Socket.
[    1.912865] systemd[1]: Starting Journal Service...
         Starting Journal Service...
[  OK  ] Started Journal Service.
[    1.913850] systemd[1]: Started Journal Service.
[  OK  ] Started udev Coldplug all Devices.
         Starting udev Wait for Complete Device Initialization...
[    1.918718] fuse init (API version 7.23)
[    1.919640] xen:xen_evtchn: Event-channel device installed
[  OK  ] Started Create Static Device Nodes in /dev.
[FAILED] Failed to start Load Kernel Modules.
See 'systemctl status systemd-modules-load.service' for details.
[DEPEND] Dependency failed for netfilter persistent configuration.
         Mounting FUSE Control File System...
         Starting Apply Kernel Variables...
         Starting udev Kernel Device Manager...
[  OK  ] Mounted FUSE Control File System.
[  OK  ] Started Apply Kernel Variables.ystemd-udevd[237]: starting
version 215

[  OK  ] Started udev Kernel Device Manager.
         Starting LSB: Set preliminary keymap...
         Starting LSB: Tune IDE hard disks...
[  OK  ] Started LSB: Tune IDE hard disks.
[    2.133765] input: PC Speaker as /devices/platform/pcspkr/input/input0
[  OK  ] Found device /dev/hvc0.
[    2.144091] AVX2 version of gcm_enc/dec engaged.
[    2.149340] alg: No test for __gcm-aes-aesni (__driver-gcm-aes-aesni)
[    2.151063] alg: No test for crc32 (crc32-pclmul)
[  OK  ] Started LSB: Set preliminary keymap.
         Starting Remount Root and Kernel File Systems...
[    2.174199] EXT4-fs (dm-0): re-mounted. Opts: (null)
[  OK  ] Started Remount Root and Kernel File Systems.
         Starting Load/Save Random Seed...
[  OK  ] Reached target Local File Systems (Pre).
         Mounting /proc/xen...
[  OK  ] Started Load/Save Random Seed.
[  OK  ] Mounted /proc/xen.
[  OK  ] Found device /dev/xvdc1.
         Activating swap /dev/xvdc1...
[    5.122599] Adding 1048572k swap on /dev/xvdc1.  Priority:-1
extents:1 across:1048572k SSFS
[  OK  ] Activated swap /dev/xvdc1.
[  OK  ] Reached target Swap.
[  OK  ] Started udev Wait for Complete Device Initialization.
         Starting Activation of LVM2 logical volumes...
         Starting Copy rules generated while the root was ro...
[  OK  ] Started Copy rules generated while the root was ro.
[  OK  ] Started Activation of LVM2 logical volumes.
[  OK  ] Reached target Encrypted Volumes.
         Starting Activation of LVM2 logical volumes...
[  OK  ] Started Activation of LVM2 logical volumes.
         Starting Monitoring of LVM2 mirrors, snapshots etc. ...ress
polling...
[  OK  ] Started Monitoring of LVM2 mirrors, snapshots etc. u...ogress
polling.
[  OK  ] Reached target Local File Systems.
         Starting Qubes DB agent...
         Starting Create Volatile Files and Directories...
         Starting LSB: Raise network interfaces....
[  OK  ] Reached target Remote File Systems.
         Starting Trigger Flushing of Journal to Persistent Storage...
         Starting LSB: AppArmor initialization...
         Starting LSB: Prepare console...
[  OK  ] Started Create Volatile Files and Directories.
[    8.233828] systemd-journald[230]: Received request to flush runtime
journal from PID 1
[  OK  ] Started Trigger Flushing of Journal to Persistent Storage.
[  OK  ] Started Qubes DB agent.
[FAILED] Failed to start LSB: AppArmor initialization.
See 'systemctl status apparmor.service' for details.
         Starting Init Qubes Services settings...
         Starting Qubes Random Seed...
         Starting Network Time Synchronization...
         Starting Update UTMP about System Boot/Shutdown...
[  OK  ] Started Qubes Random Seed.
[  OK  ] Started Update UTMP about System Boot/Shutdown.
[  OK  ] Started Network Time Synchronization.
[  OK  ] Reached target System Time Synchronized.
         Starting LSB: NFS support files common to client and server...
[  OK  ] Started LSB: Prepare console.
         Starting LSB: Set console font and keymap...
[  OK  ] Started LSB: NFS support files common to client and server.
[  OK  ] Started Init Qubes Services settings.
[  OK  ] Started LSB: Raise network interfaces..
[  OK  ] Started LSB: Set console font and keymap.
[  OK  ] Reached target System Initialization.
[  OK  ] Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
[  OK  ] Listening on saned incoming socket.
[  OK  ] Listening on D-Bus System Message Bus Socket.
[  OK  ] Reached target Timers.
[  OK  ] Listening on CUPS Printing Service Sockets.
[  OK  ] Reached target Sockets.
[  OK  ] Reached target Paths.
[  OK  ] Reached target Basic System.
         Starting Entropy daemon using the HAVEGE algorithm...
[  OK  ] Started Entropy daemon using the HAVEGE algorithm.
         Starting WPA supplicant...
         Starting Restore /etc/resolv.conf if the system cras...s shut
down....
         Starting Provide limited super user privileges to specific users...
         Starting Deferred execution scheduler...
[  OK  ] Started Deferred execution scheduler.
         Starting Avahi mDNS/DNS-SD Stack...
         Starting Enable support for additional executable binary formats...
         Starting LSB: daemon to balance interrupts for SMP systems...
         Starting (null)...
         Starting LSB: Start/stop xen driver domain daemon...
         Starting Login Service...
         Starting D-Bus System Message Bus...
[  OK  ] Started D-Bus System Message Bus.
[  OK  ] Started WPA supplicant.
[  OK  ] Started Avahi mDNS/DNS-SD Stack.
         Starting Network Service...
         Starting System Logging Service...
         Starting Permit User Sessions...
         Starting Qubes remote exec agent...
         Starting Qubes memory information reporter...
         Starting Initialize and mount /rw and /home...
         Starting CUPS Printing Service...
[  OK  ] Started CUPS Printing Service.
         Starting Make remote CUPS printers available locally...
[  OK  ] Started Make remote CUPS printers available locally.
         Starting Qubes base firewall settings...
[  OK  ] Started Network Service.
[  OK  ] Started System Logging Service.
[  OK  ] Started Restore /etc/resolv.conf if the system crash...was shut
down..
[  OK  ] Started Provide limited super user privileges to specific users.
[  OK  ] Started LSB: daemon to balance interrupts for SMP systems.
[  OK  ] Started (null).
[  OK  ] Started LSB: Start/stop xen driver domain daemon.
[  OK  ] Started Permit User Sessions.
[  OK  ] Started Qubes remote exec agent.
[  OK  ] Started Qubes memory information reporter.
         Mounting Arbitrary Executable File Formats File System...
         Starting Network Name Resolution...
[  OK  ] Started Login Service.
[  OK  ] Started Network Name Resolution.
[    8.433918] ip_tables: (C) 2000-2006 Netfilter Core Team
[  OK  ] Mounted Arbitrary Executable File Formats File System.
[  OK  ] Started Enable support for additional executable binary formats.
[    8.437102]  xvdb: unknown partition table
[    8.439192] nf_conntrack version 0.5.0 (2599 buckets, 10396 max)
[    8.443739] EXT4-fs (xvdb): mounted filesystem with ordered data
mode. Opts: discard
[  OK  ] Started Initialize and mount /rw and /home.
         Starting Qubes GUI Agent...
         Starting Qubes misc post-boot actions...
[    8.475477] ip6_tables: (C) 2000-2006 Netfilter Core Team
[  OK  ] Started Qubes base firewall settings.
[  OK  ] Started Qubes GUI Agent.
[  OK  ] Reached target Network.
         Starting OpenBSD Secure Shell server...
[  OK  ] Started OpenBSD Secure Shell server.
         Starting OpenVPN service...
         Starting /etc/rc.local Compatibility...
[  OK  ] Started OpenVPN service.
[  OK  ] Started /etc/rc.local Compatibility.
[  OK  ] Created slice user-1000.slice.
         Starting Getty on tty1...
[  OK  ] Started Getty on tty1.
         Starting Serial Getty on hvc0...
[  OK  ] Started Serial Getty on hvc0.
[  OK  ] Reached target Login Prompts.
[  OK  ] Created slice user-0.slice.
         Starting RealtimeKit Scheduling Policy Service...
[  OK  ] Started RealtimeKit Scheduling Policy Service.
         Starting Authenticate and Authorize Users to Run Privileged
Tasks...
[  OK  ] Started Authenticate and Authorize Users to Run Privileged Tasks.
[  OK  ] Removed slice user-0.slice.
[  OK  ] Listening on CUPS Printing Service Sockets.
[  OK  ] Started CUPS Printing Service.
[  OK  ] Started Qubes misc post-boot actions.
[  OK  ] Reached target Network is Online.
         Starting LSB: VirtualBox Linux kernel module...
         Starting LSB: keep memory of all UPnP devices that a...d
themselves...
         Starting LSB: exim Mail Transport Agent...
         Starting LSB: RPC portmapper replacement...
[    9.913727] vboxdrv: fAsync=0 offMin=0x1e2 offMax=0x2c6ee
[    9.913812] vboxdrv: TSC mode is 'synchronous', kernel timer mode is
'normal'.
[  OK  ] Started LSB: keep memory of all UPnP devices that an...ced
themselves.
[    9.930170] vboxpci: IOMMU not found (not registered)
[  OK  ] Started LSB: VirtualBox Linux kernel module.
[  OK  ] Started LSB: RPC portmapper replacement.
[  OK  ] Reached target RPC Port Mapper.
[  OK  ] Started LSB: exim Mail Transport Agent.
[  OK  ] Reached target Multi-User System.
         Starting Update UTMP about System Runlevel Changes...
[  OK  ] Started Update UTMP about System Runlevel Changes.

Debian GNU/Linux 8 temp hvc0

temp login: user
Linux temp 3.16.0-4-amd64 #1 SMP Debian 3.16.7-ckt25-2+deb8u3
(2016-07-02) x86_64

The programs included with the Debian GNU/Linux system are free software;
the exact distribution terms for each program are described in the
individual files in /usr/share/doc/*/copyright.

Debian GNU/Linux comes with ABSOLUTELY NO WARRANTY, to the extent
permitted by applicable law.
user@temp:~$
user@temp:~$
user@temp:~$
user@temp:~$ sudo systemctl --failed list-units
  UNIT                         LOAD   ACTIVE SUB    DESCRIPTION
● apparmor.service             loaded failed failed LSB: AppArmor
initialization
● systemd-modules-load.service loaded failed failed Load Kernel Modules

LOAD   = Reflects whether the unit definition was properly loaded.
ACTIVE = The high-level unit activation state, i.e. generalization of SUB.
SUB    = The low-level unit activation state, values depend on unit type.

2 loaded units listed. Pass --all to see loaded but inactive units, too.
To show all installed unit files use 'systemctl list-unit-files'.
user@temp:~$
user@temp:~$ sudo service systemd-modules-load status
● systemd-modules-load.service - Load Kernel Modules
   Loaded: loaded (/lib/systemd/system/systemd-modules-load.service; static)
   Active: failed (Result: exit-code) since Wed 2016-07-20 06:00:14
CEST; 1min 6s ago
     Docs: man:systemd-modules-load.service(8)
           man:modules-load.d(5)
  Process: 215 ExecStart=/lib/systemd/systemd-modules-load (code=exited,
status=1/FAILURE)
 Main PID: 215 (code=exited, status=1/FAILURE)

Jul 20 06:00:14 localhost systemd-modules-load[215]: Inserted module 'lp'
Jul 20 06:00:14 localhost systemd[1]: systemd-modules-load.service: main
pr...RE
Jul 20 06:00:14 localhost systemd[1]: Failed to start Load Kernel Modules.
Jul 20 06:00:14 localhost systemd[1]: Unit systemd-modules-load.service
ent...e.
Warning: Journal has been rotated since unit was started. Log output is
incomplete or unavailable.
Hint: Some lines were ellipsized, use -l to show in full.
user@temp:~$
user@temp:~$
user@temp:~$ sudo journalctl -u systemd-modules-load
-- Logs begin at Wed 2016-07-20 06:00:14 CEST, end at Wed 2016-07-20
06:01:41 CE
Jul 20 06:00:14 localhost systemd-modules-load[215]: Inserted module 'lp'
Jul 20 06:00:14 localhost systemd[1]: systemd-modules-load.service: main
process
Jul 20 06:00:14 localhost systemd[1]: Failed to start Load Kernel Modules.
Jul 20 06:00:14 localhost systemd[1]: Unit systemd-modules-load.service
entered
user@temp:~$
user@temp:~$
user@temp:~$
user@temp:~$ sudo /lib/systemd/systemd-modules-load
Failed to find module 'u2mfn'
Failed to find module 'dummy-hcd'

adrelanos commented 8 years ago

The following command "mostly"(?) fixed it for me.

sudo apt-get install --reinstall linux-image-3.16.0-4-amd64

(There might be another command required. I mean, update-initramfs -u helped, but another command would have been required to fix this without reinstalling the kernel package.)

So not pvgrub is broken, I just managed somehow to first install a kernel, and later the Qubes packages, most likely. Still worthwhile to document, I think.

qrexec now seems to work. Got gui applications working.

There may still be an issue.

sudo service systemd-modules-load status

● systemd-modules-load.service - Load Kernel Modules
   Loaded: loaded (/lib/systemd/system/systemd-modules-load.service; static)
   Active: failed (Result: exit-code) since Wed 2016-07-20 20:48:13 CEST; 2min 54s ago
     Docs: man:systemd-modules-load.service(8)
           man:modules-load.d(5)
 Main PID: 217 (code=exited, status=1/FAILURE)

Warning: Journal has been rotated since unit was started. Log output is incomplete or unavailable.

sudo /lib/systemd/systemd-modules-load

Failed to find module 'dummy-hcd'

Is that an issue or to be expected?

marmarek commented 8 years ago

That helped. We should add that to documentation. (Troubleshooting or always just in case?)

Yes, added.

One or two more issues. VM stays yellow, qrexec apparently does start yet (symptom: cannot start gui applications). Probably a follow up issue.

systemd-modules-load failed. Related? (...)

user@temp:~$ sudo /lib/systemd/systemd-modules-load Failed to find module 'u2mfn'

Yes, this seems to be related. It looks like u2mfn module wasn't built. Another thing that should be done automatically when you install kernel package (and was done in my test yesterday...). Can be done using this: dkms autoinstall

Also added to documentation.

Missing dummy-hcd should be irrelevant.

adrelanos commented 8 years ago

Missing dummy-hcd should be irrelevant.

Let's also add that to documentation and then I think this can be closed.

QubesOS / qubes-issues