Back to build
Raw log | Switch to full mode | Login

{ "44515d91a1567f10": { "output": "DEBUG: config flag: /etc/botanist/config.json\nDEBUG: executing command: \"/etc/connectivity/host_cmds.sh\"\nenvironment of subprocess:\n[]\n2019/04/20 02:21:39 attempting to send <<netboot>>cmdline...\n2019/04/20 02:21:39 done\n2019/04/20 02:21:39 attempting to send <<image>>sparse.fvm...\n2019/04/20 02:21:41 done\n2019/04/20 02:21:41 attempting to send <<image>>bootloader.img...\n2019/04/20 02:21:41 target is busy, retrying in one second\n2019/04/20 02:21:42 attempting to send <<image>>bootloader.img...\n2019/04/20 02:21:42 done\n2019/04/20 02:21:42 attempting to send <<image>>zircona.img...\n2019/04/20 02:21:42 done\n2019/04/20 02:21:42 attempting to send <<image>>zirconr.img...\n2019/04/20 02:21:42 target is busy, retrying in one second\n[00000.000] 00000.00000> zbi: @ 0xffffff80012f2000 (17482848 bytes)\n[00000.000] 00000.00000> UART: FIFO depth 16\n[00000.000] 00000.00000> PMM: boot reserve add [0x100000, 0x2c5fff]\n[00000.000] 00000.00000> PMM: boot reserve add [0x12f2000, 0x239efff]\n[00000.000] 00000.00000> PMM: arena too small to be useful (size 4096)\n[00000.000] 00000.00000> PMM: pmm_add_arena failed to initialize arena\n[00000.000] 00000.00000> MEM: Failed to add pmm range at 0x7ffff000 size 0x1000\n[00000.000] 00000.00000> PMM: boot reserve marking WIRED [0x100000, 0x2c5fff]\n[00000.000] 00000.00000> PMM: boot reserve marking WIRED [0x12f2000, 0x239efff]\n[00000.000] 00000.00000> \n[00000.000] 00000.00000> welcome to Zircon\n[00000.000] 00000.00000> \n[00000.000] 00000.00000> KASLR: .text section at 0xffffffff00100000\n[00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001aa428 (global_prng_seed) at level 0x30000, flags 0x1\n[00000.000] 00000.00000> initializing vm pre-heap\n[00000.000] 00000.00000> VM: marking boot alloc used range [0x239f000, 0x239f100)\n[00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00147abc (elf_build_id) at level 0x4fffe, flags 0x1\n[00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00147c48 (version) at level 0x4ffff, flags 0x1\n[00000.000] 00000.00000> version:\n[00000.000] 00000.00000> \tarch: x86\n[00000.000] 00000.00000> \tplatform: pc\n[00000.000] 00000.00000> \ttarget: pc\n[00000.000] 00000.00000> \tproject: x64\n[00000.000] 00000.00000> \tbuildid: git-13ee3dc5e4c46bf127977ad28645c47442ec517d\n[00000.000] 00000.00000> \tELF build ID: 5f3e39bd4ed89d3b8122947842f1eac846a04683\n[00000.000] 00000.00000> initializing heap\n[00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00102e1c (x86_resource_init) at level 0x50000, flags 0x1\n[00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001a9630 (console) at level 0x50000, flags 0x1\n[00000.000] 00000.00000> initializing vm\n[00000.000] 00000.00000> VM: reserving kernel region [0xffffffff00100000, 0xffffffff001ee000) flags 0x28 name 'kernel_code'\n[00000.000] 00000.00000> VM: reserving kernel region [0xffffffff001ee000, 0xffffffff00232000) flags 0x8 name 'kernel_rodata'\n[00000.000] 00000.00000> VM: reserving kernel region [0xffffffff00232000, 0xffffffff00242000) flags 0x18 name 'kernel_data'\n[00000.000] 00000.00000> VM: reserving kernel region [0xffffffff00242000, 0xffffffff002c6000) flags 0x18 name 'kernel_bss'\n[00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00103ae0 (display_memtype) at level 0x60001, flags 0x1\n[00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00138fa0 (acpi_tables) at level 0x60001, flags 0x1\n[00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00101c40 (hpet) at level 0x60002, flags 0x1\n[00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00102000 (apic) at level 0x60002, flags 0x1\n[00000.000] 00000.00000> x2APIC enabled\n[00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001061b4 (timer) at level 0x60003, flags 0x1\n[00000.000] 00000.00000> HPET frequency: 23999 ticks/ms\n[00000.000] 00000.00000> TSC frequency: 2712000 ticks/ms\n[00018.421] 00000.00000> timer features: constant_tsc 1 invariant_tsc 1 tsc_deadline 1\n[00018.421] 00000.00000> Using TSC as wallclock\n[00018.421] 00000.00000> initializing kernel\n[00018.421] 00000.00000> initializing mp\n[00018.421] 00000.00000> initializing timers\n[00018.421] 00000.00000> INIT: cpu 0, calling hook 0xffffffff0013c578 (debuglog) at level 0x7ffff, flags 0x1\n[00018.421] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00195518 (thread_set_priority_experiment) at level 0x7ffff, flags 0x1\n[00018.421] 00000.00000> thread set priority experiment is : ENABLED\n[00018.421] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001aa67c (global_prng_thread_safe) at level 0x7ffff, flags 0x1\n[00018.421] 00000.00000> creating bootstrap completion thread\n[00018.764] 00000.00000> top of bootstrap2()\n[00018.764] 00000.00000> INIT: cpu 0, calling hook 0xffffffff0013151c (dpc) at level 0x80000, flags 0x1\n[00018.777] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00152978 (libobject) at level 0x80000, flags 0x1\n[00018.777] 00000.00000> OOM: started thread\n[00018.791] 00000.00000> Processor Model Info: type 0 family 0x6 model 0xe stepping 0x9\n[00018.791] 00000.00000> \tdisplay_family 0x6 display_model 0x8e\n[00018.791] 00000.00000> Vendor: Intel\n[00018.791] 00000.00000> Microarch: Kaby Lake\n[00018.791] 00000.00000> F/M/S: 6/8e/9\n[00018.791] 00000.00000> Brand: Intel(R) Core(TM) i5-7300U CPU @ 2.60GHz\n[00018.791] 00000.00000> Features: fpu sse sse2 sse3 ssse3 sse4.1 sse4.2 mmx avx avx2 fxsr pcid xsave mon aesni clflush \n[00018.791] 00000.00000> clflushopt fsgsbase tsc_adj smep smap erms rdrand rdseed syscall nx huge rdtscp \n[00018.791] 00000.00000> invar_tsc tsc_deadline x2apic vmx pt hwp \n[00018.791] 00000.00000> initializing platform\n[00018.791] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001101f4 (x86_perfmon) at level 0x90000, flags 0x1\n[00018.791] 00000.00000> PMU: version 4\n[00018.791] 00000.00000> UART: started IRQ driven RX\n[00018.791] 00000.00000> UART: started IRQ driven TX\n[00018.791] 00000.00000> cpu topology:\n[00018.791] 00000.00000> \t0: apic id 0x0 package 0 node 0 core 0 smt 0 BSP\n[00018.791] 00000.00000> \t1: apic id 0x2 package 0 node 0 core 1 smt 0\n[00018.791] 00000.00000> \t2: apic id 0x1 package 0 node 0 core 0 smt 1\n[00018.791] 00000.00000> \t3: apic id 0x3 package 0 node 0 core 1 smt 1\n[00018.791] 00000.00000> Found 4 cpus\n[00018.791] 00000.00000> booting apic ids: 0x2 0x1 0x3 \n[00018.807] 00000.00000> entering scheduler on cpu 2\n[00018.808] 00000.00000> entering scheduler on cpu 3\n[00018.808] 00000.00000> entering scheduler on cpu 1\n[00018.811] 00000.00000> smbios: manufacturer=\"Intel Corporation\" product=\"NUC7i5DNHE\"\n[00018.811] 00000.00000> initializing target\n[00018.811] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001053a0 (x86_pcie_init) at level 0xa0000, flags 0x1\n[00018.811] 00000.00000> moving to last init level\n[00018.811] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00144140 (ktrace) at level 0xc0000, flags 0x1\n[00018.814] 00000.00000> ktrace: buffer at 0xffffff9412202000 (33554432 bytes)\n[00018.814] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00147ab4 (userboot) at level 0xc0000, flags 0x1\n[00018.815] 00000.00000> userboot: ramdisk 0x10ad000 @ 0xffffff80012f2000\n[00018.815] 00000.00000> userboot: userboot rodata 0 @ [0x7f01d5f6b000,0x7f01d5f6e000)\n[00018.815] 00000.00000> userboot: userboot code 0x3000 @ [0x7f01d5f6e000,0x7f01d5f79000)\n[00018.815] 00000.00000> userboot: vdso/full rodata 0 @ [0x7f01d5f79000,0x7f01d5f80000)\n[00018.815] 00000.00000> userboot: vdso/full code 0x7000 @ [0x7f01d5f80000,0x7f01d5f81000)\n[00018.815] 00000.00000> userboot: entry point @ 0x7f01d5f6ec10\n[00018.815] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001aa030 (kernel_shell) at level 0xc0000, flags 0x1\n[00018.815] 01036.01043> userboot: option \"netsvc.disable=true\"\n[00018.815] 01036.01043> userboot: option \"zircon.system.disable-automount=true\"\n[00018.815] 01036.01043> userboot: option \"zircon.autorun.boot=/boot/infra/setup\"\n[00018.815] 01036.01043> userboot: option \"kernel.serial=legacy\"\n[00018.815] 01036.01043> userboot: option \"http://192.168.42.1/multiboot/d4%3A5d%3Adf%3A1a%3A42%3A46=\"\n[00018.815] 01036.01043> userboot: option \"multiboot.boot_loader_name=iPXE+1.0.0++(d2063)\"\n[00018.854] 01036.01043> userboot: searching bootfs for 'bin/bootsvc'\n[00018.854] 01036.01043> userboot: 'bin/bootsvc' has PT_INTERP \"lib/ld.so.1\"\n[00018.854] 01036.01043> userboot: searching bootfs for 'lib/ld.so.1'\n[00018.854] 01036.01043> userboot: userboot: loaded lib/ld.so.1 at 0x598799509000, entry point 0x59879958f0d3\n[00018.854] 01036.01043> userboot: userboot: loaded vDSO at 0x488aa5225000, entry point 0x0\n[00018.854] 01036.01043> userboot: process bin/bootsvc started.\n[00018.854] 01036.01043> userboot: waiting for loader-service requests...\n[00018.854] 01036.01043> userboot: searching bootfs for 'lib/libasync-default.so'\n[00018.854] 01036.01043> userboot: searching bootfs for 'lib/libfdio.so'\n[00018.854] 01036.01043> userboot: searching bootfs for 'lib/liblaunchpad.so'\n[00018.854] 01036.01043> userboot: searching bootfs for 'lib/libtrace-engine.so'\n[00018.854] 01050.01053> {{{reset}}}\n[00018.854] 01050.01053> {{{module:0:<application>:elf:18de7d58f51e8ba5c4f6afdd0cc8440f482c2b45}}}\n[00018.854] 01050.01053> {{{mmap:0x726263a61000:0x2e000:load:0:rx:0}}}\n[00018.854] 01050.01053> {{{mmap:0x726263a8f000:0x4000:load:0:rw:0x2e000}}}\n[00018.854] 01050.01053> dso: id=18de7d58f51e8ba5c4f6afdd0cc8440f482c2b45 base=0x0000726263a61000 name=<application>\n[00018.854] 01050.01053> {{{module:0x3:libasync-default.so:elf:c189f51f19d10785d93b5235ad1b46dca4451b00}}}\n[00018.854] 01050.01053> {{{mmap:0x7f4d940d7000:0x1000:load:0x3:rx:0}}}\n[00018.854] 01050.01053> {{{mmap:0x7f4d940d8000:0x1000:load:0x3:rw:0x1000}}}\n[00018.854] 01050.01053> dso: id=c189f51f19d10785d93b5235ad1b46dca4451b00 base=0x00007f4d940d7000 name=libasync-default.so\n[00018.854] 01050.01053> {{{module:0x2:<vDSO>:elf:40ab5fa3b61f12fef4a44477a687f608661cd450}}}\n[00018.854] 01050.01053> {{{mmap:0x488aa5225000:0x7000:load:0x2:r:0}}}\n[00018.854] 01050.01053> {{{mmap:0x488aa522c000:0x1000:load:0x2:rx:0x7000}}}\n[00018.854] 01050.01053> dso: id=40ab5fa3b61f12fef4a44477a687f608661cd450 base=0x0000488aa5225000 name=<vDSO>\n[00018.854] 01050.01053> {{{module:0x1:libc.so:elf:67a12284bce140fc32aaaeac223e0e8f85cb5085}}}\n[00018.854] 01050.01053> {{{mmap:0x598799509000:0xcb000:load:0x1:rx:0}}}\n[00018.854] 01050.01053> {{{mmap:0x5987995d5000:0x6000:load:0x1:rw:0xcc000}}}\n[00018.854] 01050.01053> dso: id=67a12284bce140fc32aaaeac223e0e8f85cb5085 base=0x0000598799509000 name=libc.so\n[00018.854] 01050.01053> {{{module:0x4:libfdio.so:elf:27787977e95f3b9563c9e0bd4d34ec92b7e9ad88}}}\n[00018.854] 01050.01053> {{{mmap:0x55f0deb28000:0x24000:load:0x4:rx:0}}}\n[00018.854] 01050.01053> {{{mmap:0x55f0deb4d000:0x4000:load:0x4:rw:0x25000}}}\n[00018.854] 01050.01053> dso: id=27787977e95f3b9563c9e0bd4d34ec92b7e9ad88 base=0x000055f0deb28000 name=libfdio.so\n[00018.854] 01050.01053> {{{module:0x5:liblaunchpad.so:elf:6fc2232dc8c1bc5c6adb8ad50ca794b5a260ee4f}}}\n[00018.854] 01050.01053> {{{mmap:0x5ecdf669d000:0x7000:load:0x5:rx:0}}}\n[00018.854] 01050.01053> {{{mmap:0x5ecdf66a4000:0x2000:load:0x5:rw:0x7000}}}\n[00018.854] 01050.01053> dso: id=6fc2232dc8c1bc5c6adb8ad50ca794b5a260ee4f base=0x00005ecdf669d000 name=liblaunchpad.so\n[00018.854] 01050.01053> {{{module:0x6:libtrace-engine.so:elf:c9042b83fe01206008fcefb16395238417248169}}}\n[00018.854] 01050.01053> {{{mmap:0x44a84c9a0000:0x9000:load:0x6:rx:0}}}\n[00018.854] 01050.01053> {{{mmap:0x44a84c9a9000:0x2000:load:0x6:rw:0x9000}}}\n[00018.854] 01050.01053> dso: id=c9042b83fe01206008fcefb16395238417248169 base=0x000044a84c9a0000 name=libtrace-engine.so\n[00018.855] 01050.01053> bootsvc: Starting...\n[00018.855] 01036.01043> userboot: loader-service channel peer closed\n[00018.855] 01036.01043> userboot: finished!\n[00018.855] 01050.01053> bootsvc: Creating bootfs service...\n[00018.855] 01050.01053> bootsvc: Processing bootdata...\n[00018.855] 01050.01053> bootsvc: Loading boot cmdline overrides...\n[00018.855] 01050.01053> bootsvc: Loading kernel VMOs...\n[00018.855] 01050.01053> bootsvc: Creating loader service...\n[00018.855] 01050.01053> bootsvc: Launching next process...\n[00018.855] 01050.01096> bootsvc: launched bin/devmgr\n[00018.856] 01104.01117> devmgr: main()\n[00018.856] 01104.01117> cmdline: netsvc.disable=true\n[00018.856] 01104.01117> cmdline: zircon.system.disable-automount=true\n[00018.856] 01104.01117> cmdline: zircon.autorun.boot=/boot/infra/setup\n[00018.856] 01104.01117> cmdline: kernel.serial=legacy\n[00018.856] 01104.01117> cmdline: http://192.168.42.1/multiboot/d4%3A5d%3Adf%3A1a%3A42%3A46=\n[00018.856] 01104.01117> cmdline: multiboot.boot_loader_name=iPXE+1.0.0++(d2063)\n[00018.856] 01104.01117> devmgr: coordinator_init()\n[00018.856] 01104.01117> devmgr: init\n[00018.856] 01104.01117> devmgr: svc init\n[00018.856] 01104.01117> devmgr: launch /boot/bin/svchost (svchost) OK\n[00018.856] 01104.01117> devmgr: vfs init\n[00018.856] 01104.01117> devmgr: launch /boot/bin/fshost (fshost) OK\n[00018.857] 01104.01221> devmgr: shell startup\n[00018.858] 01182.01202> fshost: started.\n[00018.859] 01104.01212> devmgr: launch /boot/bin/pwrbtn-monitor (pwrbtn-monitor) OK\n[00018.859] 01104.01235> devmgr: launch /boot/bin/virtual-console (virtual-console) OK\n[00018.859] 01104.01235> zircon.autorun.boot: starting '/boot/infra/setup'...\n[00018.860] 01104.01235> devmgr: launch /boot/infra/setup (autorun:boot) OK\n[00018.862] 01104.01117> devcoord: launch devhost 'devhost:sys': pid=2049\n[00018.863] 01104.01117> devcoord: launch devhost 'devhost:test': pid=2082\n[00018.863] 01104.01117> devcoord: launch devhost 'devhost:root': pid=2117\n[00018.863] 01104.01117> devcoord: launch devhost 'devhost:misc': pid=2168\n[00018.864] 02049.02073> devhost: trace provider registry begun\n[00018.865] 02082.02107> devhost: trace provider registry begun\n[00018.867] 02168.02203> devhost: trace provider registry begun\n[00018.870] 01104.01221> devmgr: launch /boot/bin/sh (sh:console) OK\n[00018.876] 02117.02152> devhost: trace provider registry begun\n[00018.936] 02049.02073> acpi-bus: not using IOMMU\n[00018.940] 02049.02073> acpi-pwrbtn: initialized\n[00018.946] 02049.02073> WARNING: ACPI found bad _CRS address entry\n[00018.952] 02049.02073> WARNING: ACPI found bad _CRS address entry\n[00018.963] 02049.02073> acpi: published device pci(0x31ffcb907200), parent=proxy(0x31ffcb903540), handle=0x31ffcb93f750\n[00018.964] 02049.02073> acpi: published device rtc(0x31ffcb902080), parent=acpi(0x31ffcb903780), handle=0x31ffcb95e330\n[00018.965] 02049.02073> acpi-ec: initialized\n[00018.967] 02049.02073> acpi: failed to create NHLT VMO (res -10)\n[00018.967] 02049.02073> acpi: failed to publish NHLT metadata\n[00018.969] 02049.02073> [sysmem_init 28] async_get_default_dispatcher(): 0x31ffcb8f60a0\n[00018.969] 02049.02073> [sysmem_bind 40] sysmem_bind()\n[00018.969] 02049.02073> acpi-bus: sysmem_register_protocol() intentionally ignored for now.\n[00018.969] 02049.02073> PMU: 7 arch events\n[00018.969] 02049.02073> PMU: arch event id range: 1-7\n[00018.969] 02049.02073> PMU: 102 model events\n[00018.969] 02049.02073> PMU: model event id range: 1-241\n[00018.969] 02049.02073> Intel Processor Trace configuration for this chipset:\n[00018.969] 02049.02073> mtc_freq_mask: 0x249\n[00018.969] 02049.02073> cyc_thresh_mask: 0x3fff\n[00018.969] 02049.02073> psb_freq_mask: 0x3f\n[00018.969] 02049.02073> num addr ranges: 2\n[00018.970] 01104.01117> devcoord: launch devhost 'devhost:pci#1:8086:5916': pid=2935\n[00018.971] 01104.01117> devcoord: launch devhost 'devhost:pci#3:8086:9d2f': pid=3009\n[00018.972] 01254.01544> vc: new input device /dev/class/input/000\n[00018.973] 01104.01117> devcoord: launch devhost 'devhost:pci#5:8086:9d60': pid=3126\n[00018.973] 02935.02979> devhost: trace provider registry begun\n[00018.973] 01104.01117> devcoord: launch devhost 'devhost:pci#6:8086:9d61': pid=3214\n[00018.974] 03009.03085> devhost: trace provider registry begun\n[00018.975] 01104.01117> devcoord: launch devhost 'devhost:pci#9:8086:9d03': pid=3306\n[00018.976] 03126.03192> devhost: trace provider registry begun\n[00018.976] 01104.01117> devcoord: launch devhost 'devhost:pci#14:8086:9d71': pid=3425\n[00018.976] 01104.01117> devcoord: launch devhost 'devhost:pci#16:8086:156f': pid=3527\n[00018.976] 03214.03272> devhost: trace provider registry begun\n[00018.977] 03306.03374> devhost: trace provider registry begun\n[00018.978] 03306.03374> ahci: using MSI interrupt\n[00018.979] 03214.03272> initialized intel serialio i2c driver, reg=0x372850704000 regsize=4096\n[00018.979] 03126.03192> initialized intel serialio i2c driver, reg=0x69b336dd5000 regsize=4096\n[00018.979] 03527.03576> devhost: trace provider registry begun\n[00018.979] 03425.03490> devhost: trace provider registry begun\n[00018.984] 03009.03858> * found USB device (0x18d1:0xa002, USB 2.0) config 1\n[00018.991] 03009.03858> * found USB device (0x18d1:0xa003, USB 3.0) config 1\n[00019.001] 03425.03490> [IHDA DSP 00:1f.3] Failed to fetch NHLT (res -25)\n[00019.001] 03425.03490> devhost[00:1f.3/intel-hda-000/intel-sst-dsp-000] bind driver '/boot/driver/dsp.so' failed: -25\n[00019.001] 01104.01117> devcoord: rpc: bind-driver 'intel-sst-dsp-000' status -25\n[00019.082] 01254.01544> vc: new display device /dev/class/display-controller/000/virtcon\n[00019.222] 02935.04139> i915: Found DP monitor\n[00019.222] 02935.04139> i915: Display 1 connected\n[00019.222] 02935.04139> i915: MST not supported\n[00019.713] 03009.03858> * found USB device (0x0781:0x5583, USB 3.0) config 1\n[00019.714] 03009.03085> UMS: parent: 'ifc-000'\n[00019.714] 03009.03085> UMS:Max lun is: 0\n[00020.290] 01254.01544> vc: Successfully attached to display 1\n[00020.466] 03009.03858> * found USB device (0x046d:0xc31c, USB 1.10) config 1\n[00020.470] 01254.01544> vc: new input device /dev/class/input/001\n[00020.475] 01254.01544> vc: new input device /dev/class/input/002\n[00020.713] 03009.03858> * found USB device (0x0cf3:0xe300, USB 2.1) config 1\n[00020.715] 03009.03085> devhost[00:14.0/xhci/usb-bus/003/003/ifc-001] bind driver '/boot/driver/bt-transport-usb.so' failed: -2\n[00020.715] 01104.01117> devcoord: rpc: bind-driver 'ifc-001' status -2\n[00020.717] 03009.04226> UMS: block size is: 0x00000200\n[00020.717] 03009.04226> UMS: total blocks is: 30031250\n[00020.717] 03009.04226> UMS: total size is: 15376000000\n[00020.717] 03009.04226> UMS: read-only: 0 removable: 1\n[00020.718] 01182.01202> devmgr: /dev/class/block/000: MBR?\n[00020.718] 01104.01117> devcoord: dc_bind_device() '/boot/driver/mbr.so'\n[00020.720] 01182.01202> devmgr: /dev/class/block/002: MBR?\n[00020.720] 01104.01117> devcoord: dc_bind_device() '/boot/driver/mbr.so'\n[00020.982] 03214.03272> intel-i2c-controller: intel_serialio_i2c_slave_transfer: -21\n[00020.982] 03214.03272> i2c-hid: could not read HID descriptor: 0\n[00020.982] 03214.03272> devhost[00:15.1/i2c-bus-9d61/004c] bind driver '/boot/driver/i2c-hid.so' failed: -2\n[00020.982] 01104.01117> devcoord: rpc: bind-driver '004c' status -2\n[00020.982] 03126.03192> intel-i2c-controller: intel_serialio_i2c_slave_transfer: -21\n[00020.982] 03126.03192> i2c-hid: could not read HID descriptor: 0\n[00020.982] 03126.03192> devhost[00:15.0/i2c-bus-9d60/0020] bind driver '/boot/driver/i2c-hid.so' failed: -2\n[00020.982] 01104.01117> devcoord: rpc: bind-driver '0020' status -2\n[00020.984] 03306.03759> sata2: dev info\n[00020.984] 03306.03759> serial=711430952084 9 \n[00020.984] 03306.03759> firmware rev=1Q01A7 0\n[00020.984] 03306.03759> model id=IBIW NSS D \n[00020.984] 03306.03759> major=0x3f0 ACS2 DMA 32 commands\n[00020.984] 03306.03759> LBA48 234441648 sectors, sector size=512\n[00020.986] 04586.04600> paver:[InitializeGpt] Located GPT is invalid; Attempting to initialize\n[00020.987] 01104.01117> devcoord: dc_bind_device() '/boot/driver/gpt.so'\n[00020.987] 03306.03374> gpt: device_get_metadata failed (-25)\n[00020.988] 04586.04600> paver:[Initialize] Successfully initialized EFI Device Partitioner\n[00020.988] 01104.01117> devcoord: dc_bind_device() ''\n[00020.988] 01182.01202> devmgr: /dev/class/block/003: GPT?\n[00020.988] 01104.01117> devcoord: dc_bind_device() '/boot/driver/gpt.so'\n[00020.988] 03306.03374> gpt: device_get_metadata failed (-25)\n[00020.991] 04744.04768> 34+0 records in\n[00020.991] 04744.04768> 34+0 records out\n[00020.991] 04744.04768> 17408 bytes copied\n[00020.992] 04859.04873> Failed to seek on output\n[00020.992] 04859.04873> 0+0 records in\n[00020.992] 04859.04873> 0+0 records out\n[00020.992] 04859.04873> 0 bytes copied\n[00020.993] 04929.04943> netifc: ? /dev/class/ethernet/000\n[00020.993] 04929.04943> netifc: create 512 eth buffers\n[00020.993] 04929.04943> macaddr: d4:5d:df:1a:42:46\n[00020.993] 04929.04943> ip6addr: fe80::d65d:df4d:fe1a:4246\n[00020.993] 04929.04943> snmaddr: ff02::1:ff1a:4246\n[00020.993] 04929.04943> netsvc: using /dev/class/ethernet/000\n[00020.993] 04929.04943> _ _ _ \n[00020.993] 04929.04943> | | | | | \n[00020.993] 04929.04943> _______ __| | |__ ___ ___ | |_ \n[00020.993] 04929.04943> |_ / _ \\/ _` | '_ \\ / _ \\ / _ \\| __|\n[00020.993] 04929.04943> / / __/ (_| | |_) | (_) | (_) | |_ \n[00020.993] 04929.04943> /___\\___|\\__,_|_.__/ \\___/ \\___/ \\__|\n[00020.993] 04929.04943> \n[00020.993] 04929.04943> \n[00020.993] 04929.04943> zedboot: version: 0.7.13\n[00020.993] 04929.04943> \n[00020.993] 04929.04943> netsvc: nodename='ocean-drank-wick-spot'\n[00020.993] 04929.04943> netsvc: will not advertise\n[00020.993] 04929.04943> netsvc: start\n[01029.637] 04929.04943> netsvc: tftp write of file <<netboot>>cmdline completed\n[01029.637] 04929.04943> netsvc: metrics: {\"inorderblks\": 1,\"oooblks\": 0,\"ack\": 1,\"nack\": 0,\"timeouts\": 0,\"sas\": 0,\"inorderbytes\": 55}\n[01029.637] 04929.04943> netsvc: Running FVM Paver\n[01029.639] 04929.05037> paver:[InitializeGpt] Located GPT is invalid; Attempting to initialize\n[01029.640] 01104.01117> devcoord: dc_bind_device() ''\n[01029.640] 01104.01117> devcoord: dc_bind_device() '/boot/driver/gpt.so'\n[01029.640] 03306.03374> gpt: device_get_metadata failed (-25)\n[01029.640] 01182.01202> devmgr: /dev/class/block/003: GPT?\n[01029.640] 01104.01117> devcoord: dc_bind_device() '/boot/driver/gpt.so'\n[01029.640] 03306.03374> devhost: rpc:bind-device failed: -20\n[01029.641] 04929.05037> paver:[Initialize] Successfully initialized EFI Device Partitioner\n[01029.641] 04929.05037> paver:[PartitionPave] Paving partition.\n[01029.641] 04929.05037> paver:[FindFirstFit] Looking for space\n[01029.641] 04929.05037> paver:[FindFirstFit] Sorting\n[01029.641] 04929.05037> paver:[FindFirstFit] Partition[0] From Block [0, 34) ... (next partition starts at block 234441614)\n[01029.641] 04929.05037> paver:[FindFirstFit] There are 234441580 free blocks (16777216 requested)\n[01029.641] 04929.05037> paver:[AddPartition] Found space in GPT - OK 234441580 @ 34\n[01029.641] 04929.05037> paver:[AddPartition] Final space in GPT - OK 16777216 @ 34\n[01029.642] 04929.05037> paver:[AddPartition] Added partition, waiting for bind\n[01029.642] 01104.01117> devcoord: dc_bind_device() ''\n[01029.642] 01182.01202> devmgr: /dev/class/block/003: GPT?\n[01029.642] 01104.01117> devcoord: dc_bind_device() '/boot/driver/gpt.so'\n[01029.642] 03306.03374> gpt: device_get_metadata failed (-25)\n[01029.643] 04929.05037> paver:[AddPartition] Added partition, waiting for bind - OK\n[01029.643] 04929.05037> paver:[PartitionPave] Streaming partitions...\n[01029.643] 05017.05031> Found compressed file\n[01029.643] 04929.05037> paver:[FvmStreamPartitions] Header Validated - OK\n[01029.644] 04929.05037> paver:[FvmPartitionFormat] Initializing partition as FVM\n[01029.646] 01104.01117> devcoord: dc_bind_device() '/boot/driver/fvm.so'\n[01030.648] 04929.05037> paver:[FvmStreamPartitions] Partitions pre-validated successfully: Enough space exists to pave.\n[01030.648] 04929.05037> paver:[AllocatePartitions] Allocating partition blobfs consisting of 1 slices\n[01030.651] 04929.05037> paver:[AllocatePartitions] Allocating partition minfs consisting of 1 slices\n[01030.652] 04929.05037> paver:[AllocatePartitions] Creating zxcrypt volume\n[01030.654] 03306.03374> devhost_get_handles(0x138a0def59c0:block) open path='zxcrypt/block', r=-2\n[01030.654] 01104.01117> devcoord: dc_bind_device() '/boot/driver/zxcrypt.so'\n[01030.654] 03306.03374> devhost_get_handles(0x138a0def59c0:block) open path='zxcrypt', r=-2\n[01030.658] 04929.05037> paver:[FvmStreamPartitions] Partition space pre-allocated successfully.\n[01030.659] 04929.05037> paver:[FvmStreamPartitions] Streaming partition 0\n[01030.659] 04929.05037> paver:[StreamFvmPartition] Writing extent 0... \n[01030.659] 04929.05043> netsvc: paver write progress 0.2%\n[01030.659] 04929.05037> paver:[StreamFvmPartition] 8192 bytes written, 8380416 zeroes left\n[01030.676] 04929.05037> paver:[StreamFvmPartition] Writing extent 1... \n[01030.676] 04929.05037> paver:[StreamFvmPartition] 8192 bytes written, 8380416 zeroes left\n[01030.701] 04929.05037> paver:[StreamFvmPartition] Writing extent 2... \n[01030.706] 04929.05037> paver:[StreamFvmPartition] 2097152 bytes written, 6291456 zeroes left\n[01030.718] 04929.05037> paver:[StreamFvmPartition] Writing extent 3... \n[01030.723] 04929.05037> paver:[StreamFvmPartition] 2097152 bytes written, 6291456 zeroes left\n[01030.738] 04929.05037> paver:[StreamFvmPartition] Writing extent 4... \n[01031.382] 04929.04943> netsvc: tftp write of file <<image>>sparse.fvm completed\n[01031.382] 04929.04943> netsvc: metrics: {\"inorderblks\": 165215,\"oooblks\": 0,\"ack\": 646,\"nack\": 0,\"timeouts\": 0,\"sas\": 0,\"inorderbytes\": 169179806}\n[01031.384] 04929.05037> paver:[StreamFvmPartition] 182378496 bytes written, 2170880 zeroes left\n[01031.388] 04929.05037> paver:[FvmStreamPartitions] Done streaming partition 0\n[01031.388] 04929.05037> paver:[FvmStreamPartitions] Done flushing partition 0\n[01031.388] 04929.05037> paver:[FvmStreamPartitions] Streaming partition 1\n[01031.388] 04929.05037> paver:[StreamFvmPartition] Writing extent 0... \n[01031.389] 04929.05037> paver:[StreamFvmPartition] 8192 bytes written, 8380416 zeroes left\n[01031.427] 04929.05037> paver:[StreamFvmPartition] Writing extent 1... \n[01031.428] 04929.05037> paver:[StreamFvmPartition] 65536 bytes written, 8323072 zeroes left\n[01031.469] 04929.05037> paver:[StreamFvmPartition] Writing extent 2... \n[01031.469] 04929.05037> paver:[StreamFvmPartition] 65536 bytes written, 8323072 zeroes left\n[01031.505] 04929.05037> paver:[StreamFvmPartition] Writing extent 3... \n[01031.547] 04929.05037> paver:[StreamFvmPartition] Writing extent 4... \n[01031.556] 04929.05037> paver:[StreamFvmPartition] 2097152 bytes written, 14680064 zeroes left\n[01031.622] 04929.05037> paver:[StreamFvmPartition] Writing extent 5... \n[01031.622] 04929.05037> paver:[StreamFvmPartition] 57344 bytes written, 8331264 zeroes left\n[01031.658] 04929.05037> paver:[FvmStreamPartitions] Done streaming partition 1\n[01031.658] 04929.05037> paver:[FvmStreamPartitions] Done flushing partition 1\n[01031.659] 05017.05031> Reading FVM from compressed file: true\n[01031.659] 05017.05031> Remaining bytes read into compression buffer: 0\n[01031.659] 05017.05031> Remaining bytes written to decompression buffer: 0\n[01031.659] 05017.05031> Time reading bytes from sparse FVM file: 647045742 (0 s)\n[01031.659] 05017.05031> Time reading bytes AND decompressing them: 744545233 (0 s)\n[01031.659] 04929.05037> paver:[PartitionPave] Completed successfully\n[01032.383] 04929.04943> netsvc: Running BOOTLOADER Paver\n[01032.386] 04929.05773> paver:[Initialize] Successfully initialized EFI Device Partitioner\n[01032.386] 04929.05773> paver:[RealMain] SKIPPING BOOTLOADER install on x64 device, pass --force if desired.\n[01032.403] 04929.04943> netsvc: tftp write of file <<image>>bootloader.img completed\n[01032.403] 04929.04943> netsvc: metrics: {\"inorderblks\": 1093,\"oooblks\": 0,\"ack\": 5,\"nack\": 0,\"timeouts\": 0,\"sas\": 0,\"inorderbytes\": 1118208}\n[01032.403] 04929.04943> netsvc: Running ZIRCON-A Paver\n[01032.406] 04929.05914> paver:[Initialize] Successfully initialized EFI Device Partitioner\n[01032.406] 04929.05914> paver:[PartitionPave] Paving partition.\n[01032.406] 04929.05914> paver:[FindFirstFit] Looking for space\n[01032.406] 04929.05914> paver:[FindFirstFit] Partition seen with start 34, end 16777249 (length 16777216)\n[01032.406] 04929.05914> paver:[FindFirstFit] Sorting\n[01032.406] 04929.05914> paver:[FindFirstFit] Partition[0] From Block [0, 34) ... (next partition starts at block 34)\n[01032.406] 04929.05914> paver:[FindFirstFit] There are 0 free blocks (32768 requested)\n[01032.406] 04929.05914> paver:[FindFirstFit] Partition[1] From Block [34, 16777250) ... (next partition starts at block 234441614)\n[01032.406] 04929.05914> paver:[FindFirstFit] There are 217664364 free blocks (32768 requested)\n[01032.406] 04929.05914> paver:[AddPartition] Found space in GPT - OK 217664364 @ 16777250\n[01032.406] 04929.05914> paver:[AddPartition] Final space in GPT - OK 32768 @ 16777250\n[01032.407] 04929.05914> paver:[AddPartition] Added partition, waiting for bind\n[01032.407] 03306.03374> block: Joining un-closed FIFO server\n[01032.407] 03306.03374> block: Joining un-closed FIFO server\n[01032.407] 01104.01117> devcoord: dc_bind_device() ''\n[01032.408] 01182.01202> devmgr: /dev/class/block/003: GPT?\n[01032.408] 01104.01117> devcoord: dc_bind_device() '/boot/driver/gpt.so'\n[01032.408] 03306.03374> gpt: device_get_metadata failed (-25)\n[01032.409] 01182.01202> devmgr: /dev/class/block/008: FVM?\n[01032.409] 01104.01117> devcoord: dc_bind_device() '/boot/driver/fvm.so'\n[01032.409] 04929.05914> paver:[AddPartition] Added partition, waiting for bind - OK\n[01032.555] 04929.04943> netsvc: tftp write of file <<image>>zircona.img completed\n[01032.555] 04929.04943> netsvc: metrics: {\"inorderblks\": 13900,\"oooblks\": 0,\"ack\": 55,\"nack\": 0,\"timeouts\": 0,\"sas\": 0,\"inorderbytes\": 14233280}\n[01032.591] 04929.05914> paver:[PartitionPave] Completed successfully\n2019/04/20 02:21:43 attempting to send <<image>>zirconr.img...\n[01033.556] 04929.04943> netsvc: Running ZIRCON-R Paver\n[01033.559] 04929.06187> paver:[Initialize] Successfully initialized EFI Device Partitioner\n[01033.559] 04929.06187> paver:[PartitionPave] Paving partition.\n[01033.559] 04929.06187> paver:[FindFirstFit] Looking for space\n[01033.559] 04929.06187> paver:[FindFirstFit] Partition seen with start 34, end 16777249 (length 16777216)\n[01033.559] 04929.06187> paver:[FindFirstFit] Partition seen with start 16777250, end 16810017 (length 32768)\n[01033.559] 04929.06187> paver:[FindFirstFit] Sorting\n[01033.559] 04929.06187> paver:[FindFirstFit] Partition[0] From Block [0, 34) ... (next partition starts at block 34)\n[01033.559] 04929.06187> paver:[FindFirstFit] There are 0 free blocks (49152 requested)\n[01033.559] 04929.06187> paver:[FindFirstFit] Partition[1] From Block [34, 16777250) ... (next partition starts at block 16777250)\n[01033.559] 04929.06187> paver:[FindFirstFit] There are 0 free blocks (49152 requested)\n[01033.559] 04929.06187> paver:[FindFirstFit] Partition[2] From Block [16777250, 16810018) ... (next partition starts at block 234441614)\n[01033.559] 04929.06187> paver:[FindFirstFit] There are 217631596 free blocks (49152 requested)\n[01033.559] 04929.06187> paver:[AddPartition] Found space in GPT - OK 217631596 @ 16810018\n[01033.559] 04929.06187> paver:[AddPartition] Final space in GPT - OK 49152 @ 16810018\n[01033.559] 04929.06187> paver:[AddPartition] Added partition, waiting for bind\n[01033.559] 03306.03374> block: Joining un-closed FIFO server\n[01033.560] 01104.01117> devcoord: dc_bind_device() ''\n[01033.560] 01182.01202> devmgr: /dev/class/block/003: GPT?\n[01033.560] 01104.01117> devcoord: dc_bind_device() '/boot/driver/gpt.so'\n[01033.560] 03306.03374> gpt: device_get_metadata failed (-25)\n[01033.561] 01182.01202> devmgr: /dev/class/block/012: FVM?\n[01033.561] 01104.01117> devcoord: dc_bind_device() '/boot/driver/fvm.so'\n[01033.561] 04929.06187> paver:[AddPartition] Added partition, waiting for bind - OK\n2019/04/20 02:21:43 done\n2019/04/20 02:21:43 attempting to send <<image>>authorized_keys...\n2019/04/20 02:21:43 target is busy, retrying in one second\n[01033.705] 04929.04943> netsvc: tftp write of file <<image>>zirconr.img completed\n[01033.705] 04929.04943> netsvc: metrics: {\"inorderblks\": 12589,\"oooblks\": 0,\"ack\": 50,\"nack\": 0,\"timeouts\": 0,\"sas\": 0,\"inorderbytes\": 12890296}\n[01033.731] 04929.06187> paver:[PartitionPave] Completed successfully\n2019/04/20 02:21:44 attempting to send <<image>>authorized_keys...\n2019/04/20 02:21:44 done\n2019/04/20 02:21:44 attempting to send <<netboot>>kernel.bin...\n2019/04/20 02:21:44 target is busy, retrying in one second\n[01034.706] 04929.04943> netsvc: Installing SSH authorized_keys\n[01034.706] 04929.04943> netsvc: tftp write of file <<image>>authorized_keys completed\n[01034.706] 04929.04943> netsvc: metrics: {\"inorderblks\": 1,\"oooblks\": 0,\"ack\": 1,\"nack\": 0,\"timeouts\": 0,\"sas\": 0,\"inorderbytes\": 806}\n[01034.709] 04929.06477> paver:[Initialize] Successfully initialized EFI Device Partitioner\n[01034.709] 01104.01117> devcoord: dc_bind_device() '/boot/driver/zxcrypt.so'\n[01034.709] 03306.03374> devhost_get_handles(0x138a0def59c0:block) open path='zxcrypt', r=-2\n[01034.738] 04929.06477> paver:[DataFilePave] Wrote ssh/authorized_keys\n2019/04/20 02:21:45 attempting to send <<netboot>>kernel.bin...\n2019/04/20 02:21:45 done\n[00000.000] 00000.00000> zbi: @ 0xffffff8006704000 (12706992 bytes)\n[00000.000] 00000.00000> UART: FIFO depth 16\n[00000.000] 00000.00000> PMM: boot reserve add [0x100000, 0x2fffff]\n[00000.000] 00000.00000> PMM: boot reserve add [0x6704000, 0x7322fff]\n[00000.000] 00000.00000> PMM: arena too small to be useful (size 4096)\n[00000.000] 00000.00000> PMM: pmm_add_arena failed to initialize arena\n[00000.000] 00000.00000> MEM: Failed to add pmm range at 0x7ffff000 size 0x1000\n[00000.000] 00000.00000> PMM: boot reserve marking WIRED [0x100000, 0x2fffff]\n[00000.000] 00000.00000> PMM: boot reserve marking WIRED [0x6704000, 0x7322fff]\n[00000.000] 00000.00000> \n[00000.000] 00000.00000> welcome to Zircon\n[00000.000] 00000.00000> \n[00000.000] 00000.00000> KASLR: .text section at 0xffffffff00100000\n[00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00114ec4 (global_prng_seed) at level 0x30000, flags 0x1\n[00000.000] 00000.00000> initializing vm pre-heap\n[00000.000] 00000.00000> VM: marking boot alloc used range [0x7323000, 0x7323100)\n[00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00122274 (elf_build_id) at level 0x4fffe, flags 0x1\n[00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00122400 (version) at level 0x4ffff, flags 0x1\n[00000.000] 00000.00000> version:\n[00000.000] 00000.00000> \tarch: x86\n[00000.000] 00000.00000> \tplatform: XXX\n[00000.000] 00000.00000> \ttarget: XXX\n[00000.000] 00000.00000> \tproject: x64\n[00000.000] 00000.00000> \tbuildid: git-808b66d9daaaacd45b64fe427fa28a1682088ff2-dirty\n[00000.000] 00000.00000> \tELF build ID: 2859c540a88a474e99cb50cee029e57fd4582132\n[00000.000] 00000.00000> initializing heap\n[00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00101fc4 (console) at level 0x50000, flags 0x1\n[00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001826c4 (x86_resource_init) at level 0x50000, flags 0x1\n[00000.000] 00000.00000> initializing vm\n[00000.000] 00000.00000> VM: reserving kernel region [0xffffffff00100000, 0xffffffff0021b000) flags 0x28 name 'kernel_code'\n[00000.000] 00000.00000> VM: reserving kernel region [0xffffffff0021b000, 0xffffffff0026c000) flags 0x8 name 'kernel_rodata'\n[00000.000] 00000.00000> VM: reserving kernel region [0xffffffff0026c000, 0xffffffff00274000) flags 0x18 name 'kernel_data'\n[00000.000] 00000.00000> VM: reserving kernel region [0xffffffff00274000, 0xffffffff00300000) flags 0x18 name 'kernel_bss'\n[00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00183388 (display_memtype) at level 0x60001, flags 0x1\n[00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00186818 (acpi_tables) at level 0x60001, flags 0x1\n[00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001814c8 (hpet) at level 0x60002, flags 0x1\n[00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00181888 (apic) at level 0x60002, flags 0x1\n[00000.000] 00000.00000> x2APIC enabled\n[00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001a7e6c (system_topology_init) at level 0x60002, flags 0x1\n[00000.000] 00000.00000> WARNING: more than 1000 levels of cache, couldn't find highest.\n[00000.000] 00000.00000> WARNING: unable to find any cache levels.\n[00000.000] 00000.00000> Could not find SRAT table. ACPICA returned: 5\n[00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00185e08 (timer) at level 0x60003, flags 0x1\n[00000.000] 00000.00000> HPET frequency: 23999 ticks/ms\n[00000.000] 00000.00000> TSC frequency: 2712000 ticks/ms\n[01036.164] 00000.00000> timer features: constant_tsc 1 invariant_tsc 1 tsc_deadline 1\n[01036.164] 00000.00000> Using TSC as wallclock\n[01036.164] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00208930 (percpu_heap_init) at level 0x60003, flags 0x1\n[01036.164] 00000.00000> initializing kernel\n[01036.164] 00000.00000> initializing mp\n[01036.164] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00115118 (global_prng_thread_safe) at level 0x7ffff, flags 0x1\n[01036.164] 00000.00000> creating bootstrap completion thread\n[01036.164] 00000.00000> top of bootstrap2()\n[01036.164] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00131734 (libobject) at level 0x80000, flags 0x1\n[01036.164] 00000.00000> OOM: started thread\n[01036.164] 00000.00000> INIT: cpu 0, calling hook 0xffffffff0020589c (dpc) at level 0x80000, flags 0x1\n[01036.164] 00000.00000> Processor Model Info: type 0 family 0x6 model 0xe stepping 0x9\n[01036.164] 00000.00000> \tdisplay_family 0x6 display_model 0x8e\n[01036.164] 00000.00000> Vendor: Intel\n[01036.164] 00000.00000> Microarch: Kaby Lake\n[01036.164] 00000.00000> F/M/S: 6/8e/9\n[01036.164] 00000.00000> patch_level: 84\n[01036.164] 00000.00000> Brand: Intel(R) Core(TM) i5-7300U CPU @ 2.60GHz\n[01036.164] 00000.00000> Features: fpu sse sse2 sse3 ssse3 sse4.1 sse4.2 mmx avx avx2 fxsr pcid xsave mon aesni clflush \n[01036.164] 00000.00000> clflushopt fsgsbase tsc_adj smep smap erms rdrand rdseed syscall nx huge rdtscp \n[01036.164] 00000.00000> invar_tsc tsc_deadline x2apic vmx pt hwp \n[01036.164] 00000.00000> Properties: meltdown l1tf pcid_good \n[01036.164] 00000.00000> initializing platform\n[01036.164] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001a157c (x86_perfmon) at level 0x90000, flags 0x1\n[01036.164] 00000.00000> PMU: version 4\n[01036.164] 00000.00000> UART: started IRQ driven RX\n[01036.164] 00000.00000> UART: started IRQ driven TX\n[01036.164] 00000.00000> cpu topology:\n[01036.164] 00000.00000> \t0: apic id 0x0 BSP\n[01036.164] 00000.00000> \t1: apic id 0x1 \n[01036.164] 00000.00000> \t2: apic id 0x2 \n[01036.164] 00000.00000> \t3: apic id 0x3 \n[01036.164] 00000.00000> Found 4 cpus\n[01036.164] 00000.00000> booting apic ids: 0x1 0x2 0x3 \n[01036.179] 00000.00000> entering scheduler on cpu 1\n[01036.180] 00000.00000> entering scheduler on cpu 2\n[01036.180] 00000.00000> entering scheduler on cpu 3\n[01036.183] 00000.00000> smbios: manufacturer=\"Intel Corporation\" product=\"NUC7i5DNHE\"\n[01036.183] 00000.00000> initializing target\n[01036.183] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001850b8 (x86_pcie_init) at level 0xa0000, flags 0x1\n[01036.183] 00000.00000> moving to last init level\n[01036.183] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001214cc (debuglog) at level 0xb0000, flags 0x1\n[01036.183] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00174d40 (kcounters) at level 0xb0000, flags 0x1\n[01036.183] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00102aec (kernel_shell) at level 0xc0000, flags 0x1\n[01036.183] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00202ad4 (ktrace) at level 0xc0000, flags 0x1\n[01036.187] 00000.00000> ktrace: buffer at 0xffffff989a983000 (33554432 bytes)\n[01036.187] 00000.00000> INIT: cpu 0, calling hook 0xffffffff002184f8 (userboot) at level 0xc0000, flags 0x1\n[01036.187] 00000.00000> userboot: ramdisk 0xc1f000 @ 0xffffff8006704000\n[01036.187] 00000.00000> userboot: userboot rodata 0 @ [0x3e8b11ecb000,0x3e8b11ece000)\n[01036.187] 00000.00000> userboot: userboot code 0x3000 @ [0x3e8b11ece000,0x3e8b11ed9000)\n[01036.187] 00000.00000> userboot: vdso/full rodata 0 @ [0x3e8b11ed9000,0x3e8b11ee0000)\n[01036.187] 00000.00000> userboot: vdso/full code 0x7000 @ [0x3e8b11ee0000,0x3e8b11ee1000)\n[01036.187] 00000.00000> userboot: entry point @ 0x3e8b11ecec90\n[01036.187] 01036.01043> userboot: option \"zircon.autorun.system=/boot/bin/sh+/boot/infra/runcmds\"\n[01036.217] 01036.01043> userboot: searching bootfs for 'bin/bootsvc'\n[01036.217] 01036.01043> userboot: 'bin/bootsvc' has PT_INTERP \"lib/ld.so.1\"\n[01036.217] 01036.01043> userboot: searching bootfs for 'lib/ld.so.1'\n[01036.217] 01036.01043> userboot: userboot: loaded lib/ld.so.1 at 0x7c36141ce000, entry point 0x7c36141ec3d0\n[01036.217] 01036.01043> userboot: userboot: loaded vDSO at 0x568699edf000, entry point 0x568699ee68fa\n[01036.217] 01036.01043> userboot: process bin/bootsvc started.\n[01036.217] 01036.01043> userboot: waiting for loader-service requests...\n[01036.217] 01036.01043> userboot: searching bootfs for 'lib/libasync-default.so'\n[01036.217] 01036.01043> userboot: searching bootfs for 'lib/libfdio.so'\n[01036.217] 01036.01043> userboot: searching bootfs for 'lib/libtrace-engine.so'\n[01036.217] 01036.01043> userboot: searching bootfs for 'lib/liblaunchpad.so'\n[01036.218] 01050.01053> {{{reset}}}\n[01036.218] 01050.01053> {{{module:0:<application>:elf:367d3374904876b8f143f2ed5e4dfb0248fef748}}}\n[01036.218] 01050.01053> {{{mmap:0x78da5011c000:0x3a000:load:0:rx:0}}}\n[01036.218] 01050.01053> {{{mmap:0x78da50156000:0x4000:load:0:rw:0x3a000}}}\n[01036.218] 01050.01053> dso: id=367d3374904876b8f143f2ed5e4dfb0248fef748 base=0x000078da5011c000 name=<application>\n[01036.218] 01050.01053> {{{module:0x2:<vDSO>:elf:71712e0a455898a51e34d40a4013786ab1fa5335}}}\n[01036.218] 01050.01053> {{{mmap:0x568699edf000:0x7000:load:0x2:r:0}}}\n[01036.218] 01050.01053> {{{mmap:0x568699ee6000:0x1000:load:0x2:rx:0x7000}}}\n[01036.218] 01050.01053> dso: id=71712e0a455898a51e34d40a4013786ab1fa5335 base=0x0000568699edf000 name=<vDSO>\n[01036.218] 01050.01053> {{{module:0x3:libasync-default.so:elf:8c511b4202cf569c8fdde14ca97222102ba3a9e0}}}\n[01036.218] 01050.01053> {{{mmap:0x756c32012000:0x1000:load:0x3:rx:0}}}\n[01036.218] 01050.01053> {{{mmap:0x756c32013000:0x1000:load:0x3:rw:0x1000}}}\n[01036.218] 01050.01053> dso: id=8c511b4202cf569c8fdde14ca97222102ba3a9e0 base=0x0000756c32012000 name=libasync-default.so\n[01036.218] 01050.01053> {{{module:0x4:libfdio.so:elf:3efa72b2e3bd2e4271f835d34a81d9719d6e08a7}}}\n[01036.218] 01050.01053> {{{mmap:0x4a76cf7e8000:0x32000:load:0x4:rx:0}}}\n[01036.218] 01050.01053> {{{mmap:0x4a76cf81b000:0x4000:load:0x4:rw:0x33000}}}\n[01036.218] 01050.01053> dso: id=3efa72b2e3bd2e4271f835d34a81d9719d6e08a7 base=0x00004a76cf7e8000 name=libfdio.so\n[01036.218] 01050.01053> {{{module:0x5:libtrace-engine.so:elf:95bc229c3cf2ab8a51f12c33a311105ff8976120}}}\n[01036.218] 01050.01053> {{{mmap:0x547341f66000:0x9000:load:0x5:rx:0}}}\n[01036.218] 01050.01053> {{{mmap:0x547341f6f000:0x2000:load:0x5:rw:0x9000}}}\n[01036.218] 01050.01053> dso: id=95bc229c3cf2ab8a51f12c33a311105ff8976120 base=0x0000547341f66000 name=libtrace-engine.so\n[01036.218] 01050.01053> {{{module:0x6:liblaunchpad.so:elf:5b0854856213eae6bab93c982f39551f8f9591c5}}}\n[01036.218] 01050.01053> {{{mmap:0x15f70c7ca000:0x7000:load:0x6:rx:0}}}\n[01036.218] 01050.01053> {{{mmap:0x15f70c7d1000:0x2000:load:0x6:rw:0x7000}}}\n[01036.218] 01050.01053> dso: id=5b0854856213eae6bab93c982f39551f8f9591c5 base=0x000015f70c7ca000 name=liblaunchpad.so\n[01036.218] 01050.01053> {{{module:0x1:libc.so:elf:5d417db61b3a48a58a8fa1ffbfc2e71b830e0857}}}\n[01036.218] 01050.01053> {{{mmap:0x7c36141ce000:0xa6000:load:0x1:rx:0}}}\n[01036.218] 01050.01053> {{{mmap:0x7c3614274000:0x5000:load:0x1:rw:0xa6000}}}\n[01036.218] 01050.01053> dso: id=5d417db61b3a48a58a8fa1ffbfc2e71b830e0857 base=0x00007c36141ce000 name=libc.so\n[01036.218] 01050.01053> bootsvc: Starting...\n[01036.218] 01036.01043> userboot: loader-service channel peer closed\n[01036.218] 01036.01043> userboot: finished!\n[01036.218] 01050.01053> bootsvc: Creating bootfs service...\n[01036.218] 01050.01053> bootsvc: Retrieving boot image...\n[01036.218] 01050.01053> bootsvc: Decommitted BOOTDATA VMO from 0 to 0xc1e000\n[01036.218] 01050.01053> bootsvc: Loading boot arguments...\n[01036.218] 01050.01053> bootsvc: Creating svcfs service...\n[01036.218] 01050.01053> bootsvc: Loading kernel VMOs...\n[01036.218] 01050.01053> bootsvc: Creating loader service...\n[01036.218] 01050.01053> bootsvc: Launching next process...\n[01036.219] 01050.01108> bootsvc: Launched bin/devcoordinator\n[01036.220] 01115.01130> devcoordinator: launch /boot/bin/svchost (svchost) OK\n[01036.221] 01115.01130> devcoordinator: launch /boot/bin/fshost (fshost) OK\n[01036.223] 01115.01321> devcoordinator: launch /boot/bin/pwrbtn-monitor (pwrbtn-monitor) OK\n[01036.223] 01115.01349> devcoordinator: launch /boot/bin/netsvc (netsvc) OK\n[01036.224] 01115.01349> devcoordinator: launch /boot/bin/virtual-console (virtual-console) OK\n[01036.227] 01115.01130> devcoordinator: full system required, ignoring fallback drivers until /system is loaded\n[01036.228] 01115.01130> devcoordinator: launch devhost 'devhost:sys': pid=2187\n[01036.228] 01115.01130> devcoordinator: launch devhost 'devhost:test': pid=2219\n[01036.228] 01115.01130> devcoordinator: launch devhost 'devhost:root': pid=2279\n[01036.229] 02187.02201> devhost: trace provider registry begun\n[01036.229] 01115.01130> devcoordinator: launch devhost 'devhost:misc': pid=2341\n[01036.229] 02219.02241> devhost: trace provider registry begun\n[01036.231] 02279.02309> devhost: trace provider registry begun\n[01036.231] 02341.02395> devhost: trace provider registry begun\n[01036.231] 01115.01333> devcoordinator: launch /boot/bin/sh (sh:console) OK\n[01036.315] 02187.02201> acpi-bus: not using IOMMU\n[01036.322] 02187.02201> PMU: 7 arch events\n[01036.322] 02187.02201> PMU: arch event id range: 1-7\n[01036.322] 02187.02201> PMU: 102 arch events\n[01036.322] 02187.02201> PMU: arch event id range: 1-241\n[01036.322] 02187.02201> Intel Processor Trace configuration for this chipset:\n[01036.322] 02187.02201> mtc_freq_mask: 0x249\n[01036.322] 02187.02201> cyc_thresh_mask: 0x3fff\n[01036.322] 02187.02201> psb_freq_mask: 0x3f\n[01036.322] 02187.02201> num addr ranges: 2\n[01036.322] 02187.02201> [sysmem_init 28] async_get_default_dispatcher(): 0x1274cb390a90\n[01036.322] 02187.02201> [sysmem_bind 40] sysmem_bind()\n[01036.322] 02187.02876> acpi-pwrbtn: initialized\n[01036.322] 01723.01767> vc: new input device /dev/class/input/000\n[01036.332] 02187.02876> WARNING: ACPI found bad _CRS address entry\n[01036.342] 02187.02876> WARNING: ACPI found bad _CRS address entry\n[01036.354] 02187.02876> acpi: published device pci(0x12740b3b5430), parent=sys(0x12754b39a310), handle=0x1273cb3a23d0\n[01036.354] 01115.01130> devcoordinator: launch devhost 'devhost:pci#1:8086:5916': pid=2972\n[01036.355] 02972.02986> devhost: trace provider registry begun\n[01036.357] 01115.01130> devcoordinator: launch devhost 'devhost:pci#3:8086:9d2f': pid=3076\n[01036.357] 02187.02876> acpi: published device rtc(0x12740b3b47d0), parent=acpi(0x12754b39a410), handle=0x1273cb3ca450\n[01036.358] 01115.01130> devcoordinator: launch devhost 'devhost:pci#5:8086:9d60': pid=3156\n[01036.358] 02187.02876> acpi-ec: initialized\n[01036.359] 01115.01130> devcoordinator: launch devhost 'devhost:pci#6:8086:9d61': pid=3220\n[01036.359] 03076.03090> devhost: trace provider registry begun\n[01036.359] 01115.01130> devcoordinator: launch devhost 'devhost:pci#9:8086:9d03': pid=3331\n[01036.360] 03156.03175> devhost: trace provider registry begun\n[01036.360] 03220.03258> devhost: trace provider registry begun\n[01036.361] 03331.03358> devhost: trace provider registry begun\n[01036.362] 02187.02876> acpi: failed to create NHLT VMO (res -10)\n[01036.362] 02187.02876> acpi: failed to publish NHLT metadata\n[01036.363] 01115.01130> devcoordinator: launch devhost 'devhost:pci#14:8086:9d71': pid=3555\n[01036.366] 01115.01130> devcoordinator: launch devhost 'devhost:pci#16:8086:156f': pid=3614\n[01036.367] 03331.03358> ahci: using MSI interrupt\n[01036.367] 03156.03175> initialized intel serialio i2c driver, reg=0x1f8cded3d000 regsize=4096\n[01036.367] 03220.03258> initialized intel serialio i2c driver, reg=0x154f9c353000 regsize=4096\n[01036.367] 03555.03569> devhost: trace provider registry begun\n[01036.368] 03614.03640> devhost: trace provider registry begun\n[01036.379] 03555.03569> [IHDA DSP 00:1f.3] Failed to fetch NHLT (res -25)\n[01036.379] 03555.03569> [IHDA Controller 00:1f.3] Failed to initialize DSP device (res = -25)\n[01036.385] 03076.03999> * found USB device (0x18d1:0xa002, USB 2.0) config 1\n[01036.385] 03076.03999> * found USB device (0x18d1:0xa003, USB 3.0) config 1\n[01036.445] 01622.01668> netifc: ? /dev/class/ethernet/000\n[01036.445] 01622.01668> netifc: create 512 eth buffers\n[01036.445] 01622.01668> macaddr: d4:5d:df:1a:42:46\n[01036.445] 01622.01668> ip6addr: fe80::d65d:df4d:fe1a:4246\n[01036.445] 01622.01668> snmaddr: ff02::1:ff1a:4246\n[01036.445] 01622.01668> netsvc: using /dev/class/ethernet/000\n[01036.445] 01622.01668> netsvc: nodename='ocean-drank-wick-spot'\n[01036.445] 01622.01668> netsvc: start\n[01036.472] 01723.01767> vc: new display device /dev/class/display-controller/000\n[01036.512] 02972.04240> i915: Found DP monitor\n[01036.512] 02972.04240> i915: Display 1 connected\n[01036.512] 02972.04240> i915: MST not supported\n[01037.092] 01723.01767> vc: Successfully attached to display 1\n[01037.101] 03076.03999> * found USB device (0x0781:0x5583, USB 3.0) config 1\n[01037.854] 03076.03999> * found USB device (0x046d:0xc31c, USB 1.10) config 1\n[01038.101] 03076.03999> * found USB device (0x0cf3:0xe300, USB 2.1) config 1\n[01038.372] 03220.03258> intel-i2c-controller: intel_serialio_i2c_slave_transfer: -21\n[01038.372] 03220.03258> i2c-hid: could not read HID descriptor: 0\n[01038.372] 03220.03258> devhost[00:15.1/i2c-bus-9d61/004c] bind driver '/boot/driver/i2c-hid.so' failed: -2\n[01038.372] 01115.01130> devcoordinator: rpc: bind-driver '004c' status -2\n[01038.372] 03156.03175> intel-i2c-controller: intel_serialio_i2c_slave_transfer: -21\n[01038.372] 03156.03175> i2c-hid: could not read HID descriptor: 0\n[01038.372] 03156.03175> devhost[00:15.0/i2c-bus-9d60/0020] bind driver '/boot/driver/i2c-hid.so' failed: -2\n[01038.372] 01115.01130> devcoordinator: rpc: bind-driver '0020' status -2\n[01038.376] 03331.03880> sata2: dev info\n[01038.376] 03331.03880> serial=711430952084 9 \n[01038.376] 03331.03880> firmware rev=1Q01A7 0\n[01038.376] 03331.03880> model id=IBIW NSS D \n[01038.376] 03331.03880> major=0x3f0 ACS2 DMA 32 commands\n[01038.376] 03331.03880> LBA48 234441648 sectors, sector size=512\n[01038.377] 01299.01313> fshost: /dev/class/block/000: GPT?\n[01038.377] 03331.03358> gpt: device_get_metadata failed (-25)\n[01038.378] 01299.01313> fshost: /dev/class/block/001: FVM?\n[01038.381] 01299.01313> devcoordinator: launch /boot/bin/blobfs (blobfs:/blob) OK\n[01038.528] 01299.01313> fshost: starting 'bin/pkgsvr' '863adb04d8f132fb55e81b20f07bba14e8e705f0f44d19b869e9490271c00d3e'...\n[01038.534] 01299.01313> devcoordinator: launch bin/pkgsvr (pkgfs) OK\n[01038.545] 04674.04878> pkgsvr: system: will be served from 863adb04d8f132fb55e81b20f07bba14e8e705f0f44d19b869e9490271c00d3e\n[01038.545] 04674.04878> pkgsvr: pkgfs serving index /data/pkgfs_index from blobfs /blob\n[01038.546] 01299.01313> fshost: /dev/class/block/005: zxcrypt?\n[01038.546] 03331.03358> devhost_get_handles(0x47d3ae5ed310:block) open path='zxcrypt', r=-2\n[01038.552] 01299.01313> fshost: mounting minfs\n[01038.556] 01299.01313> devcoordinator: launch /boot/bin/minfs (minfs:/data) OK\n[01038.567] 01115.01776> devcoordinator: launch /system/bin/appmgr (appmgr) OK\n[01038.567] 01115.01776> autorun: starting '/boot/bin/sh' '/boot/infra/runcmds'...\n[01038.591] 01115.04929> devcoordinator: adding system driver 'mt8167s_gpu' '/system/driver/libmsd_img_rgx_mtk.so'\n[01038.597] 01115.04929> devcoordinator: adding system driver 'bt_hci_passthrough' '/system/driver/bt-hci-passthrough.so'\n[01038.598] 01115.01776> devcoordinator: launch /boot/bin/sh (autorun:system) OK\n[01038.619] 01115.04929> devcoordinator: adding system driver 'wlan' '/system/driver/wlanif.so'\n[01038.634] 01115.04929> devcoordinator: adding system driver 'usb_video' '/system/driver/usb_video.so'\n[01038.642] 01115.04929> devcoordinator: adding system driver 'brcmfmac' '/system/driver/brcmfmac.so'\n[01038.644] 01115.04929> devcoordinator: adding system driver 'iwlwifi_pci' '/system/driver/iwlwifi.so'\n[01038.653] 01115.04929> devcoordinator: adding system driver 'rtl88xx' '/system/driver/rtl88xx.so'\n[01038.666] 01115.04929> devcoordinator: adding system driver 'bt_hci_fake' '/system/driver/bt-hci-fake.so'\n[01038.723] 01115.04929> devcoordinator: adding system driver 'ralink' '/system/driver/ralink.so'\n[01038.727] 01115.04929> devcoordinator: adding system driver 'ath10k_pci' '/system/driver/ath10k.so'\n[01038.729] 01115.04929> devcoordinator: adding system driver 'bt_hog' '/system/driver/bt-hog.so'\n[01038.742] 01115.04929> devcoordinator: adding system driver 'wlan' '/system/driver/wlanphy.so'\n[01038.746] 01115.04929> devcoordinator: adding system driver 'qmi_fake' '/system/driver/qmi-fake.so'\n[01038.772] 01115.04929> devcoordinator: adding system driver 'bt_hci_intel' '/system/driver/bt-hci-intel.so'\n[01038.780] 01115.04929> devcoordinator: adding system driver 'magma_pdev_gpu' '/system/driver/libmsd_arm.so'\n[01038.790] 01115.04929> devcoordinator: adding system driver 'gpu' '/system/driver/libmsd_intel.so'\n[01038.803] 05155.05169> [INFO:namespace_builder.cc(93)] config-data for sysmgr\n[01038.859] 01115.04929> devcoordinator: adding system driver 'wlan' '/system/driver/wlan.so'\n[01038.884] 01115.04929> devcoordinator: adding system driver 'bt_hci_atheros' '/system/driver/bt-hci-atheros.so'\n[01038.916] 01115.04929> devcoordinator: adding system driver 'bt_host' '/system/driver/bt-host.so'\n[01038.927] 01115.04929> devcoordinator: adding system driver 'qmi_usb' '/system/driver/qmi-transport.so'\n[01038.936] 01115.04929> devcoordinator: adding system driver 'virtual_audio' '/system/driver/virtual_audio_driver.so'\n[01038.950] 01115.04929> devcoordinator: adding system driver 'virtual_camera' '/system/driver/virtual_camera_driver.so'\n[01038.950] 01115.01130> devcoordinator: driver 'qmi_usb' added\n[01038.950] 01115.01130> devcoordinator: driver 'bt_host' added\n[01038.950] 01115.01130> devcoordinator: driver 'bt_hci_atheros' added\n[01038.950] 01115.01130> devcoordinator: driver 'wlan' added\n[01038.950] 01115.01130> devcoordinator: driver 'gpu' added\n[01038.950] 01115.01130> devcoordinator: driver 'magma_pdev_gpu' added\n[01038.950] 01115.01130> devcoordinator: driver 'bt_hci_intel' added\n[01038.950] 01115.01130> devcoordinator: driver 'qmi_fake' added\n[01038.950] 01115.01130> devcoordinator: driver 'wlan' added\n[01038.950] 01115.01130> devcoordinator: driver 'bt_hog' added\n[01038.950] 01115.01130> devcoordinator: driver 'ath10k_pci' added\n[01038.951] 01115.01130> devcoordinator: launch devhost 'devhost:pci#11:168c:003e': pid=6022\n[01038.951] 01115.01130> devcoordinator: driver 'ralink' added\n[01038.951] 01115.01130> devcoordinator: driver 'bt_hci_fake' added\n[01038.951] 01115.01130> devcoordinator: driver 'rtl88xx' added\n[01038.951] 01115.01130> devcoordinator: driver 'iwlwifi_pci' added\n[01038.951] 01115.01130> devcoordinator: driver 'brcmfmac' added\n[01038.951] 01115.01130> devcoordinator: driver 'usb_video' added\n[01038.951] 01115.01130> devcoordinator: driver 'wlan' added\n[01038.951] 01115.01130> devcoordinator: driver 'mt8167s_gpu' added\n[01038.951] 01115.01130> devcoordinator: driver 'bt_hci_passthrough' added\n[01038.951] 01115.01130> devcoordinator: fallback driver 'usb_composite' is available\n[01038.951] 01115.01130> devcoordinator: driver 'usb_composite' added\n[01038.952] 01115.01130> devcoordinator: fallback driver 'intel_disp' is available\n[01038.952] 01115.01130> devcoordinator: driver 'intel_disp' added\n[01038.954] 06022.06043> devhost: trace provider registry begun\n[01038.954] 06022.06043> ath10k: Probed chip QCA6174 ver: 2.1\n[01038.959] 03076.03090> UMS: parent: 'ifc-000'\n[01038.959] 03076.03090> UMS:Max lun is: 0\n[01038.963] 03076.03090> devhost[00:14.0/xhci/usb-bus/003/003/ifc-001] bind driver '/boot/driver/bt-transport-usb.so' failed: -2\n[01038.963] 03076.07025> UMS: block size is: 0x00000200\n[01038.963] 03076.07025> UMS: total blocks is: 30031250\n[01038.963] 03076.07025> UMS: total size is: 15376000000\n[01038.963] 03076.07025> UMS: read-only: 0 removable: 1\n[01038.963] 01115.01130> devcoordinator: rpc: bind-driver 'ifc-001' status -2\n[01039.038] 03076.10805> btatheros: Making visible\n[01039.041] 03076.10805> btatheros: loaded successfully\n[01039.048] 03076.11151> [INFO:command_channel.cc(149)] hci: initialized\n[01039.058] 01299.01313> fshost: /dev/class/block/007: MBR?\n[01039.073] 01299.01313> fshost: /dev/class/block/009: MBR?\n[01039.100] 05155.05169> [INFO:namespace_builder.cc(93)] config-data for amber\n[01039.113] 03076.11151> [WARN:adapter.cc(274)] gap: controller is using legacy HCI version 4.1\n[01039.124] 03076.11151> [INFO:acl_data_channel.cc(91)] hci: initialized\n[01039.162] 05155.05169> [INFO:namespace_builder.cc(93)] config-data for pkg_resolver\n[01039.183] 01723.01767> vc: new input device /dev/class/input/001\n[01039.198] 01723.01767> vc: new input device /dev/class/input/002\n[01039.325] 13704.13716> [INFO:cobalt_main.cc(219)] Cobalt is starting with the following parameters: schedule_interval=3600 seconds, min_interval=10 seconds, initial_interval=60 seconds, max_bytes_per_observation_store=1048576, event_aggregator_bac\n[01039.325] 13704.13716> kfill_days=2, start_event_aggregator_worker=1.\n[01039.346] 06022.13528> devhost: rpc:load-firmware failed: -25\n[01039.346] 06022.13528> devhost: rpc:load-firmware failed: -25\n[01039.422] 05155.05169> [INFO:namespace_builder.cc(93)] config-data for wlancfg\n[01039.468] 05155.05169> [INFO:namespace_builder.cc(93)] config-data for mdns\n[01039.654] 05155.05169> [INFO:namespace_builder.cc(93)] config-data for crashpad_agent\n[01039.692] 16884.16896> [INFO:main.cc(35)] Trace Manager starting with config: /pkg/data/tracing.config\n[01039.710] 15774.15786> [INFO:system_metrics_main.cc(23)] Cobalt SystemMetricsDaemon: System metrics daemon created.\n[01039.813] 17315.17327> wlanstack2 [I]: Starting\n[01040.392] 06022.12364> ath10k: Unknown eventid: 0x1d019\n[01040.395] 06022.12364> ath10k: Unknown eventid: 0x16006\n[01040.400] 06022.06043> wlanphy: event loop started\n[01040.400] 06022.06043> wlanphy_bind\n[01040.401] 17315.17327> wlanstack2::device [I]: new phy #0: /dev/sys/pci/01:00.0/ath10k-wlanphy/wlanphy\n[01040.401] 14710.14726> wlancfg got event: OnPhyAdded { phy_id: 0 }\n[01040.401] 14710.14726> wlancfg: phy 0 added\n[01040.401] 17315.17327> wlanstack2::service [I]: query_phy(id = 0)\n[01040.402] 17315.17327> wlanstack2::service [I]: query_phy(id = 0): received a 'QueryResult' from device\n[01040.402] 14710.14726> wlancfg: received a PhyInfo from phy #0: path is /dev/sys/pci/01:00.0/ath10k-wlanphy/wlanphy\n[01040.402] 14710.14726> using default wlan config entry for phy\n[01040.402] 14710.14726> wlancfg: Creating Client iface for phy 0\n[01040.408] 06022.06043> wlan_bind\n[01040.409] 06022.06043> ath10k: adding a station interface (vdev_id=0) ...\n[01040.411] 06022.06043> wlan: [I] Initialize a client MLME.\n[01040.411] 06022.18168> wlan: [I] starting MainLoop\n[01040.412] 06022.06043> wlan: [I] channel opened\n[01040.413] 17315.17327> wlanstack2::device [I]: new iface #0 with role 'Client': /dev/sys/pci/01:00.0/ath10k-wlanphy/ath10k-wlanmac/wlan\n[01040.413] 14710.14726> wlancfg got event: OnIfaceAdded { iface_id: 0 }\n[01040.413] 14710.14726> wlancfg: new iface 0 added successfully\n[01040.414] 14710.14726> wlancfg: Starting auto-connect loop with 0 saved networks\n[01040.740] 15050.15063> Starting mDNS on interface ethp001f6 [80fe::5dd6:ffdf:1afe:4642]\n[01040.740] 15050.15063> [WARNING:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(29)] NET-291 IPV6_MULTICAST_LOOP not supported (ENOPROTOOPT), continuing anyway\n[01040.740] 15050.15063> [WARNING:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(50)] NET-2180 IPV6_JOIN_GROUP returned ENODEV, mDNS will not communicate via IPV6\n[01044.532] 15050.15063> Starting mDNS on interface ethp001f6 192.168.42.101\n[01044.532] 15050.15063> [WARNING:garnet/bin/mdns/service/mdns_interface_transceiver_v4.cc(68)] NET-2177 IP_TTL not supported (ENOPROTOOPT), continuing anyway. May cause spurious IP traffic\n[01044.532] 15050.15063> Starting mDNS on interface ethp001f6 [80fe::5dd6:ffdf:1afe:4642]\n[01044.532] 15050.15063> [WARNING:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(29)] NET-291 IPV6_MULTICAST_LOOP not supported (ENOPROTOOPT), continuing anyway\n[01044.532] 15050.15063> [WARNING:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(50)] NET-2180 IPV6_JOIN_GROUP returned ENODEV, mDNS will not communicate via IPV6\n[01044.533] 15050.15063> mDNS: Verifying uniqueness of host name ocean-drank-wick-spot.local.\n[01045.516] 15050.15063> mDNS: Using unique host name ocean-drank-wick-spot.local.\nRunning tests sequentially.\n[tb] 2019-04-20 02:22:42.011 \u001b[22mINFO\u001b[0m [SshConnection | 192.168.42.101] Starting master ssh connection. [connection.py:setup_master_ssh:141]\n[01092.411] 23373.23385> setsockopt IP_TOS 32: Protocol not available:\n[tb] 2019-04-20 02:22:42.252 \u001b[22mINFO\u001b[0m [SshConnection | 192.168.42.101] Starting master ssh connection. [connection.py:setup_master_ssh:141]\n[01092.642] 23949.23964> setsockopt IP_TOS 32: Protocol not available:\n[tb] 2019-04-20 02:22:43.497 \u001b[22mINFO\u001b[0m [SshConnection | 192.168.42.101] Starting master ssh connection. [connection.py:setup_master_ssh:141]\n[01093.884] 24989.25002> setsockopt IP_TOS 32: Protocol not available:\n[tb] 2019-04-20 02:22:43.746 \u001b[22mINFO\u001b[0m Controller FuchsiaDevice: [{'ip': '192.168.42.101'}] [base_test.py:register_controller:396]\n[tb] 2019-04-20 02:22:43.749 \u001b[22mINFO\u001b[0m ==========> Sl4fSanityTest <========== [base_test.py:run:967]\n[tb] 2019-04-20 02:22:43.751 \u001b[22mINFO\u001b[0m Congratulations! Fuchsia controllers have been initialized successfully! [Sl4fSanityTest.py:setup_class:41]\n[tb] 2019-04-20 02:22:43.753 \u001b[22mINFO\u001b[0m [Test Case] test_example [base_test.py:exec_one_testcase:662]\n[tb] 2019-04-20 02:22:43.755 \u001b[22mINFO\u001b[0m Congratulations! You've run your first test. [Sl4fSanityTest.py:test_example:46]\n[tb] 2019-04-20 02:22:43.759 \u001b[22mINFO\u001b[0m [Test Case] test_example PASS [base_test.py:_on_pass:542]\n[tb] 2019-04-20 02:22:43.762 \u001b[22mINFO\u001b[0m Summary for test class Sl4fSanityTest: ControllerInfo {'FuchsiaDevice': [{'ip': '192.168.42.101'}]}, Requested 1, Executed 1, Passed 1, Failed 0, Skipped 0, Blocked 0, Unknown 0, Errors 0 [base_test.py:run:1027]\n[tb] 2019-04-20 02:22:43.769 \u001b[22mINFO\u001b[0m [SshConnection | 192.168.42.101] Starting master ssh connection. [connection.py:setup_master_ssh:141]\n[01094.151] 25541.25554> setsockopt IP_TOS 32: Protocol not available:\n[tb] 2019-04-20 02:22:44.012 \u001b[22mINFO\u001b[0m [SshConnection | 192.168.42.101] Starting master ssh connection. [connection.py:setup_master_ssh:141]\n[01094.401] 26123.26138> setsockopt IP_TOS 32: Protocol not available:\n[tb] 2019-04-20 02:22:45.258 \u001b[22mINFO\u001b[0m [SshConnection | 192.168.42.101] Starting master ssh connection. [connection.py:setup_master_ssh:141]\n[01095.645] 27184.27200> setsockopt IP_TOS 32: Protocol not available:\n[tb] 2019-04-20 02:22:45.507 \u001b[22mINFO\u001b[0m Controller FuchsiaDevice: [{'ip': '192.168.42.101'}] [base_test.py:register_controller:396]\n[tb] 2019-04-20 02:22:45.511 \u001b[22mINFO\u001b[0m ==========> WlanScanTest <========== [base_test.py:run:967]\n[tb] 2019-04-20 02:22:45.514 \u001b[22mINFO\u001b[0m [Test Case] test_basic_scan_request [base_test.py:exec_one_testcase:662]\n[01095.799] 06022.18168> ath10k: starting a hardware scan\n[tb] 2019-04-20 02:22:51.036 \u001b[22mINFO\u001b[0m scan contained 63 results [WlanScanTest.py:test_basic_scan_request:86]\n[tb] 2019-04-20 02:22:51.039 \u001b[22mINFO\u001b[0m scan time: 5523 ms [WlanScanTest.py:test_basic_scan_request:89]\n[tb] 2019-04-20 02:22:51.053 \u001b[22mINFO\u001b[0m [Test Case] test_basic_scan_request PASS [base_test.py:_on_pass:542]\n[tb] 2019-04-20 02:22:51.055 \u001b[22mINFO\u001b[0m [Test Case] test_scan_while_connected_open_network [base_test.py:exec_one_testcase:662]\n[tb] 2019-04-20 02:22:51.057 \u001b[22mINFO\u001b[0m got the ssid! GoogleGuest [WlanScanTest.py:test_scan_while_connected_open_network:101]\n[01101.342] 06022.18168> ath10k: starting a hardware scan\n[01106.843] 06022.18168> ath10k: setting channel (pri: 132, sec: 0, bw: CBW40ABOVE)\n[01106.843] 06022.18168> ath10k: basic setting: phymode 11na-ht40 center_freq=5670\n[01106.896] 06022.18168> ath10k: configuring BSS\n[01106.896] 06022.18168> ath10k: basic setting: phymode 11na-ht40 center_freq=5670\n[01106.952] 06022.18168> ath10k: as a client, configuring an association with an AP [04:BD:88:3F:61:11]\n[01106.952] 06022.18168> ath10k: mac peer 04:BD:88:3F:61:11 phymode 11ac-vht40\n[01106.952] 06022.18168> ath10k: setting peer smps mode to none\n[01106.952] 06022.18168> ath10k: successfully associated with bssid 04:BD:88:3F:61:11\n[01106.953] 06022.18168> wlan: [I] NIC 00:0e:8e:8b:40:ec associated with \"GoogleGuest\"(04:bd:88:3f:61:11) in channel 132 CBW40, 5 GHz, 802.11ac VHT\n[tb] 2019-04-20 02:22:56.679 \u001b[22mINFO\u001b[0m connection to network successful [WlanScanTest.py:check_connect_response:58]\n[01106.954] 15050.15063> Starting mDNS on interface ethp001f6 [80fe::5dd6:ffdf:1afe:4642]\n[01106.954] 15050.15063> [WARNING:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(29)] NET-291 IPV6_MULTICAST_LOOP not supported (ENOPROTOOPT), continuing anyway\n[01106.954] 15050.15063> [WARNING:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(50)] NET-2180 IPV6_JOIN_GROUP returned ENODEV, mDNS will not communicate via IPV6\n[01106.954] 15050.15063> Starting mDNS on interface wlanp01 [80fe::e02:ff8e:8bfe:ec40]\n[01106.955] 15050.15063> [WARNING:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(29)] NET-291 IPV6_MULTICAST_LOOP not supported (ENOPROTOOPT), continuing anyway\n[01106.955] 15050.15063> [WARNING:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(50)] NET-2180 IPV6_JOIN_GROUP returned ENODEV, mDNS will not communicate via IPV6\n[01106.964] 06022.18168> ath10k: starting a hardware scan\n[01108.273] 15050.15063> Starting mDNS on interface wlanp01 100.110.68.14\n[01108.273] 15050.15063> [WARNING:garnet/bin/mdns/service/mdns_interface_transceiver_v4.cc(68)] NET-2177 IP_TTL not supported (ENOPROTOOPT), continuing anyway. May cause spurious IP traffic\n[01108.273] 15050.15063> Starting mDNS on interface wlanp01 [80fe::e02:ff8e:8bfe:ec40]\n[01108.274] 15050.15063> [WARNING:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(29)] NET-291 IPV6_MULTICAST_LOOP not supported (ENOPROTOOPT), continuing anyway\n[01108.274] 15050.15063> [WARNING:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(50)] NET-2180 IPV6_JOIN_GROUP returned ENODEV, mDNS will not communicate via IPV6\n[01108.274] 15050.15063> Starting mDNS on interface ethp001f6 [80fe::5dd6:ffdf:1afe:4642]\n[01108.274] 15050.15063> [WARNING:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(29)] NET-291 IPV6_MULTICAST_LOOP not supported (ENOPROTOOPT), continuing anyway\n[01108.274] 15050.15063> [WARNING:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(50)] NET-2180 IPV6_JOIN_GROUP returned ENODEV, mDNS will not communicate via IPV6\n[tb] 2019-04-20 02:23:06.291 \u001b[22mINFO\u001b[0m scan contained 57 results [WlanScanTest.py:test_basic_scan_request:86]\n[tb] 2019-04-20 02:23:06.293 \u001b[22mINFO\u001b[0m scan time: 9612 ms [WlanScanTest.py:test_basic_scan_request:89]\n[tb] 2019-04-20 02:23:06.309 \u001b[22mINFO\u001b[0m [Test Case] test_scan_while_connected_open_network PASS [base_test.py:_on_pass:542]\n[tb] 2019-04-20 02:23:06.311 \u001b[22mINFO\u001b[0m [Test Case] test_scan_while_connected_wpa2_network [base_test.py:exec_one_testcase:662]\n[tb] 2019-04-20 02:23:06.312 \u001b[22mINFO\u001b[0m got the ssid! acts hotspot [WlanScanTest.py:test_scan_while_connected_wpa2_network:114]\n[01116.580] 06022.18168> wlan: [I] deauthenticating from \"GoogleGuest\" (04:bd:88:3f:61:11), reason=36\n[01116.580] 06022.18168> ath10k: as a client, clearing the association with an AP [04:BD:88:3F:61:11]\n[01116.581] 17315.17327> wlan_sme::client::state [W]: Unexpected MLME message while Idle: DeauthenticateConf { resp: DeauthenticateConfirm { peer_sta_address: [4, 189, 136, 63, 97, 17] } }\n[01116.582] 15050.15063> Starting mDNS on interface ethp001f6 [80fe::5dd6:ffdf:1afe:4642]\n[01116.587] 15050.15063> [WARNING:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(29)] NET-291 IPV6_MULTICAST_LOOP not supported (ENOPROTOOPT), continuing anyway\n[01116.589] 15050.15063> [WARNING:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(50)] NET-2180 IPV6_JOIN_GROUP returned ENODEV, mDNS will not communicate via IPV6\n[01116.597] 06022.18168> ath10k: starting a hardware scan\n[01124.368] 17315.17327> wlan_rsn::rsna::esssa [I]: spawned ESSSA for: Supplicant\n[01124.368] 06022.18168> ath10k: setting channel (pri: 3, sec: 0, bw: CBW20)\n[01124.369] 06022.18168> ath10k: basic setting: phymode 11ng-ht20 center_freq=2422\n[01124.434] 06022.18168> ath10k: configuring BSS\n[01124.434] 06022.18168> ath10k: basic setting: phymode 11ng-ht20 center_freq=2422\n[tb] 2019-04-20 02:23:14.254 \u001b[22mINFO\u001b[0m connection to network successful [WlanScanTest.py:check_connect_response:58]\n[01124.502] 06022.18168> ath10k: as a client, configuring an association with an AP [40:4E:36:D1:DC:10]\n[01124.502] 06022.18168> ath10k: mac peer 40:4E:36:D1:DC:10 phymode 11b\n[01124.502] 06022.18168> ath10k: successfully associated with bssid 40:4E:36:D1:DC:10\n[01124.503] 17315.17327> wlan_rsn::rsna::esssa [I]: resetting ESSSA\n[01124.503] 17315.17327> wlan_rsn::rsna::esssa [I]: establishing ESSSA...\n[01124.503] 17315.17327> wlan_rsn::rsna::esssa [I]: established PMKSA\n[01124.503] 06022.18168> wlan: [I] NIC 00:0e:8e:8b:40:ec associated with \"acts hotspot\"(40:4e:36:d1:dc:10) in channel 3 CBW20, 2 GHz, 802.11n HT\n[01124.517] 17315.17327> wlan_rsn::rsna::esssa [I]: established PTKSA\n[01124.527] 17315.17327> wlan_rsn::rsna::esssa [I]: established GTKSA\n[01124.527] 17315.17327> wlan_rsn::rsna::esssa [I]: established ESSSA\n[01124.527] 06022.18168> ath10k: attempting to set key (bssid: 0, prot: rx/tx, cipher: CCMP128, type: pairwise, len: 16, addr: 40:4e:36:d1:dc:10 key_idx: 0)\n[01124.527] 06022.18168> ath10k: attempting to set key (bssid: 0, prot: rx/tx, cipher: CCMP128, type: group, len: 16, addr: ff:ff:ff:ff:ff:ff key_idx: 2)\n[01124.530] 15050.15063> Starting mDNS on interface ethp001f6 [80fe::5dd6:ffdf:1afe:4642]\n[01124.533] 15050.15063> [WARNING:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(29)] NET-291 IPV6_MULTICAST_LOOP not supported (ENOPROTOOPT), continuing anyway\n[01124.533] 15050.15063> [WARNING:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(50)] NET-2180 IPV6_JOIN_GROUP returned ENODEV, mDNS will not communicate via IPV6\n[01124.533] 15050.15063> Starting mDNS on interface wlanp01 [80fe::e02:ff8e:8bfe:ec40]\n[01124.534] 15050.15063> [WARNING:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(29)] NET-291 IPV6_MULTICAST_LOOP not supported (ENOPROTOOPT), continuing anyway\n[01124.534] 15050.15063> [WARNING:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(50)] NET-2180 IPV6_JOIN_GROUP returned ENODEV, mDNS will not communicate via IPV6\n[01124.539] 06022.18168> ath10k: starting a hardware scan\n[01132.737] 15050.15063> Starting mDNS on interface ethp001f6 [80fe::5dd6:ffdf:1afe:4642]\n[01132.738] 15050.15063> [WARNING:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(29)] NET-291 IPV6_MULTICAST_LOOP not supported (ENOPROTOOPT), continuing anyway\n[01132.738] 15050.15063> [WARNING:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(50)] NET-2180 IPV6_JOIN_GROUP returned ENODEV, mDNS will not communicate via IPV6\n[01132.738] 15050.15063> Starting mDNS on interface wlanp01 192.168.43.147\n[01132.738] 15050.15063> [WARNING:garnet/bin/mdns/service/mdns_interface_transceiver_v4.cc(68)] NET-2177 IP_TTL not supported (ENOPROTOOPT), continuing anyway. May cause spurious IP traffic\n[01132.739] 15050.15063> Starting mDNS on interface wlanp01 [80fe::e02:ff8e:8bfe:ec40]\n[01132.739] 15050.15063> [WARNING:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(29)] NET-291 IPV6_MULTICAST_LOOP not supported (ENOPROTOOPT), continuing anyway\n[01132.739] 15050.15063> [WARNING:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(50)] NET-2180 IPV6_JOIN_GROUP returned ENODEV, mDNS will not communicate via IPV6\n[tb] 2019-04-20 02:23:23.959 \u001b[22mINFO\u001b[0m scan contained 61 results [WlanScanTest.py:test_basic_scan_request:86]\n[tb] 2019-04-20 02:23:23.961 \u001b[22mINFO\u001b[0m scan time: 9705 ms [WlanScanTest.py:test_basic_scan_request:89]\n[tb] 2019-04-20 02:23:23.977 \u001b[22mINFO\u001b[0m [Test Case] test_scan_while_connected_wpa2_network PASS [base_test.py:_on_pass:542]\n[01134.251] 06022.18168> wlan: [I] deauthenticating from \"acts hotspot\" (40:4e:36:d1:dc:10), reason=36\n[01134.251] 06022.18168> ath10k: as a client, clearing the association with an AP [40:4E:36:D1:DC:10]\n[01134.251] 17315.17327> wlan_sme::client::state [W]: Unexpected MLME message while Idle: DeauthenticateConf { resp: DeauthenticateConfirm { peer_sta_address: [64, 78, 54, 209, 220, 16] } }\n[01134.253] 15050.15063> Starting mDNS on interface ethp001f6 [80fe::5dd6:ffdf:1afe:4642]\n[01134.253] 15050.15063> [WARNING:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(29)] NET-291 IPV6_MULTICAST_LOOP not supported (ENOPROTOOPT), continuing anyway\n[01134.253] 15050.15063> [WARNING:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(50)] NET-2180 IPV6_JOIN_GROUP returned ENODEV, mDNS will not communicate via IPV6\n[tb] 2019-04-20 02:23:23.982 \u001b[22mINFO\u001b[0m Summary for test class WlanScanTest: ControllerInfo {'FuchsiaDevice': [{'ip': '192.168.42.101'}]}, Requested 3, Executed 3, Passed 3, Failed 0, Skipped 0, Blocked 0, Unknown 0, Errors 0 [base_test.py:run:1027]\n[tb] 2019-04-20 02:23:23.984 \u001b[22mINFO\u001b[0m Summary for test run tb@2019-04-20_02-22-39-578: ControllerInfo {'FuchsiaDevice': [{'ip': '192.168.42.101'}]}, Requested 4, Executed 4, Passed 4, Failed 0, Skipped 0, Blocked 0, Unknown 0, Errors 0\n/etc/connectivity/acts/tools/test/connectivity/acts/framework/acts/controllers/buds_lib/test_actions/bt_utils.py:201: SyntaxWarning: assertion is always true, perhaps remove parentheses?\n assert (True is pri_device.droid.bluetoothUnbond(target_address),\nCommand succeeded!\n", "outputs": [ "out.tar" ], "results": { "bot_dimensions": [ { "key": "device_type", "value": [ "Intel NUC Kit NUC7i5DNHE" ] }, { "key": "host_cpu", "value": [ "x64", "x86", "x86-64-avx2" ] }, { "key": "host_os", "value": [ "Debian", "Debian-9.8", "Linux" ] }, { "key": "id", "value": [ "fuchsia-tests-x64-lab01-0001--ocean-drank-wick-spot" ] }, { "key": "pool", "value": [ "fuchsia.tests.connectivity" ] }, { "key": "serial", "value": [ "0" ] }, { "key": "server_version", "value": [ "4267-53b17d5" ] }, { "key": "testbed", "value": [ "wifi_rf_chamber_nuc" ] }, { "key": "zone", "value": [ "cloud" ] } ], "bot_id": "fuchsia-tests-x64-lab01-0001--ocean-drank-wick-spot", "bot_version": "bd68e04e43fc4d1b7b5b65890a29c5d5bae55ab1423851733f92abd7e13dda14", "cipd_pins": { "client_package": { "package_name": "infra/tools/cipd/linux-amd64", "version": "nFftZl2kFquaI2EUmPGwzbc5sIbCocl9L55UeASqlLsC" }, "packages": [ { "package_name": "fuchsia/tools/botanist/linux-amd64", "path": ".", "version": "UKxkEVGmq0NiYbOjB0eSh6K_lJJ4_Mmm4BAi94owjbMC" } ] }, "completed_ts": "2019-04-20T02:23:34.991007", "costs_usd": [ 0.05707809438100489 ], "created_ts": "2019-04-20T02:21:02.706781", "duration": 115.68565082550049, "modified_ts": "2019-04-20T02:23:34.991007", "name": "all tests", "outputs_ref": { "isolated": "32b7f4ba1ffd3457bbd9f01ee23b09372f48afe1", "isolatedserver": "https://isolateserver.appspot.com", "namespace": "default-gzip" }, "run_id": "44515d91a1567f11", "server_versions": [ "4267-53b17d5" ], "started_ts": "2019-04-20T02:21:26.705369", "state": "COMPLETED", "tags": [ "device_type:Intel NUC Kit NUC7i5DNHE", "pool:fuchsia.tests.connectivity", "priority:200", "service_account:none", "swarming.pool.template:none", "swarming.pool.version:b0a6cb78cd34832b49b843b36f65ec5a7a568a79", "testbed:wifi_rf_chamber_nuc", "user:None" ], "task_id": "44515d91a1567f10", "try_number": "1" } } }