Join GitHub today
GitHub is home to over 28 million developers working together to host and review code, manage projects, and build software together.
Sign up[Arch Linux] starting AppVM by launching application occasionally fails #2649
Comments
andrewdavidwong
added
bug
C: Arch Linux
labels
Feb 25, 2017
andrewdavidwong
added this to the Release 3.2 updates milestone
Feb 25, 2017
This comment has been minimized.
Show comment
Hide comment
This comment has been minimized.
Show comment
Hide comment
yilmi
Mar 1, 2017
I think I've hit the same issue, could you share the output of the AppVM console when you're having the problem ?
sudo xl console appvm
yilmi
commented
Mar 1, 2017
|
I think I've hit the same issue, could you share the output of the AppVM console when you're having the problem ?
|
This comment has been minimized.
Show comment
Hide comment
This comment has been minimized.
Show comment
Hide comment
crat0z
Mar 2, 2017
Console output:
[ 0.000000] bootconsole [xenboot0] disabled
[ 0.000000] clocksource: xen: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
[ 0.000000] installing Xen timer for CPU 0
[ 0.000000] tsc: Detected 3502.736 MHz processor
[ 0.001000] Calibrating delay loop (skipped), value calculated using timer frequency.. 7005.47 BogoMIPS (lpj=3502736)
[ 0.001000] pid_max: default: 32768 minimum: 301
[ 0.001000] Security Framework initialized
[ 0.001000] AppArmor: AppArmor disabled by boot time parameter
[ 0.001328] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes)
[ 0.002879] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes)
[ 0.003413] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes)
[ 0.003441] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes)
[ 0.003809] Initializing cgroup subsys io
[ 0.003823] Initializing cgroup subsys memory
[ 0.003840] Initializing cgroup subsys devices
[ 0.003850] Initializing cgroup subsys freezer
[ 0.003859] Initializing cgroup subsys net_cls
[ 0.003868] Initializing cgroup subsys perf_event
[ 0.003878] Initializing cgroup subsys net_prio
[ 0.003890] Initializing cgroup subsys hugetlb
[ 0.003899] Initializing cgroup subsys pids
[ 0.003982] ENERGY_PERF_BIAS: Set to 'normal', was 'performance'
[ 0.003991] ENERGY_PERF_BIAS: View and update with x86_energy_perf_policy(8)
[ 0.004005] CPU: Physical Processor ID: 0
[ 0.004012] CPU: Processor Core ID: 3
[ 0.004023] Last level iTLB entries: 4KB 512, 2MB 8, 4MB 8
[ 0.004030] Last level dTLB entries: 4KB 512, 2MB 32, 4MB 32, 1GB 0
[ 0.043380] ftrace: allocating 28748 entries in 113 pages
[ 0.048055] cpu 0 spinlock event irq 1
[ 0.048063] Could not initialize VPMU for cpu 0, error -95
[ 0.048095] Performance Events: unsupported p6 CPU model 42 no PMU driver, software events only.
[ 0.048541] NMI watchdog: disabled (cpu0): hardware events not enabled
[ 0.048546] NMI watchdog: Shutting down hard lockup detector on all cpus
[ 0.048630] SMP alternatives: switching to SMP code
[ 0.063179] installing Xen timer for CPU 1
[ 0.063194] cpu 1 spinlock event irq 8
[ 0.063326] x86: Booted up 1 node, 2 CPUs
[ 0.063382] devtmpfs: initialized
[ 0.065058] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
[ 0.065157] atomic64_test: passed for x86-64 platform with CX8 and with SSE
[ 0.065165] pinctrl core: initialized pinctrl subsystem
[ 0.085161] RTC time: 165:165:165, date: 165/165/65
[ 0.085276] NET: Registered protocol family 16
[ 0.085289] xen:grant_table: Grant tables using version 1 layout
[ 0.085297] Grant table initialized
[ 0.085383] PCI: setting up Xen PCI frontend stub
[ 0.091153] ACPI: Interpreter disabled.
[ 0.091153] xen:balloon: Initialising balloon driver
[ 0.150020] xen_balloon: Initialising balloon driver
[ 0.150046] vgaarb: loaded
[ 0.150092] SCSI subsystem initialized
[ 0.150106] usbcore: registered new interface driver usbfs
[ 0.150106] usbcore: registered new interface driver hub
[ 0.150132] usbcore: registered new device driver usb
[ 0.150219] dmi: Firmware registration failed.
[ 0.150219] PCI: System does not support PCI
[ 0.150219] PCI: System does not support PCI
[ 0.150229] NetLabel: Initializing
[ 0.150237] NetLabel: domain hash size = 128
[ 0.150244] NetLabel: protocols = UNLABELED CIPSOv4
[ 0.150267] NetLabel: unlabeled traffic allowed by default
[ 0.150372] amd_nb: Cannot enumerate AMD northbridges
[ 0.151004] clocksource: Switched to clocksource xen
[ 0.160222] pnp: PnP ACPI: disabled
[ 0.165324] NET: Registered protocol family 2
[ 0.165600] TCP established hash table entries: 32768 (order: 6, 262144 bytes)
[ 0.165855] TCP bind hash table entries: 32768 (order: 7, 524288 bytes)
[ 0.165945] TCP: Hash tables configured (established 32768 bind 32768)
[ 0.166033] UDP hash table entries: 2048 (order: 4, 65536 bytes)
[ 0.166060] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes)
[ 0.166163] NET: Registered protocol family 1
[ 0.166243] Unpacking initramfs...
[ 0.179394] Freeing initrd memory: 11012K (ffff880002057000 - ffff880002b18000)
[ 0.179485] RAPL PMU detected, API unit is 2^-32 Joules, 3 fixed counters 163840 ms ovfl timer
[ 0.179491] hw unit of domain pp0-core 2^-16 Joules
[ 0.179494] hw unit of domain package 2^-16 Joules
[ 0.179497] hw unit of domain pp1-gpu 2^-16 Joules
[ 0.194983] futex hash table entries: 512 (order: 3, 32768 bytes)
[ 0.195014] audit: initializing netlink subsys (disabled)
[ 0.195036] audit: type=2000 audit(1488474736.643:1): initialized
[ 0.195201] Initialise system trusted keyring
[ 0.196172] zbud: loaded
[ 0.196332] VFS: Disk quotas dquot_6.6.0
[ 0.196360] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[ 0.196539] hugetlbfs: disabling because there are no supported hugepage sizes
[ 0.196660] Key type big_key registered
[ 0.199830] NET: Registered protocol family 38
[ 0.199839] Key type asymmetric registered
[ 0.199843] Asymmetric key parser 'x509' registered
[ 0.199877] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
[ 0.199910] io scheduler noop registered
[ 0.199915] io scheduler deadline registered
[ 0.199939] io scheduler cfq registered (default)
[ 0.200104] xen:xen_evtchn: Event-channel device installed
[ 0.200383] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[ 0.200663] Non-volatile memory driver v1.3
[ 0.200690] Linux agpgart interface v0.103
[ 0.200915] libphy: Fixed MDIO Bus: probed
[ 0.200965] usbcore: registered new interface driver usbserial
[ 0.200973] usbcore: registered new interface driver usbserial_generic
[ 0.200980] usbserial: USB Serial support registered for generic
[ 0.200996] i8042: PNP: No PS/2 controller found. Probing ports directly.
[ 1.209922] i8042: No controller found
[ 1.209940] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x327d69680f6, max_idle_ns: 440795213305 ns
[ 1.210062] mousedev: PS/2 mouse device common for all mice
[ 1.210140] device-mapper: uevent: version 1.0.3
[ 1.210202] device-mapper: ioctl: 4.34.0-ioctl (2015-10-28) initialised: dm-devel@redhat.com
[ 1.210262] dmi-sysfs: dmi entry is absent.
[ 1.210283] hidraw: raw HID events driver (C) Jiri Kosina
[ 1.210315] usbcore: registered new interface driver usbhid
[ 1.210319] usbhid: USB HID core driver
[ 1.210357] drop_monitor: Initializing network drop monitor service
[ 1.210407] ip_tables: (C) 2000-2006 Netfilter Core Team
[ 1.210510] Initializing XFRM netlink socket
[ 1.210596] NET: Registered protocol family 10
[ 1.210723] mip6: Mobile IPv6
[ 1.210728] NET: Registered protocol family 17
[ 1.210736] mce: Unable to init device /dev/mcelog (rc: -5)
[ 1.210788] AVX version of gcm_enc/dec engaged.
[ 1.210792] AES CTR mode by8 optimization enabled
[ 1.228362] registered taskstats version 1
[ 1.228385] Loading compiled-in X.509 certificates
[ 1.228420] zswap: loaded using pool lzo/zbud
[ 1.228477] xenbus_probe_frontend: Device with no driver: device/vbd/51712
[ 1.228483] xenbus_probe_frontend: Device with no driver: device/vbd/51728
[ 1.228488] xenbus_probe_frontend: Device with no driver: device/vbd/51744
[ 1.228493] xenbus_probe_frontend: Device with no driver: device/vbd/51760
[ 1.228498] xenbus_probe_frontend: Device with no driver: device/vif/0
[ 1.228580] Magic number: 1:252:3141
[ 1.228602] hctosys: unable to open rtc device (rtc0)
[ 1.229020] Freeing unused kernel memory: 1532K (ffffffff81d3a000 - ffffffff81eb9000)
[ 1.229026] Write protecting the kernel read-only data: 12288k
[ 1.232351] Freeing unused kernel memory: 636K (ffff880001761000 - ffff880001800000)
[ 1.232916] Freeing unused kernel memory: 684K (ffff880001b55000 - ffff880001c00000)
Qubes initramfs script here:
[ 1.238320] random: modprobe: uninitialized urandom read (8 bytes read, 25 bits of entropy available)
[ 1.296940] blkfront: xvda: flush diskcache: enabled; persistent grants: enabled; indirect descriptors: enabled;
[ 1.311560] blkfront: xvdb: flush diskcache: enabled; persistent grants: enabled; indirect descriptors: enabled;
[ 1.346349] blkfront: xvdc: flush diskcache: enabled; persistent grants: enabled; indirect descriptors: enabled;
[ 1.379247] blkfront: xvdd: barrier or flush: disabled; persistent grants: enabled; indirect descriptors: enabled;
Waiting for /dev/xvda* devices...
[ 1.467760] random: cat: uninitialized urandom read (8 bytes read, 36 bits of entropy available)
Qubes: Doing COW setup for AppVM...
[ 1.468933] random: cat: uninitialized urandom read (8 bytes read, 36 bits of entropy available)
[ 1.470022] random: cat: uninitialized urandom read (8 bytes read, 36 bits of entropy available)
[ 1.480628] random: sfdisk: uninitialized urandom read (4 bytes read, 37 bits of entropy available)
[ 1.480961] xvdc: xvdc1 xvdc2
[ 1.511383] random: mkswap: uninitialized urandom read (8 bytes read, 38 bits of entropy available)
Setting up swapspace version 1, size = 1073737728 bytes
[ 1.511697] random: mkswap: uninitialized urandom read (16 bytes read, 38 bits of entropy available)
UUID=2d6a6935-0cd7-422b-a0ea-e5b2f4845935
[ 1.532638] random: cat: uninitialized urandom read (8 bytes read, 38 bits of entropy available)
Qubes: done.
[ 1.542781] random: modprobe: uninitialized urandom read (8 bytes read, 38 bits of entropy available)
modprobe: module ext4 not found in modules.dep
[ 1.544210] random: mkdir: uninitialized urandom read (8 bytes read, 39 bits of entropy available)
[ 1.545505] EXT4-fs (dm-0): couldn't mount as ext3 due to feature incompatibilities
[ 1.545654] EXT4-fs (dm-0): couldn't mount as ext2 due to feature incompatibilities
[ 1.545970] EXT4-fs (dm-0): INFO: recovery required on readonly filesystem
[ 1.545980] EXT4-fs (dm-0): write access will be enabled during recovery
[ 1.713086] EXT4-fs (dm-0): orphan cleanup on readonly fs
[ 1.713239] EXT4-fs (dm-0): 4 orphan inodes deleted
[ 1.713250] EXT4-fs (dm-0): recovery complete
[ 1.803844] EXT4-fs (dm-0): mounted filesystem with ordered data mode. Opts: (null)
Waiting for /dev/xvdd device...
[ 1.809105] EXT4-fs (xvdd): mounting ext3 file system using the ext4 subsystem
[ 1.811120] EXT4-fs (xvdd): mounted filesystem with ordered data mode. Opts: (null)
[ 1.890279] systemd[1]: systemd 232 running in system mode. (+PAM -AUDIT -SELINUX -IMA -APPARMOR +SMACK -SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN)
[ 1.890384] systemd[1]: Detected virtualization xen.
[ 1.890402] systemd[1]: Detected architecture x86-64.
Welcome to Arch Linux!
[ 1.891144] systemd[1]: No hostname configured.
[ 1.891166] systemd[1]: Set hostname to <localhost>.
[ 1.937474] systemd[1]: alsa-restore.service: Cannot add dependency job, ignoring: Unit alsa-restore.service is masked.
[ 1.938566] systemd[1]: Listening on Journal Audit Socket.
[ OK ] Listening on Journal Audit Socket.
[ 1.938876] systemd[1]: Listening on Journal Socket.
[ OK ] Listening on Journal Socket.
[ 1.939088] systemd[1]: Listening on LVM2 metadata daemon socket.
[ OK ] Listening on LVM2 metadata daemon socket.
[ 1.939286] systemd[1]: Listening on udev Control Socket.
[ OK ] Listening on udev Control Socket.
[ 1.940130] systemd[1]: Created slice User and Session Slice.
[ OK ] Created slice User and Session Slice.
[ 1.940325] systemd[1]: Listening on Device-mapper event daemon FIFOs.
[ OK ] Listening on Device-mapper event daemon FIFOs.
[ 1.940511] systemd[1]: Listening on Journal Socket (/dev/log).
[ OK ] Listening on Journal Socket (/dev/log).
[ 1.940670] systemd[1]: Listening on udev Kernel Socket.
[ OK ] Listening on udev Kernel Socket.
[ 1.940869] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[ OK ] Started Forward Password Requests to Wall Directory Watch.
[ 1.941095] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[ OK ] Started Dispatch Password Requests to Console Directory Watch.
[ 1.941228] systemd[1]: Reached target Remote File Systems.
[ OK ] Reached target Remote File Systems.
[ 1.941602] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[ OK ] Set up automount Arbitrary Executab…rmats File System Automount Point.
[ 1.941760] systemd[1]: Reached target Encrypted Volumes.
[ OK ] Reached target Encrypted Volumes.
[ 1.941832] systemd[1]: Reached target Paths.
[ OK ] Reached target Paths.
[ 1.943124] systemd[1]: Listening on Process Core Dump Socket.
[ OK ] Listening on Process Core Dump Socket.
[ 1.943317] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
[ OK ] Listening on /dev/initctl Compatibility Named Pipe.
[ 1.943633] systemd[1]: Created slice System Slice.
[ OK ] Created slice System Slice.
[ 1.955297] systemd[1]: Mounting /proc/xen...
Mounting /proc/xen...
[ 1.956049] systemd[1]: Created slice system-systemd\x2dfsck.slice.
[ OK ] Created slice system-systemd\x2dfsck.slice.
[ 1.959662] systemd[1]: Starting Load Kernel Modules...
Starting Load Kernel Modules...
[ 2.007605] systemd[1]: Starting File System Check on Root Device...
Starting File System Check on Root Device...
[ 2.008385] dummy_hcd dummy_hcd.0: USB Host+Gadget Emulator, driver 02 May 2005
[ 2.008398] dummy_hcd dummy_hcd.0: Dummy host controller
[ 2.008603] systemd[1]: Mounting POSIX Message Queue File System...
Mounting POSIX Message Queue File System...
[ 2.008903] dummy_hcd dummy_hcd.0: new USB bus registered, assigned bus number 1
[ 2.008941] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[ 2.008948] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 2.008954] usb usb1: Product: Dummy host controller
[ 2.008958] usb usb1: Manufacturer: Linux 4.4.38-11.pvops.qubes.x86_64 dummy_hcd
[ 2.008964] usb usb1: SerialNumber: dummy_hcd.0
[ 2.009083] hub 1-0:1.0: USB hub found
[ 2.009096] hub 1-0:1.0: 1 port detected
[ 2.009514] systemd[1]: Starting Create list of required static device nodes for the current kernel...
Starting Create list of required st…ce nodes for the current kernel...
[ 2.011221] systemd[1]: Created slice system-serial\x2dgetty.slice.
[ OK ] Created slice system-serial\x2dgetty.slice.
[ 2.012280] systemd[1]: Mounting Debug File System...
Mounting Debug File System...
[ 2.012476] systemd[1]: Created slice system-getty.slice.
[ OK ] Created slice system-getty.slice.
[ 2.012624] systemd[1]: Reached target Slices.
[ OK ] Reached target Slices.
[ 2.013459] systemd[1]: Starting Journal Service...
Starting Journal Service...
[ 2.018155] systemd[1]: Mounted POSIX Message Queue File System.
[ OK ] Mounted POSIX Message Queue File System.
[ 2.018219] systemd[1]: Mounted /proc/xen.
[ OK ] Mounted /proc/xen.
[ 2.018526] systemd[1]: Started Create list of required static device nodes for the current kernel.
[ OK ] Started Create list of required sta…vice nodes for the current kernel.
[ 2.030096] systemd[1]: Mounted Debug File System.
[ OK ] Mounted Debug File System.
[ 2.030204] systemd[1]: Started Journal Service.
[ OK ] Started Journal Service.
[ OK ] Started File System Check on Root Device.
Starting Remount Root and Kernel File Systems...
[FAILED] Failed to start Load Kernel Modules.
See 'systemctl status systemd-modules-load.service' for details.
Starting Qubes DB agent...
Starting Apply Kernel Variables...
Mounting Configuration File System...
[ OK ] Started Apply Kernel Variables.
[ OK ] Mounted Configuration File System.
[ 2.072898] EXT4-fs (dm-0): re-mounted. Opts: (null)
[ OK ] Started Remount Root and Kernel File Systems.
Starting Rebuild Hardware Database...
Starting Flush Journal to Persistent Storage...
Starting Create System Users...
[ OK ] Started Qubes DB agent.
[ 2.090087] systemd-journald[177]: Received request to flush runtime journal from PID 1
Starting Load/Save Random Seed...
Starting Init Qubes Services settings...
[ 2.103382] systemd-journald[177]: File /var/log/journal/8465d01295b9426c90d5fa7a81aca692/system.journal corrupted or uncleanly shut down, renaming and replacing.
[ OK ] Started Create System Users.
Starting Create Static Device Nodes in /dev...
[ OK ] Started Load/Save Random Seed.
[ OK ] Started Create Static Device Nodes in /dev.
Starting udev Kernel Device Manager...
[ OK ] Reached target Local File Systems (Pre).
[ OK ] Started udev Kernel Device Manager.
[ OK ] Started Flush Journal to Persistent Storage.
[ OK ] Started Init Qubes Services settings.
[ OK ] Started Rebuild Hardware Database.
Starting udev Coldplug all Devices...
[ OK ] Started udev Coldplug all Devices.
[ 2.490271] input: PC Speaker as /devices/platform/pcspkr/input/input0
[ 2.494916] Error: Driver 'pcspkr' is already registered, aborting...
[ 2.496900] intel_rapl: Found RAPL domain package
[ 2.496906] intel_rapl: Found RAPL domain core
[ 2.496910] intel_rapl: Found RAPL domain uncore
[ 2.496916] intel_rapl: RAPL package 0 domain package locked by BIOS
[ OK ] Found device /dev/xvdb.
[ 2.517255] xen_netfront: Initialising Xen virtual ethernet driver
Starting Initialize and mount /rw and /home...
Starting File System Check on /dev/xvdb...
[ OK ] Found device /dev/hvc0.
[FAILED] Failed to start Initialize and mount /rw and /home.
See 'systemctl status qubes-mount-dirs.service' for details.
[ OK ] Started File System Check on /dev/xvdb.
Mounting /rw...
[ 2.567327] EXT4-fs (xvdb): mounted filesystem with ordered data mode. Opts: (null)
[ OK ] Mounted /rw.
[ 2.619665] random: nonblocking pool is initialized
[ OK ] Found device /dev/xvdc1.
Activating swap /dev/xvdc1...
[ 5.637255] Adding 1048572k swap on /dev/xvdc1. Priority:-1 extents:1 across:1048572k SSFS
[ OK ] Activated swap /dev/xvdc1.
[ OK ] Reached target Swap.
Mounting Temporary Directory...
[ OK ] Mounted Temporary Directory.
[ OK ] Reached target Local File Systems.
Starting Create Volatile Files and Directories...
Starting Rebuild Dynamic Linker Cache...
Starting Early Qubes VM settings...
Starting Rebuild Journal Catalog...
[FAILED] Failed to start Create Volatile Files and Directories.
See 'systemctl status systemd-tmpfiles-setup.service' for details.
[ OK ] Started Rebuild Journal Catalog.
Starting Update UTMP about System Boot/Shutdown...
[ OK ] Started Update UTMP about System Boot/Shutdown.
[ OK ] Started Rebuild Dynamic Linker Cache.
Starting Update is Completed...
[ OK ] Started Early Qubes VM settings.
[ OK ] Started Update is Completed.
[ OK ] Reached target System Initialization.
[ OK ] Started Daily rotation of log files.
[ OK ] Started Daily man-db cache update.
[ OK ] Listening on D-Bus System Message Bus Socket.
[ OK ] Reached target Sockets.
[ OK ] Reached target Basic System.
Starting Login Service...
[ OK ] Started Entropy Harvesting Daemon.
Starting Qubes memory information reporter...
[ OK ] Started Daily verification of password and group files.
Starting Qubes base firewall settings...
[ OK ] Started Daily Cleanup of Temporary Directories.
[ OK ] Started D-Bus System Message Bus.
[ 5.729652] nf_conntrack version 0.5.0 (2445 buckets, 9780 max)
Starting Permit User Sessions...
[ OK ] Started Periodically check for updates.
[ OK ] Reached target Timers.
[ OK ] Started Qubes memory information reporter.
[ 5.748056] audit: type=1325 audit(1488474742.195:2): table=nat family=2 entries=0
[ 5.748086] audit: type=1300 audit(1488474742.195:2): arch=c000003e syscall=313 success=yes exit=0 a0=3 a1=41aada a2=0 a3=3 items=0 ppid=62 pid=422 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="modprobe" exe="/usr/bin/kmod" key=(null)
[ 5.748100] audit: type=1327 audit(1488474742.195:2): proctitle=2F7362696E2F6D6F6470726F6265002D71002D2D0069707461626C655F6E6174
Starting Qubes GUI Agent...
[ OK ] Started Qubes GUI Agent.[ 5.826186] audit: type=1325 audit(1488474742.197:3): table=nat family=2 entries=5
[ 5.826234] audit: type=1300 audit(1488474742.197:3): arch=c000003e syscall=54 success=yes exit=0 a0=4 a1=0 a2=40 a3=2256180 items=0 ppid=418 pid=421 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables-restor" exe="/usr/bin/xtables-multi" key=(null)
[ 5.826274] audit: type=1327 audit(1488474742.197:3): proctitle=69707461626C65732D726573746F7265002F6574632F71756265732F69707461626C65732E72756C6573
[ 5.839593] audit: type=1325 audit(1488474742.287:4): table=filter family=2 entries=4
[ 5.839618] audit: type=1300 audit(1488474742.287:4): arch=c000003e syscall=54 success=yes exit=0 a0=4 a1=0 a2=40 a3=2259040 items=0 ppid=418 pid=421 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables-restor" exe="/usr/bin/xtables-multi" key=(null)
[ 5.839636] audit: type=1327 audit(1488474742.287:4): proctitle=69707461626C65732D726573746F7265002F6574632F71756265732F69707461626C65732E72756C6573
[ 5.843522] ip6_tables: (C) 2000-2006 Netfilter Core Team
[ 5.845701] audit: type=1325 audit(1488474742.292:5): table=filter family=10 entries=0
[ OK ] Started Qubes base firewall settings.
[ OK ] Started Permit User Sessions.
[ OK ] Started Login Service.
[ OK ] Started Getty on tty1.
[ OK ] Started Serial Getty on hvc0.
[ OK ] Reached target Login Prompts.
Starting Xen driver domain device daemon...
Starting Qubes misc post-boot actions...
[ OK ] Started Xen driver domain device daemon.
Starting Qubes remote exec agent...
[ OK ] Started Qubes remote exec agent.
[ OK ] Started Qubes misc post-boot actions.
[ OK ] Reached target Multi-User System.
[ 6.055201] systemd-journald[177]: File /var/log/journal/8465d01295b9426c90d5fa7a81aca692/user-1000.journal corrupted or uncleanly shut down, renaming and replacing.
Arch Linux 4.4.38-11.pvops.qubes.x86_64 (hvc0)
arch-test login: [ 51.288648] audit_printk_skb: 36 callbacks suppressed
[ 51.288658] audit: type`test`=1006 audit(1488474787.736:14): pid=1406 uid=0 old-auid=4294967295 auid=0 old-ses=4294967295 ses=8 res=1```
crat0z
commented
Mar 2, 2017
|
Console output: |
This comment has been minimized.
Show comment
Hide comment
This comment has been minimized.
Show comment
Hide comment
starfys
Mar 9, 2017
I have the same issue. The issue is very likely connected to these lines
[FAILED] Failed to start Load Kernel Modules.
See 'systemctl status systemd-modules-load.service' for details.
[FAILED] Failed to start Initialize and mount /rw and /home.
See 'systemctl status qubes-mount-dirs.service' for details.
[FAILED] Failed to start Create Volatile Files and Directories.
See 'systemctl status systemd-tmpfiles-setup.service' for details.
starfys
commented
Mar 9, 2017
•
edited
Edited 1 time
-
starfys
edited Mar 9, 2017 (most recent)
edited
Edited 1 time
-
Mar 9, 2017 (most recent)
|
I have the same issue. The issue is very likely connected to these lines |
This comment has been minimized.
Show comment
Hide comment
This comment has been minimized.
Show comment
Hide comment
starfys
Mar 9, 2017
Here's the output of systemctl status systemd-modules-load
[root@archlinux-test ~]# systemctl --no-pager -l status systemd-modules-load
● systemd-modules-load.service - Load Kernel Modules
Loaded: loaded (/usr/lib/systemd/system/systemd-modules-load.service; static; vendor preset: disabled)
Active: failed (Result: exit-code) since Thu 2017-03-09 14:09:53 CST; 23min ago
Docs: man:systemd-modules-load.service(8)
man:modules-load.d(5)
Process: 175 ExecStart=/usr/lib/systemd/systemd-modules-load (code=exited, status=1/FAILURE)
Main PID: 175 (code=exited, status=1/FAILURE)
Mar 09 14:09:53 localhost systemd-modules-load[175]: Failed to find module 'gntdev'
Mar 09 14:09:53 localhost systemd-modules-load[175]: Failed to find module 'netbk'
Mar 09 14:09:53 localhost systemd-modules-load[175]: Failed to find module 'blkbk'
Mar 09 14:09:53 localhost systemd-modules-load[175]: Failed to find module 'xen-scsibk'
Mar 09 14:09:53 localhost systemd-modules-load[175]: Failed to find module 'usbbk'
Mar 09 14:09:53 localhost systemd-modules-load[175]: Failed to find module 'pciback'
Mar 09 14:09:53 localhost systemd[1]: systemd-modules-load.service: Main process exited, code=exited, status=1/FAILURE
Mar 09 14:09:53 localhost systemd[1]: Failed to start Load Kernel Modules.
Mar 09 14:09:53 localhost systemd[1]: systemd-modules-load.service: Unit entered failed state.
Mar 09 14:09:53 localhost systemd[1]: systemd-modules-load.service: Failed with result 'exit-code'.
starfys
commented
Mar 9, 2017
•
edited
Edited 1 time
-
starfys
edited
Mar 9, 2017 (most recent)
edited
Edited 1 time
-
Mar 9, 2017 (most recent)
|
Here's the output of |
Sign up for free
to join this conversation on GitHub.
Already have an account?
Sign in to comment
crat0z commentedFeb 24, 2017
Qubes OS version (e.g.,
R3.2):R3.2 with
qubes-dom0-current-testingenabledAffected TemplateVMs (e.g.,
fedora-23, if applicable):Arch Linux Community TemplateVM
Expected behavior:
Start AppVM with e.g.
qvm-start arch-test 'firefox', AppVM starts and application startsActual behavior:
Occasionally doesn't work
Steps to reproduce the behavior:
Shut down an AppVM based on an Arch Linux Template,
qvm-start arch-test 'firefox'or open via XFCE GUIGeneral notes:
I've confirmed this on my desktop and laptop. In general, this is remedied by simply shutting down the AppVM and trying again. When the VM is in this state, qubes-manager doesn't report any errors.
When the VM does start properly and works fine, it's unable to shutdown normally and needs to be killed. This may be a separate issue though.
Related issues:
#2625