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

[00000.000000][0][0][klog] INFO: zbi: @ 0xffffff8000e97000 (14234752 bytes) [00000.000000][0][0][klog] INFO: UART: FIFO depth 16 [00000.000000][0][0][klog] INFO: PMM: boot reserve add [0x100000, 0x2fffff] [00000.000000][0][0][klog] INFO: PMM: boot reserve add [0xe97000, 0x1c2afff] [00000.000000][0][0][klog] INFO: PMM: boot reserve marking WIRED [0x100000, 0x2fffff] [00000.000000][0][0][klog] INFO: PMM: boot reserve marking WIRED [0xe97000, 0x1c2afff] [00000.000000][0][0][klog] INFO: [00000.000000][0][0][klog] INFO: welcome to Zircon [00000.000000][0][0][klog] INFO: [00000.000000][0][0][klog] INFO: KASLR: .text section at 0xffffffff00100000 [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00114ec4 (global_prng_seed) at level 0x30000, flags 0x1 [00000.000000][0][0][klog] INFO: initializing vm pre-heap [00000.000000][0][0][klog] INFO: VM: marking boot alloc used range [0x1c2b000, 0x1c2b080) [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00122274 (elf_build_id) at level 0x4fffe, flags 0x1 [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00122400 (version) at level 0x4ffff, flags 0x1 [00000.000000][0][0][klog] INFO: version: [00000.000000][0][0][klog] INFO: arch: x86 [00000.000000][0][0][klog] INFO: platform: XXX [00000.000000][0][0][klog] INFO: target: XXX [00000.000000][0][0][klog] INFO: project: x64 [00000.000000][0][0][klog] INFO: buildid: git-82b292aae1f792fa45e1a3130b748ff42d629eb7-dirty [00000.000000][0][0][klog] INFO: ELF build ID: 24379dec163ea89b4944285c9d30d33291ba3673 [00000.000000][0][0][klog] INFO: initializing heap [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00101fc4 (console) at level 0x50000, flags 0x1 [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff001826c4 (x86_resource_init) at level 0x50000, flags 0x1 [00000.000000][0][0][klog] INFO: initializing vm [00000.000000][0][0][klog] INFO: VM: reserving kernel region [0xffffffff00100000, 0xffffffff0021b000) flags 0x28 name 'kernel_code' [00000.000000][0][0][klog] INFO: VM: reserving kernel region [0xffffffff0021b000, 0xffffffff0026c000) flags 0x8 name 'kernel_rodata' [00000.000000][0][0][klog] INFO: VM: reserving kernel region [0xffffffff0026c000, 0xffffffff00274000) flags 0x18 name 'kernel_data' [00000.000000][0][0][klog] INFO: VM: reserving kernel region [0xffffffff00274000, 0xffffffff00300000) flags 0x18 name 'kernel_bss' [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00183388 (display_memtype) at level 0x60001, flags 0x1 [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00186818 (acpi_tables) at level 0x60001, flags 0x1 [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff001814c8 (hpet) at level 0x60002, flags 0x1 [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00181888 (apic) at level 0x60002, flags 0x1 [00000.000000][0][0][klog] INFO: x2APIC enabled [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff001a7e6c (system_topology_init) at level 0x60002, flags 0x1 [00000.000000][0][0][klog] INFO: WARNING: unable to find any cache levels. [00000.000000][0][0][klog] INFO: WARNING: unable to find any cache levels. [00000.000000][0][0][klog] INFO: Could not find SRAT table. ACPICA returned: 5 [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00185e08 (timer) at level 0x60003, flags 0x1 [00000.000000][0][0][klog] INFO: pvclock: Clocksource is stable [00000.000000][0][0][klog] INFO: HPET frequency: 100000 ticks/ms [00000.000000][0][0][klog] INFO: pvclock: Fetching TSC frequency [00000.000000][0][0][klog] INFO: TSC frequency: 2299808 ticks/ms [00000.516105][0][0][klog] INFO: timer features: constant_tsc 1 invariant_tsc 1 tsc_deadline 1 [00000.516105][0][0][klog] INFO: Using TSC as wallclock [00000.516106][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00208930 (percpu_heap_init) at level 0x60003, flags 0x1 [00000.516110][0][0][klog] INFO: initializing kernel [00000.516111][0][0][klog] INFO: initializing mp [00000.516112][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00115118 (global_prng_thread_safe) at level 0x7ffff, flags 0x1 [00000.516117][0][0][klog] INFO: creating bootstrap completion thread [00000.516219][0][0][klog] INFO: top of bootstrap2() [00000.516220][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00131734 (libobject) at level 0x80000, flags 0x1 [00000.516311][0][0][klog] INFO: OOM: started thread [00000.516312][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff0020589c (dpc) at level 0x80000, flags 0x1 [00000.516353][0][0][klog] INFO: Processor Model Info: type 0 family 0x6 model 0xf stepping 0 [00000.516354][0][0][klog] INFO: display_family 0x6 display_model 0x3f [00000.516355][0][0][klog] INFO: Vendor: Intel [00000.516356][0][0][klog] INFO: Microarch: Haswell [00000.516356][0][0][klog] INFO: F/M/S: 6/3f/0 [00000.516356][0][0][klog] INFO: patch_level: 0 [00000.516357][0][0][klog] INFO: Brand: Intel(R) Xeon(R) CPU @ 2.30GHz [00000.516359][0][0][klog] INFO: Features: fpu sse sse2 sse3 ssse3 sse4.1 sse4.2 mmx avx avx2 fxsr pcid xsave aesni clflush [00000.516361][0][0][klog] INFO: fsgsbase tsc_adj smep erms rdrand syscall nx rdtscp tsc_deadline x2apic hypervisor [00000.516361][0][0][klog] INFO: Properties: meltdown l1tf pcid_good [00000.516376][0][0][klog] INFO: initializing platform [00000.516377][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff001a157c (x86_perfmon) at level 0x90000, flags 0x1 [00000.516398][0][0][klog] INFO: x86_perfmon_init_once:454: perfmon: unexpected programmable counter width 0 in cpuid.0AH [00000.516993][0][0][klog] INFO: UART: started IRQ driven RX [00000.516994][0][0][klog] INFO: UART: started IRQ driven TX [00000.517012][0][0][klog] INFO: cpu topology: [00000.517012][0][0][klog] INFO: 0: apic id 0x0 BSP [00000.517013][0][0][klog] INFO: 1: apic id 0x1 [00000.517014][0][0][klog] INFO: 2: apic id 0x2 [00000.517014][0][0][klog] INFO: 3: apic id 0x3 [00000.517015][0][0][klog] INFO: Found 4 cpus [00000.517452][0][0][klog] INFO: booting apic ids: 0x1 0x2 0x3 [00000.529592][0][0][klog] INFO: entering scheduler on cpu 2 [00000.529755][0][0][klog] INFO: entering scheduler on cpu 3 [00000.529780][0][0][klog] INFO: entering scheduler on cpu 1 [00000.531557][0][0][klog] INFO: smbios: manufacturer="QEMU" product="Standard PC (Q35 + ICH9, 2009)" [00000.531558][0][0][klog] INFO: initializing target [00000.531560][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff001850b8 (x86_pcie_init) at level 0xa0000, flags 0x1 [00000.531615][0][0][klog] INFO: moving to last init level [00000.531616][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff001214cc (debuglog) at level 0xb0000, flags 0x1 [00000.531681][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00174d40 (kcounters) at level 0xb0000, flags 0x1 [00000.531686][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00102aec (kernel_shell) at level 0xc0000, flags 0x1 [00000.531713][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00202ad4 (ktrace) at level 0xc0000, flags 0x1 [00000.919052][0][0][klog] INFO: ktrace: buffer at 0xffffff9b27d86000 (33554432 bytes) [00000.919122][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff002184f8 (userboot) at level 0xc0000, flags 0x1 [00000.919124][0][0][klog] INFO: userboot: ramdisk 0xd94000 @ 0xffffff8000e97000 [00000.934673][0][0][klog] INFO: userboot: userboot rodata 0 @ [0x51291c354000,0x51291c357000) [00000.934696][0][0][klog] INFO: userboot: userboot code 0x3000 @ [0x51291c357000,0x51291c362000) [00000.934697][0][0][klog] INFO: userboot: vdso/full rodata 0 @ [0x51291c362000,0x51291c369000) [00000.934698][0][0][klog] INFO: userboot: vdso/full code 0x7000 @ [0x51291c369000,0x51291c36a000) [00000.934736][0][0][klog] INFO: userboot: entry point @ 0x51291c357c90 [00000.958150][1034][1041][klog] INFO: userboot: option "/b/s/w/ir/multiboot-created_by_recipe.bin=" [00000.958186][1034][1041][klog] INFO: userboot: option "zircon.autorun.system=/boot/bin/sh+/boot/infra/runcmds" [00000.958187][1034][1041][klog] INFO: userboot: option "kernel.halt-on-panic=true" [00000.958188][1034][1041][klog] INFO: userboot: option "devmgr.suspend-timeout-debug=true" [00000.958189][1034][1041][klog] INFO: userboot: option "TERM=dumb" [00000.958189][1034][1041][klog] INFO: userboot: option "kernel.serial=legacy" [00000.958190][1034][1041][klog] INFO: userboot: option "multiboot.boot_loader_name=qemu" [00001.004596][1034][1041][klog] INFO: userboot: searching bootfs for 'bin/bootsvc' [00001.004697][1034][1041][klog] INFO: userboot: 'bin/bootsvc' has PT_INTERP "lib/ld.so.1" [00001.004697][1034][1041][klog] INFO: userboot: searching bootfs for 'lib/ld.so.1' [00001.004738][1034][1041][klog] INFO: userboot: userboot: loaded lib/ld.so.1 at 0x7d84ae097000, entry point 0x7d84ae0b53d0 [00001.004806][1034][1041][klog] INFO: userboot: userboot: loaded vDSO at 0x438de1af6000, entry point 0x438de1afd8fa [00001.004883][1034][1041][klog] INFO: userboot: process bin/bootsvc started. [00001.004911][1034][1041][klog] INFO: userboot: waiting for loader-service requests... [00001.005481][1034][1041][klog] INFO: userboot: searching bootfs for 'lib/libasync-default.so' [00001.005769][1034][1041][klog] INFO: userboot: searching bootfs for 'lib/libfdio.so' [00001.042304][1034][1041][klog] INFO: userboot: searching bootfs for 'lib/libtrace-engine.so' [00001.042555][1034][1041][klog] INFO: userboot: searching bootfs for 'lib/liblaunchpad.so' [00001.043166][1048][1051][klog] INFO: {{{reset}}} [00001.043195][1048][1051][klog] INFO: {{{module:0:<application>:elf:04b4a34a2691363e32b2d7bb7f817640b16ec624}}} [00001.043197][1048][1051][klog] INFO: {{{mmap:0x2a5414385000:0x3a000:load:0:rx:0}}} [00001.043197][1048][1051][klog] INFO: {{{mmap:0x2a54143bf000:0x4000:load:0:rw:0x3a000}}} [00001.043198][1048][1051][klog] INFO: dso: id=04b4a34a2691363e32b2d7bb7f817640b16ec624 base=0x00002a5414385000 name=<application> [00001.043199][1048][1051][klog] INFO: {{{module:0x2:<vDSO>:elf:71712e0a455898a51e34d40a4013786ab1fa5335}}} [00001.043200][1048][1051][klog] INFO: {{{mmap:0x438de1af6000:0x7000:load:0x2:r:0}}} [00001.043201][1048][1051][klog] INFO: {{{mmap:0x438de1afd000:0x1000:load:0x2:rx:0x7000}}} [00001.043201][1048][1051][klog] INFO: dso: id=71712e0a455898a51e34d40a4013786ab1fa5335 base=0x0000438de1af6000 name=<vDSO> [00001.043202][1048][1051][klog] INFO: {{{module:0x3:libasync-default.so:elf:8c511b4202cf569c8fdde14ca97222102ba3a9e0}}} [00001.043202][1048][1051][klog] INFO: {{{mmap:0x53c44f3bd000:0x1000:load:0x3:rx:0}}} [00001.043203][1048][1051][klog] INFO: {{{mmap:0x53c44f3be000:0x1000:load:0x3:rw:0x1000}}} [00001.043203][1048][1051][klog] INFO: dso: id=8c511b4202cf569c8fdde14ca97222102ba3a9e0 base=0x000053c44f3bd000 name=libasync-default.so [00001.043204][1048][1051][klog] INFO: {{{module:0x4:libfdio.so:elf:57a73a8b04ea66e0b20945ca56238e6bc8a0b0bc}}} [00001.043205][1048][1051][klog] INFO: {{{mmap:0x2420ea8df000:0x32000:load:0x4:rx:0}}} [00001.043205][1048][1051][klog] INFO: {{{mmap:0x2420ea912000:0x4000:load:0x4:rw:0x33000}}} [00001.043205][1048][1051][klog] INFO: dso: id=57a73a8b04ea66e0b20945ca56238e6bc8a0b0bc base=0x00002420ea8df000 name=libfdio.so [00001.043206][1048][1051][klog] INFO: {{{module:0x5:libtrace-engine.so:elf:95bc229c3cf2ab8a51f12c33a311105ff8976120}}} [00001.043207][1048][1051][klog] INFO: {{{mmap:0x749b5480000:0x9000:load:0x5:rx:0}}} [00001.043207][1048][1051][klog] INFO: {{{mmap:0x749b5489000:0x2000:load:0x5:rw:0x9000}}} [00001.043208][1048][1051][klog] INFO: dso: id=95bc229c3cf2ab8a51f12c33a311105ff8976120 base=0x00000749b5480000 name=libtrace-engine.so [00001.043208][1048][1051][klog] INFO: {{{module:0x6:liblaunchpad.so:elf:5b0854856213eae6bab93c982f39551f8f9591c5}}} [00001.043209][1048][1051][klog] INFO: {{{mmap:0x5bdfd8a30000:0x7000:load:0x6:rx:0}}} [00001.043209][1048][1051][klog] INFO: {{{mmap:0x5bdfd8a37000:0x2000:load:0x6:rw:0x7000}}} [00001.043209][1048][1051][klog] INFO: dso: id=5b0854856213eae6bab93c982f39551f8f9591c5 base=0x00005bdfd8a30000 name=liblaunchpad.so [00001.043210][1048][1051][klog] INFO: {{{module:0x1:libc.so:elf:5d417db61b3a48a58a8fa1ffbfc2e71b830e0857}}} [00001.043210][1048][1051][klog] INFO: {{{mmap:0x7d84ae097000:0xa6000:load:0x1:rx:0}}} [00001.043211][1048][1051][klog] INFO: {{{mmap:0x7d84ae13d000:0x5000:load:0x1:rw:0xa6000}}} [00001.043211][1048][1051][klog] INFO: dso: id=5d417db61b3a48a58a8fa1ffbfc2e71b830e0857 base=0x00007d84ae097000 name=libc.so [00001.187129][1048][1051][klog] INFO: bootsvc: Starting... [00001.187253][1048][1051][klog] INFO: bootsvc: Creating bootfs service... [00001.187352][1034][1041][klog] INFO: userboot: loader-service channel peer closed [00001.187400][1034][1041][klog] INFO: userboot: finished! [00001.188001][1048][1051][klog] INFO: bootsvc: Retrieving boot image... [00001.202624][1048][1051][klog] INFO: bootsvc: Decommitted BOOTDATA VMO from 0 to 0xd93000 [00001.202658][1048][1051][klog] INFO: bootsvc: Loading boot arguments... [00001.202714][1048][1051][klog] INFO: bootsvc: Creating svcfs service... [00001.202740][1048][1051][klog] INFO: bootsvc: Loading kernel VMOs... [00001.202783][1048][1051][klog] INFO: bootsvc: Creating loader service... [00001.202814][1048][1051][klog] INFO: bootsvc: Launching next process... [00001.203667][1048][1106][klog] INFO: bootsvc: Launched bin/devcoordinator [00001.229126][1113][1128][klog] INFO: devcoordinator: launch /boot/bin/svchost (svchost) OK [00001.232224][1113][1128][klog] INFO: devcoordinator: launch /boot/bin/fshost (fshost) OK [00001.244144][1113][1321][klog] INFO: devcoordinator: launch /boot/bin/pwrbtn-monitor (pwrbtn-monitor) OK [00001.249463][1113][1335][klog] INFO: devcoordinator: launch /boot/bin/netsvc (netsvc) OK [00001.264396][1113][1335][klog] INFO: devcoordinator: launch /boot/bin/virtual-console (virtual-console) OK [00001.296160][1113][1128][klog] INFO: devcoordinator: full system required, ignoring fallback drivers until /system is loaded [00001.303363][1113][1128][klog] INFO: devcoordinator: launch devhost 'devhost:sys': pid=2185 [00001.305760][1113][1128][klog] INFO: devcoordinator: launch devhost 'devhost:test': pid=2228 [00001.314100][2185][2199][klog] INFO: devhost: trace provider registry begun [00001.314519][1113][1128][klog] INFO: devcoordinator: launch devhost 'devhost:root': pid=2282 [00001.318415][2228][2251][klog] INFO: devhost: trace provider registry begun [00001.327611][1113][1128][klog] INFO: devcoordinator: launch devhost 'devhost:misc': pid=2405 [00001.328926][2282][2314][klog] INFO: devhost: trace provider registry begun [00001.336034][2405][2428][klog] INFO: devhost: trace provider registry begun [00001.354144][1113][1328][klog] INFO: devcoordinator: launch /boot/bin/sh (sh:console) OK [00001.368027][2185][2199][klog] INFO: acpi-bus: not using IOMMU [00001.371967][2185][2199][klog] INFO: GetHwProperties: No PM support [00001.372057][2185][2199][klog] INFO: IntelPT: No PT support [00001.372069][2185][2199][klog] INFO: devhost[sys/cpu-trace] bind driver '/boot/driver/cpu-trace.so' failed: -2 [00001.372159][1113][1128][klog] INFO: devcoordinator: rpc: bind-driver 'cpu-trace' status -2 [00001.388125][2185][2199][klog] INFO: [sysmem_init 28] async_get_default_dispatcher(): 0x15589eec1e50 [00001.388204][2185][2199][klog] INFO: [sysmem_bind 40] sysmem_bind() [00001.389600][2185][2797][klog] INFO: acpi-pwrbtn: initialized [00001.400784][1685][1719][klog] INFO: vc: new input device /dev/class/input/000 [00001.495588][2185][2797][klog] INFO: acpi: published device pci(0x1557deed29b0), parent=sys(0x15591eeb6610), handle=0x15579eec0590 [00001.495768][2185][2797][klog] INFO: acpi: failed to get PCI base bus number for device 'PCI0' (status 4294967295) [00001.498356][2185][2797][klog] INFO: acpi: published device rtc(0x1557deec12b0), parent=acpi(0x15591eeb6810), handle=0x15579eec07d0 [00001.501503][2185][2797][klog] INFO: acpi: published device i8042(0x1557deec8b10), parent=acpi(0x15591eeb6810), handle=0x15579eec0710 [00001.512424][1113][1128][klog] INFO: devcoordinator: launch devhost 'devhost:pci#1:1234:1111': pid=2902 [00001.519731][1113][1128][klog] INFO: devcoordinator: launch devhost 'devhost:pci#2:1af4:1001': pid=2962 [00001.523727][2902][2916][klog] INFO: devhost: trace provider registry begun [00001.525804][1113][1128][klog] INFO: devcoordinator: launch devhost 'devhost:pci#3:1af4:1000': pid=3039 [00001.532639][2962][2989][klog] INFO: devhost: trace provider registry begun [00001.533701][1113][1128][klog] INFO: devcoordinator: launch devhost 'devhost:pci#4:1af4:1001': pid=3123 [00001.538312][3039][3068][klog] INFO: devhost: trace provider registry begun [00001.541922][1113][1128][klog] INFO: devcoordinator: launch devhost 'devhost:pci#6:8086:2922': pid=3231 [00001.543522][3123][3176][klog] INFO: devhost: trace provider registry begun [00001.550823][2185][2199][klog] INFO: devhost: WARNING: driver '/boot/driver/pc-ps2.so' did not add device in bind() [00001.554780][2185][3362][klog] INFO: i8042 device 0xab 0x41 [00001.576910][3231][3280][klog] INFO: devhost: trace provider registry begun [00001.576915][1685][1719][klog] INFO: vc: new input device /dev/class/input/001 [00001.585230][2902][2916][klog] INFO: bochs_vbe: initialized display, 1024 x 768 (stride=1024 format=00020001) [00001.585232][3231][3280][klog] INFO: ahci: using MSI interrupt [00001.597158][1685][1719][klog] INFO: vc: new display device /dev/class/display-controller/000 [00001.605556][2902][2916][klog] INFO: GetSysmemConnection failed (continuing) - status: -2 [00001.615903][1685][1719][klog] INFO: vc: Successfully attached to display 1 [00001.639683][1297][1311][klog] INFO: fshost: /dev/class/block/000: FVM? [00001.658565][1533][1623][klog] INFO: netifc: ? /dev/class/ethernet/000 [00001.670693][1533][1623][klog] INFO: netifc: create 512 eth buffers [00001.679169][1297][1311][klog] INFO: devcoordinator: launch /boot/bin/blobfs (blobfs:/blob) OK [00001.684775][1533][1623][klog] INFO: macaddr: 52:54:00:12:34:56 [00001.684936][1533][1623][klog] INFO: ip6addr: fe80::5054:4d:fe12:3456 [00001.684979][1533][1623][klog] INFO: snmaddr: ff02::1:ff12:3456 [00001.685127][1533][1623][klog] INFO: netsvc: using /dev/class/ethernet/000 [00001.685823][1533][1623][klog] INFO: netsvc: nodename='swarm-donut-petri-acre' [00001.685908][1533][1623][klog] INFO: netsvc: start [00001.753041][2185][3362][klog] INFO: i8042 device 0x00 [00001.758944][2185][2199][klog] INFO: hid: boot mouse hack for "hid-device-002": report count (0->1), inp sz (24->24), out sz (0->0), feat sz (0->0) [00001.766976][1685][1719][klog] INFO: vc: new input device /dev/class/input/002 [00001.888230][1297][1311][klog] INFO: fshost: starting 'bin/pkgsvr' 'caf04bc259f36cf7ddce32f2d672d884f941962434cb2f5e92e4ca98c77426db'... [00001.904294][1297][1311][klog] INFO: devcoordinator: launch bin/pkgsvr (pkgfs) OK [00001.935142][4044][4248][klog] INFO: pkgsvr: system: will be served from caf04bc259f36cf7ddce32f2d672d884f941962434cb2f5e92e4ca98c77426db [00001.935229][4044][4248][klog] INFO: pkgsvr: pkgfs serving index /data/pkgfs_index from blobfs /blob [00001.943144][1297][1311][klog] INFO: fshost: mounting minfs [00001.954635][1297][1311][klog] INFO: devcoordinator: launch /boot/bin/minfs (minfs:/data) OK [00001.966557][1113][4318][klog] INFO: devcoordinator: adding system driver 'gpu' '/system/driver/libmsd_intel.so' [00002.012927][1113][1731][klog] INFO: devcoordinator: launch /system/bin/appmgr (appmgr) OK [00002.012965][1113][1731][klog] INFO: autorun: starting '/boot/bin/sh' '/boot/infra/runcmds'... [00010.674752][1113][4318][klog] INFO: devcoordinator: adding system driver 'wlan' '/system/driver/wlan.so' [00010.683922][1113][1731][klog] INFO: devcoordinator: launch /boot/bin/sh (autorun:system) OK [00010.700137][1113][4318][klog] INFO: devcoordinator: adding system driver 'magma_pdev_gpu' '/system/driver/libmsd_arm.so' [00010.785464][1113][4318][klog] INFO: devcoordinator: adding system driver 'ralink' '/system/driver/ralink.so' [00010.799551][1113][4318][klog] INFO: devcoordinator: adding system driver 'bt_hog' '/system/driver/bt-hog.so' [00010.813333][1113][4318][klog] INFO: devcoordinator: adding system driver 'ath10k_pci' '/system/driver/ath10k.so' [00010.829317][1113][4318][klog] INFO: devcoordinator: adding system driver 'rtl88xx' '/system/driver/rtl88xx.so' [00010.866041][1113][4318][klog] INFO: devcoordinator: adding system driver 'bt_hci_atheros' '/system/driver/bt-hci-atheros.so' [00010.885619][1113][4318][klog] INFO: devcoordinator: adding system driver 'virtual_audio' '/system/driver/virtual_audio_driver.so' [00010.924387][1113][4318][klog] INFO: devcoordinator: adding system driver 'bt_hci_intel' '/system/driver/bt-hci-intel.so' [00010.974527][1113][4318][klog] INFO: devcoordinator: adding system driver 'bt_host' '/system/driver/bt-host.so' [00011.004118][1113][4318][klog] INFO: devcoordinator: adding system driver 'mt8167s_gpu' '/system/driver/libmsd_img_rgx_mtk.so' [00011.027035][1113][4318][klog] INFO: devcoordinator: adding system driver 'wlan' '/system/driver/wlanif.so' [00011.049493][1113][4318][klog] INFO: devcoordinator: adding system driver 'bt_hci_fake' '/system/driver/bt-hci-fake.so' [00011.069972][1113][4318][klog] INFO: devcoordinator: adding system driver 'qmi_usb' '/system/driver/qmi-transport.so' [00011.099198][1113][4318][klog] INFO: devcoordinator: adding system driver 'brcmfmac' '/system/driver/brcmfmac.so' [00011.100128][4514][4532][klog] INFO: [INFO:namespace_builder.cc(93)] config-data for sysmgr [00011.118806][1113][4318][klog] INFO: devcoordinator: adding system driver 'qmi_fake' '/system/driver/qmi-fake.so' [00011.127595][1113][4318][klog] INFO: devcoordinator: adding system driver 'bt_hci_passthrough' '/system/driver/bt-hci-passthrough.so' [00011.148841][1113][4318][klog] INFO: devcoordinator: adding system driver 'usb_video' '/system/driver/usb_video.so' [00011.183416][1113][4318][klog] INFO: devcoordinator: adding system driver 'virtual_camera' '/system/driver/virtual_camera_driver.so' [00011.211604][1113][4318][klog] INFO: devcoordinator: adding system driver 'wlan' '/system/driver/wlanphy.so' [00011.225457][1113][4318][klog] INFO: devcoordinator: adding system driver 'iwlwifi_pci' '/system/driver/iwlwifi.so' [00011.228983][1113][1128][klog] INFO: devcoordinator: driver 'iwlwifi_pci' added [00011.229039][1113][1128][klog] INFO: devcoordinator: driver 'wlan' added [00011.229158][1113][1128][klog] INFO: devcoordinator: driver 'usb_video' added [00011.229161][1113][1128][klog] INFO: devcoordinator: driver 'qmi_fake' added [00011.229164][1113][1128][klog] INFO: devcoordinator: driver 'brcmfmac' added [00011.229168][1113][1128][klog] INFO: devcoordinator: driver 'qmi_usb' added [00011.229171][1113][1128][klog] INFO: devcoordinator: driver 'bt_hci_fake' added [00011.229173][1113][1128][klog] INFO: devcoordinator: driver 'wlan' added [00011.229176][1113][1128][klog] INFO: devcoordinator: driver 'mt8167s_gpu' added [00011.229180][1113][1128][klog] INFO: devcoordinator: driver 'bt_host' added [00011.229182][1113][1128][klog] INFO: devcoordinator: driver 'bt_hci_intel' added [00011.229191][1113][1128][klog] INFO: devcoordinator: driver 'bt_hci_atheros' added [00011.229193][1113][1128][klog] INFO: devcoordinator: driver 'rtl88xx' added [00011.229197][1113][1128][klog] INFO: devcoordinator: driver 'ath10k_pci' added [00011.229199][1113][1128][klog] INFO: devcoordinator: driver 'bt_hog' added [00011.229202][1113][1128][klog] INFO: devcoordinator: driver 'ralink' added [00011.229204][1113][1128][klog] INFO: devcoordinator: driver 'magma_pdev_gpu' added [00011.229208][1113][1128][klog] INFO: devcoordinator: driver 'wlan' added [00011.229211][1113][1128][klog] INFO: devcoordinator: driver 'gpu' added [00011.229214][1113][1128][klog] INFO: devcoordinator: driver 'bt_hci_passthrough' added [00011.589445][1570994003][0][netstack] INFO: netstack.go(674): NIC lo added [sniff = false] [00011.229217][1113][1128][klog] INFO: devcoordinator: fallback driver 'usb_composite' is available [00011.591447][1570994003][0][netstack] WARNING: main.go(167): OnInterfacesChanged failed: ErrBadHandle: zx.Channel.Write [00011.229219][1113][1128][klog] INFO: devcoordinator: driver 'usb_composite' added [00016.181481][6773][6791][pkg_resolver] INFO: starting package resolver [00016.211727][6773][6791][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/main.rs(116): error loading static repo config: file /config/data/pkg_resolver/repositories failed to parse: No such file or directory (os error 2) [00011.229222][1113][1128][klog] INFO: devcoordinator: fallback driver 'intel_disp' is available [00011.229223][1113][1128][klog] INFO: devcoordinator: driver 'intel_disp' added [00011.467904][4514][4532][klog] INFO: [INFO:namespace_builder.cc(93)] config-data for amber [00016.130923][4514][4532][klog] INFO: [INFO:namespace_builder.cc(93)] config-data for pkg_resolver [00016.505913][7488][7500][pkg_cache] INFO: starting package cache service [00016.507445][7326][7338][klog] INFO: [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 [00016.507497][7326][7338][klog] INFO: kfill_days=2, start_event_aggregator_worker=1. [00016.531102][7488][7595][pkg_cache] INFO: spawning package cache service [00016.574759][688330799][0][amber] INFO: error opening /misc/ota/current_channel.json: open /misc/ota/current_channel.json: ErrNotFound: io.directory.Open [00016.577954][688330799][0][amber] INFO: monitoring for updates [00016.578090][688330799][0][amber] INFO: system update monitor exited [00016.583330][688330799][0][amber] INFO: control_server: could not get update for mdns/0: merkle not found for package mdns/0 [00016.586442][6773][7016][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/mdns/0 was not found: merkle not found for package mdns/0 [00016.586568][6773][7016][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00016.588607][688330799][0][amber] INFO: control_server: could not get update for netcfg/0: merkle not found for package netcfg/0 [00016.589584][6773][7011][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/netcfg/0 was not found: merkle not found for package netcfg/0 [00016.589634][6773][7011][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00016.590448][688330799][0][amber] INFO: control_server: could not get update for wlancfg/0: merkle not found for package wlancfg/0 [00016.591630][6773][7016][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/wlancfg/0 was not found: merkle not found for package wlancfg/0 [00016.591710][6773][7016][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00016.592915][688330799][0][amber] INFO: control_server: could not get update for cobalt_system_metrics/0: merkle not found for package cobalt_system_metrics/0 [00016.593976][6773][7011][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/cobalt_system_metrics/0 was not found: merkle not found for package cobalt_system_metrics/0 [00016.594080][6773][7011][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00016.595240][688330799][0][amber] INFO: control_server: could not get update for network_time/0: merkle not found for package network_time/0 [00016.595425][6773][7011][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/network_time/0 was not found: merkle not found for package network_time/0 [00016.595464][6773][7011][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00016.596227][688330799][0][amber] INFO: control_server: could not get update for sshd-host/0: merkle not found for package sshd-host/0 [00016.597081][6773][7011][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/sshd-host/0 was not found: merkle not found for package sshd-host/0 [00016.597122][6773][7011][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00016.598377][688330799][0][amber] INFO: control_server: could not get update for memory_monitor/0: merkle not found for package memory_monitor/0 [00016.598583][6773][7011][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/memory_monitor/0 was not found: merkle not found for package memory_monitor/0 [00016.599093][6773][7011][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00016.599973][688330799][0][amber] INFO: control_server: could not get update for thermd/0: merkle not found for package thermd/0 [00016.600887][6773][7016][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/thermd/0 was not found: merkle not found for package thermd/0 [00016.601997][6773][7016][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00016.602782][688330799][0][amber] INFO: control_server: could not get update for kernel_crash_checker/0: merkle not found for package kernel_crash_checker/0 [00016.603523][6773][7011][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/kernel_crash_checker/0 was not found: merkle not found for package kernel_crash_checker/0 [00016.604242][6773][7011][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00016.605118][688330799][0][amber] INFO: control_server: could not get update for log_listener/0: merkle not found for package log_listener/0 [00016.605622][6773][7016][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/log_listener/0 was not found: merkle not found for package log_listener/0 [00016.605694][6773][7016][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00016.606292][688330799][0][amber] INFO: control_server: could not get update for mkdir/0: merkle not found for package mkdir/0 [00016.606499][6773][7011][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/mkdir/0 was not found: merkle not found for package mkdir/0 [00016.606548][6773][7011][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00016.607328][688330799][0][amber] INFO: control_server: could not get update for crashpad_agent/0: merkle not found for package crashpad_agent/0 [00016.607546][6773][7011][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/crashpad_agent/0 was not found: merkle not found for package crashpad_agent/0 [00016.607685][6773][7011][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00016.608142][688330799][0][amber] INFO: control_server: could not get update for trace_manager/0: merkle not found for package trace_manager/0 [00016.608732][6773][7016][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/trace_manager/0 was not found: merkle not found for package trace_manager/0 [00016.608861][6773][7016][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00016.609500][688330799][0][amber] INFO: control_server: could not get update for device_settings_manager/0: merkle not found for package device_settings_manager/0 [00016.609769][6773][7011][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/device_settings_manager/0 was not found: merkle not found for package device_settings_manager/0 [00016.609821][6773][7011][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00016.667318][4514][4532][klog] INFO: [INFO:namespace_builder.cc(93)] config-data for mdns [00016.827549][4514][4532][klog] INFO: [INFO:namespace_builder.cc(93)] config-data for wlancfg [00016.780900][8128][8140][netcfg] INFO: Started [00017.362688][4514][4532][klog] INFO: [INFO:namespace_builder.cc(93)] config-data for crashpad_agent [00017.430562][688330799][0][amber] INFO: control_server: could not get update for wlanstack2/0: merkle not found for package wlanstack2/0 [00017.434208][8527][8570][klog] INFO: [INFO:system_metrics_main.cc(23)] Cobalt SystemMetricsDaemon: System metrics daemon created. [00017.442014][6773][7016][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/wlanstack2/0 was not found: merkle not found for package wlanstack2/0 [00017.446208][6773][7016][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00017.049756][8799][8813][network_time] INFO: main.cc(29): Opening client config from /pkg/data/roughtime-servers.json [00017.049769][8799][8813][network_time] INFO: main.cc(33): Connecting to RTC device at /dev/class/rtc/000 [00017.049775][8799][8813][network_time] INFO: timezone.cc(28): started [00017.253310][9660][9672][crash] INFO: main.cc(57): no kernel crash log found [00017.628535][10814][10834][klog] INFO: [INFO:main.cc(35)] Trace Manager starting with config: /pkg/data/tracing.config [00017.655583][10405][10421][klog] INFO: [00017.655621][10405][10421][klog] INFO: ------------------------------------------------ [00017.655625][10405][10421][klog] INFO: RUNNING TEST: /pkgfs/packages/ledger_tests/0/test/disabled/ledger_e2e_sync [00017.655625][10405][10421][klog] INFO: [00017.672526][1570994003][0][netstack] WARNING: client.go(115): ErrNotSupported: ConfigMulticastSetPromiscuousMode [00017.706109][1570994003][0][netstack] INFO: netstack.go(674): NIC ethp0003 added [sniff = false] [00017.707652][1570994003][0][netstack] INFO: netstack.go(701): NIC ethp0003: link-local IPv6: fe80::5054:ff:fe12:3456 [00017.738673][1570994003][0][netstack] INFO: netstack.go(451): NIC ethp0003: starting [00017.748450][1570994003][0][netstack] WARNING: netstack.go(536): getNodeName: device settings error: key not set [00017.754259][1570994003][0][netstack] INFO: netstack.go(369): NIC ethp0003: DHCP acquired IP 10.0.2.15 for 24h0m0s [00017.754325][1570994003][0][netstack] INFO: netstack.go(370): NIC ethp0003: Adding DNS servers: [10.0.2.3] [00017.755787][1570994003][0][netstack] INFO: netstack.go(380): adding routes [{Destination:0.0.0.0 Mask:0.0.0.0 Gateway:10.0.2.2 NIC:2} {Destination::: Mask::: Gateway: NIC:2} {Destination:10.0.2.0 Mask:255.255.255.0 Gateway: NIC:2}] with metric=<not-set> dynamic=true [00017.765017][1570994003][0][netstack] WARNING: netstack.go(536): getNodeName: device settings error: key not set [00017.839524][8799][8813][network_time] INFO: timezone.cc(107): time set to: 2019-04-20T19:19:50Z [00017.911532][11153][11281][device_settings] INFO: setting string key: "DeviceName", val: "swarm-donut-petri-acre" [00017.913725][688330799][0][amber] INFO: control_server: could not get update for cpuperf_provider/0: merkle not found for package cpuperf_provider/0 [00017.923870][6773][7011][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/cpuperf_provider/0 was not found: merkle not found for package cpuperf_provider/0 [00017.924664][6773][7011][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00017.934806][688330799][0][amber] INFO: control_server: could not get update for ktrace_provider/0: merkle not found for package ktrace_provider/0 [00017.936778][1570994003][0][netstack, listen] INFO: socket_server.go(708): 0xa8348070820: local={NIC:0 Addr: Port:22} [00017.939195][6773][7016][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/ktrace_provider/0 was not found: merkle not found for package ktrace_provider/0 [00017.941459][6773][7016][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00017.949344][688330799][0][amber] INFO: control_server: could not get update for run_test_component/0: merkle not found for package run_test_component/0 [00017.952541][6773][7016][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/run_test_component/0 was not found: merkle not found for package run_test_component/0 [00017.953638][6773][7016][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00017.965312][11561][11575][klog] INFO: wlanstack2 [I]: Starting [00018.127545][688330799][0][amber] INFO: control_server: could not get update for ledger_tests/0: merkle not found for package ledger_tests/0 [00018.128294][6773][7011][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/ledger_tests/0 was not found: merkle not found for package ledger_tests/0 [00018.128353][6773][7011][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00018.170326][688330799][0][amber] INFO: control_server: could not get update for ledger_tests/0: merkle not found for package ledger_tests/0 [00018.170822][6773][7011][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/ledger_tests/0 was not found: merkle not found for package ledger_tests/0 [00018.170869][6773][7011][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00018.294986][688330799][0][amber] INFO: control_server: could not get update for http/0: merkle not found for package http/0 [00018.295200][6773][7011][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/http/0 was not found: merkle not found for package http/0 [00018.295220][6773][7011][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00018.429611][1570994003][0][netstack] VLOG(1): getaddrinfo: addr=[10.0.2.2], err=lookup 10.0.2.2: invalid domain name [00018.440430][10405][10421][klog] INFO: [INFO:sync_params.cc(189)] Sync credentials sha256: 33D155E63AAF5302E51675D96D087B8F0D8994B7C2FEDD443DABD77E043E9BAD [00018.457168][10405][10421][klog] INFO: [==========] Running 6 tests from 3 test suites. [00018.457232][10405][10421][klog] INFO: [----------] Global test environment set-up. [00018.457269][10405][10421][klog] INFO: [----------] 2 tests from ManyLedgersConvergenceTest/ConvergenceTest [00018.461147][688330799][0][amber] INFO: control_server: could not get update for cloud_provider_firestore/0: merkle not found for package cloud_provider_firestore/0 [00018.461599][6773][7016][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/cloud_provider_firestore/0 was not found: merkle not found for package cloud_provider_firestore/0 [00018.461648][6773][7016][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00018.457272][10405][10421][klog] INFO: [ RUN ] ManyLedgersConvergenceTest/ConvergenceTest.NLedgersConverge/0 [00018.463618][688330799][0][amber] INFO: control_server: could not get update for ledger/0: merkle not found for package ledger/0 [00018.464462][6773][7011][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/ledger/0 was not found: merkle not found for package ledger/0 [00018.464556][6773][7011][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00018.610361][13071][13083][klog] INFO: [ERROR:src/lib/cobalt/cpp/cobalt_logger.cc(22)] Could not find config file at /pkg/data/firebase_auth_cobalt_config.pb [00018.701328][688330799][0][amber] INFO: control_server: could not get update for cloud_provider_firestore/0: merkle not found for package cloud_provider_firestore/0 [00018.701795][6773][7016][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/cloud_provider_firestore/0 was not found: merkle not found for package cloud_provider_firestore/0 [00018.702288][6773][7016][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00018.703375][688330799][0][amber] INFO: control_server: could not get update for ledger/0: merkle not found for package ledger/0 [00018.704249][6773][7011][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/ledger/0 was not found: merkle not found for package ledger/0 [00018.704290][6773][7011][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00018.789737][7898][7911][klog] INFO: Starting mDNS on interface ethp0003 10.0.2.15 [00018.798224][1570994003][0][netstack] VLOG(1): socket_server.go:787: unknown option for protocol [00018.798411][7898][7911][klog] INFO: [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 [00018.808183][7898][7911][klog] INFO: Starting mDNS on interface ethp0003 [80fe::5450:ff00:12fe:5634] [00018.815155][1570994003][0][netstack] INFO: socket_conv.go(523): unimplemented setsockopt: SOL_IPV6 name=19 optVal=00 [00018.815208][1570994003][0][netstack] VLOG(1): socket_server.go:787: unknown option for protocol [00018.815688][7898][7911][klog] INFO: [WARNING:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(29)] NET-291 IPV6_MULTICAST_LOOP not supported (ENOPROTOOPT), continuing anyway [00018.816392][1570994003][0][netstack] VLOG(1): socket_server.go:787: unknown device [00018.816558][7898][7911][klog] INFO: [WARNING:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(50)] NET-2180 IPV6_JOIN_GROUP returned ENODEV, mDNS will not communicate via IPV6 [00018.818526][7898][7911][klog] INFO: mDNS: Verifying uniqueness of host name swarm-donut-petri-acre.local. [00018.858767][13848][13867][klog] INFO: [ERROR:src/lib/cobalt/cpp/cobalt_logger.cc(22)] Could not find config file at /pkg/data/firebase_auth_cobalt_config.pb [00019.447981][13240][13252][klog] INFO: [INFO:page_manager.cc(151)] Initial sync in background finished. Clients will receive a change notification. [00019.795111][7898][7911][klog] INFO: mDNS: Using unique host name swarm-donut-petri-acre.local. [00020.141444][13848][13867][klog] INFO: [ERROR:src/ledger/cloud_provider_firestore/bin/app/cloud_provider_impl.cc(79)] Lost connection to the token provider, shutting down the cloud provider. [00020.203064][10405][10421][klog] INFO: [ OK ] ManyLedgersConvergenceTest/ConvergenceTest.NLedgersConverge/0 (1746 ms) [00020.203102][10405][10421][klog] INFO: [ RUN ] ManyLedgersConvergenceTest/ConvergenceTest.NLedgersConverge/1 [00020.206833][10405][10421][klog] INFO: TraceProvider: registry failed: status=-24(ZX_ERR_PEER_CLOSED) [00020.258926][688330799][0][amber] INFO: control_server: could not get update for cloud_provider_firestore/0: merkle not found for package cloud_provider_firestore/0 [00020.261618][6773][7016][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/cloud_provider_firestore/0 was not found: merkle not found for package cloud_provider_firestore/0 [00020.262829][6773][7016][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00020.269662][688330799][0][amber] INFO: control_server: could not get update for ledger/0: merkle not found for package ledger/0 [00020.273707][6773][7016][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/ledger/0 was not found: merkle not found for package ledger/0 [00020.275751][6773][7016][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00020.515020][15486][15498][klog] INFO: [ERROR:src/lib/cobalt/cpp/cobalt_logger.cc(22)] Could not find config file at /pkg/data/firebase_auth_cobalt_config.pb [00020.556934][688330799][0][amber] INFO: control_server: could not get update for cloud_provider_firestore/0: merkle not found for package cloud_provider_firestore/0 [00020.557213][6773][7016][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/cloud_provider_firestore/0 was not found: merkle not found for package cloud_provider_firestore/0 [00020.557276][6773][7016][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00020.558323][688330799][0][amber] INFO: control_server: could not get update for ledger/0: merkle not found for package ledger/0 [00020.558786][6773][7011][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/ledger/0 was not found: merkle not found for package ledger/0 [00020.558857][6773][7011][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00020.643420][16208][16220][klog] INFO: [ERROR:src/lib/cobalt/cpp/cobalt_logger.cc(22)] Could not find config file at /pkg/data/firebase_auth_cobalt_config.pb [00020.683762][15486][15498][klog] INFO: [ERROR:src/lib/cobalt/cpp/cobalt_logger.cc(22)] Could not find config file at /pkg/data/firebase_auth_cobalt_config.pb [00020.705430][16208][16220][klog] INFO: [ERROR:src/lib/cobalt/cpp/cobalt_logger.cc(22)] Could not find config file at /pkg/data/firebase_auth_cobalt_config.pb [00021.492276][15616][15628][klog] INFO: [INFO:page_manager.cc(151)] Initial sync in background finished. Clients will receive a change notification. [00022.063182][16208][16220][klog] INFO: [ERROR:src/ledger/cloud_provider_firestore/bin/app/cloud_provider_impl.cc(79)] Lost connection to the token provider, shutting down the cloud provider. [00022.106243][15486][15498][klog] INFO: [ERROR:src/ledger/cloud_provider_firestore/bin/app/cloud_provider_impl.cc(79)] Lost connection to the token provider, shutting down the cloud provider. [00022.143079][10405][10421][klog] INFO: [ OK ] ManyLedgersConvergenceTest/ConvergenceTest.NLedgersConverge/1 (1940 ms) [00022.143103][10405][10421][klog] INFO: [----------] 2 tests from ManyLedgersConvergenceTest/ConvergenceTest (3686 ms total) [00022.143104][10405][10421][klog] INFO: [00022.143104][10405][10421][klog] INFO: [----------] 1 test from LongHistorySyncTest/LongHistorySyncTest [00022.143104][10405][10421][klog] INFO: [ RUN ] LongHistorySyncTest/LongHistorySyncTest.SyncLongHistory/0 [00022.168919][10405][10421][klog] INFO: TraceProvider: registry failed: status=-24(ZX_ERR_PEER_CLOSED) [00022.210959][688330799][0][amber] INFO: control_server: could not get update for cloud_provider_firestore/0: merkle not found for package cloud_provider_firestore/0 [00022.215236][6773][7011][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/cloud_provider_firestore/0 was not found: merkle not found for package cloud_provider_firestore/0 [00022.217404][6773][7011][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00022.220657][688330799][0][amber] INFO: control_server: could not get update for ledger/0: merkle not found for package ledger/0 [00022.222523][6773][7016][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/ledger/0 was not found: merkle not found for package ledger/0 [00022.224608][6773][7016][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00022.388414][17932][17944][klog] INFO: [ERROR:src/lib/cobalt/cpp/cobalt_logger.cc(22)] Could not find config file at /pkg/data/firebase_auth_cobalt_config.pb [00022.465203][18107][18119][klog] INFO: fidl channel writing error at ../../sdk/lib/fidl/cpp/internal/proxy_controller.cc:59: zx_status_t -24, type fuchsia.netconnector/NetConnectorRegisterServiceProviderRequest, 88 bytes, 0 handles [00022.465256][18107][18119][klog] INFO: fidl channel writing error at ../../sdk/lib/fidl/cpp/internal/proxy_controller.cc:59: zx_status_t -24, type fuchsia.netconnector/NetConnectorGetKnownDeviceNamesRequest, 24 bytes, 0 handles [00023.003566][18107][18119][klog] INFO: [INFO:page_manager.cc(151)] Initial sync in background finished. Clients will receive a change notification. [00025.730274][3039][11300][klog] INFO: dropping packet; out of descriptors [00026.290395][688330799][0][amber] INFO: control_server: could not get update for cloud_provider_firestore/0: merkle not found for package cloud_provider_firestore/0 [00026.290623][6773][7011][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/cloud_provider_firestore/0 was not found: merkle not found for package cloud_provider_firestore/0 [00026.290701][6773][7011][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00026.291643][6773][7016][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/ledger/0 was not found: merkle not found for package ledger/0 [00026.291652][6773][7016][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00026.291360][688330799][0][amber] INFO: control_server: could not get update for ledger/0: merkle not found for package ledger/0 [00026.355302][34003][34015][klog] INFO: [ERROR:src/lib/cobalt/cpp/cobalt_logger.cc(22)] Could not find config file at /pkg/data/firebase_auth_cobalt_config.pb [00027.193012][34003][34015][klog] INFO: [ERROR:src/ledger/cloud_provider_firestore/bin/app/cloud_provider_impl.cc(79)] Lost connection to the token provider, shutting down the cloud provider. [00027.195381][17932][17944][klog] INFO: [ERROR:src/ledger/cloud_provider_firestore/bin/app/cloud_provider_impl.cc(79)] Lost connection to the token provider, shutting down the cloud provider. [00027.200530][10405][10421][klog] INFO: [ OK ] LongHistorySyncTest/LongHistorySyncTest.SyncLongHistory/0 (5058 ms) [00027.200531][10405][10421][klog] INFO: [----------] 1 test from LongHistorySyncTest/LongHistorySyncTest (5058 ms total) [00027.200531][10405][10421][klog] INFO: [00027.200532][10405][10421][klog] INFO: [----------] 3 tests from SyncIntegrationTest/SyncIntegrationTest [00027.200533][10405][10421][klog] INFO: [ RUN ] SyncIntegrationTest/SyncIntegrationTest.SerialConnection/0 [00027.296592][688330799][0][amber] INFO: control_server: could not get update for cloud_provider_firestore/0: merkle not found for package cloud_provider_firestore/0 [00027.301827][6773][7011][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/cloud_provider_firestore/0 was not found: merkle not found for package cloud_provider_firestore/0 [00027.303767][6773][7011][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00027.309044][688330799][0][amber] INFO: control_server: could not get update for ledger/0: merkle not found for package ledger/0 [00027.314482][6773][7016][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/ledger/0 was not found: merkle not found for package ledger/0 [00027.317108][6773][7016][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00027.559754][35116][35128][klog] INFO: [ERROR:src/lib/cobalt/cpp/cobalt_logger.cc(22)] Could not find config file at /pkg/data/firebase_auth_cobalt_config.pb [00027.592047][35245][35260][klog] INFO: fidl channel writing error at ../../sdk/lib/fidl/cpp/internal/proxy_controller.cc:59: zx_status_t -24, type fuchsia.netconnector/NetConnectorRegisterServiceProviderRequest, 88 bytes, 0 handles [00027.592089][35245][35260][klog] INFO: fidl channel writing error at ../../sdk/lib/fidl/cpp/internal/proxy_controller.cc:59: zx_status_t -24, type fuchsia.netconnector/NetConnectorGetKnownDeviceNamesRequest, 24 bytes, 0 handles [00028.128202][35245][35260][klog] INFO: [INFO:page_manager.cc(151)] Initial sync in background finished. Clients will receive a change notification. [00028.233590][688330799][0][amber] INFO: control_server: could not get update for cloud_provider_firestore/0: merkle not found for package cloud_provider_firestore/0 [00028.233780][6773][7016][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/cloud_provider_firestore/0 was not found: merkle not found for package cloud_provider_firestore/0 [00028.233872][6773][7016][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00028.234452][688330799][0][amber] INFO: control_server: could not get update for ledger/0: merkle not found for package ledger/0 [00028.234834][6773][7011][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/ledger/0 was not found: merkle not found for package ledger/0 [00028.234870][6773][7011][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00028.308110][36182][36194][klog] INFO: [ERROR:src/lib/cobalt/cpp/cobalt_logger.cc(22)] Could not find config file at /pkg/data/firebase_auth_cobalt_config.pb [00028.342242][36298][36314][klog] INFO: fidl channel writing error at ../../sdk/lib/fidl/cpp/internal/proxy_controller.cc:59: zx_status_t -24, type fuchsia.netconnector/NetConnectorRegisterServiceProviderRequest, 88 bytes, 0 handles [00028.342283][36298][36314][klog] INFO: fidl channel writing error at ../../sdk/lib/fidl/cpp/internal/proxy_controller.cc:59: zx_status_t -24, type fuchsia.netconnector/NetConnectorGetKnownDeviceNamesRequest, 24 bytes, 0 handles [00029.111521][10405][10421][klog] INFO: [ OK ] SyncIntegrationTest/SyncIntegrationTest.SerialConnection/0 (1911 ms) [00029.111585][10405][10421][klog] INFO: [ RUN ] SyncIntegrationTest/SyncIntegrationTest.ConcurrentConnection/0 [00029.117913][10405][10421][klog] INFO: TraceProvider: registry failed: status=-24(ZX_ERR_PEER_CLOSED) [00029.163365][688330799][0][amber] INFO: control_server: could not get update for cloud_provider_firestore/0: merkle not found for package cloud_provider_firestore/0 [00029.166516][6773][7011][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/cloud_provider_firestore/0 was not found: merkle not found for package cloud_provider_firestore/0 [00029.168921][6773][7011][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00029.175930][688330799][0][amber] INFO: control_server: could not get update for ledger/0: merkle not found for package ledger/0 [00029.178974][6773][7011][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/ledger/0 was not found: merkle not found for package ledger/0 [00029.181970][6773][7011][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00029.252732][688330799][0][amber] INFO: control_server: could not get update for cloud_provider_firestore/0: merkle not found for package cloud_provider_firestore/0 [00029.253094][6773][7016][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/cloud_provider_firestore/0 was not found: merkle not found for package cloud_provider_firestore/0 [00029.253139][6773][7016][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00029.332264][688330799][0][amber] INFO: control_server: could not get update for ledger/0: merkle not found for package ledger/0 [00029.332492][6773][7016][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/ledger/0 was not found: merkle not found for package ledger/0 [00029.332552][6773][7016][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00029.410470][37288][37304][klog] INFO: [ERROR:src/lib/cobalt/cpp/cobalt_logger.cc(22)] Could not find config file at /pkg/data/firebase_auth_cobalt_config.pb [00029.492594][37619][37640][klog] INFO: [ERROR:src/lib/cobalt/cpp/cobalt_logger.cc(22)] Could not find config file at /pkg/data/firebase_auth_cobalt_config.pb [00029.547302][37851][37872][klog] INFO: fidl channel writing error at ../../sdk/lib/fidl/cpp/internal/proxy_controller.cc:59: zx_status_t -24, type fuchsia.netconnector/NetConnectorRegisterServiceProviderRequest, 88 bytes, 0 handles [00029.547412][37851][37872][klog] INFO: fidl channel writing error at ../../sdk/lib/fidl/cpp/internal/proxy_controller.cc:59: zx_status_t -24, type fuchsia.netconnector/NetConnectorGetKnownDeviceNamesRequest, 24 bytes, 0 handles [00030.168528][37456][37480][klog] INFO: [INFO:page_manager.cc(151)] Initial sync in background finished. Clients will receive a change notification. [00030.402033][37288][37304][klog] INFO: [ERROR:src/ledger/cloud_provider_firestore/bin/app/cloud_provider_impl.cc(79)] Lost connection to the token provider, shutting down the cloud provider. [00030.442496][10405][10421][klog] INFO: [ OK ] SyncIntegrationTest/SyncIntegrationTest.ConcurrentConnection/0 (1331 ms) [00030.442540][10405][10421][klog] INFO: [ RUN ] SyncIntegrationTest/SyncIntegrationTest.LazyToEagerTransition/0 [00030.451746][10405][10421][klog] INFO: TraceProvider: registry failed: status=-24(ZX_ERR_PEER_CLOSED) [00030.483958][688330799][0][amber] INFO: control_server: could not get update for cloud_provider_firestore/0: merkle not found for package cloud_provider_firestore/0 [00030.486430][6773][7016][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/cloud_provider_firestore/0 was not found: merkle not found for package cloud_provider_firestore/0 [00030.487964][6773][7016][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00030.493265][688330799][0][amber] INFO: control_server: could not get update for ledger/0: merkle not found for package ledger/0 [00030.494926][6773][7011][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/ledger/0 was not found: merkle not found for package ledger/0 [00030.496851][6773][7011][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00030.540791][688330799][0][amber] INFO: control_server: could not get update for cloud_provider_firestore/0: merkle not found for package cloud_provider_firestore/0 [00030.543466][6773][7011][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/cloud_provider_firestore/0 was not found: merkle not found for package cloud_provider_firestore/0 [00030.543972][6773][7011][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00030.581728][688330799][0][amber] INFO: control_server: could not get update for ledger/0: merkle not found for package ledger/0 [00030.582115][6773][7011][pkg_resolver] INFO: package fuchsia-pkg://fuchsia.com/ledger/0 was not found: merkle not found for package ledger/0 [00030.582185][6773][7011][pkg_resolver] ERROR: /b/s/w/ir/k/garnet/bin/pkg_resolver/src/resolver_service.rs(144): error when waiting for amber to complete: Status(NOT_FOUND) [00030.721364][39391][39404][klog] INFO: [ERROR:src/lib/cobalt/cpp/cobalt_logger.cc(22)] Could not find config file at /pkg/data/firebase_auth_cobalt_config.pb [00030.790254][39536][39551][klog] INFO: fidl channel writing error at ../../sdk/lib/fidl/cpp/internal/proxy_controller.cc:59: zx_status_t -24, type fuchsia.netconnector/NetConnectorRegisterServiceProviderRequest, 88 bytes, 0 handles [00030.790311][39536][39551][klog] INFO: fidl channel writing error at ../../sdk/lib/fidl/cpp/internal/proxy_controller.cc:59: zx_status_t -24, type fuchsia.netconnector/NetConnectorGetKnownDeviceNamesRequest, 24 bytes, 0 handles [00030.816081][39753][39769][klog] INFO: [ERROR:src/lib/cobalt/cpp/cobalt_logger.cc(22)] Could not find config file at /pkg/data/firebase_auth_cobalt_config.pb [00030.875100][39974][39992][klog] INFO: fidl channel writing error at ../../sdk/lib/fidl/cpp/internal/proxy_controller.cc:59: zx_status_t -24, type fuchsia.netconnector/NetConnectorRegisterServiceProviderRequest, 88 bytes, 0 handles [00030.875141][39974][39992][klog] INFO: fidl channel writing error at ../../sdk/lib/fidl/cpp/internal/proxy_controller.cc:59: zx_status_t -24, type fuchsia.netconnector/NetConnectorGetKnownDeviceNamesRequest, 24 bytes, 0 handles [00031.705043][39536][39551][klog] INFO: [INFO:page_manager.cc(151)] Initial sync in background finished. Clients will receive a change notification. [00032.210447][39753][39769][klog] INFO: [ERROR:src/ledger/cloud_provider_firestore/bin/app/cloud_provider_impl.cc(79)] Lost connection to the token provider, shutting down the cloud provider. [00032.264389][39391][39404][klog] INFO: [ERROR:src/ledger/cloud_provider_firestore/bin/app/cloud_provider_impl.cc(79)] Lost connection to the token provider, shutting down the cloud provider. [00032.296442][10405][10421][klog] INFO: [ OK ] SyncIntegrationTest/SyncIntegrationTest.LazyToEagerTransition/0 (1854 ms) [00032.296447][10405][10421][klog] INFO: [----------] 3 tests from SyncIntegrationTest/SyncIntegrationTest (5096 ms total) [00032.296448][10405][10421][klog] INFO: [00032.296448][10405][10421][klog] INFO: [----------] Global test environment tear-down [00032.296449][10405][10421][klog] INFO: [==========] 6 tests from 3 test suites ran. (13840 ms total) [00032.296449][10405][10421][klog] INFO: [ PASSED ] 6 tests. [00032.535065][10405][10421][klog] INFO: PASSED: /pkgfs/packages/ledger_tests/0/test/disabled/ledger_e2e_sync passed