Terminal/Sublime Text etc fails to launch after previously successful install |
||||
Issue descriptionChrome Version: 68.0.3440.4 (Official Build) dev (64-bit) OS: Chrome OS, Google_Eve.9584.151.0 What steps will reproduce the problem? (1) Click on Terminal icon (or eg the Sublime Text icon) in the launcher What is the expected result? Terminal opens What happens instead? Nothing. Paste from var/log/messages below 2018-06-06T10:15:18.811823+10:00 NOTICE temp_logger[12368]: 00:37 02:41 03:40 04:35 05:47 06:36 07:34 2018-06-06T10:15:39.976775+10:00 NOTICE dbus[550]: [system] Activating service name='org.chromium.ImageLoader' (using servicehelper) 2018-06-06T10:15:40.125462+10:00 NOTICE dbus[550]: [system] Successfully activated service 'org.chromium.ImageLoader' 2018-06-06T10:15:40.160462+10:00 WARNING kernel: [22900.807110] device-mapper: verity: Argument 0: 'payload=/dev/loop7' 2018-06-06T10:15:40.160476+10:00 WARNING kernel: [22900.807117] device-mapper: verity: Argument 1: 'hashtree=/dev/loop7' 2018-06-06T10:15:40.160477+10:00 WARNING kernel: [22900.807120] device-mapper: verity: Argument 2: 'hashstart=461568' 2018-06-06T10:15:40.160478+10:00 WARNING kernel: [22900.807123] device-mapper: verity: Argument 3: 'alg=sha256' 2018-06-06T10:15:40.160479+10:00 WARNING kernel: [22900.807125] device-mapper: verity: Argument 4: 'root_hexdigest=516e3ccbed6880e2d8d443ecafb5af42385c6c7c24c7d0a618118b943a2c3c96' 2018-06-06T10:15:40.160842+10:00 WARNING kernel: [22900.807128] device-mapper: verity: Argument 5: 'salt=db713d76b6d316135a1bc9a3e64c093c4b69e182eb85cc6bcd93beb8074bc924' 2018-06-06T10:15:40.160844+10:00 WARNING kernel: [22900.807130] device-mapper: verity: Argument 6: 'error_behavior=eio' 2018-06-06T10:15:40.193691+10:00 INFO kernel: [22900.841656] EXT4-fs (dm-3): mounted filesystem without journal. Opts: 2018-06-06T10:15:40.196851+10:00 INFO debugd[1247]: Starting vm_concierge 2018-06-06T10:15:40.218685+10:00 INFO kernel: [22900.867316] NET: Registered protocol family 40 2018-06-06T10:15:40.247310+10:00 INFO crosdns[12493]: Starting CrOS DNS daemon 2018-06-06T10:15:40.249154+10:00 INFO minijail0[12494]: libminijail[12494]: mount / -> / type '' 2018-06-06T10:15:40.249164+10:00 INFO minijail0[12494]: libminijail[12494]: mount proc -> /proc type 'proc' 2018-06-06T10:15:40.249169+10:00 INFO minijail0[12494]: libminijail[12494]: mount /sys -> /sys type '' 2018-06-06T10:15:40.249173+10:00 INFO minijail0[12494]: libminijail[12494]: mount /dev/log -> /dev/log type '' 2018-06-06T10:15:40.249178+10:00 INFO minijail0[12494]: libminijail[12494]: mount /dev/kvm -> /dev/kvm type '' 2018-06-06T10:15:40.249183+10:00 INFO minijail0[12494]: libminijail[12494]: mount /dev/net -> /dev/net type '' 2018-06-06T10:15:40.249188+10:00 INFO minijail0[12494]: libminijail[12494]: mount /dev/vhost-vsock -> /dev/vhost-vsock type '' 2018-06-06T10:15:40.249193+10:00 INFO minijail0[12494]: libminijail[12494]: mount /dev/dri -> /dev/dri type '' 2018-06-06T10:15:40.249198+10:00 INFO minijail0[12494]: libminijail[12494]: mount run -> /run type 'tmpfs' 2018-06-06T10:15:40.249204+10:00 INFO minijail0[12494]: libminijail[12494]: mount /run/chrome -> /run/chrome type '' 2018-06-06T10:15:40.249208+10:00 INFO minijail0[12494]: libminijail[12494]: mount /run/dbus -> /run/dbus type '' 2018-06-06T10:15:40.249213+10:00 INFO minijail0[12494]: libminijail[12494]: mount /run/vm -> /run/vm type '' 2018-06-06T10:15:40.249219+10:00 INFO minijail0[12494]: libminijail[12494]: mount var -> /var type 'tmpfs' 2018-06-06T10:15:40.249225+10:00 INFO minijail0[12494]: libminijail[12494]: mount empty -> /var/empty type 'tmpfs' 2018-06-06T10:15:40.249230+10:00 INFO minijail0[12494]: libminijail[12494]: mount /run/imageloader -> /run/imageloader type 'none' 2018-06-06T10:15:40.249235+10:00 INFO minijail0[12494]: libminijail[12494]: mount /home -> /home type 'none' 2018-06-06T10:15:40.251203+10:00 INFO minijail0[12494]: libminijail[12494]: not running init loop, exiting immediately 2018-06-06T10:15:40.284419+10:00 INFO vm_concierge[12495]: libminijail[1]: mount / -> / type '' 2018-06-06T10:15:40.284444+10:00 INFO vm_concierge[12495]: libminijail[1]: mount proc -> /proc type 'proc' 2018-06-06T10:15:40.284457+10:00 INFO vm_concierge[12495]: libminijail[1]: mount /sys -> /sys type '' 2018-06-06T10:15:40.284469+10:00 INFO vm_concierge[12495]: libminijail[1]: mount /dev/log -> /dev/log type '' 2018-06-06T10:15:40.284481+10:00 INFO vm_concierge[12495]: libminijail[1]: mount /dev/kvm -> /dev/kvm type '' 2018-06-06T10:15:40.284493+10:00 INFO vm_concierge[12495]: libminijail[1]: mount /dev/net -> /dev/net type '' 2018-06-06T10:15:40.284506+10:00 INFO vm_concierge[12495]: libminijail[1]: mount /dev/vhost-vsock -> /dev/vhost-vsock type '' 2018-06-06T10:15:40.284520+10:00 INFO vm_concierge[12495]: libminijail[1]: mount /dev/dri -> /dev/dri type '' 2018-06-06T10:15:40.284535+10:00 INFO vm_concierge[12495]: libminijail[1]: mount run -> /run type 'tmpfs' 2018-06-06T10:15:40.284547+10:00 INFO vm_concierge[12495]: libminijail[1]: mount /run/chrome -> /run/chrome type '' 2018-06-06T10:15:40.284560+10:00 INFO vm_concierge[12495]: libminijail[1]: mount /run/dbus -> /run/dbus type '' 2018-06-06T10:15:40.284573+10:00 INFO vm_concierge[12495]: libminijail[1]: mount /run/vm -> /run/vm type '' 2018-06-06T10:15:40.284587+10:00 INFO vm_concierge[12495]: libminijail[1]: mount var -> /var type 'tmpfs' 2018-06-06T10:15:40.284600+10:00 INFO vm_concierge[12495]: libminijail[1]: mount empty -> /var/empty type 'tmpfs' 2018-06-06T10:15:40.284614+10:00 INFO vm_concierge[12495]: libminijail[1]: mount /run/imageloader -> /run/imageloader type 'none' 2018-06-06T10:15:40.284627+10:00 INFO vm_concierge[12495]: libminijail[1]: mount /home -> /home type 'none' 2018-06-06T10:15:40.285954+10:00 INFO vmlog_forwarder[12488]: VM log forwarder listening on port 9999 2018-06-06T10:15:40.311376+10:00 INFO vm_concierge[12495]: Received CreateDiskImage request 2018-06-06T10:15:40.315182+10:00 INFO vm_concierge[12495]: Received StartVm request 2018-06-06T10:15:40.332914+10:00 INFO localhos[12501]: crosvm[6]: [src/linux.rs:768] crosvm entering multiprocess mode 2018-06-06T10:15:40.361988+10:00 WARNING localhos[12501]: crosvm[6]: [devices/src/virtio/block.rs:326] Disk size 87674790297 is not a multiple of sector size 512; the remainder will not be visible to the guest. 2018-06-06T10:15:40.368963+10:00 INFO crosvm[12503]: libminijail[6]: reset SIGSYS disposition 2018-06-06T10:15:40.370185+10:00 INFO kernel: [22901.018752] tun: Universal TUN/TAP device driver, 1.6 2018-06-06T10:15:40.370198+10:00 INFO kernel: [22901.018759] tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com> 2018-06-06T10:15:40.371839+10:00 INFO crosvm[12505]: libminijail[6]: reset SIGSYS disposition 2018-06-06T10:15:40.373839+10:00 INFO crosvm[12506]: libminijail[6]: reset SIGSYS disposition 2018-06-06T10:15:40.376615+10:00 INFO crosvm[12501]: libminijail[6]: mount none -> / type 'tmpfs' 2018-06-06T10:15:40.376623+10:00 INFO crosvm[12501]: libminijail[6]: mount /run/chrome/wayland-0 -> /wayland-0 type '' 2018-06-06T10:15:40.378389+10:00 INFO localhos[12501]: crosvm[6]: [src/linux.rs:595] created GPU buffer device for DMABuf allocations 2018-06-06T10:15:40.380820+10:00 INFO crosvm[12507]: libminijail[6]: reset SIGSYS disposition 2018-06-06T10:15:40.398849+10:00 INFO crosvm[12514]: libminijail[6]: reset SIGSYS disposition 2018-06-06T10:15:40.409800+10:00 INFO crosvm[12517]: libminijail[6]: reset SIGSYS disposition 2018-06-06T10:15:40.412786+10:00 INFO crosvm[12515]: libminijail[6]: reset SIGSYS disposition 2018-06-06T10:15:40.492924+10:00 WARNING localhos[12501]: crosvm[6]: [src/linux.rs:635] failed to add stdin to poll context: Error(1) 2018-06-06T10:15:41.756993+10:00 INFO vm_concierge[12495]: Configuring network for VM 3 2018-06-06T10:15:41.763143+10:00 INFO vm_concierge[12495]: Starting lxd 2018-06-06T10:15:41.763158+10:00 INFO vm_concierge[12495]: Launching stateful_setup.sh inside VM 3 2018-06-06T10:15:41.769046+10:00 NOTICE VM(3)[12488]: kernel: Linux version 4.14.37-04878-g7f86c373d980 (chrome-bot@cros-beefy500-c2) (gcc version 4.9.x 20150123 (prerelease) (4.9.2_cos_gg_4.9.2-r189-71959ce8f47f676a26bb21da7117101d9d73867e_4.9.2-r189)) #1 SMP PREEMPT Thu May 3 15:25:53 PDT 2018 2018-06-06T10:15:41.769051+10:00 INFO VM(3)[12488]: kernel: Command line: console=ttyS0 noacpi reboot=k panic=1 pci=off virtio_mmio.device=4K@0xd0000000:5 virtio_mmio.device=4K@0xd0001000:6 virtio_mmio.device=4K@0xd0002000:7 virtio_mmio.device=4K@0xd0003000:8 virtio_mmio.device=4K@0xd0004000:9 virtio_mmio.device=4K@0xd0005000:10 virtio_mmio.device=4K@0xd0006000:11 root=/dev/vda ro 2018-06-06T10:15:41.769053+10:00 ERR VM(3)[12488]: kernel: CPU: vendor_id 'crosvmBestVM' unknown, using generic init.\x0aCPU: Your system may be unstable. 2018-06-06T10:15:41.769054+10:00 INFO VM(3)[12488]: kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' 2018-06-06T10:15:41.769056+10:00 INFO VM(3)[12488]: kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' 2018-06-06T10:15:41.769057+10:00 INFO VM(3)[12488]: kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' 2018-06-06T10:15:41.769059+10:00 INFO VM(3)[12488]: kernel: x86/fpu: Supporting XSAVE feature 0x008: 'MPX bounds registers' 2018-06-06T10:15:41.769061+10:00 INFO VM(3)[12488]: kernel: x86/fpu: Supporting XSAVE feature 0x010: 'MPX CSR' 2018-06-06T10:15:41.769063+10:00 INFO VM(3)[12488]: kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 2018-06-06T10:15:41.769065+10:00 INFO VM(3)[12488]: kernel: x86/fpu: xstate_offset[3]: 960, xstate_sizes[3]: 64 2018-06-06T10:15:41.769066+10:00 INFO VM(3)[12488]: kernel: x86/fpu: xstate_offset[4]: 1024, xstate_sizes[4]: 64 2018-06-06T10:15:41.769068+10:00 INFO VM(3)[12488]: kernel: x86/fpu: Enabled xstate features 0x1f, context size is 1088 bytes, using 'standard' format. 2018-06-06T10:15:41.769069+10:00 INFO VM(3)[12488]: kernel: e820: BIOS-provided physical RAM map: 2018-06-06T10:15:41.769070+10:00 INFO VM(3)[12488]: kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable 2018-06-06T10:15:41.769071+10:00 INFO VM(3)[12488]: kernel: BIOS-e820: [mem 0x0000000000200000-0x00000000cfffffff] usable 2018-06-06T10:15:41.769073+10:00 INFO VM(3)[12488]: kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000170cfffff] usable 2018-06-06T10:15:41.769074+10:00 INFO VM(3)[12488]: kernel: NX (Execute Disable) protection: active 2018-06-06T10:15:41.769076+10:00 INFO VM(3)[12488]: kernel: DMI not present or invalid. 2018-06-06T10:15:41.769077+10:00 INFO VM(3)[12488]: kernel: Hypervisor detected: KVM 2018-06-06T10:15:41.769078+10:00 INFO VM(3)[12488]: kernel: tsc: Fast TSC calibration using PIT 2018-06-06T10:15:41.769079+10:00 DEBUG VM(3)[12488]: kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved 2018-06-06T10:15:41.769081+10:00 DEBUG VM(3)[12488]: kernel: e820: remove [mem 0x000a0000-0x000fffff] usable 2018-06-06T10:15:41.769082+10:00 INFO VM(3)[12488]: kernel: e820: last_pfn = 0x170d00 max_arch_pfn = 0x400000000 2018-06-06T10:15:41.769083+10:00 DEBUG VM(3)[12488]: kernel: MTRR default type: uncachable 2018-06-06T10:15:41.769085+10:00 DEBUG VM(3)[12488]: kernel: MTRR fixed ranges disabled: 2018-06-06T10:15:41.769086+10:00 DEBUG VM(3)[12488]: kernel: 00000-FFFFF uncachable 2018-06-06T10:15:41.769087+10:00 DEBUG VM(3)[12488]: kernel: MTRR variable ranges disabled: 2018-06-06T10:15:41.769088+10:00 DEBUG VM(3)[12488]: kernel: 0 disabled 2018-06-06T10:15:41.769089+10:00 DEBUG VM(3)[12488]: kernel: 1 disabled 2018-06-06T10:15:41.769091+10:00 DEBUG VM(3)[12488]: kernel: 2 disabled 2018-06-06T10:15:41.769092+10:00 DEBUG VM(3)[12488]: kernel: 3 disabled 2018-06-06T10:15:41.769093+10:00 DEBUG VM(3)[12488]: kernel: 4 disabled 2018-06-06T10:15:41.769093+10:00 DEBUG VM(3)[12488]: kernel: 5 disabled 2018-06-06T10:15:41.769095+10:00 DEBUG VM(3)[12488]: kernel: 6 disabled 2018-06-06T10:15:41.769096+10:00 DEBUG VM(3)[12488]: kernel: 7 disabled 2018-06-06T10:15:41.769098+10:00 INFO VM(3)[12488]: kernel: MTRR: Disabled 2018-06-06T10:15:41.769099+10:00 INFO VM(3)[12488]: kernel: x86/PAT: MTRRs disabled, skipping PAT initialization too. 2018-06-06T10:15:41.769100+10:00 INFO VM(3)[12488]: kernel: CPU MTRRs all blank - virtualized system. 2018-06-06T10:15:41.769101+10:00 INFO VM(3)[12488]: kernel: x86/PAT: Configuration [0-7]: WB WT UC- UC WB WT UC- UC 2018-06-06T10:15:41.769102+10:00 INFO VM(3)[12488]: kernel: e820: last_pfn = 0xd0000 max_arch_pfn = 0x400000000 2018-06-06T10:15:41.769103+10:00 INFO VM(3)[12488]: kernel: found SMP MP-table at [mem 0x0009fc00-0x0009fc0f] mapped at [ffffffffff200c00] 2018-06-06T10:15:41.769104+10:00 INFO VM(3)[12488]: kernel: Scanning 1 areas for low memory corruption 2018-06-06T10:15:41.769105+10:00 DEBUG VM(3)[12488]: kernel: Base memory trampoline at [ffff880000099000] 99000 size 24576 2018-06-06T10:15:41.769106+10:00 INFO VM(3)[12488]: kernel: Using GB pages for direct mapping 2018-06-06T10:15:41.769107+10:00 DEBUG VM(3)[12488]: kernel: BRK [0x02224000, 0x02224fff] PGTABLE 2018-06-06T10:15:41.769109+10:00 DEBUG VM(3)[12488]: kernel: BRK [0x02225000, 0x02225fff] PGTABLE 2018-06-06T10:15:41.769109+10:00 DEBUG VM(3)[12488]: kernel: BRK [0x02226000, 0x02226fff] PGTABLE 2018-06-06T10:15:41.769111+10:00 DEBUG VM(3)[12488]: kernel: BRK [0x02227000, 0x02227fff] PGTABLE 2018-06-06T10:15:41.769112+10:00 DEBUG VM(3)[12488]: kernel: BRK [0x02228000, 0x02228fff] PGTABLE 2018-06-06T10:15:41.769113+10:00 INFO VM(3)[12488]: kernel: ACPI: Early table checksum verification disabled 2018-06-06T10:15:41.769114+10:00 ERR VM(3)[12488]: kernel: ACPI BIOS Error (bug): A valid RSDP was not found (20170728/tbxfroot-244) 2018-06-06T10:15:41.769115+10:00 INFO VM(3)[12488]: kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 2018-06-06T10:15:41.769116+10:00 INFO VM(3)[12488]: kernel: kvm-clock: cpu 0, msr 1:70cff001, primary cpu clock 2018-06-06T10:15:41.769117+10:00 INFO VM(3)[12488]: kernel: kvm-clock: using sched offset of 168252864702654 cycles 2018-06-06T10:15:41.769118+10:00 INFO VM(3)[12488]: kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns 2018-06-06T10:15:41.769120+10:00 INFO VM(3)[12488]: kernel: Zone ranges: 2018-06-06T10:15:41.769122+10:00 INFO VM(3)[12488]: kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] 2018-06-06T10:15:41.769122+10:00 INFO VM(3)[12488]: kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] 2018-06-06T10:15:41.769123+10:00 INFO VM(3)[12488]: kernel: Normal [mem 0x0000000100000000-0x0000000170cfffff] 2018-06-06T10:15:41.773543+10:00 INFO VM(3)[12488]: kernel: Movable zone start for each node 2018-06-06T10:15:41.773546+10:00 INFO VM(3)[12488]: kernel: Early memory node ranges 2018-06-06T10:15:41.773547+10:00 INFO VM(3)[12488]: kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] 2018-06-06T10:15:41.773549+10:00 INFO VM(3)[12488]: kernel: node 0: [mem 0x0000000000200000-0x00000000cfffffff] 2018-06-06T10:15:41.773550+10:00 INFO VM(3)[12488]: kernel: node 0: [mem 0x0000000100000000-0x0000000170cfffff] 2018-06-06T10:15:41.773551+10:00 INFO VM(3)[12488]: kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000000170cfffff] 2018-06-06T10:15:41.773551+10:00 DEBUG VM(3)[12488]: kernel: On node 0 totalpages: 1313694 2018-06-06T10:15:41.773552+10:00 DEBUG VM(3)[12488]: kernel: DMA zone: 59 pages used for memmap 2018-06-06T10:15:41.773553+10:00 DEBUG VM(3)[12488]: kernel: DMA zone: 21 pages reserved 2018-06-06T10:15:41.773554+10:00 DEBUG VM(3)[12488]: kernel: DMA zone: 3742 pages, LIFO batch:0 2018-06-06T10:15:41.773556+10:00 DEBUG VM(3)[12488]: kernel: DMA32 zone: 13248 pages used for memmap 2018-06-06T10:15:41.773557+10:00 DEBUG VM(3)[12488]: kernel: DMA32 zone: 847872 pages, LIFO batch:31 2018-06-06T10:15:41.773558+10:00 DEBUG VM(3)[12488]: kernel: Normal zone: 7220 pages used for memmap 2018-06-06T10:15:41.773559+10:00 DEBUG VM(3)[12488]: kernel: Normal zone: 462080 pages, LIFO batch:31 2018-06-06T10:15:41.773560+10:00 INFO VM(3)[12488]: kernel: Intel MultiProcessor Specification v1.4 2018-06-06T10:15:41.773562+10:00 INFO VM(3)[12488]: kernel: MPTABLE: OEM ID: CROSVM 2018-06-06T10:15:41.773563+10:00 INFO VM(3)[12488]: kernel: MPTABLE: Product ID: 000000000000 2018-06-06T10:15:41.773564+10:00 INFO VM(3)[12488]: kernel: MPTABLE: APIC at: 0xFEE00000 2018-06-06T10:15:41.773565+10:00 INFO VM(3)[12488]: kernel: Processor #0 (Bootup-CPU) 2018-06-06T10:15:41.773566+10:00 INFO VM(3)[12488]: kernel: Processor #1 2018-06-06T10:15:41.773567+10:00 INFO VM(3)[12488]: kernel: Processor #2 2018-06-06T10:15:41.773568+10:00 INFO VM(3)[12488]: kernel: Processor #3 2018-06-06T10:15:41.773569+10:00 INFO VM(3)[12488]: kernel: IOAPIC[0]: apic_id 5, version 17, address 0xfec00000, GSI 0-23 2018-06-06T10:15:41.773570+10:00 INFO VM(3)[12488]: kernel: Processors: 4 2018-06-06T10:15:41.773571+10:00 INFO VM(3)[12488]: kernel: smpboot: Allowing 4 CPUs, 0 hotplug CPUs 2018-06-06T10:15:41.773572+10:00 INFO VM(3)[12488]: kernel: e820: [mem 0xd0000000-0xffffffff] available for PCI devices 2018-06-06T10:15:41.773573+10:00 INFO VM(3)[12488]: kernel: Booting paravirtualized kernel on KVM 2018-06-06T10:15:41.773575+10:00 INFO VM(3)[12488]: kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns 2018-06-06T10:15:41.773576+10:00 INFO VM(3)[12488]: kernel: setup_percpu: NR_CPUS:64 nr_cpumask_bits:64 nr_cpu_ids:4 nr_node_ids:1 2018-06-06T10:15:41.773577+10:00 INFO VM(3)[12488]: kernel: percpu: Embedded 40 pages/cpu @ffff880170800000 s134056 r0 d29784 u524288 2018-06-06T10:15:41.773579+10:00 DEBUG VM(3)[12488]: kernel: pcpu-alloc: s134056 r0 d29784 u524288 alloc=1*2097152 2018-06-06T10:15:41.773580+10:00 DEBUG VM(3)[12488]: kernel: pcpu-alloc: [0] 0 1 2 3 2018-06-06T10:15:41.773581+10:00 INFO VM(3)[12488]: kernel: KVM setup async PF for cpu 0 2018-06-06T10:15:41.773582+10:00 INFO VM(3)[12488]: kernel: kvm-stealtime: cpu 0, msr 170814f80 2018-06-06T10:15:41.773582+10:00 INFO VM(3)[12488]: kernel: Built 1 zonelists, mobility grouping on. Total pages: 1293146 2018-06-06T10:15:41.773584+10:00 NOTICE VM(3)[12488]: kernel: Kernel command line: console=ttyS0 noacpi reboot=k panic=1 pci=off virtio_mmio.device=4K@0xd0000000:5 virtio_mmio.device=4K@0xd0001000:6 virtio_mmio.device=4K@0xd0002000:7 virtio_mmio.device=4K@0xd0003000:8 virtio_mmio.device=4K@0xd0004000:9 virtio_mmio.device=4K@0xd0005000:10 virtio_mmio.device=4K@0xd0006000:11 root=/dev/vda ro 2018-06-06T10:15:41.773585+10:00 INFO VM(3)[12488]: kernel: PID hash table entries: 4096 (order: 3, 32768 bytes) 2018-06-06T10:15:41.773586+10:00 INFO VM(3)[12488]: kernel: Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes) 2018-06-06T10:15:41.773587+10:00 INFO VM(3)[12488]: kernel: Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes) 2018-06-06T10:15:41.773588+10:00 INFO VM(3)[12488]: kernel: Memory: 5075176K/5254776K available (8204K kernel code, 670K rwdata, 1804K rodata, 852K init, 688K bss, 179600K reserved, 0K cma-reserved) 2018-06-06T10:15:41.773591+10:00 INFO VM(3)[12488]: kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 2018-06-06T10:15:41.773592+10:00 INFO VM(3)[12488]: kernel: Kernel/User page tables isolation: enabled 2018-06-06T10:15:41.773593+10:00 INFO VM(3)[12488]: kernel: Preemptible hierarchical RCU implementation. 2018-06-06T10:15:41.773594+10:00 INFO VM(3)[12488]: kernel: \x09RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=4. 2018-06-06T10:15:41.773596+10:00 INFO VM(3)[12488]: kernel: \x09Tasks RCU enabled. 2018-06-06T10:15:41.773597+10:00 INFO VM(3)[12488]: kernel: RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 2018-06-06T10:15:41.773598+10:00 INFO VM(3)[12488]: kernel: NR_IRQS: 4352, nr_irqs: 456, preallocated irqs: 16 2018-06-06T10:15:41.773599+10:00 INFO VM(3)[12488]: kernel: console [ttyS0] enabled 2018-06-06T10:15:41.773600+10:00 INFO VM(3)[12488]: kernel: tsc: Initial usec timer 414319 2018-06-06T10:15:41.773601+10:00 INFO VM(3)[12488]: kernel: tsc: Detected 1607.986 MHz processor 2018-06-06T10:15:41.773602+10:00 INFO VM(3)[12488]: kernel: tsc: Marking TSC unstable due to TSCs unsynchronized 2018-06-06T10:15:41.773603+10:00 INFO VM(3)[12488]: kernel: Calibrating delay loop (skipped) preset value.. 3215.97 BogoMIPS (lpj=1607986) 2018-06-06T10:15:41.773604+10:00 INFO VM(3)[12488]: kernel: pid_max: default: 32768 minimum: 301 2018-06-06T10:15:41.773605+10:00 INFO VM(3)[12488]: kernel: Security Framework initialized 2018-06-06T10:15:41.773607+10:00 INFO VM(3)[12488]: kernel: Yama: becoming mindful. 2018-06-06T10:15:41.773608+10:00 INFO VM(3)[12488]: kernel: SELinux: Initializing. 2018-06-06T10:15:41.773609+10:00 DEBUG VM(3)[12488]: kernel: SELinux: Starting in permissive mode 2018-06-06T10:15:41.773610+10:00 INFO VM(3)[12488]: kernel: Chromium OS LSM: enabled 2018-06-06T10:15:41.773611+10:00 INFO VM(3)[12488]: kernel: Mount-cache hash table entries: 16384 (order: 5, 131072 bytes) 2018-06-06T10:15:41.773612+10:00 INFO VM(3)[12488]: kernel: Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes) 2018-06-06T10:15:41.773613+10:00 INFO VM(3)[12488]: kernel: CPU: Physical Processor ID: 0 2018-06-06T10:15:41.773614+10:00 INFO VM(3)[12488]: kernel: CPU: Processor Core ID: 0 2018-06-06T10:15:41.777899+10:00 INFO VM(3)[12488]: kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 2018-06-06T10:15:41.777971+10:00 INFO VM(3)[12488]: kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 2018-06-06T10:15:41.778033+10:00 INFO VM(3)[12488]: kernel: Spectre V2 : Mitigation: Full generic retpoline 2018-06-06T10:15:41.778064+10:00 INFO VM(3)[12488]: kernel: Freeing SMP alternatives memory: 24K 2018-06-06T10:15:41.778089+10:00 INFO VM(3)[12488]: kernel: smpboot: Max logical packages: 4 2018-06-06T10:15:41.778113+10:00 INFO VM(3)[12488]: kernel: x2apic enabled 2018-06-06T10:15:41.778138+10:00 INFO VM(3)[12488]: kernel: Switched APIC routing to physical x2apic. 2018-06-06T10:15:41.778162+10:00 INFO VM(3)[12488]: kernel: ..TIMER: vector=0x30 apic1=-1 pin1=0 apic2=-1 pin2=-1 2018-06-06T10:15:41.778187+10:00 INFO VM(3)[12488]: kernel: smpboot: CPU0: crosvmBestVM 06/8e (family: 0x6, model: 0x8e, stepping: 0x9) 2018-06-06T10:15:41.778200+10:00 INFO VM(3)[12488]: kernel: Performance Events: no PMU driver, software events only. 2018-06-06T10:15:41.778210+10:00 INFO VM(3)[12488]: kernel: Hierarchical SRCU implementation. 2018-06-06T10:15:41.778220+10:00 INFO VM(3)[12488]: kernel: NMI watchdog: Perf event create on CPU 0 failed with -2 2018-06-06T10:15:41.778230+10:00 INFO VM(3)[12488]: kernel: NMI watchdog: Perf NMI watchdog permanently disabled 2018-06-06T10:15:41.778239+10:00 INFO VM(3)[12488]: kernel: smp: Bringing up secondary CPUs ... 2018-06-06T10:15:41.778249+10:00 INFO VM(3)[12488]: kernel: x86: Booting SMP configuration: 2018-06-06T10:15:41.778258+10:00 INFO VM(3)[12488]: kernel: .... node #0, CPUs: #1 2018-06-06T10:15:41.778268+10:00 INFO VM(3)[12488]: kernel: kvm-clock: cpu 1, msr 1:70cff041, secondary cpu clock 2018-06-06T10:15:41.778281+10:00 INFO VM(3)[12488]: kernel: KVM setup async PF for cpu 1 2018-06-06T10:15:41.778290+10:00 INFO VM(3)[12488]: kernel: kvm-stealtime: cpu 1, msr 170894f80 2018-06-06T10:15:41.778302+10:00 WARNING VM(3)[12488]: kernel: #2 2018-06-06T10:15:41.778304+10:00 INFO VM(3)[12488]: kernel: kvm-clock: cpu 2, msr 1:70cff081, secondary cpu clock 2018-06-06T10:15:41.778305+10:00 INFO VM(3)[12488]: kernel: KVM setup async PF for cpu 2 2018-06-06T10:15:41.778307+10:00 INFO VM(3)[12488]: kernel: kvm-stealtime: cpu 2, msr 170914f80 2018-06-06T10:15:41.778309+10:00 WARNING VM(3)[12488]: kernel: #3 2018-06-06T10:15:41.778311+10:00 INFO VM(3)[12488]: kernel: kvm-clock: cpu 3, msr 1:70cff0c1, secondary cpu clock 2018-06-06T10:15:41.778313+10:00 INFO VM(3)[12488]: kernel: KVM setup async PF for cpu 3 2018-06-06T10:15:41.778315+10:00 INFO VM(3)[12488]: kernel: kvm-stealtime: cpu 3, msr 170994f80 2018-06-06T10:15:41.778316+10:00 INFO VM(3)[12488]: kernel: smp: Brought up 1 node, 4 CPUs 2018-06-06T10:15:41.778317+10:00 INFO VM(3)[12488]: kernel: smpboot: Total of 4 processors activated (12863.88 BogoMIPS) 2018-06-06T10:15:41.778319+10:00 INFO VM(3)[12488]: kernel: devtmpfs: initialized 2018-06-06T10:15:41.778320+10:00 INFO VM(3)[12488]: kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns 2018-06-06T10:15:41.778322+10:00 INFO VM(3)[12488]: kernel: futex hash table entries: 1024 (order: 4, 65536 bytes) 2018-06-06T10:15:41.778323+10:00 INFO VM(3)[12488]: kernel: xor: automatically using best checksumming function avx 2018-06-06T10:15:41.778325+10:00 INFO VM(3)[12488]: kernel: RTC time: 0:15:41, date: 06/06/18 2018-06-06T10:15:41.778326+10:00 INFO VM(3)[12488]: kernel: NET: Registered protocol family 16 2018-06-06T10:15:41.778328+10:00 INFO VM(3)[12488]: kernel: cpuidle: using governor ladder 2018-06-06T10:15:41.778329+10:00 INFO VM(3)[12488]: kernel: cpuidle: using governor menu 2018-06-06T10:15:41.778330+10:00 INFO VM(3)[12488]: kernel: clocksource: Switched to clocksource kvm-clock 2018-06-06T10:15:41.778332+10:00 ERR VM(3)[12488]: kernel: PCI: Fatal: No config space access function found 2018-06-06T10:15:41.778334+10:00 INFO VM(3)[12488]: kernel: raid6: sse2x1 gen() 10511 MB/s 2018-06-06T10:15:41.778336+10:00 INFO VM(3)[12488]: kernel: raid6: sse2x1 xor() 7693 MB/s 2018-06-06T10:15:41.778337+10:00 INFO VM(3)[12488]: kernel: raid6: sse2x2 gen() 14125 MB/s 2018-06-06T10:15:41.778338+10:00 INFO VM(3)[12488]: kernel: raid6: sse2x2 xor() 9638 MB/s 2018-06-06T10:15:41.778340+10:00 INFO VM(3)[12488]: kernel: raid6: sse2x4 gen() 15953 MB/s 2018-06-06T10:15:41.778341+10:00 INFO VM(3)[12488]: kernel: raid6: sse2x4 xor() 10214 MB/s 2018-06-06T10:15:41.778342+10:00 INFO VM(3)[12488]: kernel: raid6: avx2x1 gen() 21398 MB/s 2018-06-06T10:15:41.778344+10:00 INFO VM(3)[12488]: kernel: raid6: avx2x1 xor() 14751 MB/s 2018-06-06T10:15:41.778345+10:00 INFO VM(3)[12488]: kernel: raid6: avx2x2 gen() 25613 MB/s 2018-06-06T10:15:41.778346+10:00 INFO VM(3)[12488]: kernel: raid6: avx2x2 xor() 17943 MB/s 2018-06-06T10:15:41.778348+10:00 INFO VM(3)[12488]: kernel: raid6: avx2x4 gen() 29707 MB/s 2018-06-06T10:15:41.778350+10:00 INFO VM(3)[12488]: kernel: raid6: avx2x4 xor() 21595 MB/s 2018-06-06T10:15:41.778351+10:00 INFO VM(3)[12488]: kernel: raid6: using algorithm avx2x4 gen() 29707 MB/s 2018-06-06T10:15:41.778353+10:00 INFO VM(3)[12488]: kernel: raid6: .... xor() 21595 MB/s, rmw enabled 2018-06-06T10:15:41.778354+10:00 INFO VM(3)[12488]: kernel: raid6: using avx2x2 recovery algorithm 2018-06-06T10:15:41.778356+10:00 INFO VM(3)[12488]: kernel: ACPI: Interpreter disabled. 2018-06-06T10:15:41.778357+10:00 NOTICE VM(3)[12488]: kernel: SCSI subsystem initialized 2018-06-06T10:15:41.778358+10:00 ERR VM(3)[12488]: kernel: dmi: Firmware registration failed. 2018-06-06T10:15:41.778360+10:00 INFO VM(3)[12488]: kernel: PCI: System does not support PCI 2018-06-06T10:15:41.778361+10:00 NOTICE VM(3)[12488]: kernel: FS-Cache: Loaded 2018-06-06T10:15:41.778363+10:00 INFO VM(3)[12488]: kernel: pnp: PnP ACPI: disabled 2018-06-06T10:15:41.778365+10:00 INFO VM(3)[12488]: kernel: NET: Registered protocol family 2 2018-06-06T10:15:41.778366+10:00 INFO VM(3)[12488]: kernel: TCP established hash table entries: 65536 (order: 7, 524288 bytes) 2018-06-06T10:15:41.778368+10:00 INFO VM(3)[12488]: kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes) 2018-06-06T10:15:41.778369+10:00 INFO VM(3)[12488]: kernel: TCP: Hash tables configured (established 65536 bind 65536) 2018-06-06T10:15:41.778371+10:00 INFO VM(3)[12488]: kernel: UDP hash table entries: 4096 (order: 6, 393216 bytes) 2018-06-06T10:15:41.778372+10:00 INFO VM(3)[12488]: kernel: UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes) 2018-06-06T10:15:41.778374+10:00 INFO VM(3)[12488]: kernel: NET: Registered protocol family 1 2018-06-06T10:15:41.778375+10:00 INFO VM(3)[12488]: kernel: RPC: Registered named UNIX socket transport module. 2018-06-06T10:15:41.778377+10:00 INFO VM(3)[12488]: kernel: RPC: Registered udp transport module. 2018-06-06T10:15:41.778378+10:00 INFO VM(3)[12488]: kernel: RPC: Registered tcp transport module. 2018-06-06T10:15:41.778380+10:00 INFO VM(3)[12488]: kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. 2018-06-06T10:15:41.778381+10:00 DEBUG VM(3)[12488]: kernel: PCI: CLS 0 bytes, default 64 2018-06-06T10:15:41.778383+10:00 INFO VM(3)[12488]: kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) 2018-06-06T10:15:41.780131+10:00 INFO VM(3)[12488]: kernel: software IO TLB [mem 0xcc000000-0xd0000000] (64MB) mapped at [ffff8800cc000000-ffff8800cfffffff] 2018-06-06T10:15:41.780155+10:00 INFO VM(3)[12488]: kernel: virtio-mmio: Registering device virtio-mmio.0 at 0xd0000000-0xd0000fff, IRQ 5. 2018-06-06T10:15:41.780166+10:00 INFO VM(3)[12488]: kernel: virtio-mmio: Registering device virtio-mmio.1 at 0xd0001000-0xd0001fff, IRQ 6. 2018-06-06T10:15:41.780176+10:00 INFO VM(3)[12488]: kernel: virtio-mmio: Registering device virtio-mmio.2 at 0xd0002000-0xd0002fff, IRQ 7. 2018-06-06T10:15:41.780185+10:00 INFO VM(3)[12488]: kernel: virtio-mmio: Registering device virtio-mmio.3 at 0xd0003000-0xd0003fff, IRQ 8. 2018-06-06T10:15:41.780195+10:00 INFO VM(3)[12488]: kernel: virtio-mmio: Registering device virtio-mmio.4 at 0xd0004000-0xd0004fff, IRQ 9. 2018-06-06T10:15:41.780204+10:00 INFO VM(3)[12488]: kernel: virtio-mmio: Registering device virtio-mmio.5 at 0xd0005000-0xd0005fff, IRQ 10. 2018-06-06T10:15:41.780214+10:00 INFO VM(3)[12488]: kernel: virtio-mmio: Registering device virtio-mmio.6 at 0xd0006000-0xd0006fff, IRQ 11. 2018-06-06T10:15:41.780223+10:00 INFO VM(3)[12488]: kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x172d9cfad0e, max_idle_ns: 440795213245 ns 2018-06-06T10:15:41.780233+10:00 INFO VM(3)[12488]: kernel: platform rtc_cmos: registered platform RTC device (no PNP device found) 2018-06-06T10:15:41.780243+10:00 INFO VM(3)[12488]: kernel: Scanning for low memory corruption every 60 seconds 2018-06-06T10:15:41.780252+10:00 INFO VM(3)[12488]: kernel: audit: initializing netlink subsys (disabled) 2018-06-06T10:15:41.780261+10:00 NOTICE VM(3)[12488]: kernel: audit: type=2000 audit(1528244141.163:1): state=initialized audit_enabled=0 res=1 2018-06-06T10:15:41.780271+10:00 INFO VM(3)[12488]: kernel: workingset: timestamp_bits=46 max_order=21 bucket_order=0 2018-06-06T10:15:41.780280+10:00 INFO VM(3)[12488]: kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher 2018-06-06T10:15:41.780289+10:00 INFO VM(3)[12488]: kernel: 9p: Installing v9fs 9p2000 file system support 2018-06-06T10:15:41.780299+10:00 DEBUG VM(3)[12488]: kernel: SELinux: Registering netfilter hooks 2018-06-06T10:15:41.780308+10:00 INFO VM(3)[12488]: kernel: alt_syscall: table 'read_write_test' available. 2018-06-06T10:15:41.780317+10:00 INFO VM(3)[12488]: kernel: alt_syscall: table 'android' available. 2018-06-06T10:15:41.780326+10:00 INFO VM(3)[12488]: kernel: alt_syscall: table 'android_permissive' available. 2018-06-06T10:15:41.780335+10:00 INFO VM(3)[12488]: kernel: alt_syscall: table 'third_party' available. 2018-06-06T10:15:41.780345+10:00 INFO VM(3)[12488]: kernel: alt_syscall: table 'third_party_permissive' available. 2018-06-06T10:15:41.780354+10:00 INFO VM(3)[12488]: kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) 2018-06-06T10:15:41.780363+10:00 INFO VM(3)[12488]: kernel: io scheduler noop registered 2018-06-06T10:15:41.780373+10:00 INFO VM(3)[12488]: kernel: io scheduler deadline registered 2018-06-06T10:15:41.780382+10:00 INFO VM(3)[12488]: kernel: io scheduler cfq registered (default) 2018-06-06T10:15:41.780391+10:00 INFO VM(3)[12488]: kernel: io scheduler mq-deadline registered 2018-06-06T10:15:41.780400+10:00 INFO VM(3)[12488]: kernel: io scheduler kyber registered 2018-06-06T10:15:41.780410+10:00 WARNING VM(3)[12488]: kernel: virtio-mmio virtio-mmio.0: Failed to enable 64-bit or 32-bit DMA. Trying to continue, but this might not work. 2018-06-06T10:15:41.780419+10:00 WARNING VM(3)[12488]: kernel: virtio-mmio virtio-mmio.1: Failed to enable 64-bit or 32-bit DMA. Trying to continue, but this might not work. 2018-06-06T10:15:41.780428+10:00 WARNING VM(3)[12488]: kernel: virtio-mmio virtio-mmio.2: Failed to enable 64-bit or 32-bit DMA. Trying to continue, but this might not work. 2018-06-06T10:15:41.780438+10:00 WARNING VM(3)[12488]: kernel: virtio-mmio virtio-mmio.3: Failed to enable 64-bit or 32-bit DMA. Trying to continue, but this might not work. 2018-06-06T10:15:41.780447+10:00 WARNING VM(3)[12488]: kernel: virtio-mmio virtio-mmio.4: Failed to enable 64-bit or 32-bit DMA. Trying to continue, but this might not work. 2018-06-06T10:15:41.780457+10:00 WARNING VM(3)[12488]: kernel: virtio-mmio virtio-mmio.5: Failed to enable 64-bit or 32-bit DMA. Trying to continue, but this might not work. 2018-06-06T10:15:41.780467+10:00 WARNING VM(3)[12488]: kernel: virtio-mmio virtio-mmio.6: Failed to enable 64-bit or 32-bit DMA. Trying to continue, but this might not work. 2018-06-06T10:15:41.780476+10:00 INFO VM(3)[12488]: kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled 2018-06-06T10:15:41.780486+10:00 INFO VM(3)[12488]: kernel: serial8250: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a U6_16550A 2018-06-06T10:15:41.780495+10:00 INFO VM(3)[12488]: kernel: serial8250: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a U6_16550A 2018-06-06T10:15:41.780504+10:00 INFO VM(3)[12488]: kernel: serial8250: ttyS2 at I/O 0x3e8 (irq = 4, base_baud = 115200) is a U6_16550A 2018-06-06T10:15:41.780514+10:00 INFO VM(3)[12488]: kernel: Non-volatile memory driver v1.3 2018-06-06T10:15:41.780523+10:00 NOTICE VM(3)[12488]: kernel: random: fast init done 2018-06-06T10:15:41.780532+10:00 NOTICE VM(3)[12488]: kernel: random: crng init done 2018-06-06T10:15:41.780541+10:00 INFO VM(3)[12488]: kernel: loop: module loaded 2018-06-06T10:15:41.780551+10:00 INFO VM(3)[12488]: kernel: libphy: Fixed MDIO Bus: probed 2018-06-06T10:15:41.780560+10:00 INFO VM(3)[12488]: kernel: tun: Universal TUN/TAP device driver, 1.6 2018-06-06T10:15:41.780569+10:00 WARNING VM(3)[12488]: kernel: rtc_cmos rtc_cmos: only 24-hr supported 2018-06-06T10:15:41.780578+10:00 INFO VM(3)[12488]: kernel: device-mapper: ioctl: 4.37.0-ioctl (2017-09-20) initialised: dm-devel@redhat.com 2018-06-06T10:15:41.780588+10:00 ERR VM(3)[12488]: kernel: intel_rapl: driver does not support CPU family 6 model 142 2018-06-06T10:15:41.780597+10:00 INFO VM(3)[12488]: kernel: GACT probability NOT on 2018-06-06T10:15:41.780606+10:00 INFO VM(3)[12488]: kernel: Netfilter messages via NETLINK v0.30. 2018-06-06T10:15:41.780615+10:00 INFO VM(3)[12488]: kernel: nfnl_acct: registering with nfnetlink. 2018-06-06T10:15:41.780625+10:00 INFO VM(3)[12488]: kernel: nf_conntrack version 0.5.0 (65536 buckets, 262144 max) 2018-06-06T10:15:41.780634+10:00 INFO VM(3)[12488]: kernel: ctnetlink v0.93: registering with nfnetlink. 2018-06-06T10:15:41.842860+10:00 INFO vm_concierge[12495]: Launching lxd inside VM 3 2018-06-06T10:15:41.862149+10:00 INFO vm_concierge[12495]: Launching lxd inside VM 3 2018-06-06T10:15:42.346359+10:00 INFO VM(3)[12488]: dnsmasq[189]: started, version 2.78 cachesize 150 2018-06-06T10:15:42.346366+10:00 INFO VM(3)[12488]: dnsmasq[189]: compile time options: IPv6 GNU-getopt no-DBus no-i18n no-IDN DHCP DHCPv6 no-Lua no-TFTP no-conntrack ipset no-auth no-DNSSEC loop-detect inotify 2018-06-06T10:15:42.346369+10:00 WARNING VM(3)[12488]: dnsmasq[189]: LOUD WARNING: listening on 100.115.92.193 may accept requests via interfaces other than lxdbr0 2018-06-06T10:15:42.346372+10:00 WARNING VM(3)[12488]: dnsmasq[189]: LOUD WARNING: use --bind-dynamic rather than --bind-interfaces to avoid DNS amplification attacks via these interface(s) 2018-06-06T10:15:42.346374+10:00 INFO VM(3)[12488]: dnsmasq-dhcp[189]: DHCP, IP range 100.115.92.194 -- 100.115.92.206, lease time 1h 2018-06-06T10:15:42.346376+10:00 INFO VM(3)[12488]: dnsmasq-dhcp[189]: DHCP, sockets bound exclusively to interface lxdbr0 2018-06-06T10:15:42.346379+10:00 INFO VM(3)[12488]: dnsmasq[189]: using local addresses only for domain lxd 2018-06-06T10:15:42.346383+10:00 INFO VM(3)[12488]: dnsmasq[189]: reading /etc/resolv.conf 2018-06-06T10:15:42.346386+10:00 INFO VM(3)[12488]: dnsmasq[189]: using local addresses only for domain lxd 2018-06-06T10:15:42.346388+10:00 INFO VM(3)[12488]: dnsmasq[189]: using nameserver 8.8.8.8#53 2018-06-06T10:15:42.346390+10:00 INFO VM(3)[12488]: dnsmasq[189]: using nameserver 8.8.4.4#53 2018-06-06T10:15:42.346392+10:00 WARNING VM(3)[12488]: dnsmasq[189]: LOUD WARNING: listening on 100.115.92.193 may accept requests via interfaces other than lxdbr0 2018-06-06T10:15:42.346395+10:00 WARNING VM(3)[12488]: dnsmasq[189]: LOUD WARNING: use --bind-dynamic rather than --bind-interfaces to avoid DNS amplification attacks via these interface(s) 2018-06-06T10:15:42.348820+10:00 INFO VM(3)[12488]: kernel: nf_tables: (c) 2007-2009 Patrick McHardy <kaber@trash.net> 2018-06-06T10:15:42.348826+10:00 INFO VM(3)[12488]: kernel: nf_tables_compat: (c) 2012 Pablo Neira Ayuso <pablo@netfilter.org> 2018-06-06T10:15:42.348829+10:00 INFO VM(3)[12488]: kernel: xt_time: kernel timezone is -0000 2018-06-06T10:15:42.348831+10:00 INFO VM(3)[12488]: kernel: ip_set: protocol 6 2018-06-06T10:15:42.348833+10:00 INFO VM(3)[12488]: kernel: IPVS: Registered protocols () 2018-06-06T10:15:42.348835+10:00 INFO VM(3)[12488]: kernel: IPVS: Connection hash table configured (size=4096, memory=64Kbytes) 2018-06-06T10:15:42.348838+10:00 DEBUG VM(3)[12488]: kernel: IPVS: Each connection entry needs 312 bytes at least 2018-06-06T10:15:42.348841+10:00 INFO VM(3)[12488]: kernel: IPVS: ipvs loaded. 2018-06-06T10:15:42.348843+10:00 INFO VM(3)[12488]: kernel: ip_tables: (C) 2000-2006 Netfilter Core Team 2018-06-06T10:15:42.348845+10:00 INFO VM(3)[12488]: kernel: ipt_CLUSTERIP: ClusterIP Version 0.8 loaded successfully 2018-06-06T10:15:42.348847+10:00 INFO VM(3)[12488]: kernel: arp_tables: arp_tables: (C) 2002 David S. Miller 2018-06-06T10:15:42.348850+10:00 INFO VM(3)[12488]: kernel: Initializing XFRM netlink socket 2018-06-06T10:15:42.348852+10:00 INFO VM(3)[12488]: kernel: NET: Registered protocol family 10 2018-06-06T10:15:42.348855+10:00 INFO VM(3)[12488]: kernel: Segment Routing with IPv6 2018-06-06T10:15:42.348858+10:00 INFO VM(3)[12488]: kernel: ip6_tables: (C) 2000-2006 Netfilter Core Team 2018-06-06T10:15:42.348861+10:00 INFO VM(3)[12488]: kernel: NET: Registered protocol family 17 2018-06-06T10:15:42.348863+10:00 NOTICE VM(3)[12488]: kernel: Bridge firewalling registered 2018-06-06T10:15:42.348865+10:00 INFO VM(3)[12488]: kernel: Ebtables v2.0 registered 2018-06-06T10:15:42.348867+10:00 INFO VM(3)[12488]: kernel: 8021q: 802.1Q VLAN Support v1.8 2018-06-06T10:15:42.348870+10:00 INFO VM(3)[12488]: kernel: 9pnet: Installing 9P2000 support 2018-06-06T10:15:42.348873+10:00 INFO VM(3)[12488]: kernel: NET: Registered protocol family 40 2018-06-06T10:15:42.348875+10:00 INFO VM(3)[12488]: kernel: AVX2 version of gcm_enc/dec engaged. 2018-06-06T10:15:42.348878+10:00 INFO VM(3)[12488]: kernel: AES CTR mode by8 optimization enabled 2018-06-06T10:15:42.348880+10:00 INFO VM(3)[12488]: kernel: sched_clock: Marking stable (611209128, 0)->(936469873, -325260745) 2018-06-06T10:15:42.348882+10:00 INFO VM(3)[12488]: kernel: Btrfs loaded, crc32c=crc32c-generic 2018-06-06T10:15:42.348885+10:00 NOTICE VM(3)[12488]: kernel: Key type encrypted registered 2018-06-06T10:15:42.348888+10:00 INFO VM(3)[12488]: kernel: Magic number: 6:166:253 2018-06-06T10:15:42.348890+10:00 ERR VM(3)[12488]: kernel: EXT4-fs (vda): couldn't mount as ext3 due to feature incompatibilities 2018-06-06T10:15:42.348893+10:00 ERR VM(3)[12488]: kernel: EXT4-fs (vda): couldn't mount as ext2 due to feature incompatibilities 2018-06-06T10:15:42.348895+10:00 INFO VM(3)[12488]: kernel: EXT4-fs (vda): mounted filesystem without journal. Opts: (null) 2018-06-06T10:15:42.348898+10:00 INFO VM(3)[12488]: kernel: VFS: Mounted root (ext4 filesystem) readonly on device 254:0. 2018-06-06T10:15:42.348900+10:00 INFO VM(3)[12488]: kernel: devtmpfs: mounted 2018-06-06T10:15:42.348902+10:00 INFO VM(3)[12488]: kernel: Freeing unused kernel memory: 852K 2018-06-06T10:15:42.348905+10:00 INFO VM(3)[12488]: kernel: Write protecting the kernel read-only data: 12288k 2018-06-06T10:15:42.348907+10:00 INFO VM(3)[12488]: kernel: Freeing unused kernel memory: 2020K 2018-06-06T10:15:42.348910+10:00 INFO VM(3)[12488]: kernel: Freeing unused kernel memory: 244K 2018-06-06T10:15:42.348913+10:00 INFO VM(3)[12488]: kernel: maitred: Starting system log collector 2018-06-06T10:15:42.348915+10:00 INFO VM(3)[12488]: kernel: maitred: system log collector started 2018-06-06T10:15:42.348918+10:00 INFO VM(3)[12488]: kernel: maitred: Starting vsock remote shell daemon 2018-06-06T10:15:42.348920+10:00 INFO VM(3)[12488]: kernel: maitred: vsock remote shell daemon started 2018-06-06T10:15:42.348923+10:00 INFO VM(3)[12488]: kernel: maitred: Server listening on port 8888 2018-06-06T10:15:42.348926+10:00 INFO VM(3)[12488]: kernel: maitred: Received network configuration request 2018-06-06T10:15:42.348928+10:00 INFO VM(3)[12488]: kernel: maitred: Set IPv4 address for interface eth0 to 100.115.92.6 2018-06-06T10:15:42.348931+10:00 INFO VM(3)[12488]: kernel: maitred: Set IPv4 netmask for interface eth0 to 255.255.255.252 2018-06-06T10:15:42.348934+10:00 INFO VM(3)[12488]: kernel: maitred: Set interface eth0 up and running 2018-06-06T10:15:42.348936+10:00 INFO VM(3)[12488]: kernel: maitred: Set default IPv4 gateway for interface eth0 to 100.115.92.5 2018-06-06T10:15:42.348939+10:00 INFO VM(3)[12488]: kernel: BTRFS: device fsid 5a6f449f-1730-4149-af29-7c0f45db9a87 devid 1 transid 282 /dev/vdb 2018-06-06T10:15:42.348941+10:00 INFO VM(3)[12488]: kernel: BTRFS info (device vdb): disk space caching is enabled 2018-06-06T10:15:42.348944+10:00 INFO VM(3)[12488]: kernel: BTRFS info (device vdb): has skinny extents 2018-06-06T10:15:44.156185+10:00 INFO vm_concierge[12495]: Launching lxd_setup.sh inside VM 3 2018-06-06T10:15:44.363083+10:00 INFO vm_concierge[12495]: Launching lxc inside VM 3 2018-06-06T10:15:44.377930+10:00 INFO vm_concierge[12495]: Started VM with pid 6 2018-06-06T10:15:44.378367+10:00 INFO vm_concierge[12495]: Received StartContainer request 2018-06-06T10:15:44.378383+10:00 INFO vm_concierge[12495]: Checking if container penguin is running 2018-06-06T10:15:44.378387+10:00 INFO vm_concierge[12495]: No such container: penguin 2018-06-06T10:15:44.380194+10:00 INFO vm_concierge[12495]: Launching /sbin/minijail0 inside VM 3 2018-06-06T10:15:47.352442+10:00 INFO VM(3)[12488]: dnsmasq[189]: read /etc/hosts - 2 addresses 2018-06-06T10:15:47.352452+10:00 INFO VM(3)[12488]: dnsmasq-dhcp[189]: read /mnt/stateful/lxd/networks/lxdbr0/dnsmasq.hosts/penguin 2018-06-06T10:15:47.352456+10:00 WARNING VM(3)[12488]: dnsmasq[189]: LOUD WARNING: listening on 100.115.92.193 may accept requests via interfaces other than lxdbr0 2018-06-06T10:15:47.352460+10:00 WARNING VM(3)[12488]: dnsmasq[189]: LOUD WARNING: use --bind-dynamic rather than --bind-interfaces to avoid DNS amplification attacks via these interface(s) 2018-06-06T10:15:47.352463+10:00 INFO VM(3)[12488]: dnsmasq[189]: read /etc/hosts - 2 addresses 2018-06-06T10:15:47.352467+10:00 INFO VM(3)[12488]: dnsmasq-dhcp[189]: read /mnt/stateful/lxd/networks/lxdbr0/dnsmasq.hosts/penguin 2018-06-06T10:15:47.352471+10:00 WARNING VM(3)[12488]: dnsmasq[189]: LOUD WARNING: listening on 100.115.92.193 may accept requests via interfaces other than lxdbr0 2018-06-06T10:15:47.352475+10:00 WARNING VM(3)[12488]: dnsmasq[189]: LOUD WARNING: use --bind-dynamic rather than --bind-interfaces to avoid DNS amplification attacks via these interface(s) 2018-06-06T10:15:47.352477+10:00 WARNING VM(3)[12488]: dnsmasq[189]: LOUD WARNING: listening on 100.115.92.193 may accept requests via interfaces other than lxdbr0 2018-06-06T10:15:47.352481+10:00 WARNING VM(3)[12488]: dnsmasq[189]: LOUD WARNING: use --bind-dynamic rather than --bind-interfaces to avoid DNS amplification attacks via these interface(s) 2018-06-06T10:15:47.352483+10:00 WARNING VM(3)[12488]: dnsmasq[189]: LOUD WARNING: listening on 100.115.92.193 may accept requests via interfaces other than lxdbr0 2018-06-06T10:15:47.352487+10:00 WARNING VM(3)[12488]: dnsmasq[189]: LOUD WARNING: use --bind-dynamic rather than --bind-interfaces to avoid DNS amplification attacks via these interface(s) 2018-06-06T10:15:47.352491+10:00 INFO VM(3)[12488]: minijail0: libminijail[255]: child process 256 exited with status 1
,
Jun 6 2018
After a reboot, Terminal still doesn't launch. 2018-06-06T10:47:13.850251+10:00 ERR chrome[3108]: [3108:3108:0606/104713.850158:INFO:status_uploader.cc(232)] Status upload successful 2018-06-06T10:47:21.883858+10:00 NOTICE dbus[537]: [system] Activating service name='org.chromium.ImageLoader' (using servicehelper) 2018-06-06T10:47:21.902013+10:00 NOTICE dbus[537]: [system] Successfully activated service 'org.chromium.ImageLoader' 2018-06-06T10:47:21.930809+10:00 WARNING kernel: [ 94.143417] device-mapper: verity: Argument 0: 'payload=/dev/loop7' 2018-06-06T10:47:21.930824+10:00 WARNING kernel: [ 94.143423] device-mapper: verity: Argument 1: 'hashtree=/dev/loop7' 2018-06-06T10:47:21.930826+10:00 WARNING kernel: [ 94.143425] device-mapper: verity: Argument 2: 'hashstart=461568' 2018-06-06T10:47:21.930826+10:00 WARNING kernel: [ 94.143427] device-mapper: verity: Argument 3: 'alg=sha256' 2018-06-06T10:47:21.930827+10:00 WARNING kernel: [ 94.143428] device-mapper: verity: Argument 4: 'root_hexdigest=516e3ccbed6880e2d8d443ecafb5af42385c6c7c24c7d0a618118b943a2c3c96' 2018-06-06T10:47:21.930839+10:00 WARNING kernel: [ 94.143430] device-mapper: verity: Argument 5: 'salt=db713d76b6d316135a1bc9a3e64c093c4b69e182eb85cc6bcd93beb8074bc924' 2018-06-06T10:47:21.930841+10:00 WARNING kernel: [ 94.143432] device-mapper: verity: Argument 6: 'error_behavior=eio' 2018-06-06T10:47:21.968804+10:00 INFO kernel: [ 94.182133] EXT4-fs (dm-3): mounted filesystem without journal. Opts: 2018-06-06T10:47:21.969541+10:00 INFO debugd[1231]: Starting vm_concierge 2018-06-06T10:47:21.993054+10:00 INFO kernel: [ 94.205414] NET: Registered protocol family 40 2018-06-06T10:47:22.006342+10:00 INFO minijail0[6663]: libminijail[6663]: mount / -> / type '' 2018-06-06T10:47:22.006354+10:00 INFO minijail0[6663]: libminijail[6663]: mount proc -> /proc type 'proc' 2018-06-06T10:47:22.006359+10:00 INFO minijail0[6663]: libminijail[6663]: mount /sys -> /sys type '' 2018-06-06T10:47:22.006364+10:00 INFO minijail0[6663]: libminijail[6663]: mount /dev/log -> /dev/log type '' 2018-06-06T10:47:22.006368+10:00 INFO minijail0[6663]: libminijail[6663]: mount /dev/kvm -> /dev/kvm type '' 2018-06-06T10:47:22.006372+10:00 INFO minijail0[6663]: libminijail[6663]: mount /dev/net -> /dev/net type '' 2018-06-06T10:47:22.006377+10:00 INFO minijail0[6663]: libminijail[6663]: mount /dev/vhost-vsock -> /dev/vhost-vsock type '' 2018-06-06T10:47:22.006381+10:00 INFO minijail0[6663]: libminijail[6663]: mount /dev/dri -> /dev/dri type '' 2018-06-06T10:47:22.006386+10:00 INFO minijail0[6663]: libminijail[6663]: mount run -> /run type 'tmpfs' 2018-06-06T10:47:22.006391+10:00 INFO minijail0[6663]: libminijail[6663]: mount /run/chrome -> /run/chrome type '' 2018-06-06T10:47:22.006396+10:00 INFO minijail0[6663]: libminijail[6663]: mount /run/dbus -> /run/dbus type '' 2018-06-06T10:47:22.006401+10:00 INFO minijail0[6663]: libminijail[6663]: mount /run/vm -> /run/vm type '' 2018-06-06T10:47:22.006406+10:00 INFO minijail0[6663]: libminijail[6663]: mount var -> /var type 'tmpfs' 2018-06-06T10:47:22.006412+10:00 INFO minijail0[6663]: libminijail[6663]: mount empty -> /var/empty type 'tmpfs' 2018-06-06T10:47:22.006417+10:00 INFO minijail0[6663]: libminijail[6663]: mount /run/imageloader -> /run/imageloader type 'none' 2018-06-06T10:47:22.006422+10:00 INFO minijail0[6663]: libminijail[6663]: mount /home -> /home type 'none' 2018-06-06T10:47:22.007433+10:00 INFO crosdns[6660]: Starting CrOS DNS daemon 2018-06-06T10:47:22.008022+10:00 INFO minijail0[6663]: libminijail[6663]: not running init loop, exiting immediately 2018-06-06T10:47:22.031470+10:00 INFO vmlog_forwarder[6662]: VM log forwarder listening on port 9999 2018-06-06T10:47:22.031616+10:00 INFO vm_concierge[6664]: libminijail[1]: mount / -> / type '' 2018-06-06T10:47:22.031625+10:00 INFO vm_concierge[6664]: libminijail[1]: mount proc -> /proc type 'proc' 2018-06-06T10:47:22.031630+10:00 INFO vm_concierge[6664]: libminijail[1]: mount /sys -> /sys type '' 2018-06-06T10:47:22.031634+10:00 INFO vm_concierge[6664]: libminijail[1]: mount /dev/log -> /dev/log type '' 2018-06-06T10:47:22.031638+10:00 INFO vm_concierge[6664]: libminijail[1]: mount /dev/kvm -> /dev/kvm type '' 2018-06-06T10:47:22.031643+10:00 INFO vm_concierge[6664]: libminijail[1]: mount /dev/net -> /dev/net type '' 2018-06-06T10:47:22.031648+10:00 INFO vm_concierge[6664]: libminijail[1]: mount /dev/vhost-vsock -> /dev/vhost-vsock type '' 2018-06-06T10:47:22.031652+10:00 INFO vm_concierge[6664]: libminijail[1]: mount /dev/dri -> /dev/dri type '' 2018-06-06T10:47:22.031657+10:00 INFO vm_concierge[6664]: libminijail[1]: mount run -> /run type 'tmpfs' 2018-06-06T10:47:22.031662+10:00 INFO vm_concierge[6664]: libminijail[1]: mount /run/chrome -> /run/chrome type '' 2018-06-06T10:47:22.031667+10:00 INFO vm_concierge[6664]: libminijail[1]: mount /run/dbus -> /run/dbus type '' 2018-06-06T10:47:22.031671+10:00 INFO vm_concierge[6664]: libminijail[1]: mount /run/vm -> /run/vm type '' 2018-06-06T10:47:22.031674+10:00 INFO vm_concierge[6664]: libminijail[1]: mount var -> /var type 'tmpfs' 2018-06-06T10:47:22.031678+10:00 INFO vm_concierge[6664]: libminijail[1]: mount empty -> /var/empty type 'tmpfs' 2018-06-06T10:47:22.031681+10:00 INFO vm_concierge[6664]: libminijail[1]: mount /run/imageloader -> /run/imageloader type 'none' 2018-06-06T10:47:22.031684+10:00 INFO vm_concierge[6664]: libminijail[1]: mount /home -> /home type 'none' 2018-06-06T10:47:22.048179+10:00 INFO vm_concierge[6664]: Received CreateDiskImage request 2018-06-06T10:47:22.051872+10:00 INFO vm_concierge[6664]: Received StartVm request 2018-06-06T10:47:22.062376+10:00 INFO localhos[6670]: crosvm[6]: [src/linux.rs:768] crosvm entering multiprocess mode 2018-06-06T10:47:22.077963+10:00 WARNING localhos[6670]: crosvm[6]: [devices/src/virtio/block.rs:326] Disk size 87674790297 is not a multiple of sector size 512; the remainder will not be visible to the guest. 2018-06-06T10:47:22.083467+10:00 INFO crosvm[6672]: libminijail[6]: reset SIGSYS disposition 2018-06-06T10:47:22.086805+10:00 INFO kernel: [ 94.300165] tun: Universal TUN/TAP device driver, 1.6 2018-06-06T10:47:22.086819+10:00 INFO kernel: [ 94.300170] tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com> 2018-06-06T10:47:22.086944+10:00 INFO crosvm[6674]: libminijail[6]: reset SIGSYS disposition 2018-06-06T10:47:22.092936+10:00 INFO crosvm[6670]: libminijail[6]: mount none -> / type 'tmpfs' 2018-06-06T10:47:22.092952+10:00 INFO crosvm[6670]: libminijail[6]: mount /run/chrome/wayland-0 -> /wayland-0 type '' 2018-06-06T10:47:22.095242+10:00 INFO localhos[6670]: crosvm[6]: [src/linux.rs:595] created GPU buffer device for DMABuf allocations 2018-06-06T10:47:22.100209+10:00 INFO crosvm[6677]: libminijail[6]: reset SIGSYS disposition 2018-06-06T10:47:22.100653+10:00 INFO crosvm[6675]: libminijail[6]: reset SIGSYS disposition 2018-06-06T10:47:22.104111+10:00 INFO crosvm[6684]: libminijail[6]: reset SIGSYS disposition 2018-06-06T10:47:22.104112+10:00 INFO crosvm[6683]: libminijail[6]: reset SIGSYS disposition 2018-06-06T10:47:22.104369+10:00 INFO crosvm[6686]: libminijail[6]: reset SIGSYS disposition 2018-06-06T10:47:22.215862+10:00 WARNING localhos[6670]: crosvm[6]: [src/linux.rs:635] failed to add stdin to poll context: Error(1) 2018-06-06T10:47:23.443085+10:00 INFO vm_concierge[6664]: Configuring network for VM 3 2018-06-06T10:47:23.448049+10:00 INFO vm_concierge[6664]: Starting lxd 2018-06-06T10:47:23.448062+10:00 INFO vm_concierge[6664]: Launching stateful_setup.sh inside VM 3 2018-06-06T10:47:23.452201+10:00 NOTICE VM(3)[6662]: kernel: Linux version 4.14.37-04878-g7f86c373d980 (chrome-bot@cros-beefy500-c2) (gcc version 4.9.x 20150123 (prerelease) (4.9.2_cos_gg_4.9.2-r189-71959ce8f47f676a26bb21da7117101d9d73867e_4.9.2-r189)) #1 SMP PREEMPT Thu May 3 15:25:53 PDT 2018 2018-06-06T10:47:23.452205+10:00 INFO VM(3)[6662]: kernel: Command line: console=ttyS0 noacpi reboot=k panic=1 pci=off virtio_mmio.device=4K@0xd0000000:5 virtio_mmio.device=4K@0xd0001000:6 virtio_mmio.device=4K@0xd0002000:7 virtio_mmio.device=4K@0xd0003000:8 virtio_mmio.device=4K@0xd0004000:9 virtio_mmio.device=4K@0xd0005000:10 virtio_mmio.device=4K@0xd0006000:11 root=/dev/vda ro 2018-06-06T10:47:23.452206+10:00 ERR VM(3)[6662]: kernel: CPU: vendor_id 'crosvmBestVM' unknown, using generic init.\x0aCPU: Your system may be unstable. 2018-06-06T10:47:23.452208+10:00 INFO VM(3)[6662]: kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' 2018-06-06T10:47:23.452209+10:00 INFO VM(3)[6662]: kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' 2018-06-06T10:47:23.452211+10:00 INFO VM(3)[6662]: kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' 2018-06-06T10:47:23.452212+10:00 INFO VM(3)[6662]: kernel: x86/fpu: Supporting XSAVE feature 0x008: 'MPX bounds registers' 2018-06-06T10:47:23.452213+10:00 INFO VM(3)[6662]: kernel: x86/fpu: Supporting XSAVE feature 0x010: 'MPX CSR' 2018-06-06T10:47:23.452214+10:00 INFO VM(3)[6662]: kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 2018-06-06T10:47:23.452216+10:00 INFO VM(3)[6662]: kernel: x86/fpu: xstate_offset[3]: 960, xstate_sizes[3]: 64 2018-06-06T10:47:23.452217+10:00 INFO VM(3)[6662]: kernel: x86/fpu: xstate_offset[4]: 1024, xstate_sizes[4]: 64 2018-06-06T10:47:23.452220+10:00 INFO VM(3)[6662]: kernel: x86/fpu: Enabled xstate features 0x1f, context size is 1088 bytes, using 'standard' format. 2018-06-06T10:47:23.452221+10:00 INFO VM(3)[6662]: kernel: e820: BIOS-provided physical RAM map: 2018-06-06T10:47:23.452222+10:00 INFO VM(3)[6662]: kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable 2018-06-06T10:47:23.452224+10:00 INFO VM(3)[6662]: kernel: BIOS-e820: [mem 0x0000000000200000-0x00000000cfffffff] usable 2018-06-06T10:47:23.452225+10:00 INFO VM(3)[6662]: kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000170cfffff] usable 2018-06-06T10:47:23.452226+10:00 INFO VM(3)[6662]: kernel: NX (Execute Disable) protection: active 2018-06-06T10:47:23.452227+10:00 INFO VM(3)[6662]: kernel: DMI not present or invalid. 2018-06-06T10:47:23.452229+10:00 INFO VM(3)[6662]: kernel: Hypervisor detected: KVM 2018-06-06T10:47:23.452230+10:00 INFO VM(3)[6662]: kernel: tsc: Fast TSC calibration using PIT 2018-06-06T10:47:23.452231+10:00 DEBUG VM(3)[6662]: kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved 2018-06-06T10:47:23.452233+10:00 DEBUG VM(3)[6662]: kernel: e820: remove [mem 0x000a0000-0x000fffff] usable 2018-06-06T10:47:23.452234+10:00 INFO VM(3)[6662]: kernel: e820: last_pfn = 0x170d00 max_arch_pfn = 0x400000000 2018-06-06T10:47:23.452235+10:00 DEBUG VM(3)[6662]: kernel: MTRR default type: uncachable 2018-06-06T10:47:23.452263+10:00 DEBUG VM(3)[6662]: kernel: MTRR fixed ranges disabled: 2018-06-06T10:47:23.452265+10:00 DEBUG VM(3)[6662]: kernel: 00000-FFFFF uncachable 2018-06-06T10:47:23.452267+10:00 DEBUG VM(3)[6662]: kernel: MTRR variable ranges disabled: 2018-06-06T10:47:23.452269+10:00 DEBUG VM(3)[6662]: kernel: 0 disabled 2018-06-06T10:47:23.452270+10:00 DEBUG VM(3)[6662]: kernel: 1 disabled 2018-06-06T10:47:23.452272+10:00 DEBUG VM(3)[6662]: kernel: 2 disabled 2018-06-06T10:47:23.452273+10:00 DEBUG VM(3)[6662]: kernel: 3 disabled 2018-06-06T10:47:23.452274+10:00 DEBUG VM(3)[6662]: kernel: 4 disabled 2018-06-06T10:47:23.452275+10:00 DEBUG VM(3)[6662]: kernel: 5 disabled 2018-06-06T10:47:23.452277+10:00 DEBUG VM(3)[6662]: kernel: 6 disabled 2018-06-06T10:47:23.452278+10:00 DEBUG VM(3)[6662]: kernel: 7 disabled 2018-06-06T10:47:23.452279+10:00 INFO VM(3)[6662]: kernel: MTRR: Disabled 2018-06-06T10:47:23.452281+10:00 INFO VM(3)[6662]: kernel: x86/PAT: MTRRs disabled, skipping PAT initialization too. 2018-06-06T10:47:23.452282+10:00 INFO VM(3)[6662]: kernel: CPU MTRRs all blank - virtualized system. 2018-06-06T10:47:23.452283+10:00 INFO VM(3)[6662]: kernel: x86/PAT: Configuration [0-7]: WB WT UC- UC WB WT UC- UC 2018-06-06T10:47:23.452284+10:00 INFO VM(3)[6662]: kernel: e820: last_pfn = 0xd0000 max_arch_pfn = 0x400000000 2018-06-06T10:47:23.452286+10:00 INFO VM(3)[6662]: kernel: found SMP MP-table at [mem 0x0009fc00-0x0009fc0f] mapped at [ffffffffff200c00] 2018-06-06T10:47:23.452287+10:00 INFO VM(3)[6662]: kernel: Scanning 1 areas for low memory corruption 2018-06-06T10:47:23.452288+10:00 DEBUG VM(3)[6662]: kernel: Base memory trampoline at [ffff880000099000] 99000 size 24576 2018-06-06T10:47:23.452290+10:00 INFO VM(3)[6662]: kernel: Using GB pages for direct mapping 2018-06-06T10:47:23.452291+10:00 DEBUG VM(3)[6662]: kernel: BRK [0x02224000, 0x02224fff] PGTABLE 2018-06-06T10:47:23.452292+10:00 DEBUG VM(3)[6662]: kernel: BRK [0x02225000, 0x02225fff] PGTABLE 2018-06-06T10:47:23.452293+10:00 DEBUG VM(3)[6662]: kernel: BRK [0x02226000, 0x02226fff] PGTABLE 2018-06-06T10:47:23.452294+10:00 DEBUG VM(3)[6662]: kernel: BRK [0x02227000, 0x02227fff] PGTABLE 2018-06-06T10:47:23.452295+10:00 DEBUG VM(3)[6662]: kernel: BRK [0x02228000, 0x02228fff] PGTABLE 2018-06-06T10:47:23.452297+10:00 INFO VM(3)[6662]: kernel: ACPI: Early table checksum verification disabled 2018-06-06T10:47:23.452298+10:00 ERR VM(3)[6662]: kernel: ACPI BIOS Error (bug): A valid RSDP was not found (20170728/tbxfroot-244) 2018-06-06T10:47:23.452300+10:00 INFO VM(3)[6662]: kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 2018-06-06T10:47:23.452301+10:00 INFO VM(3)[6662]: kernel: kvm-clock: cpu 0, msr 1:70cff001, primary cpu clock 2018-06-06T10:47:23.452302+10:00 INFO VM(3)[6662]: kernel: kvm-clock: using sched offset of 94469501494 cycles 2018-06-06T10:47:23.452303+10:00 INFO VM(3)[6662]: kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns 2018-06-06T10:47:23.452304+10:00 INFO VM(3)[6662]: kernel: Zone ranges: 2018-06-06T10:47:23.452305+10:00 INFO VM(3)[6662]: kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] 2018-06-06T10:47:23.452307+10:00 INFO VM(3)[6662]: kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] 2018-06-06T10:47:23.452308+10:00 INFO VM(3)[6662]: kernel: Normal [mem 0x0000000100000000-0x0000000170cfffff] 2018-06-06T10:47:23.453519+10:00 INFO VM(3)[6662]: kernel: Movable zone start for each node 2018-06-06T10:47:23.453522+10:00 INFO VM(3)[6662]: kernel: Early memory node ranges 2018-06-06T10:47:23.453524+10:00 INFO VM(3)[6662]: kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] 2018-06-06T10:47:23.453525+10:00 INFO VM(3)[6662]: kernel: node 0: [mem 0x0000000000200000-0x00000000cfffffff] 2018-06-06T10:47:23.453526+10:00 INFO VM(3)[6662]: kernel: node 0: [mem 0x0000000100000000-0x0000000170cfffff] 2018-06-06T10:47:23.453528+10:00 INFO VM(3)[6662]: kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000000170cfffff] 2018-06-06T10:47:23.453529+10:00 DEBUG VM(3)[6662]: kernel: On node 0 totalpages: 1313694 2018-06-06T10:47:23.453532+10:00 DEBUG VM(3)[6662]: kernel: DMA zone: 59 pages used for memmap 2018-06-06T10:47:23.453533+10:00 DEBUG VM(3)[6662]: kernel: DMA zone: 21 pages reserved 2018-06-06T10:47:23.453534+10:00 DEBUG VM(3)[6662]: kernel: DMA zone: 3742 pages, LIFO batch:0 2018-06-06T10:47:23.453536+10:00 DEBUG VM(3)[6662]: kernel: DMA32 zone: 13248 pages used for memmap 2018-06-06T10:47:23.453537+10:00 DEBUG VM(3)[6662]: kernel: DMA32 zone: 847872 pages, LIFO batch:31 2018-06-06T10:47:23.453538+10:00 DEBUG VM(3)[6662]: kernel: Normal zone: 7220 pages used for memmap 2018-06-06T10:47:23.453540+10:00 DEBUG VM(3)[6662]: kernel: Normal zone: 462080 pages, LIFO batch:31 2018-06-06T10:47:23.453541+10:00 INFO VM(3)[6662]: kernel: Intel MultiProcessor Specification v1.4 2018-06-06T10:47:23.453543+10:00 INFO VM(3)[6662]: kernel: MPTABLE: OEM ID: CROSVM 2018-06-06T10:47:23.453545+10:00 INFO VM(3)[6662]: kernel: MPTABLE: Product ID: 000000000000 2018-06-06T10:47:23.453546+10:00 INFO VM(3)[6662]: kernel: MPTABLE: APIC at: 0xFEE00000 2018-06-06T10:47:23.453547+10:00 INFO VM(3)[6662]: kernel: Processor #0 (Bootup-CPU) 2018-06-06T10:47:23.453549+10:00 INFO VM(3)[6662]: kernel: Processor #1 2018-06-06T10:47:23.453550+10:00 INFO VM(3)[6662]: kernel: Processor #2 2018-06-06T10:47:23.453551+10:00 INFO VM(3)[6662]: kernel: Processor #3 2018-06-06T10:47:23.453553+10:00 INFO VM(3)[6662]: kernel: IOAPIC[0]: apic_id 5, version 17, address 0xfec00000, GSI 0-23 2018-06-06T10:47:23.453555+10:00 INFO VM(3)[6662]: kernel: Processors: 4 2018-06-06T10:47:23.453556+10:00 INFO VM(3)[6662]: kernel: smpboot: Allowing 4 CPUs, 0 hotplug CPUs 2018-06-06T10:47:23.453558+10:00 INFO VM(3)[6662]: kernel: e820: [mem 0xd0000000-0xffffffff] available for PCI devices 2018-06-06T10:47:23.453559+10:00 INFO VM(3)[6662]: kernel: Booting paravirtualized kernel on KVM 2018-06-06T10:47:23.453560+10:00 INFO VM(3)[6662]: kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns 2018-06-06T10:47:23.453562+10:00 INFO VM(3)[6662]: kernel: setup_percpu: NR_CPUS:64 nr_cpumask_bits:64 nr_cpu_ids:4 nr_node_ids:1 2018-06-06T10:47:23.453563+10:00 INFO VM(3)[6662]: kernel: percpu: Embedded 40 pages/cpu @ffff880170800000 s134056 r0 d29784 u524288 2018-06-06T10:47:23.453564+10:00 DEBUG VM(3)[6662]: kernel: pcpu-alloc: s134056 r0 d29784 u524288 alloc=1*2097152 2018-06-06T10:47:23.453566+10:00 DEBUG VM(3)[6662]: kernel: pcpu-alloc: [0] 0 1 2 3 2018-06-06T10:47:23.453568+10:00 INFO VM(3)[6662]: kernel: KVM setup async PF for cpu 0 2018-06-06T10:47:23.453570+10:00 INFO VM(3)[6662]: kernel: kvm-stealtime: cpu 0, msr 170814f80 2018-06-06T10:47:23.453571+10:00 INFO VM(3)[6662]: kernel: Built 1 zonelists, mobility grouping on. Total pages: 1293146 2018-06-06T10:47:23.453573+10:00 NOTICE VM(3)[6662]: kernel: Kernel command line: console=ttyS0 noacpi reboot=k panic=1 pci=off virtio_mmio.device=4K@0xd0000000:5 virtio_mmio.device=4K@0xd0001000:6 virtio_mmio.device=4K@0xd0002000:7 virtio_mmio.device=4K@0xd0003000:8 virtio_mmio.device=4K@0xd0004000:9 virtio_mmio.device=4K@0xd0005000:10 virtio_mmio.device=4K@0xd0006000:11 root=/dev/vda ro 2018-06-06T10:47:23.453574+10:00 INFO VM(3)[6662]: kernel: PID hash table entries: 4096 (order: 3, 32768 bytes) 2018-06-06T10:47:23.453575+10:00 INFO VM(3)[6662]: kernel: Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes) 2018-06-06T10:47:23.453577+10:00 INFO VM(3)[6662]: kernel: Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes) 2018-06-06T10:47:23.453578+10:00 INFO VM(3)[6662]: kernel: Memory: 5075176K/5254776K available (8204K kernel code, 670K rwdata, 1804K rodata, 852K init, 688K bss, 179600K reserved, 0K cma-reserved) 2018-06-06T10:47:23.453579+10:00 INFO VM(3)[6662]: kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 2018-06-06T10:47:23.453580+10:00 INFO VM(3)[6662]: kernel: Kernel/User page tables isolation: enabled 2018-06-06T10:47:23.453582+10:00 INFO VM(3)[6662]: kernel: Preemptible hierarchical RCU implementation. 2018-06-06T10:47:23.453583+10:00 INFO VM(3)[6662]: kernel: \x09RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=4. 2018-06-06T10:47:23.453584+10:00 INFO VM(3)[6662]: kernel: \x09Tasks RCU enabled. 2018-06-06T10:47:23.453585+10:00 INFO VM(3)[6662]: kernel: RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 2018-06-06T10:47:23.453587+10:00 INFO VM(3)[6662]: kernel: NR_IRQS: 4352, nr_irqs: 456, preallocated irqs: 16 2018-06-06T10:47:23.453588+10:00 INFO VM(3)[6662]: kernel: console [ttyS0] enabled 2018-06-06T10:47:23.453589+10:00 INFO VM(3)[6662]: kernel: tsc: Initial usec timer 429842 2018-06-06T10:47:23.453591+10:00 INFO VM(3)[6662]: kernel: tsc: Detected 1607.998 MHz processor 2018-06-06T10:47:23.453593+10:00 INFO VM(3)[6662]: kernel: tsc: Marking TSC unstable due to TSCs unsynchronized 2018-06-06T10:47:23.453594+10:00 INFO VM(3)[6662]: kernel: Calibrating delay loop (skipped) preset value.. 3215.99 BogoMIPS (lpj=1607998) 2018-06-06T10:47:23.453595+10:00 INFO VM(3)[6662]: kernel: pid_max: default: 32768 minimum: 301 2018-06-06T10:47:23.453597+10:00 INFO VM(3)[6662]: kernel: Security Framework initialized 2018-06-06T10:47:23.453598+10:00 INFO VM(3)[6662]: kernel: Yama: becoming mindful. 2018-06-06T10:47:23.453599+10:00 INFO VM(3)[6662]: kernel: SELinux: Initializing. 2018-06-06T10:47:23.453600+10:00 DEBUG VM(3)[6662]: kernel: SELinux: Starting in permissive mode 2018-06-06T10:47:23.453602+10:00 INFO VM(3)[6662]: kernel: Chromium OS LSM: enabled 2018-06-06T10:47:23.453603+10:00 INFO VM(3)[6662]: kernel: Mount-cache hash table entries: 16384 (order: 5, 131072 bytes) 2018-06-06T10:47:23.453604+10:00 INFO VM(3)[6662]: kernel: Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes) 2018-06-06T10:47:23.453606+10:00 INFO VM(3)[6662]: kernel: CPU: Physical Processor ID: 0 2018-06-06T10:47:23.453607+10:00 INFO VM(3)[6662]: kernel: CPU: Processor Core ID: 0 2018-06-06T10:47:23.454967+10:00 INFO VM(3)[6662]: kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 2018-06-06T10:47:23.454970+10:00 INFO VM(3)[6662]: kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 2018-06-06T10:47:23.454971+10:00 INFO VM(3)[6662]: kernel: Spectre V2 : Mitigation: Full generic retpoline 2018-06-06T10:47:23.454972+10:00 INFO VM(3)[6662]: kernel: Freeing SMP alternatives memory: 24K 2018-06-06T10:47:23.454973+10:00 INFO VM(3)[6662]: kernel: smpboot: Max logical packages: 4 2018-06-06T10:47:23.454974+10:00 INFO VM(3)[6662]: kernel: x2apic enabled 2018-06-06T10:47:23.454975+10:00 INFO VM(3)[6662]: kernel: Switched APIC routing to physical x2apic. 2018-06-06T10:47:23.454976+10:00 INFO VM(3)[6662]: kernel: ..TIMER: vector=0x30 apic1=-1 pin1=0 apic2=-1 pin2=-1 2018-06-06T10:47:23.454977+10:00 INFO VM(3)[6662]: kernel: smpboot: CPU0: crosvmBestVM 06/8e (family: 0x6, model: 0x8e, stepping: 0x9) 2018-06-06T10:47:23.454978+10:00 INFO VM(3)[6662]: kernel: Performance Events: no PMU driver, software events only. 2018-06-06T10:47:23.454979+10:00 INFO VM(3)[6662]: kernel: Hierarchical SRCU implementation. 2018-06-06T10:47:23.454980+10:00 INFO VM(3)[6662]: kernel: NMI watchdog: Perf event create on CPU 0 failed with -2 2018-06-06T10:47:23.454981+10:00 INFO VM(3)[6662]: kernel: NMI watchdog: Perf NMI watchdog permanently disabled 2018-06-06T10:47:23.454982+10:00 INFO VM(3)[6662]: kernel: smp: Bringing up secondary CPUs ... 2018-06-06T10:47:23.454983+10:00 INFO VM(3)[6662]: kernel: x86: Booting SMP configuration: 2018-06-06T10:47:23.454985+10:00 INFO VM(3)[6662]: kernel: .... node #0, CPUs: #1 2018-06-06T10:47:23.454986+10:00 INFO VM(3)[6662]: kernel: kvm-clock: cpu 1, msr 1:70cff041, secondary cpu clock 2018-06-06T10:47:23.454987+10:00 INFO VM(3)[6662]: kernel: KVM setup async PF for cpu 1 2018-06-06T10:47:23.454988+10:00 INFO VM(3)[6662]: kernel: kvm-stealtime: cpu 1, msr 170894f80 2018-06-06T10:47:23.454989+10:00 WARNING VM(3)[6662]: kernel: #2 2018-06-06T10:47:23.454991+10:00 INFO VM(3)[6662]: kernel: kvm-clock: cpu 2, msr 1:70cff081, secondary cpu clock 2018-06-06T10:47:23.454992+10:00 INFO VM(3)[6662]: kernel: KVM setup async PF for cpu 2 2018-06-06T10:47:23.454993+10:00 INFO VM(3)[6662]: kernel: kvm-stealtime: cpu 2, msr 170914f80 2018-06-06T10:47:23.454994+10:00 WARNING VM(3)[6662]: kernel: #3 2018-06-06T10:47:23.454995+10:00 INFO VM(3)[6662]: kernel: kvm-clock: cpu 3, msr 1:70cff0c1, secondary cpu clock 2018-06-06T10:47:23.454996+10:00 INFO VM(3)[6662]: kernel: KVM setup async PF for cpu 3 2018-06-06T10:47:23.454997+10:00 INFO VM(3)[6662]: kernel: kvm-stealtime: cpu 3, msr 170994f80 2018-06-06T10:47:23.454998+10:00 INFO VM(3)[6662]: kernel: smp: Brought up 1 node, 4 CPUs 2018-06-06T10:47:23.454999+10:00 INFO VM(3)[6662]: kernel: smpboot: Total of 4 processors activated (12863.98 BogoMIPS) 2018-06-06T10:47:23.455000+10:00 INFO VM(3)[6662]: kernel: devtmpfs: initialized 2018-06-06T10:47:23.455001+10:00 INFO VM(3)[6662]: kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns 2018-06-06T10:47:23.455002+10:00 INFO VM(3)[6662]: kernel: futex hash table entries: 1024 (order: 4, 65536 bytes) 2018-06-06T10:47:23.455003+10:00 INFO VM(3)[6662]: kernel: xor: automatically using best checksumming function avx 2018-06-06T10:47:23.455004+10:00 INFO VM(3)[6662]: kernel: RTC time: 0:47:22, date: 06/06/18 2018-06-06T10:47:23.455005+10:00 INFO VM(3)[6662]: kernel: NET: Registered protocol family 16 2018-06-06T10:47:23.455006+10:00 INFO VM(3)[6662]: kernel: cpuidle: using governor ladder 2018-06-06T10:47:23.455007+10:00 INFO VM(3)[6662]: kernel: cpuidle: using governor menu 2018-06-06T10:47:23.455008+10:00 INFO VM(3)[6662]: kernel: clocksource: Switched to clocksource kvm-clock 2018-06-06T10:47:23.455009+10:00 ERR VM(3)[6662]: kernel: PCI: Fatal: No config space access function found 2018-06-06T10:47:23.455010+10:00 INFO VM(3)[6662]: kernel: raid6: sse2x1 gen() 9601 MB/s 2018-06-06T10:47:23.455011+10:00 INFO VM(3)[6662]: kernel: raid6: sse2x1 xor() 7435 MB/s 2018-06-06T10:47:23.455012+10:00 INFO VM(3)[6662]: kernel: raid6: sse2x2 gen() 14144 MB/s 2018-06-06T10:47:23.455013+10:00 INFO VM(3)[6662]: kernel: raid6: sse2x2 xor() 9699 MB/s 2018-06-06T10:47:23.455015+10:00 INFO VM(3)[6662]: kernel: raid6: sse2x4 gen() 16582 MB/s 2018-06-06T10:47:23.455015+10:00 INFO VM(3)[6662]: kernel: raid6: sse2x4 xor() 11464 MB/s 2018-06-06T10:47:23.455017+10:00 INFO VM(3)[6662]: kernel: raid6: avx2x1 gen() 21472 MB/s 2018-06-06T10:47:23.455018+10:00 INFO VM(3)[6662]: kernel: raid6: avx2x1 xor() 13900 MB/s 2018-06-06T10:47:23.455019+10:00 INFO VM(3)[6662]: kernel: raid6: avx2x2 gen() 24804 MB/s 2018-06-06T10:47:23.455020+10:00 INFO VM(3)[6662]: kernel: raid6: avx2x2 xor() 17144 MB/s 2018-06-06T10:47:23.455021+10:00 INFO VM(3)[6662]: kernel: raid6: avx2x4 gen() 27539 MB/s 2018-06-06T10:47:23.455022+10:00 INFO VM(3)[6662]: kernel: raid6: avx2x4 xor() 19736 MB/s 2018-06-06T10:47:23.455023+10:00 INFO VM(3)[6662]: kernel: raid6: using algorithm avx2x4 gen() 27539 MB/s 2018-06-06T10:47:23.455024+10:00 INFO VM(3)[6662]: kernel: raid6: .... xor() 19736 MB/s, rmw enabled 2018-06-06T10:47:23.455025+10:00 INFO VM(3)[6662]: kernel: raid6: using avx2x2 recovery algorithm 2018-06-06T10:47:23.455026+10:00 INFO VM(3)[6662]: kernel: ACPI: Interpreter disabled. 2018-06-06T10:47:23.455027+10:00 NOTICE VM(3)[6662]: kernel: SCSI subsystem initialized 2018-06-06T10:47:23.455028+10:00 ERR VM(3)[6662]: kernel: dmi: Firmware registration failed. 2018-06-06T10:47:23.455029+10:00 INFO VM(3)[6662]: kernel: PCI: System does not support PCI 2018-06-06T10:47:23.455030+10:00 NOTICE VM(3)[6662]: kernel: FS-Cache: Loaded 2018-06-06T10:47:23.455031+10:00 INFO VM(3)[6662]: kernel: pnp: PnP ACPI: disabled 2018-06-06T10:47:23.455032+10:00 INFO VM(3)[6662]: kernel: NET: Registered protocol family 2 2018-06-06T10:47:23.455308+10:00 INFO VM(3)[6662]: kernel: TCP established hash table entries: 65536 (order: 7, 524288 bytes) 2018-06-06T10:47:23.455310+10:00 INFO VM(3)[6662]: kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes) 2018-06-06T10:47:23.455312+10:00 INFO VM(3)[6662]: kernel: TCP: Hash tables configured (established 65536 bind 65536) 2018-06-06T10:47:23.455313+10:00 INFO VM(3)[6662]: kernel: UDP hash table entries: 4096 (order: 6, 393216 bytes) 2018-06-06T10:47:23.455314+10:00 INFO VM(3)[6662]: kernel: UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes) 2018-06-06T10:47:23.455315+10:00 INFO VM(3)[6662]: kernel: NET: Registered protocol family 1 2018-06-06T10:47:23.455316+10:00 INFO VM(3)[6662]: kernel: RPC: Registered named UNIX socket transport module. 2018-06-06T10:47:23.455318+10:00 INFO VM(3)[6662]: kernel: RPC: Registered udp transport module. 2018-06-06T10:47:23.455319+10:00 INFO VM(3)[6662]: kernel: RPC: Registered tcp transport module. 2018-06-06T10:47:23.455320+10:00 INFO VM(3)[6662]: kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. 2018-06-06T10:47:23.455321+10:00 DEBUG VM(3)[6662]: kernel: PCI: CLS 0 bytes, default 64 2018-06-06T10:47:23.455874+10:00 INFO VM(3)[6662]: kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) 2018-06-06T10:47:23.455877+10:00 INFO VM(3)[6662]: kernel: software IO TLB [mem 0xcc000000-0xd0000000] (64MB) mapped at [ffff8800cc000000-ffff8800cfffffff] 2018-06-06T10:47:23.455879+10:00 INFO VM(3)[6662]: kernel: virtio-mmio: Registering device virtio-mmio.0 at 0xd0000000-0xd0000fff, IRQ 5. 2018-06-06T10:47:23.455880+10:00 INFO VM(3)[6662]: kernel: virtio-mmio: Registering device virtio-mmio.1 at 0xd0001000-0xd0001fff, IRQ 6. 2018-06-06T10:47:23.455881+10:00 INFO VM(3)[6662]: kernel: virtio-mmio: Registering device virtio-mmio.2 at 0xd0002000-0xd0002fff, IRQ 7. 2018-06-06T10:47:23.455882+10:00 INFO VM(3)[6662]: kernel: virtio-mmio: Registering device virtio-mmio.3 at 0xd0003000-0xd0003fff, IRQ 8. 2018-06-06T10:47:23.455884+10:00 INFO VM(3)[6662]: kernel: virtio-mmio: Registering device virtio-mmio.4 at 0xd0004000-0xd0004fff, IRQ 9. 2018-06-06T10:47:23.455885+10:00 INFO VM(3)[6662]: kernel: virtio-mmio: Registering device virtio-mmio.5 at 0xd0005000-0xd0005fff, IRQ 10. 2018-06-06T10:47:23.455886+10:00 INFO VM(3)[6662]: kernel: virtio-mmio: Registering device virtio-mmio.6 at 0xd0006000-0xd0006fff, IRQ 11. 2018-06-06T10:47:23.455887+10:00 INFO VM(3)[6662]: kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x172da86403b, max_idle_ns: 440795249167 ns 2018-06-06T10:47:23.455888+10:00 INFO VM(3)[6662]: kernel: platform rtc_cmos: registered platform RTC device (no PNP device found) 2018-06-06T10:47:23.455889+10:00 INFO VM(3)[6662]: kernel: Scanning for low memory corruption every 60 seconds 2018-06-06T10:47:23.455891+10:00 INFO VM(3)[6662]: kernel: audit: initializing netlink subsys (disabled) 2018-06-06T10:47:23.455892+10:00 NOTICE VM(3)[6662]: kernel: audit: type=2000 audit(1528246042.890:1): state=initialized audit_enabled=0 res=1 2018-06-06T10:47:23.455893+10:00 INFO VM(3)[6662]: kernel: workingset: timestamp_bits=46 max_order=21 bucket_order=0 2018-06-06T10:47:23.455895+10:00 INFO VM(3)[6662]: kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher 2018-06-06T10:47:23.455896+10:00 INFO VM(3)[6662]: kernel: 9p: Installing v9fs 9p2000 file system support 2018-06-06T10:47:23.455897+10:00 DEBUG VM(3)[6662]: kernel: SELinux: Registering netfilter hooks 2018-06-06T10:47:23.455899+10:00 INFO VM(3)[6662]: kernel: alt_syscall: table 'read_write_test' available. 2018-06-06T10:47:23.455900+10:00 INFO VM(3)[6662]: kernel: alt_syscall: table 'android' available. 2018-06-06T10:47:23.455902+10:00 INFO VM(3)[6662]: kernel: alt_syscall: table 'android_permissive' available. 2018-06-06T10:47:23.455903+10:00 INFO VM(3)[6662]: kernel: alt_syscall: table 'third_party' available. 2018-06-06T10:47:23.455904+10:00 INFO VM(3)[6662]: kernel: alt_syscall: table 'third_party_permissive' available. 2018-06-06T10:47:23.455912+10:00 INFO VM(3)[6662]: kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) 2018-06-06T10:47:23.455914+10:00 INFO VM(3)[6662]: kernel: io scheduler noop registered 2018-06-06T10:47:23.455915+10:00 INFO VM(3)[6662]: kernel: io scheduler deadline registered 2018-06-06T10:47:23.455916+10:00 INFO VM(3)[6662]: kernel: io scheduler cfq registered (default) 2018-06-06T10:47:23.455917+10:00 INFO VM(3)[6662]: kernel: io scheduler mq-deadline registered 2018-06-06T10:47:23.455918+10:00 INFO VM(3)[6662]: kernel: io scheduler kyber registered 2018-06-06T10:47:23.455920+10:00 WARNING VM(3)[6662]: kernel: virtio-mmio virtio-mmio.0: Failed to enable 64-bit or 32-bit DMA. Trying to continue, but this might not work. 2018-06-06T10:47:23.455921+10:00 WARNING VM(3)[6662]: kernel: virtio-mmio virtio-mmio.1: Failed to enable 64-bit or 32-bit DMA. Trying to continue, but this might not work. 2018-06-06T10:47:23.455922+10:00 WARNING VM(3)[6662]: kernel: virtio-mmio virtio-mmio.2: Failed to enable 64-bit or 32-bit DMA. Trying to continue, but this might not work. 2018-06-06T10:47:23.455923+10:00 WARNING VM(3)[6662]: kernel: virtio-mmio virtio-mmio.3: Failed to enable 64-bit or 32-bit DMA. Trying to continue, but this might not work. 2018-06-06T10:47:23.455924+10:00 WARNING VM(3)[6662]: kernel: virtio-mmio virtio-mmio.4: Failed to enable 64-bit or 32-bit DMA. Trying to continue, but this might not work. 2018-06-06T10:47:23.455926+10:00 WARNING VM(3)[6662]: kernel: virtio-mmio virtio-mmio.5: Failed to enable 64-bit or 32-bit DMA. Trying to continue, but this might not work. 2018-06-06T10:47:23.455927+10:00 WARNING VM(3)[6662]: kernel: virtio-mmio virtio-mmio.6: Failed to enable 64-bit or 32-bit DMA. Trying to continue, but this might not work. 2018-06-06T10:47:23.455929+10:00 INFO VM(3)[6662]: kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled 2018-06-06T10:47:23.455933+10:00 INFO VM(3)[6662]: kernel: serial8250: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a U6_16550A 2018-06-06T10:47:23.455934+10:00 INFO VM(3)[6662]: kernel: serial8250: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a U6_16550A 2018-06-06T10:47:23.455935+10:00 INFO VM(3)[6662]: kernel: serial8250: ttyS2 at I/O 0x3e8 (irq = 4, base_baud = 115200) is a U6_16550A 2018-06-06T10:47:23.455936+10:00 INFO VM(3)[6662]: kernel: Non-volatile memory driver v1.3 2018-06-06T10:47:23.455937+10:00 NOTICE VM(3)[6662]: kernel: random: fast init done 2018-06-06T10:47:23.455938+10:00 NOTICE VM(3)[6662]: kernel: random: crng init done 2018-06-06T10:47:23.455940+10:00 INFO VM(3)[6662]: kernel: loop: module loaded 2018-06-06T10:47:23.455941+10:00 INFO VM(3)[6662]: kernel: libphy: Fixed MDIO Bus: probed 2018-06-06T10:47:23.455942+10:00 INFO VM(3)[6662]: kernel: tun: Universal TUN/TAP device driver, 1.6 2018-06-06T10:47:23.455943+10:00 WARNING VM(3)[6662]: kernel: rtc_cmos rtc_cmos: only 24-hr supported 2018-06-06T10:47:23.455944+10:00 INFO VM(3)[6662]: kernel: device-mapper: ioctl: 4.37.0-ioctl (2017-09-20) initialised: dm-devel@redhat.com 2018-06-06T10:47:23.455946+10:00 ERR VM(3)[6662]: kernel: intel_rapl: driver does not support CPU family 6 model 142 2018-06-06T10:47:23.455947+10:00 INFO VM(3)[6662]: kernel: GACT probability NOT on 2018-06-06T10:47:23.455949+10:00 INFO VM(3)[6662]: kernel: Netfilter messages via NETLINK v0.30. 2018-06-06T10:47:23.455950+10:00 INFO VM(3)[6662]: kernel: nfnl_acct: registering with nfnetlink. 2018-06-06T10:47:23.516751+10:00 INFO vm_concierge[6664]: Launching lxd inside VM 3 2018-06-06T10:47:23.531662+10:00 INFO vm_concierge[6664]: Launching lxd inside VM 3 2018-06-06T10:47:23.945482+10:00 INFO VM(3)[6662]: dnsmasq[188]: started, version 2.78 cachesize 150 2018-06-06T10:47:23.945486+10:00 INFO VM(3)[6662]: dnsmasq[188]: compile time options: IPv6 GNU-getopt no-DBus no-i18n no-IDN DHCP DHCPv6 no-Lua no-TFTP no-conntrack ipset no-auth no-DNSSEC loop-detect inotify 2018-06-06T10:47:23.945487+10:00 WARNING VM(3)[6662]: dnsmasq[188]: LOUD WARNING: listening on 100.115.92.193 may accept requests via interfaces other than lxdbr0 2018-06-06T10:47:23.945488+10:00 WARNING VM(3)[6662]: dnsmasq[188]: LOUD WARNING: use --bind-dynamic rather than --bind-interfaces to avoid DNS amplification attacks via these interface(s) 2018-06-06T10:47:23.945489+10:00 INFO VM(3)[6662]: dnsmasq-dhcp[188]: DHCP, IP range 100.115.92.194 -- 100.115.92.206, lease time 1h 2018-06-06T10:47:23.945490+10:00 INFO VM(3)[6662]: dnsmasq-dhcp[188]: DHCP, sockets bound exclusively to interface lxdbr0 2018-06-06T10:47:23.945491+10:00 INFO VM(3)[6662]: dnsmasq[188]: using local addresses only for domain lxd 2018-06-06T10:47:23.945492+10:00 INFO VM(3)[6662]: dnsmasq[188]: reading /etc/resolv.conf 2018-06-06T10:47:23.945493+10:00 INFO VM(3)[6662]: dnsmasq[188]: using local addresses only for domain lxd 2018-06-06T10:47:23.945494+10:00 INFO VM(3)[6662]: dnsmasq[188]: using nameserver 8.8.8.8#53 2018-06-06T10:47:23.945494+10:00 INFO VM(3)[6662]: dnsmasq[188]: using nameserver 8.8.4.4#53 2018-06-06T10:47:23.946932+10:00 INFO VM(3)[6662]: kernel: nf_conntrack version 0.5.0 (65536 buckets, 262144 max) 2018-06-06T10:47:23.946935+10:00 INFO VM(3)[6662]: kernel: ctnetlink v0.93: registering with nfnetlink. 2018-06-06T10:47:23.946937+10:00 INFO VM(3)[6662]: kernel: nf_tables: (c) 2007-2009 Patrick McHardy <kaber@trash.net> 2018-06-06T10:47:23.946938+10:00 INFO VM(3)[6662]: kernel: nf_tables_compat: (c) 2012 Pablo Neira Ayuso <pablo@netfilter.org> 2018-06-06T10:47:23.946939+10:00 INFO VM(3)[6662]: kernel: xt_time: kernel timezone is -0000 2018-06-06T10:47:23.946940+10:00 INFO VM(3)[6662]: kernel: ip_set: protocol 6 2018-06-06T10:47:23.946942+10:00 INFO VM(3)[6662]: kernel: IPVS: Registered protocols () 2018-06-06T10:47:23.946943+10:00 INFO VM(3)[6662]: kernel: IPVS: Connection hash table configured (size=4096, memory=64Kbytes) 2018-06-06T10:47:23.946944+10:00 DEBUG VM(3)[6662]: kernel: IPVS: Each connection entry needs 312 bytes at least 2018-06-06T10:47:23.946946+10:00 INFO VM(3)[6662]: kernel: IPVS: ipvs loaded. 2018-06-06T10:47:23.946948+10:00 INFO VM(3)[6662]: kernel: ip_tables: (C) 2000-2006 Netfilter Core Team 2018-06-06T10:47:23.946949+10:00 INFO VM(3)[6662]: kernel: ipt_CLUSTERIP: ClusterIP Version 0.8 loaded successfully 2018-06-06T10:47:23.946950+10:00 INFO VM(3)[6662]: kernel: arp_tables: arp_tables: (C) 2002 David S. Miller 2018-06-06T10:47:23.946951+10:00 INFO VM(3)[6662]: kernel: Initializing XFRM netlink socket 2018-06-06T10:47:23.946953+10:00 INFO VM(3)[6662]: kernel: NET: Registered protocol family 10 2018-06-06T10:47:23.946954+10:00 INFO VM(3)[6662]: kernel: Segment Routing with IPv6 2018-06-06T10:47:23.946955+10:00 INFO VM(3)[6662]: kernel: ip6_tables: (C) 2000-2006 Netfilter Core Team 2018-06-06T10:47:23.946957+10:00 INFO VM(3)[6662]: kernel: NET: Registered protocol family 17 2018-06-06T10:47:23.946959+10:00 NOTICE VM(3)[6662]: kernel: Bridge firewalling registered 2018-06-06T10:47:23.946960+10:00 INFO VM(3)[6662]: kernel: Ebtables v2.0 registered 2018-06-06T10:47:23.946962+10:00 INFO VM(3)[6662]: kernel: 8021q: 802.1Q VLAN Support v1.8 2018-06-06T10:47:23.946963+10:00 INFO VM(3)[6662]: kernel: 9pnet: Installing 9P2000 support 2018-06-06T10:47:23.946964+10:00 INFO VM(3)[6662]: kernel: NET: Registered protocol family 40 2018-06-06T10:47:23.946966+10:00 INFO VM(3)[6662]: kernel: AVX2 version of gcm_enc/dec engaged. 2018-06-06T10:47:23.946968+10:00 INFO VM(3)[6662]: kernel: AES CTR mode by8 optimization enabled 2018-06-06T10:47:23.946970+10:00 INFO VM(3)[6662]: kernel: sched_clock: Marking stable (570782261, 0)->(900300799, -329518538) 2018-06-06T10:47:23.946971+10:00 INFO VM(3)[6662]: kernel: Btrfs loaded, crc32c=crc32c-generic 2018-06-06T10:47:23.946972+10:00 NOTICE VM(3)[6662]: kernel: Key type encrypted registered 2018-06-06T10:47:23.946974+10:00 INFO VM(3)[6662]: kernel: Magic number: 6:681:758 2018-06-06T10:47:23.946975+10:00 ERR VM(3)[6662]: kernel: EXT4-fs (vda): couldn't mount as ext3 due to feature incompatibilities 2018-06-06T10:47:23.946976+10:00 ERR VM(3)[6662]: kernel: EXT4-fs (vda): couldn't mount as ext2 due to feature incompatibilities 2018-06-06T10:47:23.946978+10:00 INFO VM(3)[6662]: kernel: EXT4-fs (vda): mounted filesystem without journal. Opts: (null) 2018-06-06T10:47:23.946979+10:00 INFO VM(3)[6662]: kernel: VFS: Mounted root (ext4 filesystem) readonly on device 254:0. 2018-06-06T10:47:23.946981+10:00 INFO VM(3)[6662]: kernel: devtmpfs: mounted 2018-06-06T10:47:23.946983+10:00 INFO VM(3)[6662]: kernel: Freeing unused kernel memory: 852K 2018-06-06T10:47:23.946984+10:00 INFO VM(3)[6662]: kernel: Write protecting the kernel read-only data: 12288k 2018-06-06T10:47:23.946986+10:00 INFO VM(3)[6662]: kernel: Freeing unused kernel memory: 2020K 2018-06-06T10:47:23.946987+10:00 INFO VM(3)[6662]: kernel: Freeing unused kernel memory: 244K 2018-06-06T10:47:23.946989+10:00 INFO VM(3)[6662]: kernel: maitred: Starting system log collector 2018-06-06T10:47:23.946990+10:00 INFO VM(3)[6662]: kernel: maitred: system log collector started 2018-06-06T10:47:23.946991+10:00 INFO VM(3)[6662]: kernel: maitred: Starting vsock remote shell daemon 2018-06-06T10:47:23.946993+10:00 INFO VM(3)[6662]: kernel: maitred: vsock remote shell daemon started 2018-06-06T10:47:23.946996+10:00 INFO VM(3)[6662]: kernel: maitred: Server listening on port 8888 2018-06-06T10:47:23.946997+10:00 INFO VM(3)[6662]: kernel: maitred: Received network configuration request 2018-06-06T10:47:23.946999+10:00 INFO VM(3)[6662]: kernel: maitred: Set IPv4 address for interface eth0 to 100.115.92.6 2018-06-06T10:47:23.947000+10:00 INFO VM(3)[6662]: kernel: maitred: Set IPv4 netmask for interface eth0 to 255.255.255.252 2018-06-06T10:47:23.947001+10:00 INFO VM(3)[6662]: kernel: maitred: Set interface eth0 up and running 2018-06-06T10:47:23.947002+10:00 INFO VM(3)[6662]: kernel: maitred: Set default IPv4 gateway for interface eth0 to 100.115.92.5 2018-06-06T10:47:23.947003+10:00 INFO VM(3)[6662]: kernel: BTRFS: device fsid 5a6f449f-1730-4149-af29-7c0f45db9a87 devid 1 transid 290 /dev/vdb 2018-06-06T10:47:23.947005+10:00 INFO VM(3)[6662]: kernel: BTRFS info (device vdb): disk space caching is enabled 2018-06-06T10:47:23.947006+10:00 INFO VM(3)[6662]: kernel: BTRFS info (device vdb): has skinny extents 2018-06-06T10:47:24.362073+10:00 INFO vm_concierge[6664]: Launching lxd_setup.sh inside VM 3 2018-06-06T10:47:24.734998+10:00 INFO vm_concierge[6664]: Launching lxc inside VM 3 2018-06-06T10:47:24.749271+10:00 INFO vm_concierge[6664]: Started VM with pid 6 2018-06-06T10:47:24.749687+10:00 INFO vm_concierge[6664]: Received StartContainer request 2018-06-06T10:47:24.749706+10:00 INFO vm_concierge[6664]: Checking if container penguin is running 2018-06-06T10:47:24.749711+10:00 INFO vm_concierge[6664]: No such container: penguin 2018-06-06T10:47:24.751428+10:00 INFO vm_concierge[6664]: Launching /sbin/minijail0 inside VM 3 2018-06-06T10:47:26.979890+10:00 ERR kernel: [ 99.192879] i2c_hid i2c-ACPI0C50:00: i2c_hid_get_input: incomplete report (74/60490) 2018-06-06T10:47:28.949699+10:00 WARNING VM(3)[6662]: dnsmasq[188]: LOUD WARNING: listening on 100.115.92.193 may accept requests via interfaces other than lxdbr0 2018-06-06T10:47:28.949714+10:00 WARNING VM(3)[6662]: dnsmasq[188]: LOUD WARNING: use --bind-dynamic rather than --bind-interfaces to avoid DNS amplification attacks via these interface(s) 2018-06-06T10:47:28.949719+10:00 INFO VM(3)[6662]: dnsmasq[188]: read /etc/hosts - 2 addresses 2018-06-06T10:47:28.949724+10:00 INFO VM(3)[6662]: dnsmasq-dhcp[188]: read /mnt/stateful/lxd/networks/lxdbr0/dnsmasq.hosts/penguin 2018-06-06T10:47:28.949728+10:00 WARNING VM(3)[6662]: dnsmasq[188]: LOUD WARNING: listening on 100.115.92.193 may accept requests via interfaces other than lxdbr0 2018-06-06T10:47:28.949731+10:00 WARNING VM(3)[6662]: dnsmasq[188]: LOUD WARNING: use --bind-dynamic rather than --bind-interfaces to avoid DNS amplification attacks via these interface(s) 2018-06-06T10:47:28.949735+10:00 INFO VM(3)[6662]: dnsmasq[188]: read /etc/hosts - 2 addresses 2018-06-06T10:47:28.949741+10:00 INFO VM(3)[6662]: dnsmasq-dhcp[188]: read /mnt/stateful/lxd/networks/lxdbr0/dnsmasq.hosts/penguin 2018-06-06T10:47:28.949746+10:00 INFO VM(3)[6662]: minijail0: libminijail[249]: child process 250 exited with status 1 2018-06-06T10:47:28.949752+10:00 WARNING VM(3)[6662]: dnsmasq[188]: LOUD WARNING: listening on 100.115.92.193 may accept requests via interfaces other than lxdbr0 2018-06-06T10:47:28.949756+10:00 WARNING VM(3)[6662]: dnsmasq[188]: LOUD WARNING: use --bind-dynamic rather than --bind-interfaces to avoid DNS amplification attacks via these interface(s) 2018-06-06T10:47:28.949760+10:00 WARNING VM(3)[6662]: dnsmasq[188]: LOUD WARNING: listening on 100.115.92.193 may accept requests via interfaces other than lxdbr0 2018-06-06T10:47:28.949764+10:00 WARNING VM(3)[6662]: dnsmasq[188]: LOUD WARNING: use --bind-dynamic rather than --bind-interfaces to avoid DNS amplification attacks via these interface(s) 2018-06-06T10:47:28.949767+10:00 WARNING VM(3)[6662]: dnsmasq[188]: LOUD WARNING: listening on 100.115.92.193 may accept requests via interfaces other than lxdbr0 2018-06-06T10:47:28.949771+10:00 WARNING VM(3)[6662]: dnsmasq[188]: LOUD WARNING: use --bind-dynamic rather than --bind-interfaces to avoid DNS amplification attacks via these interface(s)
,
Jun 6 2018
nverne suggested I check my Termina version. I have 10644.0.0, while he says the latest is 10739.0.0. I have now updated to 10739.0.0. Terminal is not launching, I will try rebooting.
,
Jun 6 2018
After a reboot, Terminal is now launching. I'll leave this bug here, as I'm not sure if this is a known problem or not.
,
Jun 7 2018
I have this problem too but I'm new to to ChromeOS. How exactly do I determine the version of Terminal (it doesn't appear to be an "app")? How do I upgrade Terminal? How do I view /var/log/messages?
,
Jun 7 2018
My guess is that it's not the same problem - I previously had things running, and then it stopped working. It sounds like you haven't had Crostini working before. Check out some community docs such as https://www.reddit.com/r/Crostini/wiki/getstarted/your-first-container or https://www.reddit.com/r/Crostini/comments/8o1u2o/chromebook_plus_linux_beta_quick_how_to/ for getting started. about:components will show you cros-termina version. file:///var/log/messages is how you see that. Please do note that this is a bug tracker for developer bugs, not a general support forum. If you do encounter a bug, please file a new issue here.
,
Oct 8
,
Oct 8
No longer reproducible. |
||||
►
Sign in to add a comment |
||||
Comment 1 by mikelawther@chromium.org
, Jun 6 2018