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

2019/04/12 02:25:29 attempting to send <<image>>sparse.fvm... [0.000] 00000.00000> zbi: @ 0xffffff80012f2000 (17482848 bytes) [0.000] 00000.00000> UART: FIFO depth 16 [0.000] 00000.00000> PMM: boot reserve add [0x100000, 0x2c5fff] [0.000] 00000.00000> PMM: boot reserve add [0x12f2000, 0x239efff] [0.000] 00000.00000> PMM: arena too small to be useful (size 4096) [0.000] 00000.00000> PMM: pmm_add_arena failed to initialize arena [0.000] 00000.00000> MEM: Failed to add pmm range at 0x8afff000 size 0x1000 [0.000] 00000.00000> PMM: boot reserve marking WIRED [0x100000, 0x2c5fff] [0.000] 00000.00000> PMM: boot reserve marking WIRED [0x12f2000, 0x239efff] [0.000] 00000.00000> welcome to Zircon [0.000] 00000.00000> KASLR: .text section at 0xffffffff00100000 [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001aa428 (global_prng_seed) at level 0x30000, flags 0x1 [0.000] 00000.00000> initializing vm pre-heap [0.000] 00000.00000> VM: marking boot alloc used range [0x239f000, 0x239f100) [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00147abc (elf_build_id) at level 0x4fffe, flags 0x1 [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00147c48 (version) at level 0x4ffff, flags 0x1 [0.000] 00000.00000> version: [0.000] 00000.00000> arch: x86 [0.000] 00000.00000> platform: pc [0.000] 00000.00000> target: pc [0.000] 00000.00000> project: x64 [0.000] 00000.00000> buildid: git-13ee3dc5e4c46bf127977ad28645c47442ec517d [0.000] 00000.00000> ELF build ID: 5f3e39bd4ed89d3b8122947842f1eac846a04683 [0.000] 00000.00000> initializing heap [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00102e1c (x86_resource_init) at level 0x50000, flags 0x1 [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001a9630 (console) at level 0x50000, flags 0x1 [0.000] 00000.00000> initializing vm [0.000] 00000.00000> VM: reserving kernel region [0xffffffff00100000, 0xffffffff001ee000) flags 0x28 name 'kernel_code' [0.000] 00000.00000> VM: reserving kernel region [0xffffffff001ee000, 0xffffffff00232000) flags 0x8 name 'kernel_rodata' [0.000] 00000.00000> VM: reserving kernel region [0xffffffff00232000, 0xffffffff00242000) flags 0x18 name 'kernel_data' [0.000] 00000.00000> VM: reserving kernel region [0xffffffff00242000, 0xffffffff002c6000) flags 0x18 name 'kernel_bss' [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00103ae0 (display_memtype) at level 0x60001, flags 0x1 [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00138fa0 (acpi_tables) at level 0x60001, flags 0x1 [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00101c40 (hpet) at level 0x60002, flags 0x1 [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00102000 (apic) at level 0x60002, flags 0x1 [0.000] 00000.00000> x2APIC enabled [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001061b4 (timer) at level 0x60003, flags 0x1 [0.000] 00000.00000> HPET frequency: 23999 ticks/ms [0.000] 00000.00000> TSC frequency: 2712000 ticks/ms [20.099] 00000.00000> timer features: constant_tsc 1 invariant_tsc 1 tsc_deadline 1 [20.099] 00000.00000> Using TSC as wallclock [20.099] 00000.00000> initializing kernel [20.099] 00000.00000> initializing mp [20.099] 00000.00000> initializing timers [20.099] 00000.00000> INIT: cpu 0, calling hook 0xffffffff0013c578 (debuglog) at level 0x7ffff, flags 0x1 [20.099] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00195518 (thread_set_priority_experiment) at level 0x7ffff, flags 0x1 [20.099] 00000.00000> thread set priority experiment is : ENABLED [20.099] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001aa67c (global_prng_thread_safe) at level 0x7ffff, flags 0x1 [20.099] 00000.00000> creating bootstrap completion thread [20.441] 00000.00000> top of bootstrap2() [20.441] 00000.00000> INIT: cpu 0, calling hook 0xffffffff0013151c (dpc) at level 0x80000, flags 0x1 [20.454] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00152978 (libobject) at level 0x80000, flags 0x1 [20.454] 00000.00000> OOM: started thread [20.468] 00000.00000> Processor Model Info: type 0 family 0x6 model 0xe stepping 0x9 [20.468] 00000.00000> display_family 0x6 display_model 0x8e [20.468] 00000.00000> Vendor: Intel [20.468] 00000.00000> Microarch: Kaby Lake [20.468] 00000.00000> F/M/S: 6/8e/9 [20.468] 00000.00000> Brand: Intel(R) Core(TM) i5-7300U CPU @ 2.60GHz [20.468] 00000.00000> Features: fpu sse sse2 sse3 ssse3 sse4.1 sse4.2 mmx avx avx2 fxsr pcid xsave mon aesni clflush [20.468] 00000.00000> clflushopt fsgsbase tsc_adj smep smap erms rdrand rdseed syscall nx huge rdtscp [20.468] 00000.00000> invar_tsc tsc_deadline x2apic vmx pt hwp [20.468] 00000.00000> initializing platform [20.468] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001101f4 (x86_perfmon) at level 0x90000, flags 0x1 [20.468] 00000.00000> PMU: version 4 [20.468] 00000.00000> UART: started IRQ driven RX [20.468] 00000.00000> UART: started IRQ driven TX [20.468] 00000.00000> cpu topology: [20.468] 00000.00000> 0: apic id 0x0 package 0 node 0 core 0 smt 0 BSP [20.468] 00000.00000> 1: apic id 0x2 package 0 node 0 core 1 smt 0 [20.468] 00000.00000> 2: apic id 0x1 package 0 node 0 core 0 smt 1 [20.468] 00000.00000> 3: apic id 0x3 package 0 node 0 core 1 smt 1 [20.468] 00000.00000> Found 4 cpus [20.468] 00000.00000> booting apic ids: 0x2 0x1 0x3 [20.483] 00000.00000> entering scheduler on cpu 2 [20.485] 00000.00000> entering scheduler on cpu 3 [20.485] 00000.00000> entering scheduler on cpu 1 [20.487] 00000.00000> smbios: manufacturer="Intel Corporation" product="NUC7i5DNHE" [20.487] 00000.00000> initializing target [20.487] 00000.00000> INIT: cpu 1, calling hook 0xffffffff001053a0 (x86_pcie_init) at level 0xa0000, flags 0x1 [20.487] 00000.00000> moving to last init level [20.487] 00000.00000> INIT: cpu 1, calling hook 0xffffffff00144140 (ktrace) at level 0xc0000, flags 0x1 [20.491] 00000.00000> ktrace: buffer at 0xffffff90b6bd2000 (33554432 bytes) [20.491] 00000.00000> INIT: cpu 1, calling hook 0xffffffff00147ab4 (userboot) at level 0xc0000, flags 0x1 [20.491] 00000.00000> userboot: ramdisk 0x10ad000 @ 0xffffff80012f2000 [20.491] 00000.00000> userboot: userboot rodata 0 @ [0x50d4ef4ee000,0x50d4ef4f1000) [20.491] 00000.00000> userboot: userboot code 0x3000 @ [0x50d4ef4f1000,0x50d4ef4fc000) [20.491] 00000.00000> userboot: vdso/full rodata 0 @ [0x50d4ef4fc000,0x50d4ef503000) [20.491] 00000.00000> userboot: vdso/full code 0x7000 @ [0x50d4ef503000,0x50d4ef504000) [20.491] 00000.00000> userboot: entry point @ 0x50d4ef4f1c10 [20.491] 00000.00000> INIT: cpu 1, calling hook 0xffffffff001aa030 (kernel_shell) at level 0xc0000, flags 0x1 [20.491] 01036.01043> userboot: option "netsvc.disable=true" [20.491] 01036.01043> userboot: option "zircon.system.disable-automount=true" [20.491] 01036.01043> userboot: option "zircon.autorun.boot=/boot/infra/setup" [20.491] 01036.01043> userboot: option "kernel.serial=legacy" [20.491] 01036.01043> userboot: option "http://192.168.42.1/multiboot/54%3Ab2%3A03%3A09%3A1a%3A85=" [20.491] 01036.01043> userboot: option "multiboot.boot_loader_name=iPXE+1.0.0++(d2063)" [20.531] 01036.01043> userboot: searching bootfs for 'bin/bootsvc' [20.531] 01036.01043> userboot: 'bin/bootsvc' has PT_INTERP "lib/ld.so.1" [20.531] 01036.01043> userboot: searching bootfs for 'lib/ld.so.1' [20.531] 01036.01043> userboot: userboot: loaded lib/ld.so.1 at 0x645bd1315000, entry point 0x645bd139b0d3 [20.531] 01036.01043> userboot: userboot: loaded vDSO at 0x63f0e06d1000, entry point 0x0 [20.531] 01036.01043> userboot: process bin/bootsvc started. [20.531] 01036.01043> userboot: waiting for loader-service requests... [20.531] 01036.01043> userboot: searching bootfs for 'lib/libasync-default.so' [20.531] 01036.01043> userboot: searching bootfs for 'lib/libfdio.so' [20.531] 01036.01043> userboot: searching bootfs for 'lib/liblaunchpad.so' [20.531] 01036.01043> userboot: searching bootfs for 'lib/libtrace-engine.so' [20.531] 01050.01053> dso: id=18de7d58f51e8ba5c4f6afdd0cc8440f482c2b45 base=0x000023a20a072000 name=<application> [20.531] 01050.01053> dso: id=c189f51f19d10785d93b5235ad1b46dca4451b00 base=0x000006fdf5ec7000 name=libasync-default.so [20.531] 01050.01053> dso: id=40ab5fa3b61f12fef4a44477a687f608661cd450 base=0x000063f0e06d1000 name=<vDSO> [20.531] 01050.01053> dso: id=67a12284bce140fc32aaaeac223e0e8f85cb5085 base=0x0000645bd1315000 name=libc.so [20.531] 01050.01053> dso: id=27787977e95f3b9563c9e0bd4d34ec92b7e9ad88 base=0x000076533cf2b000 name=libfdio.so [20.531] 01050.01053> dso: id=6fc2232dc8c1bc5c6adb8ad50ca794b5a260ee4f base=0x00007d626f3ec000 name=liblaunchpad.so [20.531] 01050.01053> dso: id=c9042b83fe01206008fcefb16395238417248169 base=0x00002672f66f6000 name=libtrace-engine.so [20.531] 01050.01053> bootsvc: Starting... [20.531] 01036.01043> userboot: loader-service channel peer closed [20.531] 01036.01043> userboot: finished! [20.531] 01050.01053> bootsvc: Creating bootfs service... [20.532] 01050.01053> bootsvc: Processing bootdata... [20.532] 01050.01053> bootsvc: Loading boot cmdline overrides... [20.532] 01050.01053> bootsvc: Loading kernel VMOs... [20.532] 01050.01053> bootsvc: Creating loader service... [20.532] 01050.01053> bootsvc: Launching next process... [20.532] 01050.01096> bootsvc: launched bin/devmgr [20.533] 01104.01117> devmgr: main() [20.533] 01104.01117> cmdline: netsvc.disable=true [20.533] 01104.01117> cmdline: zircon.system.disable-automount=true [20.533] 01104.01117> cmdline: zircon.autorun.boot=/boot/infra/setup [20.533] 01104.01117> cmdline: kernel.serial=legacy [20.533] 01104.01117> cmdline: http://192.168.42.1/multiboot/54%3Ab2%3A03%3A09%3A1a%3A85= [20.533] 01104.01117> cmdline: multiboot.boot_loader_name=iPXE+1.0.0++(d2063) [20.533] 01104.01117> devmgr: coordinator_init() [20.533] 01104.01117> devmgr: init [20.533] 01104.01117> devmgr: svc init [20.533] 01104.01117> devmgr: launch /boot/bin/svchost (svchost) OK [20.533] 01104.01117> devmgr: vfs init [20.533] 01104.01117> devmgr: launch /boot/bin/fshost (fshost) OK [20.533] 01104.01222> devmgr: shell startup [20.535] 01182.01202> fshost: started. [20.535] 01104.01212> devmgr: launch /boot/bin/pwrbtn-monitor (pwrbtn-monitor) OK [20.536] 01104.01230> devmgr: launch /boot/bin/virtual-console (virtual-console) OK [20.536] 01104.01230> zircon.autorun.boot: starting '/boot/infra/setup'... [20.536] 01104.01230> devmgr: launch /boot/infra/setup (autorun:boot) OK [20.539] 01104.01117> devcoord: launch devhost 'devhost:sys': pid=2049 [20.539] 01104.01117> devcoord: launch devhost 'devhost:test': pid=2082 [20.539] 01104.01117> devcoord: launch devhost 'devhost:root': pid=2115 [20.540] 01104.01117> devcoord: launch devhost 'devhost:misc': pid=2170 [20.540] 02049.02073> devhost: trace provider registry begun [20.541] 02082.02106> devhost: trace provider registry begun [20.542] 02115.02152> devhost: trace provider registry begun [20.543] 02170.02223> devhost: trace provider registry begun [20.551] 01104.01222> devmgr: launch /boot/bin/sh (sh:console) OK [20.599] 02049.02073> acpi-bus: not using IOMMU [20.604] 02049.02073> acpi-pwrbtn: initialized [20.626] 02049.02073> acpi: published device pci(0x39f6900bd200), parent=proxy(0x39f6900b9540), handle=0x39f6900f5c30 [20.627] 02049.02073> acpi: published device rtc(0x39f6900b8080), parent=acpi(0x39f6900b9780), handle=0x39f69010e5b0 [20.628] 02049.02073> acpi-ec: initialized [20.630] 02049.02073> acpi: failed to create NHLT VMO (res -10) [20.630] 02049.02073> acpi: failed to publish NHLT metadata [20.632] 02049.02073> [sysmem_init 28] async_get_default_dispatcher(): 0x39f6900ac0a0 [20.632] 02049.02073> [sysmem_bind 40] sysmem_bind() [20.632] 02049.02073> acpi-bus: sysmem_register_protocol() intentionally ignored for now. [20.632] 02049.02073> PMU: 7 arch events [20.632] 02049.02073> PMU: arch event id range: 1-7 [20.632] 02049.02073> PMU: 102 model events [20.632] 02049.02073> PMU: model event id range: 1-241 [20.632] 02049.02073> Intel Processor Trace configuration for this chipset: [20.632] 02049.02073> mtc_freq_mask: 0x249 [20.632] 02049.02073> cyc_thresh_mask: 0x3fff [20.632] 02049.02073> psb_freq_mask: 0x3f [20.632] 02049.02073> num addr ranges: 2 [20.632] 01104.01117> devcoord: launch devhost 'devhost:pci#1:8086:5916': pid=2840 [20.633] 01104.01117> devcoord: launch devhost 'devhost:pci#3:8086:9d2f': pid=2883 [20.633] 01104.01117> devcoord: launch devhost 'devhost:pci#5:8086:9d60': pid=2951 [20.634] 01104.01117> devcoord: launch devhost 'devhost:pci#6:8086:9d61': pid=3015 [20.634] 02840.02863> devhost: trace provider registry begun [20.634] 02883.02921> devhost: trace provider registry begun [20.634] 01104.01117> devcoord: launch devhost 'devhost:pci#9:8086:9d03': pid=3095 [20.635] 02951.02998> devhost: trace provider registry begun [20.635] 03015.03058> devhost: trace provider registry begun [20.635] 01104.01117> devcoord: launch devhost 'devhost:pci#14:8086:9d71': pid=3233 [20.636] 03095.03168> devhost: trace provider registry begun [20.636] 01104.01117> devcoord: launch devhost 'devhost:pci#16:8086:156f': pid=3342 [20.638] 03095.03168> ahci: using MSI interrupt [20.638] 02951.02998> initialized intel serialio i2c driver, reg=0x111e94cff000 regsize=4096 [20.638] 03015.03058> initialized intel serialio i2c driver, reg=0x7064c6ac7000 regsize=4096 [20.640] 03233.03297> devhost: trace provider registry begun [20.641] 03342.03388> devhost: trace provider registry begun [20.644] 02883.03664> * found USB device (0x18d1:0xa002, USB 2.0) config 1 [20.644] 02883.03664> * found USB device (0x18d1:0xa003, USB 3.0) config 1 [20.654] 01250.01565> vc: new input device /dev/class/input/000 [20.654] 03233.03297> [IHDA DSP 00:1f.3] Failed to fetch NHLT (res -25) [20.654] 03233.03297> devhost[00:1f.3/intel-hda-000/intel-sst-dsp-000] bind driver '/boot/driver/dsp.so' failed: -25 [20.654] 01104.01117> devcoord: rpc: bind-driver 'intel-sst-dsp-000' status -25 [20.744] 01250.01565> vc: new display device /dev/class/display-controller/000/virtcon [20.884] 02840.04142> i915: MST not supported [21.384] 02840.04142> i915: Found DP monitor [21.384] 02840.04142> i915: Display 1 connected [21.943] 01250.01565> vc: Successfully attached to display 1 [22.115] 02883.03664> * found USB device (0x0781:0x5571, USB 2.10) config 1 [22.116] 02883.02921> UMS: parent: 'ifc-000' [22.116] 02883.02921> UMS:Max lun is: 0 [22.365] 02883.03664> * found USB device (0x8087:0x0a2b, USB 2.0) config 1 [22.367] 02883.02921> devhost[00:14.0/xhci/usb-bus/002/002/ifc-001] bind driver '/boot/driver/bt-transport-usb.so' failed: -2 [22.367] 01104.01117> devcoord: rpc: bind-driver 'ifc-001' status -2 [22.642] 03015.03058> intel-i2c-controller: intel_serialio_i2c_slave_transfer: -21 [22.642] 03015.03058> i2c-hid: could not read HID descriptor: 0 [22.642] 03015.03058> devhost[00:15.1/i2c-bus-9d61/004c] bind driver '/boot/driver/i2c-hid.so' failed: -2 [22.642] 01104.01117> devcoord: rpc: bind-driver '004c' status -2 [22.642] 02951.02998> intel-i2c-controller: intel_serialio_i2c_slave_transfer: -21 [22.642] 02951.02998> i2c-hid: could not read HID descriptor: 0 [22.642] 02951.02998> devhost[00:15.0/i2c-bus-9d60/0020] bind driver '/boot/driver/i2c-hid.so' failed: -2 [22.642] 01104.01117> devcoord: rpc: bind-driver '0020' status -2 [22.665] 03095.03607> sata2: dev info [22.665] 03095.03607> serial=AA000000000000009012 [22.665] 03095.03607> firmware rev=0R08A1 0 [22.665] 03095.03607> model id=ETMAT 8MSP1582 G [22.665] 03095.03607> major=0x3f0 ACS2 DMA 32 commands [22.665] 03095.03607> LBA48 250069680 sectors, sector size=512 [22.667] 04413.04427> paver:[InitializeGpt] Located GPT is invalid; Attempting to initialize [22.668] 01104.01117> devcoord: dc_bind_device() '/boot/driver/gpt.so' [22.668] 03095.03168> gpt: device_get_metadata failed (-25) [22.670] 04413.04427> paver:[Initialize] Successfully initialized EFI Device Partitioner [22.670] 01104.01117> devcoord: dc_bind_device() '' [22.670] 01182.01202> devmgr: /dev/class/block/000: GPT? [22.670] 01104.01117> devcoord: dc_bind_device() '/boot/driver/gpt.so' [22.670] 03095.03168> gpt: device_get_metadata failed (-25) [22.673] 04558.04579> 34+0 records in [22.673] 04558.04579> 34+0 records out [22.673] 04558.04579> 17408 bytes copied [22.675] 04674.04688> 34+0 records in [22.675] 04674.04688> 34+0 records out [22.675] 04674.04688> 17408 bytes copied [22.676] 04778.04792> netifc: ? /dev/class/ethernet/000 [22.676] 04778.04792> netifc: create 512 eth buffers [22.676] 04778.04792> macaddr: 54:b2:03:09:1a:85 [22.676] 04778.04792> ip6addr: fe80::56b2:34d:fe09:1a85 [22.676] 04778.04792> snmaddr: ff02::1:ff09:1a85 [22.676] 04778.04792> netsvc: using /dev/class/ethernet/000 [22.676] 04778.04792> _ _ _ [22.676] 04778.04792> | | | | | [22.676] 04778.04792> _______ __| | |__ ___ ___ | |_ [22.676] 04778.04792> |_ / _ \/ _` | '_ \ / _ \ / _ \| __| [22.676] 04778.04792> / / __/ (_| | |_) | (_) | (_) | |_ [22.676] 04778.04792> /___\___|\__,_|_.__/ \___/ \___/ \__| [22.676] 04778.04792> zedboot: version: 0.7.13 [22.676] 04778.04792> netsvc: nodename='atom-chaos-cult-penny' [22.676] 04778.04792> netsvc: will not advertise [22.676] 04778.04792> netsvc: start [23.118] 02883.04246> UMS: block size is: 0x00000200 [23.118] 02883.04246> UMS: total blocks is: 15630336 [23.118] 02883.04246> UMS: total size is: 8002732032 [23.118] 02883.04246> UMS: read-only: 0 removable: 1 [23.119] 01182.01202> devmgr: /dev/class/block/001: MBR? [23.119] 01104.01117> devcoord: dc_bind_device() '/boot/driver/mbr.so' [23.122] 01182.01202> devmgr: /dev/class/block/003: MBR? [23.122] 01104.01117> devcoord: dc_bind_device() '/boot/driver/mbr.so' [7752.904] 04778.04792> netsvc: Running FVM Paver [7752.906] 04778.04961> paver:[InitializeGpt] Located GPT is invalid; Attempting to initialize [7752.907] 01104.01117> devcoord: dc_bind_device() '' [7752.907] 01104.01117> devcoord: dc_bind_device() '/boot/driver/gpt.so' [7752.907] 03095.03168> gpt: device_get_metadata failed (-25) [7752.908] 01182.01202> devmgr: /dev/class/block/000: GPT? [7752.908] 01104.01117> devcoord: dc_bind_device() '/boot/driver/gpt.so' [7752.908] 03095.03168> devhost: rpc:bind-device failed: -20 [7752.909] 04778.04961> paver:[Initialize] Successfully initialized EFI Device Partitioner [7752.909] 04778.04961> paver:[PartitionPave] Paving partition. [7752.909] 04778.04961> paver:[FindFirstFit] Looking for space [7752.909] 04778.04961> paver:[FindFirstFit] Sorting [7752.909] 04778.04961> paver:[FindFirstFit] Partition[0] From Block [0, 34) ... (next partition starts at block 250069646) [7752.909] 04778.04961> paver:[FindFirstFit] There are 250069612 free blocks (16777216 requested) [7752.909] 04778.04961> paver:[AddPartition] Found space in GPT - OK 250069612 @ 34 [7752.909] 04778.04961> paver:[AddPartition] Final space in GPT - OK 16777216 @ 34 [7752.910] 04778.04961> paver:[AddPartition] Added partition, waiting for bind [7752.910] 01104.01117> devcoord: dc_bind_device() '' [7752.911] 01182.01202> devmgr: /dev/class/block/000: GPT? [7752.911] 01104.01117> devcoord: dc_bind_device() '/boot/driver/gpt.so' [7752.911] 03095.03168> gpt: device_get_metadata failed (-25) [7752.912] 04778.04961> paver:[AddPartition] Added partition, waiting for bind - OK [7752.912] 04778.04961> paver:[PartitionPave] Streaming partitions... [7752.912] 04941.04955> Found compressed file [7752.912] 04778.04961> paver:[FvmStreamPartitions] Header Validated - OK [7752.912] 04778.04961> paver:[FvmPartitionFormat] Initializing partition as FVM [7752.914] 01104.01117> devcoord: dc_bind_device() '/boot/driver/fvm.so' [7753.916] 04778.04961> paver:[FvmStreamPartitions] Partitions pre-validated successfully: Enough space exists to pave. [7753.916] 04778.04961> paver:[AllocatePartitions] Allocating partition blobfs consisting of 1 slices [7753.920] 04778.04961> paver:[AllocatePartitions] Allocating partition minfs consisting of 1 slices [7753.921] 04778.04961> paver:[AllocatePartitions] Creating zxcrypt volume [7753.923] 03095.03168> devhost_get_handles(0x1a48fc8e89c0:block) open path='zxcrypt/block', r=-2 [7753.923] 01104.01117> devcoord: dc_bind_device() '/boot/driver/zxcrypt.so' [7753.924] 03095.03168> devhost_get_handles(0x1a48fc8e89c0:block) open path='zxcrypt', r=-2 [7753.927] 04778.04961> paver:[FvmStreamPartitions] Partition space pre-allocated successfully. [7753.927] 04778.04961> paver:[FvmStreamPartitions] Streaming partition 0 [7753.927] 04778.04961> paver:[StreamFvmPartition] Writing extent 0... [7753.928] 04778.04967> netsvc: paver write progress 0.0% [7753.928] 04778.04961> paver:[StreamFvmPartition] 8192 bytes written, 8380416 zeroes left [7753.944] 04778.04961> paver:[StreamFvmPartition] Writing extent 1... [7753.944] 04778.04961> paver:[StreamFvmPartition] 16384 bytes written, 8372224 zeroes left [7753.960] 04778.04961> paver:[StreamFvmPartition] Writing extent 2... [7753.965] 04778.04961> paver:[StreamFvmPartition] 2097152 bytes written, 6291456 zeroes left [7753.989] 04778.04961> paver:[StreamFvmPartition] Writing extent 3... [7753.993] 04778.04961> paver:[StreamFvmPartition] 2097152 bytes written, 6291456 zeroes left [7754.005] 04778.04961> paver:[StreamFvmPartition] Writing extent 4... [7754.927] 04778.04967> netsvc: paver write progress 28.3% [7755.928] 04778.04967> netsvc: paver write progress 42.3% [7756.928] 04778.04967> netsvc: paver write progress 56.2% [7757.928] 04778.04967> netsvc: paver write progress 70.2% [7758.928] 04778.04967> netsvc: paver write progress 84.2% [7759.928] 04778.04967> netsvc: paver write progress 98.2% 2019/04/12 02:25:36 done 2019/04/12 02:25:36 attempting to send <<image>>efi.img... 2019/04/12 02:25:36 target is busy, retrying in one second [7760.055] 04778.04792> netsvc: tftp write of file <<image>>sparse.fvm completed [7760.055] 04778.04792> netsvc: metrics: {"inorderblks": 710616,"oooblks": 0,"ack": 2776,"nack": 0,"timeouts": 0,"sas": 0,"inorderbytes": 727670125} [7760.055] 04778.04961> paver:[StreamFvmPartition] 758480896 bytes written, 4882432 zeroes left [7760.069] 04778.04961> paver:[FvmStreamPartitions] Done streaming partition 0 [7760.069] 04778.04961> paver:[FvmStreamPartitions] Done flushing partition 0 [7760.069] 04778.04961> paver:[FvmStreamPartitions] Streaming partition 1 [7760.069] 04778.04961> paver:[StreamFvmPartition] Writing extent 0... [7760.069] 04778.04961> paver:[StreamFvmPartition] 8192 bytes written, 8380416 zeroes left [7760.108] 04778.04961> paver:[StreamFvmPartition] Writing extent 1... [7760.109] 04778.04961> paver:[StreamFvmPartition] 65536 bytes written, 8323072 zeroes left [7760.147] 04778.04961> paver:[StreamFvmPartition] Writing extent 2... [7760.147] 04778.04961> paver:[StreamFvmPartition] 65536 bytes written, 8323072 zeroes left [7760.183] 04778.04961> paver:[StreamFvmPartition] Writing extent 3... [7760.233] 04778.04961> paver:[StreamFvmPartition] Writing extent 4... [7760.243] 04778.04961> paver:[StreamFvmPartition] 2097152 bytes written, 14680064 zeroes left [7760.305] 04778.04961> paver:[StreamFvmPartition] Writing extent 5... [7760.306] 04778.04961> paver:[StreamFvmPartition] 57344 bytes written, 8331264 zeroes left [7760.341] 04778.04961> paver:[FvmStreamPartitions] Done streaming partition 1 [7760.341] 04778.04961> paver:[FvmStreamPartitions] Done flushing partition 1 [7760.342] 04941.04955> Reading FVM from compressed file: true [7760.342] 04941.04955> Remaining bytes read into compression buffer: 0 [7760.342] 04941.04955> Remaining bytes written to decompression buffer: 0 [7760.342] 04941.04955> Time reading bytes from sparse FVM file: 11754817869 (4 s) [7760.342] 04941.04955> Time reading bytes AND decompressing them: 12082954394 (4 s) [7760.343] 04778.04961> paver:[PartitionPave] Completed successfully 2019/04/12 02:25:37 attempting to send <<image>>efi.img... 2019/04/12 02:25:37 done 2019/04/12 02:25:37 attempting to send <<image>>kernc.img... 2019/04/12 02:25:37 target is busy, retrying in one second [7761.056] 04778.04792> netsvc: Running EFI Paver [7761.058] 04778.06237> paver:[Initialize] Successfully initialized EFI Device Partitioner [7761.058] 04778.06237> paver:[PartitionPave] Paving partition. [7761.058] 04778.06237> paver:[FindFirstFit] Looking for space [7761.058] 04778.06237> paver:[FindFirstFit] Partition seen with start 34, end 16777249 (length 16777216) [7761.058] 04778.06237> paver:[FindFirstFit] Sorting [7761.058] 04778.06237> paver:[FindFirstFit] Partition[0] From Block [0, 34) ... (next partition starts at block 34) [7761.058] 04778.06237> paver:[FindFirstFit] There are 0 free blocks (40960 requested) [7761.058] 04778.06237> paver:[FindFirstFit] Partition[1] From Block [34, 16777250) ... (next partition starts at block 250069646) [7761.058] 04778.06237> paver:[FindFirstFit] There are 233292396 free blocks (40960 requested) [7761.058] 04778.06237> paver:[AddPartition] Found space in GPT - OK 233292396 @ 16777250 [7761.058] 04778.06237> paver:[AddPartition] Final space in GPT - OK 40960 @ 16777250 [7761.059] 04778.06237> paver:[AddPartition] Added partition, waiting for bind [7761.059] 03095.03168> block: Joining un-closed FIFO server [7761.059] 03095.03168> block: Joining un-closed FIFO server [7761.059] 01104.01117> devcoord: dc_bind_device() '' [7761.060] 01182.01202> devmgr: /dev/class/block/000: GPT? [7761.060] 01104.01117> devcoord: dc_bind_device() '/boot/driver/gpt.so' [7761.060] 03095.03168> gpt: device_get_metadata failed (-25) [7761.061] 01182.01202> devmgr: /dev/class/block/008: FVM? [7761.061] 01104.01117> devcoord: dc_bind_device() '/boot/driver/fvm.so' [7761.061] 04778.06237> paver:[AddPartition] Added partition, waiting for bind - OK [7761.068] 04778.04792> netsvc: tftp write of file <<image>>efi.img completed [7761.068] 04778.04792> netsvc: metrics: {"inorderblks": 1093,"oooblks": 0,"ack": 5,"nack": 0,"timeouts": 0,"sas": 0,"inorderbytes": 1118208} [7761.070] 04778.06237> paver:[PartitionPave] Completed successfully 2019/04/12 02:25:38 attempting to send <<image>>kernc.img... [7762.068] 04778.04792> netsvc: Running KERN-C Paver [7762.071] 04778.06492> paver:[Initialize] Successfully initialized EFI Device Partitioner [7762.071] 04778.06492> paver:[RealMain] SKIPPING KERNC install on non-CROS device, pass --force if desired. [7763.073] 04778.06498> netsvc: paver write progress 59.2% 2019/04/12 02:25:39 done 2019/04/12 02:25:39 attempting to send <<image>>zircona.img... 2019/04/12 02:25:39 target is busy, retrying in one second [7763.217] 04778.04792> netsvc: tftp write of file <<image>>kernc.img completed [7763.217] 04778.04792> netsvc: metrics: {"inorderblks": 13697,"oooblks": 0,"ack": 54,"nack": 0,"timeouts": 0,"sas": 0,"inorderbytes": 14024704} 2019/04/12 02:25:40 attempting to send <<image>>zircona.img... [7764.218] 04778.04792> netsvc: Running ZIRCON-A Paver [7764.221] 04778.06621> paver:[Initialize] Successfully initialized EFI Device Partitioner [7764.221] 04778.06621> paver:[PartitionPave] Paving partition. [7764.221] 04778.06621> paver:[FindFirstFit] Looking for space [7764.221] 04778.06621> paver:[FindFirstFit] Partition seen with start 34, end 16777249 (length 16777216) [7764.221] 04778.06621> paver:[FindFirstFit] Partition seen with start 16777250, end 16818209 (length 40960) [7764.221] 04778.06621> paver:[FindFirstFit] Sorting [7764.221] 04778.06621> paver:[FindFirstFit] Partition[0] From Block [0, 34) ... (next partition starts at block 34) [7764.221] 04778.06621> paver:[FindFirstFit] There are 0 free blocks (32768 requested) [7764.221] 04778.06621> paver:[FindFirstFit] Partition[1] From Block [34, 16777250) ... (next partition starts at block 16777250) [7764.221] 04778.06621> paver:[FindFirstFit] There are 0 free blocks (32768 requested) [7764.221] 04778.06621> paver:[FindFirstFit] Partition[2] From Block [16777250, 16818210) ... (next partition starts at block 250069646) [7764.221] 04778.06621> paver:[FindFirstFit] There are 233251436 free blocks (32768 requested) [7764.221] 04778.06621> paver:[AddPartition] Found space in GPT - OK 233251436 @ 16818210 [7764.221] 04778.06621> paver:[AddPartition] Final space in GPT - OK 32768 @ 16818210 [7764.221] 04778.06621> paver:[AddPartition] Added partition, waiting for bind [7764.221] 03095.03168> block: Joining un-closed FIFO server [7764.221] 01104.01117> devcoord: dc_bind_device() '' [7764.222] 01182.01202> devmgr: /dev/class/block/000: GPT? [7764.222] 01104.01117> devcoord: dc_bind_device() '/boot/driver/gpt.so' [7764.222] 03095.03168> gpt: device_get_metadata failed (-25) [7764.223] 01182.01202> devmgr: /dev/class/block/012: FVM? [7764.223] 01104.01117> devcoord: dc_bind_device() '/boot/driver/fvm.so' [7764.223] 04778.06621> paver:[AddPartition] Added partition, waiting for bind - OK 2019/04/12 02:25:40 done 2019/04/12 02:25:40 attempting to send <<image>>zirconr.img... 2019/04/12 02:25:40 target is busy, retrying in one second [7764.357] 04778.04792> netsvc: tftp write of file <<image>>zircona.img completed [7764.357] 04778.04792> netsvc: metrics: {"inorderblks": 13620,"oooblks": 0,"ack": 54,"nack": 0,"timeouts": 0,"sas": 0,"inorderbytes": 13946712} [7764.391] 04778.06621> paver:[PartitionPave] Completed successfully 2019/04/12 02:25:41 attempting to send <<image>>zirconr.img... [7765.358] 04778.04792> netsvc: Running ZIRCON-R Paver [7765.361] 04778.06899> paver:[Initialize] Successfully initialized EFI Device Partitioner [7765.361] 04778.06899> paver:[PartitionPave] Paving partition. [7765.361] 04778.06899> paver:[FindFirstFit] Looking for space [7765.361] 04778.06899> paver:[FindFirstFit] Partition seen with start 34, end 16777249 (length 16777216) [7765.361] 04778.06899> paver:[FindFirstFit] Partition seen with start 16777250, end 16818209 (length 40960) [7765.361] 04778.06899> paver:[FindFirstFit] Partition seen with start 16818210, end 16850977 (length 32768) [7765.361] 04778.06899> paver:[FindFirstFit] Sorting [7765.361] 04778.06899> paver:[FindFirstFit] Partition[0] From Block [0, 34) ... (next partition starts at block 34) [7765.361] 04778.06899> paver:[FindFirstFit] There are 0 free blocks (49152 requested) [7765.361] 04778.06899> paver:[FindFirstFit] Partition[1] From Block [34, 16777250) ... (next partition starts at block 16777250) [7765.361] 04778.06899> paver:[FindFirstFit] There are 0 free blocks (49152 requested) [7765.361] 04778.06899> paver:[FindFirstFit] Partition[2] From Block [16777250, 16818210) ... (next partition starts at block 16818210) [7765.361] 04778.06899> paver:[FindFirstFit] There are 0 free blocks (49152 requested) [7765.361] 04778.06899> paver:[FindFirstFit] Partition[3] From Block [16818210, 16850978) ... (next partition starts at block 250069646) [7765.361] 04778.06899> paver:[FindFirstFit] There are 233218668 free blocks (49152 requested) [7765.361] 04778.06899> paver:[AddPartition] Found space in GPT - OK 233218668 @ 16850978 [7765.361] 04778.06899> paver:[AddPartition] Final space in GPT - OK 49152 @ 16850978 [7765.361] 04778.06899> paver:[AddPartition] Added partition, waiting for bind [7765.361] 03095.03168> block: Joining un-closed FIFO server [7765.361] 01104.01117> devcoord: dc_bind_device() '' [7765.362] 01182.01202> devmgr: /dev/class/block/000: GPT? [7765.362] 01104.01117> devcoord: dc_bind_device() '/boot/driver/gpt.so' [7765.362] 03095.03168> gpt: device_get_metadata failed (-25) [7765.364] 01182.01202> devmgr: /dev/class/block/017: FVM? [7765.364] 01104.01117> devcoord: dc_bind_device() '/boot/driver/fvm.so' [7765.364] 04778.06899> paver:[AddPartition] Added partition, waiting for bind - OK 2019/04/12 02:25:41 done 2019/04/12 02:25:41 attempting to send <<image>>authorized_keys... 2019/04/12 02:25:41 target is busy, retrying in one second [7765.487] 04778.04792> netsvc: tftp write of file <<image>>zirconr.img completed [7765.487] 04778.04792> netsvc: metrics: {"inorderblks": 12426,"oooblks": 0,"ack": 49,"nack": 0,"timeouts": 0,"sas": 0,"inorderbytes": 12723424} [7765.511] 04778.06899> paver:[PartitionPave] Completed successfully 2019/04/12 02:25:42 attempting to send <<image>>authorized_keys... 2019/04/12 02:25:42 done 2019/04/12 02:25:42 attempting to send <<netboot>>kernel.bin... 2019/04/12 02:25:42 target is busy, retrying in one second [7766.488] 04778.04792> netsvc: Installing SSH authorized_keys [7766.488] 04778.04792> netsvc: tftp write of file <<image>>authorized_keys completed [7766.488] 04778.04792> netsvc: metrics: {"inorderblks": 1,"oooblks": 0,"ack": 1,"nack": 0,"timeouts": 0,"sas": 0,"inorderbytes": 806} [7766.491] 04778.07192> paver:[Initialize] Successfully initialized EFI Device Partitioner [7766.491] 01104.01117> devcoord: dc_bind_device() '/boot/driver/zxcrypt.so' [7766.491] 03095.03168> devhost_get_handles(0x1a48fc8e8840:block) open path='zxcrypt', r=-2 [7766.518] 04778.07192> paver:[DataFilePave] Wrote ssh/authorized_keys 2019/04/12 02:25:43 attempting to send <<netboot>>kernel.bin... 2019/04/12 02:25:44 done IPv4 address of atom-chaos-cult-penny found: 192.168.42.106 environment of subprocess: [FUCHSIA_TEST_OUTDIR=/b/s/w/ioYGb_Zi BUILDBUCKET_ID=8916423202876032320 SWARMING_SERVER=https://chromium-swarm.appspot.com CIPD_PROTOCOL=v2 SWARMING_EXTERNAL_BOT_SETUP=1 HOSTNAME=build416-a9--atom-chaos-cult-penny SWARMING_HEADLESS=1 SWARMING_TASK_ID=44282e40ce773411 NO_GCE_CHECK=False DEBIAN_FRONTEND=noninteractive BOTO_CONFIG=/home/swarming/.boto SWARMING_BOT_ID=build416-a9--atom-chaos-cult-penny LUCI_CONTEXT=/b/s/w/itwxdu9p/luci_ctx.mHxQph.json HOME=/home/swarming CIPD_CACHE_DIR=/b/s/cipd_cache/cache TMPDIR=/b/s/w/itwxdu9p PATH=/b/s/cipd_cache/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin FUCHSIA_NODENAME=atom-chaos-cult-penny FUCHSIA_IPV4_ADDR=192.168.42.106 FUCHSIA_SSH_KEY=/etc/botanist/keys/pkey_infra] TAP version 13 1..41 ------------------------------------------------ RUNNING TEST: /pkgfs/packages/ril-qmi-tests/0/test/ril-qmi-tests PASSED: /pkgfs/packages/ril-qmi-tests/0/test/ril-qmi-tests passed running 2 tests test client::tests::no_client ... ok test client::tests::request_id ... ok test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out SUMMARY: Ran 1 tests: 0 failed (0.038 sec) ok 1 //src/connectivity/telephony/ril-qmi:ril_qmi_bin_test [0.000] 00000.00000> zbi: @ 0xffffff8006771000 (12445336 bytes) [0.000] 00000.00000> UART: FIFO depth 16 [0.000] 00000.00000> PMM: boot reserve add [0x100000, 0x2f8fff] [0.000] 00000.00000> PMM: boot reserve add [0x6771000, 0x734ffff] [0.000] 00000.00000> PMM: arena too small to be useful (size 4096) [0.000] 00000.00000> PMM: pmm_add_arena failed to initialize arena [0.000] 00000.00000> MEM: Failed to add pmm range at 0x8afff000 size 0x1000 [0.000] 00000.00000> PMM: boot reserve marking WIRED [0x100000, 0x2f8fff] [0.000] 00000.00000> PMM: boot reserve marking WIRED [0x6771000, 0x734ffff] [0.000] 00000.00000> welcome to Zircon [0.000] 00000.00000> KASLR: .text section at 0xffffffff00100000 [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00114e28 (global_prng_seed) at level 0x30000, flags 0x1 [0.000] 00000.00000> initializing vm pre-heap [0.000] 00000.00000> VM: marking boot alloc used range [0x7350000, 0x7350100) [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001221d8 (elf_build_id) at level 0x4fffe, flags 0x1 [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00122364 (version) at level 0x4ffff, flags 0x1 [0.000] 00000.00000> version: [0.000] 00000.00000> arch: x86 [0.000] 00000.00000> platform: XXX [0.000] 00000.00000> target: XXX [0.000] 00000.00000> project: x64 [0.000] 00000.00000> buildid: git-781fb6ce3d87072aa9f1fccebc05409821f99cc9-dirty [0.000] 00000.00000> ELF build ID: d514a2455b11a13bdea6fbceaa81e7c88508d267 [0.000] 00000.00000> initializing heap [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00101fc0 (console) at level 0x50000, flags 0x1 [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00181918 (x86_resource_init) at level 0x50000, flags 0x1 [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00206408 (percpu_heap_init) at level 0x50000, flags 0x1 [0.000] 00000.00000> initializing vm [0.000] 00000.00000> VM: reserving kernel region [0xffffffff00100000, 0xffffffff00216000) flags 0x28 name 'kernel_code' [0.000] 00000.00000> VM: reserving kernel region [0xffffffff00216000, 0xffffffff00266000) flags 0x8 name 'kernel_rodata' [0.000] 00000.00000> VM: reserving kernel region [0xffffffff00266000, 0xffffffff0026e000) flags 0x18 name 'kernel_data' [0.000] 00000.00000> VM: reserving kernel region [0xffffffff0026e000, 0xffffffff002f9000) flags 0x18 name 'kernel_bss' [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001825dc (display_memtype) at level 0x60001, flags 0x1 [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00185644 (acpi_tables) at level 0x60001, flags 0x1 [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff0018071c (hpet) at level 0x60002, flags 0x1 [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00180adc (apic) at level 0x60002, flags 0x1 [0.000] 00000.00000> x2APIC enabled [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00184c34 (timer) at level 0x60003, flags 0x1 [0.000] 00000.00000> HPET frequency: 23999 ticks/ms [0.000] 00000.00000> TSC frequency: 2712000 ticks/ms [7767.902] 00000.00000> timer features: constant_tsc 1 invariant_tsc 1 tsc_deadline 1 [7767.902] 00000.00000> Using TSC as wallclock [7767.902] 00000.00000> initializing kernel [7767.902] 00000.00000> initializing mp [7767.902] 00000.00000> INIT: cpu 0, calling hook 0xffffffff0011507c (global_prng_thread_safe) at level 0x7ffff, flags 0x1 [7767.902] 00000.00000> creating bootstrap completion thread [7767.902] 00000.00000> top of bootstrap2() [7767.902] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00130fec (libobject) at level 0x80000, flags 0x1 [7767.902] 00000.00000> OOM: started thread [7767.902] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00203414 (dpc) at level 0x80000, flags 0x1 [7767.902] 00000.00000> Processor Model Info: type 0 family 0x6 model 0xe stepping 0x9 [7767.902] 00000.00000> display_family 0x6 display_model 0x8e [7767.902] 00000.00000> Vendor: Intel [7767.902] 00000.00000> Microarch: Kaby Lake [7767.902] 00000.00000> F/M/S: 6/8e/9 [7767.902] 00000.00000> patch_level: 8e [7767.902] 00000.00000> Brand: Intel(R) Core(TM) i5-7300U CPU @ 2.60GHz [7767.902] 00000.00000> Features: fpu sse sse2 sse3 ssse3 sse4.1 sse4.2 mmx avx avx2 fxsr pcid xsave mon aesni clflush [7767.902] 00000.00000> clflushopt fsgsbase tsc_adj smep smap erms rdrand rdseed syscall nx huge rdtscp [7767.902] 00000.00000> invar_tsc tsc_deadline x2apic vmx pt hwp [7767.902] 00000.00000> Properties: meltdown l1tf pcid_good [7767.902] 00000.00000> initializing platform [7767.902] 00000.00000> INIT: cpu 0, calling hook 0xffffffff0019e5d0 (x86_perfmon) at level 0x90000, flags 0x1 [7767.902] 00000.00000> PMU: version 4 [7767.902] 00000.00000> UART: started IRQ driven RX [7767.902] 00000.00000> UART: started IRQ driven TX [7767.902] 00000.00000> cpu topology: [7767.902] 00000.00000> 0: apic id 0x0 package 0 node 0 core 0 smt 0 BSP [7767.902] 00000.00000> 1: apic id 0x2 package 0 node 0 core 1 smt 0 [7767.902] 00000.00000> 2: apic id 0x1 package 0 node 0 core 0 smt 1 [7767.902] 00000.00000> 3: apic id 0x3 package 0 node 0 core 1 smt 1 [7767.902] 00000.00000> Found 4 cpus [7767.902] 00000.00000> booting apic ids: 0x2 0x1 0x3 [7767.917] 00000.00000> entering scheduler on cpu 2 [7767.919] 00000.00000> entering scheduler on cpu 1 [7767.919] 00000.00000> entering scheduler on cpu 3 [7767.921] 00000.00000> smbios: manufacturer="Intel Corporation" product="NUC7i5DNHE" [7767.921] 00000.00000> initializing target [7767.921] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00183ee4 (x86_pcie_init) at level 0xa0000, flags 0x1 [7767.921] 00000.00000> moving to last init level [7767.921] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00121430 (debuglog) at level 0xb0000, flags 0x1 [7767.921] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00102ae8 (kernel_shell) at level 0xc0000, flags 0x1 [7767.921] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001fe834 (ktrace) at level 0xc0000, flags 0x1 [7767.925] 00000.00000> ktrace: buffer at 0xffffff9be5150000 (33554432 bytes) [7767.925] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00212af0 (userboot) at level 0xc0000, flags 0x1 [7767.925] 00000.00000> userboot: ramdisk 0xbdf000 @ 0xffffff8006771000 [7767.925] 00000.00000> userboot: userboot rodata 0 @ [0x4f988ba31000,0x4f988ba34000) [7767.925] 00000.00000> userboot: userboot code 0x3000 @ [0x4f988ba34000,0x4f988ba3f000) [7767.925] 00000.00000> userboot: vdso/full rodata 0 @ [0x4f988ba3f000,0x4f988ba46000) [7767.925] 00000.00000> userboot: vdso/full code 0x7000 @ [0x4f988ba46000,0x4f988ba47000) [7767.925] 00000.00000> userboot: entry point @ 0x4f988ba34c90 [7767.955] 01036.01043> userboot: searching bootfs for 'bin/bootsvc' [7767.955] 01036.01043> userboot: 'bin/bootsvc' has PT_INTERP "lib/ld.so.1" [7767.955] 01036.01043> userboot: searching bootfs for 'lib/ld.so.1' [7767.955] 01036.01043> userboot: userboot: loaded lib/ld.so.1 at 0x569543b9f000, entry point 0x569543bbd080 [7767.955] 01036.01043> userboot: userboot: loaded vDSO at 0x52fd0bf00000, entry point 0x52fd0bf078fa [7767.955] 01036.01043> userboot: process bin/bootsvc started. [7767.955] 01036.01043> userboot: waiting for loader-service requests... [7767.955] 01036.01043> userboot: searching bootfs for 'lib/libasync-default.so' [7767.955] 01036.01043> userboot: searching bootfs for 'lib/libfdio.so' [7767.955] 01036.01043> userboot: searching bootfs for 'lib/libtrace-engine.so' [7767.955] 01036.01043> userboot: searching bootfs for 'lib/liblaunchpad.so' [7767.955] 01050.01053> dso: id=887771c1ae3e8f3705e6c5047b66ccd70b80c5e0 base=0x00001b3c6c083000 name=<application> [7767.955] 01050.01053> dso: id=2243cedb3d53d07d9ab576656ff6a3ee4847fbc2 base=0x000052fd0bf00000 name=<vDSO> [7767.955] 01050.01053> dso: id=90ee144ef27c5dc90dc67076e94910489f38d790 base=0x00004aeb51168000 name=libasync-default.so [7767.955] 01050.01053> dso: id=37f0c40ca0eae1d680d2d83ed03f05fa3b7d5915 base=0x0000715aef67d000 name=libfdio.so [7767.955] 01050.01053> dso: id=54b402f1f418a6f00e0a894c7758747c093b40cd base=0x000002ebb5514000 name=libtrace-engine.so [7767.955] 01050.01053> dso: id=deb23e0aec1895ea2e463752805ff66cd6f1517c base=0x00000007d730b000 name=liblaunchpad.so [7767.955] 01050.01053> dso: id=02872f27f30161aa9b37ffd99002e900a759ae2a base=0x0000569543b9f000 name=libc.so [7767.956] 01050.01053> bootsvc: Starting... [7767.956] 01036.01043> userboot: loader-service channel peer closed [7767.956] 01036.01043> userboot: finished! [7767.956] 01050.01053> bootsvc: Creating bootfs service... [7767.956] 01050.01053> bootsvc: Retrieving boot image... [7767.956] 01050.01053> bootsvc: Decommitted BOOTDATA VMO from 0 to 0xbde000 [7767.956] 01050.01053> bootsvc: Loading boot arguments... [7767.956] 01050.01053> bootsvc: Creating svcfs service... [7767.956] 01050.01053> bootsvc: Loading kernel VMOs... [7767.956] 01050.01053> bootsvc: Creating loader service... [7767.956] 01050.01053> bootsvc: Launching next process... [7767.956] 01050.01107> bootsvc: Launched bin/devcoordinator [7767.958] 01114.01130> devcoordinator: launch /boot/bin/svchost (svchost) OK [7767.959] 01114.01130> devcoordinator: launch /boot/bin/fshost (fshost) OK [7767.961] 01114.01322> devcoordinator: launch /boot/bin/pwrbtn-monitor (pwrbtn-monitor) OK [7767.962] 01114.01342> devcoordinator: launch /boot/bin/netsvc (netsvc) OK [7767.962] 01114.01342> devcoordinator: launch /boot/bin/virtual-console (virtual-console) OK [7767.965] 01114.01130> devcoordinator: full system required, ignoring fallback drivers until /system is loaded [7767.965] 01114.01130> devcoordinator: launch devhost 'devhost:sys': pid=2184 [7767.965] 01114.01130> devcoordinator: launch devhost 'devhost:test': pid=2216 [7767.966] 01114.01130> devcoordinator: launch devhost 'devhost:root': pid=2263 [7767.966] 01114.01130> devcoordinator: launch devhost 'devhost:misc': pid=2327 [7767.966] 02184.02198> devhost: trace provider registry begun [7767.967] 02216.02236> devhost: trace provider registry begun [7767.968] 02263.02293> devhost: trace provider registry begun [7767.969] 02327.02359> devhost: trace provider registry begun [7767.969] 01114.01334> devcoordinator: launch /boot/bin/sh (sh:console) OK [7768.054] 02184.02198> acpi-bus: not using IOMMU [7768.061] 02184.02198> PMU: 7 arch events [7768.061] 02184.02198> PMU: arch event id range: 1-7 [7768.061] 02184.02198> PMU: 102 arch events [7768.061] 02184.02198> PMU: arch event id range: 1-241 [7768.061] 02184.02198> Intel Processor Trace configuration for this chipset: [7768.061] 02184.02198> mtc_freq_mask: 0x249 [7768.061] 02184.02198> cyc_thresh_mask: 0x3fff [7768.061] 02184.02198> psb_freq_mask: 0x3f [7768.061] 02184.02198> num addr ranges: 2 [7768.061] 02184.02198> [sysmem_init 28] async_get_default_dispatcher(): 0x31f06c534750 [7768.061] 02184.02198> [sysmem_bind 40] sysmem_bind() [7768.061] 02184.02876> acpi-pwrbtn: initialized [7768.062] 01746.01773> vc: new input device /dev/class/input/000 [7768.093] 02184.02876> acpi: published device pci(0x31efac599610), parent=sys(0x31f0ec538d10), handle=0x31ef6c552150 [7768.093] 01114.01130> devcoordinator: launch devhost 'devhost:pci#1:8086:5916': pid=2972 [7768.093] 01114.01130> devcoordinator: launch devhost 'devhost:pci#3:8086:9d2f': pid=3019 [7768.094] 02972.02986> devhost: trace provider registry begun [7768.094] 01114.01130> devcoordinator: launch devhost 'devhost:pci#5:8086:9d60': pid=3097 [7768.095] 01114.01130> devcoordinator: launch devhost 'devhost:pci#6:8086:9d61': pid=3190 [7768.095] 03019.03042> devhost: trace provider registry begun [7768.096] 01114.01130> devcoordinator: launch devhost 'devhost:pci#9:8086:9d03': pid=3292 [7768.096] 03097.03152> devhost: trace provider registry begun [7768.096] 02184.02876> acpi: published device rtc(0x31efac59b230), parent=acpi(0x31f0ec538110), handle=0x31ef6c578410 [7768.098] 01114.01130> devcoordinator: launch devhost 'devhost:pci#14:8086:9d71': pid=3450 [7768.098] 03292.03313> devhost: trace provider registry begun [7768.098] 02184.02876> acpi-ec: initialized [7768.099] 03190.03213> devhost: trace provider registry begun [7768.099] 01114.01130> devcoordinator: launch devhost 'devhost:pci#16:8086:156f': pid=3545 [7768.102] 02184.02876> acpi: failed to create NHLT VMO (res -10) [7768.102] 02184.02876> acpi: failed to publish NHLT metadata [7768.105] 03292.03313> ahci: using MSI interrupt [7768.106] 03097.03152> initialized intel serialio i2c driver, reg=0x542a3bf87000 regsize=4096 [7768.106] 03190.03213> initialized intel serialio i2c driver, reg=0x4708b793f000 regsize=4096 [7768.106] 03450.03478> devhost: trace provider registry begun [7768.110] 03545.03598> devhost: trace provider registry begun [7768.112] 03450.03478> [IHDA DSP 00:1f.3] Failed to fetch NHLT (res -25) [7768.112] 03450.03478> [IHDA Controller 00:1f.3] Failed to initialize DSP device (res = -25) [7768.112] 03019.03947> * found USB device (0x18d1:0xa002, USB 2.0) config 1 [7768.112] 03019.03947> * found USB device (0x18d1:0xa003, USB 3.0) config 1 [7768.184] 01661.01684> netifc: ? /dev/class/ethernet/000 [7768.184] 01661.01684> netifc: create 512 eth buffers [7768.184] 01661.01684> macaddr: 54:b2:03:09:1a:85 [7768.184] 01661.01684> ip6addr: fe80::56b2:34d:fe09:1a85 [7768.184] 01661.01684> snmaddr: ff02::1:ff09:1a85 [7768.184] 01661.01684> netsvc: using /dev/class/ethernet/000 [7768.184] 01661.01684> netsvc: nodename='atom-chaos-cult-penny' [7768.184] 01661.01684> netsvc: start [7768.212] 01746.01773> vc: new display device /dev/class/display-controller/000/virtcon [7768.252] 02972.04240> i915: MST not supported [7768.752] 02972.04240> i915: Found DP monitor [7768.752] 02972.04240> i915: Display 1 connected [7769.310] 01746.01773> vc: Successfully attached to display 1 [7769.582] 03019.03947> * found USB device (0x0781:0x5571, USB 2.10) config 1 [7769.833] 03019.03947> * found USB device (0x8087:0x0a2b, USB 2.0) config 1 [7770.110] 03190.03213> intel-i2c-controller: intel_serialio_i2c_slave_transfer: -21 [7770.110] 03190.03213> i2c-hid: could not read HID descriptor: 0 [7770.110] 03190.03213> devhost[00:15.1/i2c-bus-9d61/004c] bind driver '/boot/driver/i2c-hid.so' failed: -2 [7770.110] 03097.03152> intel-i2c-controller: intel_serialio_i2c_slave_transfer: -21 [7770.110] 03097.03152> i2c-hid: could not read HID descriptor: 0 [7770.110] 03097.03152> devhost[00:15.0/i2c-bus-9d60/0020] bind driver '/boot/driver/i2c-hid.so' failed: -2 [7770.110] 01114.01130> devcoordinator: rpc: bind-driver '004c' status -2 [7770.110] 01114.01130> devcoordinator: rpc: bind-driver '0020' status -2 [7770.120] 03292.03767> sata2: dev info [7770.120] 03292.03767> serial=AA000000000000009012 [7770.120] 03292.03767> firmware rev=0R08A1 0 [7770.120] 03292.03767> model id=ETMAT 8MSP1582 G [7770.120] 03292.03767> major=0x3f0 ACS2 DMA 32 commands [7770.120] 03292.03767> LBA48 250069680 sectors, sector size=512 [7770.121] 01301.01314> fshost: /dev/class/block/000: GPT? [7770.121] 03292.03313> gpt: device_get_metadata failed (-25) [7770.122] 01301.01314> fshost: /dev/class/block/001: FVM? [7770.123] 01301.01314> fshost: not automounting efi [7770.126] 01301.01314> devcoordinator: launch /boot/bin/blobfs (blobfs:/blob) OK [7770.265] 01301.01314> fshost: starting 'bin/pkgsvr' 'c3e78c8d457f0c66c3639b3892f8273a122ad929034027387c02fd1485626720'... [7770.271] 01301.01314> devcoordinator: launch bin/pkgsvr (pkgfs) OK [7770.282] 04664.04878> pkgsvr: system: will be served from c3e78c8d457f0c66c3639b3892f8273a122ad929034027387c02fd1485626720 [7770.282] 04664.04878> pkgsvr: pkgfs serving index /data/pkgfs_index from blobfs /blob [7770.282] 01301.01314> fshost: /dev/class/block/006: zxcrypt? [7770.283] 03292.03313> devhost_get_handles(0x20a5a7111a10:block) open path='zxcrypt', r=-2 [7770.289] 01301.01314> fshost: mounting minfs [7770.289] 01301.01314> devcoordinator: launch /boot/bin/minfs (minfs:/data) OK [7770.347] 01114.04918> devcoordinator: adding system driver 'wlan' '/system/driver/wlanphy.so' [7770.347] 01114.01785> devcoordinator: launch /system/bin/appmgr (appmgr) OK [7770.352] 01114.04918> devcoordinator: adding system driver 'bt_hog' '/system/driver/bt-hog.so' [7770.384] 01114.04918> devcoordinator: adding system driver 'bt_host' '/system/driver/bt-host.so' [7770.399] 01114.04918> devcoordinator: adding system driver 'virtual_audio' '/system/driver/virtual_audio_driver.so' [7770.405] 01114.04918> devcoordinator: adding system driver 'brcmfmac' '/system/driver/brcmfmac.so' [7770.410] 01114.04918> devcoordinator: adding system driver 'bt_hci_passthrough' '/system/driver/bt-hci-passthrough.so' [7770.436] 01114.04918> devcoordinator: adding system driver 'bt_hci_atheros' '/system/driver/bt-hci-atheros.so' [7770.443] 01114.04918> devcoordinator: adding system driver 'qmi_fake' '/system/driver/qmi-fake.so' [7770.470] 01114.04918> devcoordinator: adding system driver 'qmi_usb' '/system/driver/qmi-transport.so' [7770.526] 01114.04918> devcoordinator: adding system driver 'ralink' '/system/driver/ralink.so' [7770.543] 01114.04918> devcoordinator: adding system driver 'usb_video' '/system/driver/usb_video.so' [7770.561] 01114.04918> devcoordinator: adding system driver 'virtual_camera' '/system/driver/virtual_camera_driver.so' [7770.617] 01114.04918> devcoordinator: adding system driver 'wlan' '/system/driver/wlan.so' [7770.624] 01114.04918> devcoordinator: adding system driver 'mt8167s_gpu' '/system/driver/libmsd_img_rgx_mtk.so' [7770.626] 01114.04918> devcoordinator: adding system driver 'iwlwifi_pci' '/system/driver/iwlwifi.so' [7770.635] 01114.04918> devcoordinator: adding system driver 'wlan' '/system/driver/wlanif.so' [7770.645] 01114.04918> devcoordinator: adding system driver 'gpu' '/system/driver/libmsd_intel.so' [7770.653] 01114.04918> devcoordinator: adding system driver 'rtl88xx' '/system/driver/rtl88xx.so' [7770.679] 01114.04918> devcoordinator: adding system driver 'bt_hci_intel' '/system/driver/bt-hci-intel.so' [7770.708] 05185.05233> [INFO:namespace_builder.cc(93)] config-data for sysmgr [7770.712] 01114.04918> devcoordinator: adding system driver 'ath10k_pci' '/system/driver/ath10k.so' [7770.725] 01114.04918> devcoordinator: adding system driver 'bt_hci_fake' '/system/driver/bt-hci-fake.so' [7770.726] 01114.01130> devcoordinator: driver 'bt_hci_fake' added [7770.726] 01114.01130> devcoordinator: driver 'ath10k_pci' added [7770.726] 01114.01130> devcoordinator: driver 'bt_hci_intel' added [7770.726] 01114.01130> devcoordinator: driver 'rtl88xx' added [7770.726] 01114.01130> devcoordinator: driver 'gpu' added [7770.726] 01114.01130> devcoordinator: driver 'wlan' added [7770.726] 01114.01130> devcoordinator: driver 'iwlwifi_pci' added [7770.726] 01114.01130> devcoordinator: driver 'mt8167s_gpu' added [7770.726] 01114.01130> devcoordinator: driver 'wlan' added [7770.726] 01114.01130> devcoordinator: driver 'usb_video' added [7770.726] 01114.01130> devcoordinator: driver 'ralink' added [7770.726] 01114.01130> devcoordinator: driver 'qmi_usb' added [7770.726] 01114.01130> devcoordinator: driver 'qmi_fake' added [7770.726] 01114.01130> devcoordinator: driver 'bt_hci_atheros' added [7770.726] 01114.01130> devcoordinator: driver 'brcmfmac' added [7770.726] 01114.01130> devcoordinator: driver 'bt_host' added [7770.726] 01114.01130> devcoordinator: driver 'bt_hog' added [7770.726] 01114.01130> devcoordinator: driver 'wlan' added [7770.726] 01114.01130> devcoordinator: driver 'bt_hci_passthrough' added [7770.726] 01114.01130> devcoordinator: fallback driver 'usb_composite' is available [7770.726] 01114.01130> devcoordinator: driver 'usb_composite' added [7770.726] 01114.01130> devcoordinator: fallback driver 'intel_disp' is available [7770.726] 01114.01130> devcoordinator: driver 'intel_disp' added [7770.728] 03019.03042> UMS: parent: 'ifc-000' [7770.728] 03019.03042> UMS:Max lun is: 0 [7770.729] 03019.03042> devhost[00:14.0/xhci/usb-bus/002/002/ifc-001] bind driver '/boot/driver/bt-transport-usb.so' failed: -2 [7770.729] 01114.01130> devcoordinator: rpc: bind-driver 'ifc-001' status -2 [7770.731] 03019.06028> UMS: block size is: 0x00000200 [7770.731] 03019.06028> UMS: total blocks is: 15630336 [7770.731] 03019.06028> UMS: total size is: 8002732032 [7770.731] 03019.06028> UMS: read-only: 0 removable: 1 [7770.786] 05887.05908> [ERROR:garnet/bin/sysmgr/app.cc(150)] Could not serve fuchsia.amber.Control: 0 [7770.786] 05887.05908> [ERROR:garnet/bin/sysmgr/app.cc(150)] Could not serve fuchsia.amber.Events: 0 [7770.786] 05887.05908> [ERROR:garnet/bin/sysmgr/app.cc(150)] Could not serve fuchsia.net.policy.Observer: 0 [7770.786] 05887.05908> [ERROR:garnet/bin/sysmgr/app.cc(150)] Could not serve fuchsia.netstack.Netstack: 0 [7770.786] 05887.05908> [ERROR:garnet/bin/sysmgr/app.cc(150)] Could not serve fuchsia.wlan.service.Wlan: 0 [7770.786] 05887.05908> [ERROR:garnet/bin/sysmgr/app.cc(150)] Could not serve fuchsia.mdns.Controller: 0 [7770.787] 03019.06380> btintel: firmware already loaded [7770.787] 03019.06390> hci_read_thread: failed to read from command channel ZX_ERR_PEER_CLOSED [7770.787] 03019.06390> hci_read_thread: failed to read from ACL channel ZX_ERR_PEER_CLOSED [7770.787] 03019.06390> bt-transport-usb: all channels closed - exiting [7770.788] 03019.06589> [INFO:command_channel.cc(149)] hci: initialized [7770.789] 01301.01314> fshost: /dev/class/block/008: MBR? [7770.792] 01301.01314> fshost: /dev/class/block/010: MBR? [7770.807] 05185.05233> [INFO:namespace_builder.cc(93)] config-data for amber [7770.810] 03019.06589> [INFO:acl_data_channel.cc(91)] hci: initialized [7770.881] 05185.05233> [INFO:namespace_builder.cc(93)] config-data for pkg_resolver [7771.158] 08467.08480> [INFO:cobalt_main.cc(217)] 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 [7771.158] 08467.08480> kfill_days=2, start_event_aggregator_worker=1. [7771.251] 05185.05233> [INFO:namespace_builder.cc(93)] config-data for wlancfg [7771.569] 05185.05233> [INFO:namespace_builder.cc(93)] config-data for crashpad_agent [7771.644] 10240.10258> [INFO:system_metrics_main.cc(23)] Cobalt SystemMetricsDaemon: System metrics daemon created. [7771.654] 03019.06589> [WARN:bredr_discovery_manager.cc(482)] gap-bredr: write inquiry scan activity failed: [status: invalid HCI command parameters (HCI 0x12)] [7771.655] 12312.12325> [INFO:main.cc(35)] Trace Manager starting with config: /pkg/data/tracing.config [7771.711] 12893.12907> wlanstack2 [I]: Starting [7772.085] 13576.13589> setsockopt IPV6_TCLASS 32: Protocol not available: [7772.237] 14057.14075> setsockopt IPV6_TCLASS 32: Protocol not available: [7772.658] 10107.10120> Starting mDNS on interface ethp001f6 [80fe::b256:ff03:9fe:851a] [7772.659] 10107.10120> [WARNING:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(29)] NET-291 IPV6_MULTICAST_LOOP not supported (ENOPROTOOPT), continuing anyway [7772.659] 10107.10120> [WARNING:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(50)] NET-2180 IPV6_JOIN_GROUP returned ENODEV, mDNS will not communicate via IPV6 ------------------------------------------------ RUNNING TEST: /pkgfs/packages/catapult_converter/0/test/catapult_converter_test Running main() from ../../third_party/googletest/googletest/src/gtest_main.cc [==========] Running 8 tests from 2 test suites. [----------] Global test environment set-up. [----------] 1 test from TestTools [ RUN ] TestTools.SplitLines [ OK ] TestTools.SplitLines (0 ms) [----------] 1 test from TestTools (0 ms total) [----------] 7 tests from CatapultConverter [ RUN ] CatapultConverter.Convert [ OK ] CatapultConverter.Convert (1 ms) [ RUN ] CatapultConverter.ConvertWithSplitFirst [ OK ] CatapultConverter.ConvertWithSplitFirst (1 ms) [ RUN ] CatapultConverter.ConvertThroughputUnits [ OK ] CatapultConverter.ConvertThroughputUnits (0 ms) [ RUN ] CatapultConverter.ConvertBytesUnit [ OK ] CatapultConverter.ConvertBytesUnit (0 ms) [ RUN ] CatapultConverter.ZeroValues [ OK ] CatapultConverter.ZeroValues (1 ms) [ RUN ] CatapultConverter.NegativeValues [ OK ] CatapultConverter.NegativeValues (0 ms) [ RUN ] CatapultConverter.ConverterMain PASSED: /pkgfs/packages/catapult_converter/0/test/catapult_converter_test passed [ OK ] CatapultConverter.ConverterMain (1 ms) [----------] 7 tests from CatapultConverter (4 ms total) [----------] Global test environment tear-down [==========] 8 tests from 2 test suites ran. (4 ms total) [ PASSED ] 8 tests. SUMMARY: Ran 1 tests: 0 failed (0.042 sec) ok 2 //garnet/bin/catapult_converter:catapult_converter_test ------------------------------------------------ RUNNING TEST: /pkgfs/packages/driver_example_mod_tests/0/test/driver_example_mod_tests FAILURE: /pkgfs/packages/driver_example_mod_tests/0/test/driver_example_mod_tests exited with nonzero status: -1 fuchsia-pkg://fuchsia.com/driver_example_mod_tests#meta/driver_example_mod_tests.cmx: failed to create component (UNKNOWN) The following tests failed: /pkgfs/packages/driver_example_mod_tests/0/test/driver_example_mod_tests: returned nonzero: -1 SUMMARY: Ran 1 tests: 1 failed (0.287 sec) 2019/04/12 02:25:50 Process exited with status 1 not ok 3 //topaz/examples/test/driver_example_mod://out/x64/driver_example_mod_tests ------------------------------------------------ RUNNING TEST: /pkgfs/packages/slider_mod_tests/0/test/slider_mod_tests FAILURE: /pkgfs/packages/slider_mod_tests/0/test/slider_mod_tests exited with nonzero status: -1 fuchsia-pkg://fuchsia.com/slider_mod_tests#meta/slider_mod_tests.cmx: failed to create component (UNKNOWN) The following tests failed: /pkgfs/packages/slider_mod_tests/0/test/slider_mod_tests: returned nonzero: -1 SUMMARY: Ran 1 tests: 1 failed (0.304 sec) 2019/04/12 02:25:50 Process exited with status 1 not ok 4 //topaz/public/dart/fuchsia_modular/examples/slider_mod://out/x64/slider_mod_tests ------------------------------------------------ RUNNING TEST: /pkgfs/packages/font_provider_tests/0/test/font_provider_test_lib_test PASSED: /pkgfs/packages/font_provider_tests/0/test/font_provider_test_lib_test passed running 6 tests test test_basic ... ok test test_aliases ... ok test test_get_family_info ... ok test test_fallback_group ... ok test test_fallback ... ok test test_font_collections ... ok test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out SUMMARY: Ran 1 tests: 0 failed (0.843 sec) ok 5 //garnet/bin/fonts:font_provider_test_lib_test ------------------------------------------------ RUNNING TEST: /pkgfs/packages/font_provider_tests/0/test/font_server_bin_test PASSED: /pkgfs/packages/font_provider_tests/0/test/font_server_bin_test passed running 7 tests test collection::tests::test_font_matching_fallback_group ... ok test collection::tests::test_font_matching_exact ... ok test collection::tests::test_font_matching_slant ... ok test collection::tests::test_font_matching_lang ... ok test collection::tests::test_font_matching_weight ... ok test collection::tests::test_font_matching_width ... ok test font_info::tests::test_charset ... ok test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out SUMMARY: Ran 1 tests: 0 failed (0.097 sec) ok 6 //garnet/bin/fonts:font_server_bin_test [7773.740] 28613.28626> dso: id=1d94037bb3f4bc61 base=0x00003ece0d820000 name=/pkg/bin/app [7773.740] 28613.28626> dso: id=f319321953e14c13 base=0x00005f17b6d41000 name=libclang_rt.asan.so [7773.740] 28613.28626> dso: id=6c8fa90153373216 base=0x0000323a71372000 name=libfxl_logging.so [7773.740] 28613.28626> dso: id=0c1eaf2c9ed03e40 base=0x00006d9a90027000 name=libz.so [7773.740] 28613.28626> dso: id=13a99b61cfcda4b1 base=0x0000243422bb6000 name=libcrypto.so [7773.740] 28613.28626> dso: id=5d18157a524cede1 base=0x000031ec9bc9a000 name=libssl.so [7773.740] 28613.28626> dso: id=b53606ac2175d4c2 base=0x000058304460f000 name=libdart_jit.so [7773.740] 28613.28626> dso: id=5bee7bc9b4b1523d base=0x0000264d204b3000 name=libfdio.so [7773.740] 28613.28626> dso: id=9f4aa5693d1dd2eb base=0x0000714006d9f000 name=libasync-default.so [7773.740] 28613.28626> dso: id=b9a4808796516ab4 base=0x000028b8e858b000 name=libsyslog.so [7773.740] 28613.28626> dso: id=21256fb0cfda0df9 base=0x000036bfcb094000 name=libtrace-engine.so [7773.740] 28613.28626> dso: id=2243cedb3d53d07d9ab576656ff6a3ee4847fbc2 base=0x00007e39ba576000 name=<vDSO> [7773.740] 28613.28626> dso: id=62d5b2b024cc78a4 base=0x0000779db124e000 name=libc++abi.so.1 [7773.740] 28613.28626> dso: id=e8d87f91321b4b5f base=0x00003ca8847d7000 name=libunwind.so.1 [7773.740] 28613.28626> dso: id=3f378ad1551e4834 base=0x0000756f3a8e9000 name=libc.so [7773.740] 28613.28626> dso: id=ddd578b014e2a2e9 base=0x000069f178084000 name=libmemfs.so [7773.740] 28613.28626> dso: id=7a0404d214e6a9b3 base=0x00004c1295b87000 name=libc++.so.2 [7773.740] 28613.28626> ================================================================= [7773.741] 28613.28626> ERROR: AddressSanitizer: odr-violation (0x3ece0dc6f040): [7773.741] 28613.28626> [1] size=1 'dart::Expect::failed_' ../../third_party/dart/runtime/platform/assert.cc:13:14 [7773.741] 28613.28626> [2] size=1 'dart::Expect::failed_' ../../third_party/dart/runtime/platform/assert.cc:13:14 [7773.741] 28613.28626> These globals were registered at these points: [7773.741] 28613.28626> [1]: [7773.742] 28613.28626> #0 0x00005f17b6d8b675 in __asan_register_globals /b/s/w/ir/k/llvm-project/compiler-rt/lib/asan/asan_globals.cc:365 <libclang_rt.asan.so>+0x4a675 [7773.742] 28613.28626> #1 0x00003ece0dc07fea in asan.module_ctor <<application>>+0x3e7fea [7773.742] 28613.28626> #2 0x0000756f3a96b998 in __libc_start_init /b/s/w/ir/k/out/build-zircon/../../zircon/third_party/ulib/musl/ldso/dynlink.c:1601 <libc.so>+0x82998 [7773.742] 28613.28626> #3 0x0000756f3a97f2c7 in start_main /b/s/w/ir/k/out/build-zircon/../../zircon/third_party/ulib/musl/src/env/__libc_start_main.c:71 <libc.so>+0x962c7 [7773.742] 28613.28626> [2]: [7773.742] 28613.28626> #0 0x00005f17b6d8b675 in __asan_register_globals /b/s/w/ir/k/llvm-project/compiler-rt/lib/asan/asan_globals.cc:365 <libclang_rt.asan.so>+0x4a675 [7773.742] 28613.28626> #1 0x000058304570381a in asan.module_ctor <libdart_jit.so>+0x10f481a [7773.742] 28613.28626> #2 0x0000756f3a96b998 in __libc_start_init /b/s/w/ir/k/out/build-zircon/../../zircon/third_party/ulib/musl/ldso/dynlink.c:1601 <libc.so>+0x82998 [7773.742] 28613.28626> #3 0x0000756f3a97f2c7 in start_main /b/s/w/ir/k/out/build-zircon/../../zircon/third_party/ulib/musl/src/env/__libc_start_main.c:71 <libc.so>+0x962c7 [7773.742] 28613.28626> HINT: if you don't care about these errors you may set ASAN_OPTIONS=detect_odr_violation=0 [7773.742] 28613.28626> SUMMARY: AddressSanitizer: odr-violation: global 'dart::Expect::failed_' at ../../third_party/dart/runtime/platform/assert.cc:13 [7773.742] 28613.28626> :14 [7773.742] 28613.28626> ABORTING [7774.467] 34032.34045> dso: id=1d94037bb3f4bc61 base=0x000025c657cd4000 name=/pkg/bin/app [7774.467] 34032.34045> dso: id=f319321953e14c13 base=0x00005337c9bc5000 name=libclang_rt.asan.so [7774.467] 34032.34045> dso: id=6c8fa90153373216 base=0x00002c7c37310000 name=libfxl_logging.so [7774.467] 34032.34045> dso: id=0c1eaf2c9ed03e40 base=0x0000260d3e18f000 name=libz.so [7774.467] 34032.34045> dso: id=13a99b61cfcda4b1 base=0x00006af505a1b000 name=libcrypto.so [7774.467] 34032.34045> dso: id=5d18157a524cede1 base=0x000040f185988000 name=libssl.so [7774.467] 34032.34045> dso: id=b53606ac2175d4c2 base=0x0000272aabeb8000 name=libdart_jit.so [7774.467] 34032.34045> dso: id=5bee7bc9b4b1523d base=0x00004d9956e52000 name=libfdio.so [7774.467] 34032.34045> dso: id=9f4aa5693d1dd2eb base=0x00004f436e680000 name=libasync-default.so [7774.467] 34032.34045> dso: id=b9a4808796516ab4 base=0x000053d2f0035000 name=libsyslog.so [7774.467] 34032.34045> dso: id=21256fb0cfda0df9 base=0x000024eafd59f000 name=libtrace-engine.so [7774.467] 34032.34045> dso: id=2243cedb3d53d07d9ab576656ff6a3ee4847fbc2 base=0x00007860bfd96000 name=<vDSO> [7774.467] 34032.34045> dso: id=62d5b2b024cc78a4 base=0x00003856d7860000 name=libc++abi.so.1 [7774.467] 34032.34045> dso: id=e8d87f91321b4b5f base=0x0000390a137ea000 name=libunwind.so.1 [7774.467] 34032.34045> dso: id=3f378ad1551e4834 base=0x000046f3a178e000 name=libc.so [7774.467] 34032.34045> dso: id=ddd578b014e2a2e9 base=0x000062dde2e83000 name=libmemfs.so [7774.467] 34032.34045> dso: id=7a0404d214e6a9b3 base=0x000030c296b53000 name=libc++.so.2 [7774.467] 34032.34045> ================================================================= [7774.467] 34032.34045> ERROR: AddressSanitizer: odr-violation (0x25c658123040): [7774.467] 34032.34045> [1] size=1 'dart::Expect::failed_' ../../third_party/dart/runtime/platform/assert.cc:13:14 [7774.467] 34032.34045> [2] size=1 'dart::Expect::failed_' ../../third_party/dart/runtime/platform/assert.cc:13:14 [7774.467] 34032.34045> These globals were registered at these points: [7774.467] 34032.34045> [1]: [7774.468] 34032.34045> #0 0x00005337c9c0f675 in __asan_register_globals /b/s/w/ir/k/llvm-project/compiler-rt/lib/asan/asan_globals.cc:365 <libclang_rt.asan.so>+0x4a675 [7774.468] 34032.34045> #1 0x000025c6580bbfea in asan.module_ctor <<application>>+0x3e7fea [7774.468] 34032.34045> #2 0x000046f3a1810998 in __libc_start_init /b/s/w/ir/k/out/build-zircon/../../zircon/third_party/ulib/musl/ldso/dynlink.c:1601 <libc.so>+0x82998 [7774.468] 34032.34045> #3 0x000046f3a18242c7 in start_main /b/s/w/ir/k/out/build-zircon/../../zircon/third_party/ulib/musl/src/env/__libc_start_main.c:71 <libc.so>+0x962c7 [7774.468] 34032.34045> [2]: [7774.468] 34032.34045> #0 0x00005337c9c0f675 in __asan_register_globals /b/s/w/ir/k/llvm-project/compiler-rt/lib/asan/asan_globals.cc:365 <libclang_rt.asan.so>+0x4a675 [7774.468] 34032.34045> #1 0x0000272aacfac81a in asan.module_ctor <libdart_jit.so>+0x10f481a [7774.468] 34032.34045> #2 0x000046f3a1810998 in __libc_start_init /b/s/w/ir/k/out/build-zircon/../../zircon/third_party/ulib/musl/ldso/dynlink.c:1601 <libc.so>+0x82998 [7774.469] 34032.34045> #3 0x000046f3a18242c7 in start_main /b/s/w/ir/k/out/build-zircon/../../zircon/third_party/ulib/musl/src/env/__libc_start_main.c:71 <libc.so>+0x962c7 [7774.469] 34032.34045> HINT: if you don't care about these errors you may set ASAN_OPTIONS=detect_odr_violation=0 [7774.469] 34032.34045> SUMMARY: AddressSanitizer: odr-violation: global 'dart::Expect::failed_' at ../../third_party/dart/runtime/platform/assert.cc:13 [7774.469] 34032.34045> :14 [7774.469] 34032.34045> ABORTING [7775.915] 10107.10120> Starting mDNS on interface ethp001f6 192.168.42.106 [7775.915] 10107.10120> [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 [7775.916] 10107.10120> Starting mDNS on interface ethp001f6 [80fe::b256:ff03:9fe:851a] [7775.917] 10107.10120> [WARNING:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(29)] NET-291 IPV6_MULTICAST_LOOP not supported (ENOPROTOOPT), continuing anyway [7775.917] 10107.10120> [WARNING:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(50)] NET-2180 IPV6_JOIN_GROUP returned ENODEV, mDNS will not communicate via IPV6 [7775.917] 10107.10120> mDNS: Verifying uniqueness of host name atom-chaos-cult-penny.local. ------------------------------------------------ RUNNING TEST: /pkgfs/packages/run_test_component_unittests/0/test/run_test_component_unittests [==========] Running 18 tests from 4 test suites. [----------] Global test environment set-up. [----------] 4 tests from EnvironmentConfigTest [ RUN ] EnvironmentConfigTest.InvalidJson [ OK ] EnvironmentConfigTest.InvalidJson (1 ms) [ RUN ] EnvironmentConfigTest.NoSysElement [ OK ] EnvironmentConfigTest.NoSysElement (1 ms) [ RUN ] EnvironmentConfigTest.InvalidSection [ OK ] EnvironmentConfigTest.InvalidSection (0 ms) [ RUN ] EnvironmentConfigTest.ValidConfig [ OK ] EnvironmentConfigTest.ValidConfig (1 ms) [----------] 4 tests from EnvironmentConfigTest (3 ms total) [----------] 2 tests from Url [ RUN ] Url.ParseURL [ OK ] Url.ParseURL (1 ms) [ RUN ] Url.GenerateComponentUrl [ OK ] Url.GenerateComponentUrl (0 ms) [----------] 2 tests from Url (1 ms total) [----------] 1 test from RunTest [ RUN ] RunTest.ParseArgs [ OK ] RunTest.ParseArgs (3 ms) [----------] 1 test from RunTest (3 ms total) [----------] 11 tests from TestMetadataTest [7776.861] 10107.10120> mDNS: Using unique host name atom-chaos-cult-penny.local. [ RUN ] TestMetadataTest.InvalidJson [ OK ] TestMetadataTest.InvalidJson (1 ms) [ RUN ] TestMetadataTest.NoFacet [ OK ] TestMetadataTest.NoFacet (0 ms) [ RUN ] TestMetadataTest.NoFuchsiaTestFacet [ OK ] TestMetadataTest.NoFuchsiaTestFacet (1 ms) [ RUN ] TestMetadataTest.NoServices [ OK ] TestMetadataTest.NoServices (1 ms) [ RUN ] TestMetadataTest.InvalidTestFacet [ OK ] TestMetadataTest.InvalidTestFacet (0 ms) [ RUN ] TestMetadataTest.InvalidServicesType [ OK ] TestMetadataTest.InvalidServicesType (1 ms) [ RUN ] TestMetadataTest.InvalidSystemServicesType [ OK ] TestMetadataTest.InvalidSystemServicesType (2 ms) [ RUN ] TestMetadataTest.InvalidServices [ OK ] TestMetadataTest.InvalidServices (1 ms) [ RUN ] TestMetadataTest.EmptyServices [ OK ] TestMetadataTest.EmptyServices (0 ms) [ RUN ] TestMetadataTest.ValidServices [ OK ] TestMetadataTest.ValidServices (1 ms) [ RUN ] TestMetadataTest.ValidSystemServices PASSED: /pkgfs/packages/run_test_component_unittests/0/test/run_test_component_unittests passed [ OK ] TestMetadataTest.ValidSystemServices (1 ms) [----------] 11 tests from TestMetadataTest (9 ms total) [----------] Global test environment tear-down [==========] 18 tests from 4 test suites ran. (16 ms total) [ PASSED ] 18 tests. SUMMARY: Ran 1 tests: 0 failed (0.135 sec) ok 7 //garnet/bin/run_test_component:run_test_component_unittests ------------------------------------------------ RUNNING TEST: /pkgfs/packages/run_test_component_test/0/test/run_test_component_test PASSED: /pkgfs/packages/run_test_component_test/0/test/run_test_component_test passed [==========] Running 1 test from 1 test suite. [----------] Global test environment set-up. [----------] 1 test from Run [ RUN ] Run.TestHermeticEnv [ OK ] Run.TestHermeticEnv (1 ms) [----------] 1 test from Run (1 ms total) [----------] Global test environment tear-down [==========] 1 test from 1 test suite ran. (1 ms total) [ PASSED ] 1 test. SUMMARY: Ran 1 tests: 0 failed (0.117 sec) ok 8 //garnet/bin/run_test_component/test:run_test_component_test ------------------------------------------------ RUNNING TEST: /pkgfs/packages/auth_provider_tests/0/test/auth_provider_unittests Running main() from ../../third_party/googletest/googletest/src/gtest_main.cc [==========] Running 20 tests from 4 test suites. [----------] Global test environment set-up. [----------] 1 test from GoogleFactoryImplTest [ RUN ] GoogleFactoryImplTest.GetAuthProvider TEST_LOOP_RANDOM_SEED="2865781479" [ OK ] GoogleFactoryImplTest.GetAuthProvider (1 ms) [----------] 1 test from GoogleFactoryImplTest (1 ms total) [----------] 12 tests from GoogleAuthProviderImplTest [ RUN ] GoogleAuthProviderImplTest.EmptyWhenClientDisconnected TEST_LOOP_RANDOM_SEED="1767167644" [ OK ] GoogleAuthProviderImplTest.EmptyWhenClientDisconnected (0 ms) [ RUN ] GoogleAuthProviderImplTest.GetAppAccessTokenSuccess TEST_LOOP_RANDOM_SEED="1325519824" [ OK ] GoogleAuthProviderImplTest.GetAppAccessTokenSuccess (12 ms) [ RUN ] GoogleAuthProviderImplTest.GetAppAccessTokenBadRequestError TEST_LOOP_RANDOM_SEED="778949691" [ OK ] GoogleAuthProviderImplTest.GetAppAccessTokenBadRequestError (1 ms) [ RUN ] GoogleAuthProviderImplTest.GetAppAccessTokenInvalidClientError TEST_LOOP_RANDOM_SEED="1392895128" [ OK ] GoogleAuthProviderImplTest.GetAppAccessTokenInvalidClientError (12 ms) [ RUN ] GoogleAuthProviderImplTest.GetAppAccessTokenInvalidUserError TEST_LOOP_RANDOM_SEED="4280318679" [ OK ] GoogleAuthProviderImplTest.GetAppAccessTokenInvalidUserError (12 ms) [ RUN ] GoogleAuthProviderImplTest.GetAppIdTokenSuccess TEST_LOOP_RANDOM_SEED="2253712738" [ OK ] GoogleAuthProviderImplTest.GetAppIdTokenSuccess (12 ms) [ RUN ] GoogleAuthProviderImplTest.GetAppIdTokenBadRequestError TEST_LOOP_RANDOM_SEED="3121901300" [ OK ] GoogleAuthProviderImplTest.GetAppIdTokenBadRequestError (0 ms) [ RUN ] GoogleAuthProviderImplTest.GetAppIdTokenInvalidAudienceError TEST_LOOP_RANDOM_SEED="3608178731" [ OK ] GoogleAuthProviderImplTest.GetAppIdTokenInvalidAudienceError (13 ms) [ RUN ] GoogleAuthProviderImplTest.GetAppIdTokenInvalidUserError TEST_LOOP_RANDOM_SEED="2530862056" [ OK ] GoogleAuthProviderImplTest.GetAppIdTokenInvalidUserError (12 ms) [ RUN ] GoogleAuthProviderImplTest.GetAppFirebaseTokenSuccess TEST_LOOP_RANDOM_SEED="2930579269" [ OK ] GoogleAuthProviderImplTest.GetAppFirebaseTokenSuccess (12 ms) [ RUN ] GoogleAuthProviderImplTest.GetAppFirebaseTokenBadRequestError TEST_LOOP_RANDOM_SEED="2750266903" [ OK ] GoogleAuthProviderImplTest.GetAppFirebaseTokenBadRequestError (0 ms) [ RUN ] GoogleAuthProviderImplTest.RevokeAppOrPersistentCredentialUnsupported TEST_LOOP_RANDOM_SEED="3148978028" [ OK ] GoogleAuthProviderImplTest.RevokeAppOrPersistentCredentialUnsupported (0 ms) [----------] 12 tests from GoogleAuthProviderImplTest (87 ms total) [----------] 6 tests from OAuthRequestBuilderTest [ RUN ] OAuthRequestBuilderTest.JsonEncodedPostRequest [ OK ] OAuthRequestBuilderTest.JsonEncodedPostRequest (0 ms) [ RUN ] OAuthRequestBuilderTest.UrlEncodedPostRequest [ OK ] OAuthRequestBuilderTest.UrlEncodedPostRequest (0 ms) [ RUN ] OAuthRequestBuilderTest.EmptyBodyPostRequest [ OK ] OAuthRequestBuilderTest.EmptyBodyPostRequest (0 ms) PASSED: /pkgfs/packages/auth_provider_tests/0/test/auth_provider_unittests passed [ RUN ] OAuthRequestBuilderTest.CheckAuthHeader [ OK ] OAuthRequestBuilderTest.CheckAuthHeader (0 ms) [ RUN ] OAuthRequestBuilderTest.GetRequest [ OK ] OAuthRequestBuilderTest.GetRequest (0 ms) [ RUN ] OAuthRequestBuilderTest.GetRequestWithQueryParams [ OK ] OAuthRequestBuilderTest.GetRequestWithQueryParams (0 ms) [----------] 6 tests from OAuthRequestBuilderTest (0 ms total) [----------] 1 test from OAuthResponseTest [ RUN ] OAuthResponseTest.CheckParseOAuthResponse [ERROR:topaz/auth_providers/oauth/oauth_response.cc(22)] Encountered error: -2 ,with description: Bad request [ OK ] OAuthResponseTest.CheckParseOAuthResponse (59 ms) [----------] 1 test from OAuthResponseTest (59 ms total) [----------] Global test environment tear-down [==========] 20 tests from 4 test suites ran. (147 ms total) [ PASSED ] 20 tests. SUMMARY: Ran 1 tests: 0 failed (0.231 sec) ok 9 //topaz/auth_providers:auth_provider_unittests ------------------------------------------------ RUNNING TEST: /pkgfs/packages/fidl_bindings_test/0/test/fidl_bindings_test FAILURE: /pkgfs/packages/fidl_bindings_test/0/test/fidl_bindings_test exited with nonzero status: -1 fuchsia-pkg://fuchsia.com/fidl_bindings_test#meta/fidl_bindings_test.cmx: failed to create component (UNKNOWN) The following tests failed: /pkgfs/packages/fidl_bindings_test/0/test/fidl_bindings_test: returned nonzero: -1 SUMMARY: Ran 1 tests: 1 failed (0.292 sec) 2019/04/12 02:25:55 Process exited with status 1 not ok 10 //topaz/bin/fidl_bindings_test/test://out/x64/fidl_bindings_test [7778.848] 68097.68110> dso: id=1d94037bb3f4bc61 base=0x0000289d44bcd000 name=/pkg/bin/app [7778.848] 68097.68110> dso: id=f319321953e14c13 base=0x0000723bdd5f9000 name=libclang_rt.asan.so [7778.848] 68097.68110> dso: id=6c8fa90153373216 base=0x00007f609d756000 name=libfxl_logging.so [7778.848] 68097.68110> dso: id=0c1eaf2c9ed03e40 base=0x00006dd271409000 name=libz.so [7778.848] 68097.68110> dso: id=13a99b61cfcda4b1 base=0x00005b6f56341000 name=libcrypto.so [7778.848] 68097.68110> dso: id=5d18157a524cede1 base=0x00003ec23cce0000 name=libssl.so [7778.848] 68097.68110> dso: id=b53606ac2175d4c2 base=0x00003b6fd362a000 name=libdart_jit.so [7778.848] 68097.68110> dso: id=5bee7bc9b4b1523d base=0x00004424b8efc000 name=libfdio.so [7778.848] 68097.68110> dso: id=9f4aa5693d1dd2eb base=0x000034e2105ce000 name=libasync-default.so [7778.848] 68097.68110> dso: id=b9a4808796516ab4 base=0x000043495668e000 name=libsyslog.so [7778.848] 68097.68110> dso: id=21256fb0cfda0df9 base=0x000020c01acef000 name=libtrace-engine.so [7778.848] 68097.68110> dso: id=2243cedb3d53d07d9ab576656ff6a3ee4847fbc2 base=0x000063f36fdd2000 name=<vDSO> [7778.848] 68097.68110> dso: id=62d5b2b024cc78a4 base=0x000070d2ec0d3000 name=libc++abi.so.1 [7778.848] 68097.68110> dso: id=e8d87f91321b4b5f base=0x0000637739ca9000 name=libunwind.so.1 [7778.848] 68097.68110> dso: id=3f378ad1551e4834 base=0x00006d6dbd496000 name=libc.so [7778.848] 68097.68110> dso: id=ddd578b014e2a2e9 base=0x00005520d5a3b000 name=libmemfs.so [7778.848] 68097.68110> dso: id=7a0404d214e6a9b3 base=0x000040d924b8a000 name=libc++.so.2 [7778.848] 68097.68110> ================================================================= [7778.848] 68097.68110> ERROR: AddressSanitizer: odr-violation (0x289d4501c040): [7778.849] 68097.68110> [1] size=1 'dart::Expect::failed_' ../../third_party/dart/runtime/platform/assert.cc:13:14 [7778.849] 68097.68110> [2] size=1 'dart::Expect::failed_' ../../third_party/dart/runtime/platform/assert.cc:13:14 [7778.849] 68097.68110> These globals were registered at these points: [7778.849] 68097.68110> [1]: [7778.850] 68097.68110> #0 0x0000723bdd643675 in __asan_register_globals /b/s/w/ir/k/llvm-project/compiler-rt/lib/asan/asan_globals.cc:365 <libclang_rt.asan.so>+0x4a675 [7778.850] 68097.68110> #1 0x0000289d44fb4fea in asan.module_ctor <<application>>+0x3e7fea [7778.850] 68097.68110> #2 0x00006d6dbd518998 in __libc_start_init /b/s/w/ir/k/out/build-zircon/../../zircon/third_party/ulib/musl/ldso/dynlink.c:1601 <libc.so>+0x82998 [7778.850] 68097.68110> #3 0x00006d6dbd52c2c7 in start_main /b/s/w/ir/k/out/build-zircon/../../zircon/third_party/ulib/musl/src/env/__libc_start_main.c:71 <libc.so>+0x962c7 [7778.851] 68097.68110> [2]: [7778.851] 68097.68110> #0 0x0000723bdd643675 in __asan_register_globals /b/s/w/ir/k/llvm-project/compiler-rt/lib/asan/asan_globals.cc:365 <libclang_rt.asan.so>+0x4a675 [7778.851] 68097.68110> #1 0x00003b6fd471e81a in asan.module_ctor <libdart_jit.so>+0x10f481a [7778.851] 68097.68110> #2 0x00006d6dbd518998 in __libc_start_init /b/s/w/ir/k/out/build-zircon/../../zircon/third_party/ulib/musl/ldso/dynlink.c:1601 <libc.so>+0x82998 [7778.851] 68097.68110> #3 0x00006d6dbd52c2c7 in start_main /b/s/w/ir/k/out/build-zircon/../../zircon/third_party/ulib/musl/src/env/__libc_start_main.c:71 <libc.so>+0x962c7 [7778.851] 68097.68110> HINT: if you don't care about these errors you may set ASAN_OPTIONS=detect_odr_violation=0 [7778.851] 68097.68110> SUMMARY: AddressSanitizer: odr-violation: global 'dart::Expect::failed_' at ../../third_party/dart/runtime/platform/assert.cc:13 [7778.851] 68097.68110> :14 [7778.851] 68097.68110> ABORTING ------------------------------------------------ RUNNING TEST: /pkgfs/packages/goodbye_dart_test/0/test/goodbye_dart_test FAILURE: /pkgfs/packages/goodbye_dart_test/0/test/goodbye_dart_test exited with nonzero status: 1 fuchsia-pkg://fuchsia.con/goodbye_dart_aot#meta/goodbye_dart_aot.cmx: failed to create component (UNKNOWN) goodbye_dart_aot --now failed The following tests failed: /pkgfs/packages/goodbye_dart_test/0/test/goodbye_dart_test: returned nonzero: 1 SUMMARY: Ran 1 tests: 1 failed (0.204 sec) 2019/04/12 02:25:55 Process exited with status 1 not ok 11 //topaz/runtime/dart_runner/examples/goodbye_dart:goodbye_dart_test [7779.522] 73716.73729> dso: id=ea5884af0401c929 base=0x000027e68a540000 name=/pkg/bin/app [7779.522] 73716.73729> dso: id=f319321953e14c13 base=0x000020fa9ff4b000 name=libclang_rt.asan.so [7779.522] 73716.73729> dso: id=6c8fa90153373216 base=0x00004523f608c000 name=libfxl_logging.so [7779.522] 73716.73729> dso: id=0c1eaf2c9ed03e40 base=0x00003cfa5fc25000 name=libz.so [7779.522] 73716.73729> dso: id=13a99b61cfcda4b1 base=0x0000733906c7b000 name=libcrypto.so [7779.522] 73716.73729> dso: id=5d18157a524cede1 base=0x00007b9a135b7000 name=libssl.so [7779.522] 73716.73729> dso: id=a71cd44705470430 base=0x0000624c22d9f000 name=libdart_precompiled_runtime.so [7779.522] 73716.73729> dso: id=5bee7bc9b4b1523d base=0x00004a09edb43000 name=libfdio.so [7779.522] 73716.73729> dso: id=9f4aa5693d1dd2eb base=0x000034579fcbe000 name=libasync-default.so [7779.522] 73716.73729> dso: id=b9a4808796516ab4 base=0x00002abcc2eb3000 name=libsyslog.so [7779.522] 73716.73729> dso: id=21256fb0cfda0df9 base=0x00004922d47c7000 name=libtrace-engine.so [7779.522] 73716.73729> dso: id=2243cedb3d53d07d9ab576656ff6a3ee4847fbc2 base=0x00007a0415d81000 name=<vDSO> [7779.522] 73716.73729> dso: id=62d5b2b024cc78a4 base=0x00002c34a9df2000 name=libc++abi.so.1 [7779.522] 73716.73729> dso: id=e8d87f91321b4b5f base=0x00005ef05b532000 name=libunwind.so.1 [7779.522] 73716.73729> dso: id=3f378ad1551e4834 base=0x0000411a40cfe000 name=libc.so [7779.522] 73716.73729> dso: id=ddd578b014e2a2e9 base=0x000069e07bf6f000 name=libmemfs.so [7779.522] 73716.73729> dso: id=7a0404d214e6a9b3 base=0x0000674e069b9000 name=libc++.so.2 [7779.522] 73716.73729> ================================================================= [7779.523] 73716.73729> ERROR: AddressSanitizer: odr-violation (0x27e68a99b080): [7779.523] 73716.73729> [1] size=1 'dart::Expect::failed_' ../../third_party/dart/runtime/platform/assert.cc:13:14 [7779.523] 73716.73729> [2] size=1 'dart::Expect::failed_' ../../third_party/dart/runtime/platform/assert.cc:13:14 [7779.523] 73716.73729> These globals were registered at these points: [7779.523] 73716.73729> [1]: [7779.524] 73716.73729> #0 0x000020fa9ff95675 in __asan_register_globals /b/s/w/ir/k/llvm-project/compiler-rt/lib/asan/asan_globals.cc:365 <libclang_rt.asan.so>+0x4a675 [7779.524] 73716.73729> #1 0x000027e68a93490a in asan.module_ctor <<application>>+0x3f490a [7779.524] 73716.73729> #2 0x0000411a40d80998 in __libc_start_init /b/s/w/ir/k/out/build-zircon/../../zircon/third_party/ulib/musl/ldso/dynlink.c:1601 <libc.so>+0x82998 [7779.524] 73716.73729> #3 0x0000411a40d942c7 in start_main /b/s/w/ir/k/out/build-zircon/../../zircon/third_party/ulib/musl/src/env/__libc_start_main.c:71 <libc.so>+0x962c7 [7779.524] 73716.73729> [2]: [7779.524] 73716.73729> #0 0x000020fa9ff95675 in __asan_register_globals /b/s/w/ir/k/llvm-project/compiler-rt/lib/asan/asan_globals.cc:365 <libclang_rt.asan.so>+0x4a675 [7779.524] 73716.73729> #1 0x0000624c236fe04a in asan.module_ctor <libdart_precompiled_runtime.so>+0x95f04a [7779.524] 73716.73729> #2 0x0000411a40d80998 in __libc_start_init /b/s/w/ir/k/out/build-zircon/../../zircon/third_party/ulib/musl/ldso/dynlink.c:1601 <libc.so>+0x82998 [7779.524] 73716.73729> #3 0x0000411a40d942c7 in start_main /b/s/w/ir/k/out/build-zircon/../../zircon/third_party/ulib/musl/src/env/__libc_start_main.c:71 <libc.so>+0x962c7 [7779.524] 73716.73729> HINT: if you don't care about these errors you may set ASAN_OPTIONS=detect_odr_violation=0 [7779.525] 73716.73729> SUMMARY: AddressSanitizer: odr-violation: global 'dart::Expect::failed_' at ../../third_party/dart/runtime/platform/assert.cc:13 [7779.525] 73716.73729> :14 [7779.525] 73716.73729> ABORTING ------------------------------------------------ RUNNING TEST: /pkgfs/packages/dart_inspect_vmo_test/0/test/dart_inspect_vmo_test [7780.362] 79925.79938> dso: id=1d94037bb3f4bc61 base=0x00007ae535876000 name=/pkg/bin/app [7780.362] 79925.79938> dso: id=f319321953e14c13 base=0x000050834d9c6000 name=libclang_rt.asan.so [7780.362] 79925.79938> dso: id=6c8fa90153373216 base=0x0000582275e06000 name=libfxl_logging.so [7780.362] 79925.79938> dso: id=0c1eaf2c9ed03e40 base=0x00003e040af3f000 name=libz.so [7780.362] 79925.79938> dso: id=13a99b61cfcda4b1 base=0x000026422a94e000 name=libcrypto.so [7780.366] 79925.79938> dso: id=5d18157a524cede1 base=0x000026046fdea000 name=libssl.so [7780.366] 79925.79938> dso: id=b53606ac2175d4c2 base=0x00003a6990b47000 name=libdart_jit.so [7780.366] 79925.79938> dso: id=5bee7bc9b4b1523d base=0x00005d55aa30d000 name=libfdio.so [7780.366] 79925.79938> dso: id=9f4aa5693d1dd2eb base=0x00003eaf43d5e000 name=libasync-default.so [7780.369] 79925.79938> dso: id=b9a4808796516ab4 base=0x000035457e556000 name=libsyslog.so [7780.369] 79925.79938> dso: id=21256fb0cfda0df9 base=0x000056539270e000 name=libtrace-engine.so [7780.369] 79925.79938> dso: id=2243cedb3d53d07d9ab576656ff6a3ee4847fbc2 base=0x000078f80650a000 name=<vDSO> [7780.369] 79925.79938> dso: id=62d5b2b024cc78a4 base=0x000076edb21f8000 name=libc++abi.so.1 [7780.369] 79925.79938> dso: id=e8d87f91321b4b5f base=0x0000781edd25c000 name=libunwind.so.1 [7780.369] 79925.79938> dso: id=3f378ad1551e4834 base=0x00004267b7d6d000 name=libc.so [7780.369] 79925.79938> dso: id=ddd578b014e2a2e9 base=0x000079d222b01000 name=libmemfs.so [7780.369] 79925.79938> dso: id=7a0404d214e6a9b3 base=0x000020bf1f67e000 name=libc++.so.2 [7780.369] 79925.79938> ================================================================= [7780.369] 79925.79938> ERROR: AddressSanitizer: odr-violation (0x7ae535cc5040): [7780.369] 79925.79938> [1] size=1 'dart::Expect::failed_' ../../third_party/dart/runtime/platform/assert.cc:13:14 [7780.369] 79925.79938> [2] size=1 'dart::Expect::failed_' ../../third_party/dart/runtime/platform/assert.cc:13:14 [7780.369] 79925.79938> These globals were registered at these points: [7780.369] 79925.79938> [1]: [7780.370] 79925.79938> #0 0x000050834da10675 in __asan_register_globals /b/s/w/ir/k/llvm-project/compiler-rt/lib/asan/asan_globals.cc:365 <libclang_rt.asan.so>+0x4a675 [7780.370] 79925.79938> #1 0x00007ae535c5dfea in asan.module_ctor <<application>>+0x3e7fea [7780.370] 79925.79938> #2 0x00004267b7def998 in __libc_start_init /b/s/w/ir/k/out/build-zircon/../../zircon/third_party/ulib/musl/ldso/dynlink.c:1601 <libc.so>+0x82998 [7780.370] 79925.79938> #3 0x00004267b7e032c7 in start_main /b/s/w/ir/k/out/build-zircon/../../zircon/third_party/ulib/musl/src/env/__libc_start_main.c:71 <libc.so>+0x962c7 [7780.371] 79925.79938> [2]: [7780.371] 79925.79938> #0 0x000050834da10675 in __asan_register_globals /b/s/w/ir/k/llvm-project/compiler-rt/lib/asan/asan_globals.cc:365 <libclang_rt.asan.so>+0x4a675 [7780.371] 79925.79938> #1 0x00003a6991c3b81a in asan.module_ctor <libdart_jit.so>+0x10f481a [7780.371] 79925.79938> #2 0x00004267b7def998 in __libc_start_init /b/s/w/ir/k/out/build-zircon/../../zircon/third_party/ulib/musl/ldso/dynlink.c:1601 <libc.so>+0x82998 [7780.371] 79925.79938> #3 0x00004267b7e032c7 in start_main /b/s/w/ir/k/out/build-zircon/../../zircon/third_party/ulib/musl/src/env/__libc_start_main.c:71 <libc.so>+0x962c7 [7780.371] 79925.79938> HINT: if you don't care about these errors you may set ASAN_OPTIONS=detect_odr_violation=0 [7780.371] 79925.79938> SUMMARY: AddressSanitizer: odr-violation: global 'dart::Expect::failed_' at ../../third_party/dart/runtime/platform/assert.cc:13 [7780.371] 79925.79938> :14 [7780.371] 79925.79938> ABORTING DEBUG: stopping or rebooting the node "atom-chaos-cult-penny"