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

2018/12/13 18:03:46 properties flag: /etc/botanist/config.json 2018/12/13 18:03:46 starting log listener attempting to send <<image>>sparse.fvm=fvm.sparse.blk... [0.000] 00000.00000> zbi: @ 0xffffff8000d92000 (11879096 bytes) [0.000] 00000.00000> PMM: boot reserve add [0x100000, 0x2bcfff] [0.000] 00000.00000> PMM: boot reserve add [0xd92000, 0x18e6fff] [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 0x7ffff000 size 0x1000 [0.000] 00000.00000> PMM: boot reserve marking WIRED [0x100000, 0x2bcfff] [0.000] 00000.00000> PMM: boot reserve marking WIRED [0xd92000, 0x18e6fff] [0.000] 00000.00000> [0.000] 00000.00000> welcome to Zircon [0.000] 00000.00000> [0.000] 00000.00000> KASLR: .text section at 0xffffffff00100000 [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001ac398 (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 [0x18e7000, 0x18e7100) [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00147b90 (elf_build_id) at level 0x4fffe, flags 0x1 [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00147d1c (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-71785cd38a478fd12119897e6c8514fd7fdb1bf3-dirty [0.000] 00000.00000> ELF build ID: 1f5e3e417ba1f67767aa444fd66f7c0ae3e572fc [0.000] 00000.00000> initializing heap [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00103420 (x86_resource_init) at level 0x50000, flags 0x1 [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001ab000 (console) at level 0x50000, flags 0x1 [0.000] 00000.00000> initializing vm [0.000] 00000.00000> VM: reserving kernel region [0xffffffff00100000, 0xffffffff001eb000) flags 0x28 name 'kernel_code' [0.000] 00000.00000> VM: reserving kernel region [0xffffffff001eb000, 0xffffffff0022a000) flags 0x8 name 'kernel_rodata' [0.000] 00000.00000> VM: reserving kernel region [0xffffffff0022a000, 0xffffffff0023a000) flags 0x18 name 'kernel_data' [0.000] 00000.00000> VM: reserving kernel region [0xffffffff0023a000, 0xffffffff002bd000) flags 0x18 name 'kernel_bss' [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00101168 (acpi_tables) at level 0x60001, flags 0x1 [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001041c0 (display_memtype) at level 0x60001, flags 0x1 [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff0010212c (hpet) at level 0x60002, flags 0x1 [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001024b4 (apic) at level 0x60002, flags 0x1 [0.000] 00000.00000> x2APIC enabled [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00106ec8 (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.249] 00000.00000> timer features: constant_tsc 1 invariant_tsc 1 tsc_deadline 1 [20.249] 00000.00000> Using TSC as wallclock [20.249] 00000.00000> initializing kernel [20.249] 00000.00000> initializing mp [20.249] 00000.00000> initializing timers [20.249] 00000.00000> INIT: cpu 0, calling hook 0xffffffff0013b230 (debuglog) at level 0x7ffff, flags 0x1 [20.249] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00197624 (thread_set_priority_experiment) at level 0x7ffff, flags 0x1 [20.249] 00000.00000> thread set priority experiment is : ENABLED [20.249] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001ac5f8 (global_prng_thread_safe) at level 0x7ffff, flags 0x1 [20.249] 00000.00000> creating bootstrap completion thread [20.249] 00000.00000> top of bootstrap2() [20.249] 00000.00000> INIT: cpu 0, calling hook 0xffffffff0012fe24 (dpc) at level 0x80000, flags 0x1 [20.249] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001552b4 (libobject) at level 0x80000, flags 0x1 [20.249] 00000.00000> OOM: started thread [20.249] 00000.00000> Processor Model Info: type 0 family 0x6 model 0xe stepping 0x9 [20.249] 00000.00000> display_family 0x6 display_model 0x8e [20.249] 00000.00000> Vendor: Intel [20.249] 00000.00000> Microarch: Kaby Lake [20.249] 00000.00000> F/M/S: 6/8e/9 [20.249] 00000.00000> Brand: Intel(R) Core(TM) i5-7300U CPU @ 2.60GHz [20.249] 00000.00000> Features: fpu sse sse2 sse3 ssse3 sse4.1 sse4.2 mmx avx avx2 fxsr pcid xsave mon aesni clflush [20.249] 00000.00000> clflushopt fsgsbase tsc_adj smep smap erms rdrand rdseed syscall nx huge rdtscp [20.249] 00000.00000> invar_tsc tsc_deadline x2apic vmx pt hwp [20.249] 00000.00000> initializing platform [20.249] 00000.00000> cpu topology: [20.249] 00000.00000> 0: apic id 0x0 package 0 node 0 core 0 smt 0 BSP [20.249] 00000.00000> 1: apic id 0x2 package 0 node 0 core 1 smt 0 [20.249] 00000.00000> 2: apic id 0x1 package 0 node 0 core 0 smt 1 [20.249] 00000.00000> 3: apic id 0x3 package 0 node 0 core 1 smt 1 [20.249] 00000.00000> Found 4 cpus [20.249] 00000.00000> booting apic ids: 0x2 0x1 0x3 [20.263] 00000.00000> entering scheduler on cpu 2 [20.265] 00000.00000> entering scheduler on cpu 1 [20.265] 00000.00000> entering scheduler on cpu 3 [20.267] 00000.00000> smbios: manufacturer="Intel Corporation" product="NUC7i5DNKE" [20.267] 00000.00000> initializing target [20.267] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00105dfc (x86_pcie_init) at level 0xa0000, flags 0x1 [20.267] 00000.00000> moving to last init level [20.267] 00000.00000> INIT: cpu 0, calling hook 0xffffffff0014323c (ktrace) at level 0xc0000, flags 0x1 [20.271] 00000.00000> ktrace: buffer at 0xffffff91b6e74000 (33554432 bytes) [20.271] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00147b88 (userboot) at level 0xc0000, flags 0x1 [20.271] 00000.00000> userboot: ramdisk 0xb55000 @ 0xffffff8000d92000 [20.271] 00000.00000> userboot: userboot rodata 0 @ [0x4560e93cc000,0x4560e93cf000) [20.271] 00000.00000> userboot: userboot code 0x3000 @ [0x4560e93cf000,0x4560e93da000) [20.271] 00000.00000> userboot: vdso/full rodata 0 @ [0x4560e93da000,0x4560e93e0000) [20.271] 00000.00000> userboot: vdso/full code 0x6000 @ [0x4560e93e0000,0x4560e93e1000) [20.271] 00000.00000> userboot: entry point @ 0x4560e93cfbe0 [20.271] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001abab0 (kernel_shell) at level 0xc0000, flags 0x1 [20.271] 01034.01041> userboot: option "netsvc.disable=true" [20.271] 01034.01041> userboot: option "zircon.system.disable-automount=true" [20.271] 01034.01041> userboot: option "zircon.autorun.boot=/boot/infra/setup" [20.271] 01034.01041> userboot: option "http://192.168.42.1/multiboot/d4%3A5d%3Adf%3A07%3A5e%3A24=" [20.271] 01034.01041> userboot: option "multiboot.boot_loader_name=iPXE+1.0.0++(d2063)" [20.298] 01034.01041> userboot: searching bootfs for 'bin/devmgr' [20.298] 01034.01041> userboot: 'bin/devmgr' has PT_INTERP "lib/ld.so.1" [20.298] 01034.01041> userboot: searching bootfs for 'lib/ld.so.1' [20.298] 01034.01041> userboot: userboot: loaded lib/ld.so.1 at 0x75c31a15f000, entry point 0x75c31a1e55e3 [20.298] 01034.01041> userboot: userboot: loaded vDSO at 0x5c34a6402000, entry point 0x0 [20.298] 01034.01041> userboot: process bin/devmgr started. [20.298] 01034.01041> userboot: waiting for loader-service requests... [20.298] 01034.01041> userboot: searching bootfs for 'lib/libasync-default.so' [20.298] 01034.01041> userboot: searching bootfs for 'lib/liblaunchpad.so' [20.298] 01034.01041> userboot: searching bootfs for 'lib/libfdio.so' [20.298] 01048.01051> dso: id=adb289d09c14e41d57cc06e64c3fbad20af116de base=0x00003ce8cf795000 name=<application> [20.298] 01048.01051> dso: id=e185a560eff06a87149e82ac163f0cab85d35cd2 base=0x000018c19057b000 name=libasync-default.so [20.298] 01048.01051> dso: id=8b03b75574d08d314aa5efad9a560559dba03719 base=0x00004a8659410000 name=liblaunchpad.so [20.298] 01048.01051> dso: id=67093cd5a6d51815a7899f50f338c85506c5afeb base=0x00002ceec4dd8000 name=libfdio.so [20.298] 01048.01051> dso: id=669f338dda92c28c851da7148fb092cfbae24e8b base=0x00005c34a6402000 name=<vDSO> [20.298] 01048.01051> dso: id=f994d740a587fd43637c52aa7659f5ec4e448248 base=0x000075c31a15f000 name=libc.so [20.298] 01034.01041> userboot: loader-service channel peer closed [20.299] 01034.01041> userboot: finished! [20.299] 01048.01051> devmgr: main() [20.299] 01048.01051> devmgr: init [20.299] 01048.01051> coordinator_init() [20.299] 01048.01051> bootfs_open: 'config/devmgr' not found [20.299] 01048.01051> cmdline: netsvc.disable=true [20.299] 01048.01051> cmdline: zircon.system.disable-automount=true [20.299] 01048.01051> cmdline: zircon.autorun.boot=/boot/infra/setup [20.299] 01048.01051> cmdline: http://192.168.42.1/multiboot/d4%3A5d%3Adf%3A07%3A5e%3A24= [20.299] 01048.01051> cmdline: multiboot.boot_loader_name=iPXE+1.0.0++(d2063) [20.299] 01048.01051> devmgr: svc init [20.299] 01048.01051> devmgr: launch /boot/bin/svchost (svchost) OK [20.299] 01048.01051> devmgr: vfs init [20.299] 01048.01051> devmgr: launch /boot/bin/fshost (fshost) OK [20.299] 01048.01168> devmgr: shell startup [20.299] 01048.01051> devmgr: coordinator() [20.300] 01130.01144> fshost: started. [20.301] 01048.01159> devmgr: launch /boot/bin/pwrbtn-monitor (pwrbtn-monitor) OK [20.301] 01048.01180> devmgr: launch /boot/bin/crashsvc (crashsvc) OK [20.302] 01048.01180> devmgr: launch /boot/bin/virtual-console (virtual-console) OK [20.302] 01048.01180> zircon.autorun.boot: starting '/boot/infra/setup'... [20.303] 01048.01180> devmgr: launch /boot/infra/setup (autorun:boot) OK [20.305] 01048.01051> devcoord: launch devhost 'devhost:sys': pid=1951 [20.306] 01048.01051> devcoord: launch devhost 'devhost:test': pid=1984 [20.306] 01048.01051> devcoord: launch devhost 'devhost:root': pid=2017 [20.306] 01048.01051> devcoord: launch devhost 'devhost:misc': pid=2073 [20.373] 01951.01975> acpi-bus: not using IOMMU [20.383] 01951.01975> WARNING: ACPI found bad _CRS address entry [20.389] 01951.01975> WARNING: ACPI found bad _CRS address entry [20.400] 01951.01975> acpi-pwrbtn: initialized [20.400] 01951.01975> acpi: published device pci(0x3156ad3fb000), parent=proxy(0x3156ad49e480), handle=0x3156ad41c780 [20.401] 01951.01975> acpi: published device rtc(0x3156ad3e8000), parent=acpi(0x3156ad49e600), handle=0x3156ad4351c0 [20.402] 01951.01975> acpi-ec: initialized [20.403] 01951.01975> acpi: failed to create NHLT VMO (res -10) [20.403] 01951.01975> acpi: failed to publish NHLT metadata [20.406] 01951.01975> Intel Processor Trace configuration for this chipset: [20.406] 01951.01975> mtc_freq_mask: 0x249 [20.406] 01951.01975> cyc_thresh_mask: 0x3fff [20.406] 01951.01975> psb_freq_mask: 0x3f [20.406] 01951.01975> num addr ranges: 2 [20.406] 01951.01975> mtrace_ipm_control:34: action 0, options 0x0, arg 0x25ac995e3ac0, size 0x20 [20.406] 01048.01051> devcoord: launch devhost 'devhost:pci#1:8086:5916': pid=2533 [20.406] 01048.01051> devcoord: launch devhost 'devhost:pci#3:8086:9d2f': pid=2580 [20.407] 01048.01051> devcoord: launch devhost 'devhost:pci#5:8086:9d60': pid=2642 [20.407] 01048.01051> devcoord: launch devhost 'devhost:pci#6:8086:9d61': pid=2705 [20.408] 01048.01051> devcoord: launch devhost 'devhost:pci#9:8086:9d03': pid=2797 [20.409] 01048.01168> devmgr: launch /boot/bin/sh (sh:console) OK [20.409] 01048.01051> devcoord: launch devhost 'devhost:pci#14:8086:9d71': pid=2927 [20.410] 01048.01051> devcoord: launch devhost 'devhost:pci#16:8086:156f': pid=3011 [20.411] 02642.02681> initialized intel serialio i2c driver, reg=0x4a1dc45b1000 regsize=4096 [20.413] 02705.02768> initialized intel serialio i2c driver, reg=0x7217dda63000 regsize=4096 [20.414] 02797.02849> ahci: using MSI interrupt [20.414] 03011.03050> eth: using MSI mode [20.414] 02580.03250> * found USB device (0x18d1:0xa002, USB 2.0) config 1 [20.414] 03011.03050> eth: mac: d4:5d:df:07:5e:24 [20.415] 03011.03050> eth: iomem @0x75d492619000 (phys e93000) [20.415] 03011.03050> eth: intel-ethernet online [20.415] 02580.03250> * found USB device (0x18d1:0xa003, USB 3.0) config 1 [20.424] 02927.02988> [IHDA DSP 00:1f.3] Failed to fetch NHLT (res -25) [20.424] 02927.02988> devhost[00:1f.3/intel-hda-000/intel-sst-dsp-000] bind driver '/boot/driver/dsp.so' failed: -25 [20.424] 01048.01051> devcoord: rpc: bind-driver 'intel-sst-dsp-000' status -25 [20.639] 02533.03567> i915: Found DP monitor [20.639] 02533.03567> i915: Display 1 connected [20.639] 02533.03567> i915: MST not supported [21.156] 02580.03250> * found USB device (0x0781:0x5583, USB 3.0) config 1 [21.157] 02580.02613> UMS:Max lun is: 0 [21.676] 01351.01419> vc: Successfully attached to display 1 [21.676] 01351.01419> vc: new input device /dev/class/input/000 [21.879] 02580.03250> * found USB device (0x8087:0x0a2b, USB 2.0) config 1 [21.880] 02580.02613> devhost[00:14.0/xhci/usb/002/ifc-001] bind driver '/boot/driver/bt-transport-usb.so' failed: -2 [21.880] 01048.01051> devcoord: rpc: bind-driver 'ifc-001' status -2 [22.158] 02580.03645> UMS: block size is: 0x00000200 [22.158] 02580.03645> UMS: total blocks is: 30031872 [22.158] 02580.03645> UMS: total size is: 15376318464 [22.158] 02580.03645> UMS: read-only: 0 removable: 1 [22.159] 01130.01144> devmgr: /dev/class/block/000: MBR? [22.159] 01048.01051> devcoord: dc_bind_device() '/boot/driver/mbr.so' [22.162] 01130.01144> devmgr: /dev/class/block/002: MBR? [22.162] 01048.01051> devcoord: dc_bind_device() '/boot/driver/mbr.so' [22.415] 02705.02768> i2c-hid: could not read HID descriptor: -21 [22.415] 02705.02768> devhost[00:15.1/i2c-bus-9d61/004c] bind driver '/boot/driver/i2c-hid.so' failed: -2 [22.415] 01048.01051> devcoord: rpc: bind-driver '004c' status -2 [22.415] 02642.02681> i2c-hid: could not read HID descriptor: -21 [22.415] 02642.02681> devhost[00:15.0/i2c-bus-9d60/0020] bind driver '/boot/driver/i2c-hid.so' failed: -2 [22.415] 01048.01051> devcoord: rpc: bind-driver '0020' status -2 [22.419] 02797.03411> sata2: dev info [22.419] 02797.03411> serial=715400411011 0 [22.419] 02797.03411> firmware rev=1P11A1E_ [22.419] 02797.03411> model id=IBIW NSS D [22.419] 02797.03411> major=0x3f0 ACS2 DMA 32 commands [22.419] 02797.03411> LBA48 250069680 sectors, sector size=512 [22.420] 01130.01144> devmgr: /dev/class/block/003: GPT? [22.420] 01048.01051> devcoord: dc_bind_device() '/boot/driver/gpt.so' [22.421] 01130.01144> devmgr: /dev/class/block/004: FVM? [22.421] 01048.01051> devcoord: dc_bind_device() '/boot/driver/fvm.so' [22.424] 01130.01144> devmgr: /dev/class/block/007: zxcrypt? [22.424] 01048.01051> devcoord: dc_bind_device() '/boot/driver/zxcrypt.so' [22.486] 04118.04141> 1024+0 records in [22.486] 04118.04141> 1024+0 records out [22.486] 04118.04141> 4194304 bytes copied [22.486] 05377.05391> netifc: ? /dev/class/ethernet/000 [22.487] 05377.05391> netifc: create 512 eth buffers [22.487] 05377.05391> macaddr: d4:5d:df:07:5e:24 [22.487] 05377.05391> ip6addr: fe80::d65d:df4d:fe07:5e24 [22.487] 05377.05391> snmaddr: ff02::1:ff07:5e24 [22.487] 05377.05391> netsvc: using /dev/class/ethernet/000 [22.487] 05377.05391> _ _ _ [22.487] 05377.05391> | | | | | [22.487] 05377.05391> _______ __| | |__ ___ ___ | |_ [22.487] 05377.05391> |_ / _ \/ _` | '_ \ / _ \ / _ \| __| [22.487] 05377.05391> / / __/ (_| | |_) | (_) | (_) | |_ [22.487] 05377.05391> /___\___|\__,_|_.__/ \___/ \___/ \__| [22.487] 05377.05391> [22.487] 05377.05391> [22.487] 05377.05391> zedboot: version: 0.7.10 [22.487] 05377.05391> [22.487] 05377.05391> netsvc: nodename='upper-swoop-clock-grope' [22.487] 05377.05391> netsvc: will not advertise [22.487] 05377.05391> netsvc: start [1697.728] 05377.05391> netsvc: Running FVM Paver [1697.730] 05377.05483> install-disk-image:[InitializeGpt] Located GPT is invalid; Attempting to initialize [1697.732] 01048.01051> devcoord: dc_bind_device() '' [1697.732] 01048.01051> devcoord: dc_bind_device() '/boot/driver/gpt.so' [1697.732] 01130.01144> devmgr: /dev/class/block/003: GPT? [1697.732] 01048.01051> devcoord: dc_bind_device() '/boot/driver/gpt.so' [1697.732] 05377.05483> install-disk-image:[Initialize] Successfully intitialized EFI Device Partitioner [1697.732] 05377.05483> install-disk-image:[FvmPave] Paving FVM [1697.732] 05377.05483> install-disk-image:[FvmPave] Could not find FVM; attempting to add it: ZX_ERR_NOT_FOUND [1697.732] 05377.05483> install-disk-image:[FindFirstFit] Looking for space [1697.732] 05377.05483> install-disk-image:[FindFirstFit] Sorting [1697.732] 05377.05483> install-disk-image:[FindFirstFit] Partition[0] From Block [0, 34) ... (next partition starts at block 250069646) [1697.732] 05377.05483> install-disk-image:[FindFirstFit] There are 250069612 free blocks (16777216 requested) [1697.732] 05377.05483> install-disk-image:[AddPartition] Found space in GPT - OK 250069612 @ 34 [1697.732] 05377.05483> install-disk-image:[AddPartition] Final space in GPT - OK 16777216 @ 34 [1697.733] 01048.01051> devcoord: dc_bind_device() '' [1697.733] 05377.05483> install-disk-image:[AddPartition] Added partition, waiting for bind [1697.733] 01130.01144> devmgr: /dev/class/block/003: GPT? [1697.733] 01048.01051> devcoord: dc_bind_device() '/boot/driver/gpt.so' [1697.734] 05377.05483> install-disk-image:[AddPartition] Added partition, waiting for bind - OK [1697.734] 05377.05483> install-disk-image:[FvmPave] Streaming partitions... [1697.734] 05463.05477> Found compressed file [1697.734] 05377.05483> install-disk-image:[FvmStreamPartitions] Header Validated - OK [1697.734] 05377.05483> install-disk-image:[FvmPartitionFormat] Initializing partition as FVM [1697.736] 01048.01051> devcoord: dc_bind_device() '/boot/driver/fvm.so' [1699.738] 05377.05483> install-disk-image:[FvmStreamPartitions] Allocating partition blobfs consisting of 1 slices [1699.740] 05377.05483> install-disk-image:[FvmStreamPartitions] Extending partition[0] at offset 64 by length 1 [1699.740] 05377.05483> install-disk-image:[FvmStreamPartitions] Extending partition[0] at offset 128 by length 1 [1699.741] 05377.05483> install-disk-image:[FvmStreamPartitions] Extending partition[0] at offset 192 by length 1 [1699.741] 05377.05483> install-disk-image:[FvmStreamPartitions] Extending partition[0] at offset 256 by length 77 [1701.742] 05377.05483> install-disk-image:[FvmStreamPartitions] Allocating partition minfs consisting of 1 slices [1701.744] 05377.05483> install-disk-image:[FvmStreamPartitions] Creating zxcrypt volume [1701.745] 02797.02849> devhost_get_handles(0x1ed07d8919c0:block) open path='zxcrypt/block', r=-2 [1701.745] 01048.01051> devcoord: dc_bind_device() '/boot/driver/zxcrypt.so' [1701.745] 02797.02849> devhost_get_handles(0x1ed07d8919c0:block) open path='zxcrypt', r=-2 [1701.746] 05377.05483> install-disk-image:[FvmStreamPartitions] Extending partition[1] at offset 64 by length 1 [1701.746] 05377.05483> install-disk-image:[FvmStreamPartitions] Extending partition[1] at offset 128 by length 1 [1701.747] 05377.05483> install-disk-image:[FvmStreamPartitions] Extending partition[1] at offset 192 by length 1 [1701.748] 05377.05483> install-disk-image:[FvmStreamPartitions] Extending partition[1] at offset 256 by length 2 [1701.748] 05377.05483> install-disk-image:[FvmStreamPartitions] Extending partition[1] at offset 320 by length 1 [1701.749] 05377.05483> install-disk-image:[FvmStreamPartitions] Partition space pre-allocated [1701.749] 05377.05483> install-disk-image:[FvmStreamPartitions] Streaming partition 0 [1701.749] 05377.05483> install-disk-image:[StreamFvmPartition] Writing extent 0... [1701.749] 05377.05489> netsvc: paver write progress 0.0% [1701.749] 05377.05483> install-disk-image:[StreamFvmPartition] 8192 bytes written, 8380416 zeroes left [1701.800] 05377.05483> install-disk-image:[StreamFvmPartition] Writing extent 1... [1701.802] 05377.05483> install-disk-image:[StreamFvmPartition] 16384 bytes written, 8372224 zeroes left [1701.854] 05377.05483> install-disk-image:[StreamFvmPartition] Writing extent 2... [1701.868] 05377.05483> install-disk-image:[StreamFvmPartition] 2097152 bytes written, 6291456 zeroes left [1701.908] 05377.05483> install-disk-image:[StreamFvmPartition] Writing extent 3... [1701.922] 05377.05483> install-disk-image:[StreamFvmPartition] 2097152 bytes written, 6291456 zeroes left [1701.962] 05377.05483> install-disk-image:[StreamFvmPartition] Writing extent 4... [1702.753] 05377.05489> netsvc: paver write progress 19.3% done attempting to send <<image>>efi.img=fuchsia.esp.blk...target is busy, retrying in one second [1703.474] 05377.05391> netsvc: tftp write of file <<image>>sparse.fvm completed [1703.755] 05377.05489> netsvc: paver write progress 43.8% attempting to send <<image>>efi.img=fuchsia.esp.blk...target is busy, retrying in one second [1704.755] 05377.05489> netsvc: paver write progress 68.3% attempting to send <<image>>efi.img=fuchsia.esp.blk...target is busy, retrying in one second [1705.761] 05377.05489> netsvc: paver write progress 92.4% [1706.061] 05377.05483> install-disk-image:[StreamFvmPartition] 639877120 bytes written, 6045696 zeroes left [1706.100] 05377.05483> install-disk-image:[FvmStreamPartitions] Done streaming partition 0 [1706.100] 05377.05483> install-disk-image:[FvmStreamPartitions] Done flushing partition 0 [1706.101] 05377.05483> install-disk-image:[FvmStreamPartitions] Streaming partition 1 [1706.101] 05377.05483> install-disk-image:[StreamFvmPartition] Writing extent 0... [1706.101] 05377.05483> install-disk-image:[StreamFvmPartition] 8192 bytes written, 8380416 zeroes left [1706.154] 05377.05483> install-disk-image:[StreamFvmPartition] Writing extent 1... [1706.155] 05377.05483> install-disk-image:[StreamFvmPartition] 65536 bytes written, 8323072 zeroes left [1706.208] 05377.05483> install-disk-image:[StreamFvmPartition] Writing extent 2... [1706.209] 05377.05483> install-disk-image:[StreamFvmPartition] 65536 bytes written, 8323072 zeroes left [1706.262] 05377.05483> install-disk-image:[StreamFvmPartition] Writing extent 3... [1706.317] 05377.05483> install-disk-image:[StreamFvmPartition] Writing extent 4... [1706.330] 05377.05483> install-disk-image:[StreamFvmPartition] 2097152 bytes written, 14680064 zeroes left [1706.424] 05377.05483> install-disk-image:[StreamFvmPartition] Writing extent 5... [1706.425] 05377.05483> install-disk-image:[StreamFvmPartition] 57344 bytes written, 8331264 zeroes left attempting to send <<image>>efi.img=fuchsia.esp.blk...target is busy, retrying in one second [1706.478] 05377.05483> install-disk-image:[FvmStreamPartitions] Done streaming partition 1 [1706.478] 05377.05483> install-disk-image:[FvmStreamPartitions] Done flushing partition 1 [1706.480] 05463.05477> Reading FVM from compressed file: true [1706.480] 05463.05477> Remaining bytes read into compression buffer: 0 [1706.480] 05463.05477> Remaining bytes written to decompression buffer: 0 [1706.480] 05463.05477> Time reading bytes from sparse FVM file: 245815313 (0 s) [1706.480] 05463.05477> Time reading bytes AND decompressing them: 753443732 (0 s) [1706.480] 05377.05483> install-disk-image:[FvmPave] Completed successfully attempting to send <<image>>efi.img=fuchsia.esp.blk...done attempting to send <<netboot>>kernel.bin=fuchsia-Intel NUC Kit NUC7i5DNHE.zbi...target is busy, retrying in one second [1707.477] 05377.05391> netsvc: Running EFI Paver [1707.481] 05377.06641> install-disk-image:[Initialize] Successfully intitialized EFI Device Partitioner [1707.481] 05377.06641> install-disk-image:[PartitionPave] Paving partition. [1707.481] 05377.06641> install-disk-image:[FindFirstFit] Looking for space [1707.481] 05377.06641> install-disk-image:[FindFirstFit] Partition seen with start 34, end 16777249 (length 16777216) [1707.481] 05377.06641> install-disk-image:[FindFirstFit] Sorting [1707.481] 05377.06641> install-disk-image:[FindFirstFit] Partition[0] From Block [0, 34) ... (next partition starts at block 34) [1707.481] 05377.06641> install-disk-image:[FindFirstFit] There are 0 free blocks (2097152 requested) [1707.481] 05377.06641> install-disk-image:[FindFirstFit] Partition[1] From Block [34, 16777250) ... (next partition starts at block 250069646) [1707.481] 05377.06641> install-disk-image:[FindFirstFit] There are 233292396 free blocks (2097152 requested) [1707.481] 05377.06641> install-disk-image:[AddPartition] Found space in GPT - OK 233292396 @ 16777250 [1707.481] 05377.06641> install-disk-image:[AddPartition] Final space in GPT - OK 2097152 @ 16777250 [1707.482] 01048.01051> devcoord: dc_bind_device() '' [1707.482] 05377.06641> install-disk-image:[AddPartition] Added partition, waiting for bind [1707.482] 01130.01144> devmgr: /dev/class/block/003: GPT? [1707.482] 01048.01051> devcoord: dc_bind_device() '/boot/driver/gpt.so' [1707.483] 01130.01144> devmgr: /dev/class/block/013: FVM? [1707.483] 01048.01051> devcoord: dc_bind_device() '/boot/driver/fvm.so' [1707.483] 05377.06641> install-disk-image:[AddPartition] Added partition, waiting for bind - OK [1707.485] 01130.01144> devmgr: /dev/class/block/016: zxcrypt? [1707.485] 01048.01051> devcoord: dc_bind_device() '/boot/driver/zxcrypt.so' [1707.498] 05377.05391> netsvc: tftp write of file <<image>>efi.img completed [1707.501] 05377.06641> install-disk-image:[PartitionPave] Completed successfully attempting to send <<netboot>>kernel.bin=fuchsia-Intel NUC Kit NUC7i5DNHE.zbi...done 2018/12/13 18:03:57 sending cmdline "zircon.autorun.system=/boot/bin/sh+/boot/infra/runcmds " 2018/12/13 18:03:57 sending boot command 2018/12/13 18:03:57 waiting for "summary.json" [0.000] 00000.00000> zbi: @ 0xffffff8005c60000 (5530216 bytes) [0.000] 00000.00000> PMM: boot reserve add [0x100000, 0x2b5fff] [0.000] 00000.00000> PMM: boot reserve add [0x5c60000, 0x61a6fff] [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 0x7ffff000 size 0x1000 [0.000] 00000.00000> PMM: boot reserve marking WIRED [0x100000, 0x2b5fff] [0.000] 00000.00000> PMM: boot reserve marking WIRED [0x5c60000, 0x61a6fff] [0.000] 00000.00000> [0.000] 00000.00000> welcome to Zircon [0.000] 00000.00000> [0.000] 00000.00000> KASLR: .text section at 0xffffffff00100000 [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001a0e3c (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 [0x61a7000, 0x61a7100) [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00142b00 (elf_build_id) at level 0x4fffe, flags 0x1 [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00142c8c (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-8bb257375057869e2a2583b098942760f0477d23 [0.000] 00000.00000> ELF build ID: 73a9b010b50bebef36f31a99acc986e106e218ee [0.000] 00000.00000> initializing heap [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001033d8 (x86_resource_init) at level 0x50000, flags 0x1 [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff0019f2bc (console) at level 0x50000, flags 0x1 [0.000] 00000.00000> initializing vm [0.000] 00000.00000> VM: reserving kernel region [0xffffffff00100000, 0xffffffff001e0000) flags 0x28 name 'kernel_code' [0.000] 00000.00000> VM: reserving kernel region [0xffffffff001e0000, 0xffffffff00222000) flags 0x8 name 'kernel_rodata' [0.000] 00000.00000> VM: reserving kernel region [0xffffffff00222000, 0xffffffff00232000) flags 0x18 name 'kernel_data' [0.000] 00000.00000> VM: reserving kernel region [0xffffffff00232000, 0xffffffff002b6000) flags 0x18 name 'kernel_bss' [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00101168 (acpi_tables) at level 0x60001, flags 0x1 [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001040a8 (display_memtype) at level 0x60001, flags 0x1 [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00102130 (hpet) at level 0x60002, flags 0x1 [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001024b8 (apic) at level 0x60002, flags 0x1 [0.000] 00000.00000> x2APIC enabled [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001067d4 (timer) at level 0x60003, flags 0x1 [0.000] 00000.00000> HPET frequency: 23999 ticks/ms [0.000] 00000.00000> TSC frequency: 2712000 ticks/ms [1708.837] 00000.00000> timer features: constant_tsc 1 invariant_tsc 1 tsc_deadline 1 [1708.837] 00000.00000> Using TSC as wallclock [1708.837] 00000.00000> initializing kernel [1708.837] 00000.00000> initializing mp [1708.837] 00000.00000> initializing timers [1708.837] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00137774 (debuglog) at level 0x7ffff, flags 0x1 [1708.837] 00000.00000> INIT: cpu 0, calling hook 0xffffffff0018d284 (thread_set_priority_experiment) at level 0x7ffff, flags 0x1 [1708.837] 00000.00000> thread set priority experiment is : ENABLED [1708.837] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001a1090 (global_prng_thread_safe) at level 0x7ffff, flags 0x1 [1708.837] 00000.00000> creating bootstrap completion thread [1708.837] 00000.00000> top of bootstrap2() [1708.837] 00000.00000> INIT: cpu 0, calling hook 0xffffffff0012dc54 (dpc) at level 0x80000, flags 0x1 [1708.837] 00000.00000> INIT: cpu 0, calling hook 0xffffffff0014d980 (libobject) at level 0x80000, flags 0x1 [1708.837] 00000.00000> OOM: started thread [1708.837] 00000.00000> Processor Model Info: type 0 family 0x6 model 0xe stepping 0x9 [1708.837] 00000.00000> display_family 0x6 display_model 0x8e [1708.837] 00000.00000> Vendor: Intel [1708.837] 00000.00000> Microarch: Kaby Lake [1708.837] 00000.00000> F/M/S: 6/8e/9 [1708.837] 00000.00000> Brand: Intel(R) Core(TM) i5-7300U CPU @ 2.60GHz [1708.837] 00000.00000> Features: fpu sse sse2 sse3 ssse3 sse4.1 sse4.2 mmx avx avx2 fxsr pcid xsave mon aesni clflush [1708.837] 00000.00000> clflushopt fsgsbase tsc_adj smep smap erms rdrand rdseed syscall nx huge rdtscp [1708.837] 00000.00000> invar_tsc tsc_deadline x2apic vmx pt hwp [1708.837] 00000.00000> initializing platform [1708.837] 00000.00000> INIT: cpu 0, calling hook 0xffffffff0011084c (x86_perfmon) at level 0x90000, flags 0x1 [1708.837] 00000.00000> PMU: version 4 [1708.837] 00000.00000> cpu topology: [1708.837] 00000.00000> 0: apic id 0x0 package 0 node 0 core 0 smt 0 BSP [1708.837] 00000.00000> 1: apic id 0x2 package 0 node 0 core 1 smt 0 [1708.837] 00000.00000> 2: apic id 0x1 package 0 node 0 core 0 smt 1 [1708.837] 00000.00000> 3: apic id 0x3 package 0 node 0 core 1 smt 1 [1708.837] 00000.00000> Found 4 cpus [1708.837] 00000.00000> booting apic ids: 0x2 0x1 0x3 [1708.852] 00000.00000> entering scheduler on cpu 2 [1708.853] 00000.00000> entering scheduler on cpu 1 [1708.853] 00000.00000> entering scheduler on cpu 3 [1708.855] 00000.00000> smbios: manufacturer="Intel Corporation" product="NUC7i5DNKE" [1708.855] 00000.00000> initializing target [1708.855] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001059b8 (x86_pcie_init) at level 0xa0000, flags 0x1 [1708.855] 00000.00000> moving to last init level [1708.855] 00000.00000> INIT: cpu 0, calling hook 0xffffffff0013f338 (ktrace) at level 0xc0000, flags 0x1 [1708.859] 00000.00000> ktrace: buffer at 0xffffff9f5d242000 (33554432 bytes) [1708.859] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00142af8 (userboot) at level 0xc0000, flags 0x1 [1708.859] 00000.00000> userboot: ramdisk 0x547000 @ 0xffffff8005c60000 [1708.859] 00000.00000> userboot: userboot rodata 0 @ [0x2169da6f7000,0x2169da6fa000) [1708.859] 00000.00000> userboot: userboot code 0x3000 @ [0x2169da6fa000,0x2169da705000) [1708.859] 00000.00000> userboot: vdso/full rodata 0 @ [0x2169da705000,0x2169da70c000) [1708.859] 00000.00000> userboot: vdso/full code 0x7000 @ [0x2169da70c000,0x2169da70d000) [1708.859] 00000.00000> userboot: entry point @ 0x2169da6fac10 [1708.859] 00000.00000> INIT: cpu 0, calling hook 0xffffffff0019fcbc (kernel_shell) at level 0xc0000, flags 0x1 [1708.859] 01034.01041> userboot: option "zircon.autorun.system=/boot/bin/sh+/boot/infra/runcmds" [1708.871] 01034.01041> userboot: searching bootfs for 'bin/bootsvc' [1708.871] 01034.01041> userboot: 'bin/bootsvc' has PT_INTERP "lib/ld.so.1" [1708.871] 01034.01041> userboot: searching bootfs for 'lib/ld.so.1' [1708.871] 01034.01041> userboot: userboot: loaded lib/ld.so.1 at 0x6557a27b5000, entry point 0x6557a283b623 [1708.871] 01034.01041> userboot: userboot: loaded vDSO at 0x656ce3b72000, entry point 0x0 [1708.871] 01034.01041> userboot: process bin/bootsvc started. [1708.871] 01034.01041> userboot: waiting for loader-service requests... [1708.872] 01034.01041> userboot: searching bootfs for 'lib/libasync-default.so' [1708.872] 01034.01041> userboot: searching bootfs for 'lib/libfdio.so' [1708.872] 01034.01041> userboot: searching bootfs for 'lib/liblaunchpad.so' [1708.872] 01034.01041> userboot: searching bootfs for 'lib/libtrace-engine.so' [1708.872] 01048.01051> dso: id=f8e5b0ae4c0db3ae166d652f108da057e26573dc base=0x00007cc5776ad000 name=<application> [1708.872] 01048.01051> dso: id=5e3c55aea09e8943ae76d3603cc8c0b1ba27bfb7 base=0x000020f81fda5000 name=libasync-default.so [1708.872] 01048.01051> dso: id=1a34fba7caf24078ee471c50d1de84f810448877 base=0x0000656ce3b72000 name=<vDSO> [1708.872] 01048.01051> dso: id=a3c5a3bb3ab016d0bc21ad3b934d4347d27a4ac2 base=0x00006557a27b5000 name=libc.so [1708.872] 01048.01051> dso: id=25f32644dbc281d9de73dae21668edd2b273236a base=0x00001a5ca85c2000 name=libfdio.so [1708.872] 01048.01051> dso: id=3275d148fdc55d5dfbd06f5a7edb9dc4c294763f base=0x00006c1b6681b000 name=liblaunchpad.so [1708.872] 01048.01051> dso: id=071211ffaf06a901ea660bf9f3876a312b524143 base=0x00003ea86df7b000 name=libtrace-engine.so [1708.872] 01048.01051> bootsvc: Starting... [1708.872] 01034.01041> userboot: loader-service channel peer closed [1708.872] 01034.01041> userboot: finished! [1708.872] 01048.01051> bootsvc: Creating bootfs service... [1708.872] 01048.01051> bootsvc: Processing bootdata... [1708.872] 01048.01051> bootsvc: Loading boot cmdline overrides... [1708.872] 01048.01051> bootsvc: Loading kernel VMOs... [1708.872] 01048.01051> bootsvc: Creating loader service... [1708.872] 01048.01051> bootsvc: Launching next process... [1708.873] 01048.01095> bootsvc: launched bin/devmgr [1708.873] 01103.01116> devmgr: main() [1708.873] 01103.01116> devmgr: init [1708.873] 01103.01116> coordinator_init() [1708.873] 01103.01116> cmdline: zircon.autorun.system=/boot/bin/sh+/boot/infra/runcmds [1708.873] 01103.01116> cmdline: devhost.asan.strict=false [1708.873] 01103.01116> cmdline: devmgr.require-system=true [1708.873] 01103.01116> cmdline: zircon.system.pkgfs.cmd=bin/pkgsvr+d460923d57c201020da4d0fc21affc64a9026790e181ec5a1685339ab014f086 [1708.873] 01103.01116> cmdline: zircon.system.pkgfs.file.bin/pkgsvr=f97e96cc498d1cc37c62cd0bbc24065075cc1ef7220b3e00b55aa330becbf49f [1708.873] 01103.01116> cmdline: zircon.system.pkgfs.file.lib/ld.so.1=9f9954f7cb3c3490edc45e24b2181196753cf990f69387b5c564214a9d66f681 [1708.873] 01103.01116> cmdline: zircon.system.pkgfs.file.lib/libfdio.so=e180b0d8191e157034f2ce8ad1614706e8729ca104053974391908f2b32d77ef [1708.873] 01103.01116> devmgr: svc init [1708.873] 01103.01116> devmgr: launch /boot/bin/svchost (svchost) OK [1708.873] 01103.01116> devmgr: vfs init [1708.874] 01103.01116> devmgr: launch /boot/bin/fshost (fshost) OK [1708.874] 01103.01219> devmgr: shell startup [1708.874] 01103.01116> devmgr: coordinator() [1708.875] 01179.01199> fshost: started. [1708.876] 01103.01209> devmgr: launch /boot/bin/pwrbtn-monitor (pwrbtn-monitor) OK [1708.876] 01103.01232> devmgr: launch /boot/bin/crashsvc (crashsvc) OK [1708.877] 01103.01232> devmgr: launch /boot/bin/netsvc (netsvc) OK [1708.878] 01103.01232> devmgr: launch /boot/bin/virtual-console (virtual-console) OK [1708.879] 01103.01116> devcoord: launch devhost 'devhost:sys': pid=2010 [1708.879] 01103.01116> devcoord: launch devhost 'devhost:test': pid=2043 [1708.879] 01103.01116> devcoord: full system required, ignoring fallback drivers until /system is loaded [1708.880] 01103.01116> devcoord: launch devhost 'devhost:root': pid=2076 [1708.880] 01103.01116> devcoord: launch devhost 'devhost:misc': pid=2127 [1708.883] 01103.01219> devmgr: launch /boot/bin/sh (sh:console) OK [1708.938] 02010.02034> acpi-bus: not using IOMMU [1708.942] 02010.02034> acpi-pwrbtn: initialized [1708.948] 02010.02034> WARNING: ACPI found bad _CRS address entry [1708.954] 02010.02034> WARNING: ACPI found bad _CRS address entry [1708.964] 02010.02034> acpi: published device pci(0x3c701d2790c0), parent=proxy(0x3c701d284540), handle=0x3c701d2c2750 [1708.966] 02010.02034> acpi: published device rtc(0x3c701d279100), parent=acpi(0x3c701d2846c0), handle=0x3c701d2db190 [1708.967] 02010.02034> acpi-ec: initialized [1708.968] 02010.02034> acpi: failed to create NHLT VMO (res -10) [1708.968] 02010.02034> acpi: failed to publish NHLT metadata [1708.970] 02010.02034> Intel Processor Trace configuration for this chipset: [1708.970] 02010.02034> mtc_freq_mask: 0x249 [1708.970] 02010.02034> cyc_thresh_mask: 0x3fff [1708.970] 02010.02034> psb_freq_mask: 0x3f [1708.970] 02010.02034> num addr ranges: 2 [1708.970] 01643.01718> vc: new input device /dev/class/input/000 [1708.971] 01103.01116> devcoord: launch devhost 'devhost:pci#1:8086:5916': pid=2677 [1708.971] 01103.01116> devcoord: launch devhost 'devhost:pci#3:8086:9d2f': pid=2724 [1708.971] 01103.01116> devcoord: launch devhost 'devhost:pci#5:8086:9d60': pid=2785 [1708.972] 01103.01116> devcoord: launch devhost 'devhost:pci#6:8086:9d61': pid=2848 [1708.972] 01103.01116> devcoord: launch devhost 'devhost:pci#9:8086:9d03': pid=2933 [1708.973] 01103.01116> devcoord: launch devhost 'devhost:pci#14:8086:9d71': pid=3030 [1708.974] 01103.01116> devcoord: launch devhost 'devhost:pci#16:8086:156f': pid=3102 [1708.975] 02785.02818> initialized intel serialio i2c driver, reg=0x12058b312000 regsize=4096 [1708.975] 02848.02901> initialized intel serialio i2c driver, reg=0x64a54c1f000 regsize=4096 [1708.977] 02933.02978> ahci: using MSI interrupt [1708.981] 03030.03070> [IHDA DSP 00:1f.3] Failed to fetch NHLT (res -25) [1708.981] 03030.03070> devhost[00:1f.3/intel-hda-000/intel-sst-dsp-000] bind driver '/boot/driver/dsp.so' failed: -25 [1708.981] 01103.01116> devcoord: rpc: bind-driver 'intel-sst-dsp-000' status -25 [1708.988] 02724.03345> * found USB device (0x18d1:0xa002, USB 2.0) config 1 [1708.988] 02724.03345> * found USB device (0x18d1:0xa003, USB 3.0) config 1 [1709.053] 01537.01621> netifc: ? /dev/class/ethernet/000 [1709.053] 01537.01621> netifc: create 512 eth buffers [1709.053] 01537.01621> macaddr: d4:5d:df:07:5e:24 [1709.053] 01537.01621> ip6addr: fe80::d65d:df4d:fe07:5e24 [1709.053] 01537.01621> snmaddr: ff02::1:ff07:5e24 [1709.053] 01537.01621> netsvc: using /dev/class/ethernet/000 [1709.053] 01537.01621> netsvc: nodename='upper-swoop-clock-grope' [1709.053] 01537.01621> netsvc: start [1709.079] 01643.01718> vc: new display device /dev/class/display-controller/000/virtcon [1709.120] 02677.03697> i915: Found DP monitor [1709.120] 02677.03697> i915: Display 1 connected [1709.120] 02677.03697> i915: MST not supported [1709.700] 02724.03345> * found USB device (0x0781:0x5583, USB 3.0) config 1 [1710.186] 01643.01718> vc: Successfully attached to display 1 [1710.451] 02724.03345> * found USB device (0x8087:0x0a2b, USB 2.0) config 1 [1710.979] 02848.02901> intel-i2c-controller: intel_serialio_i2c_slave_transfer: -21 [1710.979] 02848.02901> i2c-hid: could not read HID descriptor: 0 [1710.979] 02848.02901> devhost[00:15.1/i2c-bus-9d61/004c] bind driver '/boot/driver/i2c-hid.so' failed: -2 [1710.979] 01103.01116> devcoord: rpc: bind-driver '004c' status -2 [1710.980] 02785.02818> intel-i2c-controller: intel_serialio_i2c_slave_transfer: -21 [1710.980] 02785.02818> i2c-hid: could not read HID descriptor: 0 [1710.980] 02785.02818> devhost[00:15.0/i2c-bus-9d60/0020] bind driver '/boot/driver/i2c-hid.so' failed: -2 [1710.980] 01103.01116> devcoord: rpc: bind-driver '0020' status -2 [1710.993] 02933.03415> sata2: dev info [1710.993] 02933.03415> serial=715400411011 0 [1710.993] 02933.03415> firmware rev=1P11A1E_ [1710.993] 02933.03415> model id=IBIW NSS D [1710.993] 02933.03415> major=0x3f0 ACS2 DMA 32 commands [1710.993] 02933.03415> LBA48 250069680 sectors, sector size=512 [1710.994] 01179.01199> devmgr: /dev/class/block/000: GPT? [1710.994] 01103.01116> devcoord: dc_bind_device() '/boot/driver/gpt.so' [1710.994] 02933.02978> gpt: device_get_metadata failed (-25) [1710.995] 01179.01199> devmgr: /dev/class/block/001: FVM? [1710.995] 01103.01116> devcoord: dc_bind_device() '/boot/driver/fvm.so' [1710.995] 01179.01199> devmgr: not automounting efi [1710.997] 01179.01199> devmgr: launch /boot/bin/blobfs (blobfs:/blob) OK [1711.053] 01179.01199> fshost: starting 'bin/pkgsvr' 'd460923d57c201020da4d0fc21affc64a9026790e181ec5a1685339ab014f086'... [1711.084] 01179.01199> devmgr: launch bin/pkgsvr (pkgfs) OK [1711.092] 04012.04228> pkgsvr: system: will be served from d460923d57c201020da4d0fc21affc64a9026790e181ec5a1685339ab014f086 [1711.092] 04012.04228> pkgsvr: pkgfs serving index /data/pkgfs_index from blobfs /blob [1711.093] 01179.01199> devmgr: /dev/class/block/004: zxcrypt? [1711.093] 01103.01116> devcoord: dc_bind_device() '/boot/driver/zxcrypt.so' [1711.095] 01179.01199> devmgr: mounting minfs [1711.096] 01179.01199> devmgr: launch /boot/bin/minfs (minfs:/data) OK [1711.128] 01103.04284> devmgr: adding system driver 'brcmfmac' '/system/driver/brcmfmac.so' [1711.128] 01103.01733> devmgr: launch /system/bin/appmgr (appmgr) OK [1711.128] 01103.01733> zircon.autorun.system: starting '/boot/bin/sh' '/boot/infra/runcmds'... [1711.129] 01103.01733> devmgr: launch /boot/bin/sh (autorun:system) OK [1711.140] 01103.04284> devmgr: adding system driver 'gpu' '/system/driver/libmsd_intel.so' [1711.155] 01103.04284> devmgr: adding system driver 'wlan' '/system/driver/wlanphy.so' [1711.160] 01103.04284> devmgr: adding system driver 'ath10k_pci' '/system/driver/ath10k.so' [1711.190] 01103.04284> devmgr: adding system driver 'bthost' '/system/driver/bthost.so' [1711.195] 01103.04284> devmgr: adding system driver 'bt_passthrough_hci' '/system/driver/bthci-passthrough.so' [1711.215] 01103.04284> devmgr: adding system driver 'wlan' '/system/driver/wlan.so' [1711.231] 01103.04284> devmgr: adding system driver 'usb_video' '/system/driver/usb_video.so' [1711.232] 05007.05027> [1711.232] 05007.05027> ------------------------------------------------ [1711.232] 05007.05027> RUNNING TEST: /pkgfs/packages/catapult_converter/0/test/catapult_converter_test [1711.232] 05007.05027> [1711.233] 01103.04284> devmgr: adding system driver 'bthog' '/system/driver/bthog.so' [1711.252] 01103.04284> devmgr: adding system driver 'ralink' '/system/driver/ralink.so' [1711.264] 01103.04284> devmgr: adding system driver 'wlantapctl' '/system/driver/wlantap.so' [1711.279] 01103.04284> devmgr: adding system driver 'bthci_fake' '/system/driver/bthci-fake.so' [1711.288] 01103.04284> devmgr: adding system driver 'qmi_usb' '/system/driver/qmi-transport.so' [1711.295] 01103.04284> devmgr: adding system driver 'rtl88xx' '/system/driver/rtl88xx.so' [1711.303] 01103.04284> devmgr: adding system driver 'wlan' '/system/driver/wlanif.so' [1711.304] 05007.05027> Running main() from gtest_main.cc [1711.305] 05007.05027> [==========] Running 8 tests from 2 test cases. [1711.305] 05007.05027> [----------] Global test environment set-up. [1711.305] 05007.05027> [----------] 1 test from TestTools [1711.305] 05007.05027> [ RUN ] TestTools.SplitLines [1711.305] 05007.05027> [ OK ] TestTools.SplitLines (0 ms) [1711.305] 05007.05027> [----------] 1 test from TestTools (0 ms total) [1711.305] 05007.05027> [1711.305] 05007.05027> [----------] 7 tests from CatapultConverter [1711.305] 05007.05027> [ RUN ] CatapultConverter.Convert [1711.305] 05007.05027> [ OK ] CatapultConverter.Convert (1 ms) [1711.305] 05007.05027> [ RUN ] CatapultConverter.ConvertWithSplitFirst [1711.305] 05007.05027> [ OK ] CatapultConverter.ConvertWithSplitFirst (0 ms) [1711.305] 05007.05027> [ RUN ] CatapultConverter.ConvertThroughputUnits [1711.306] 05007.05027> [ OK ] CatapultConverter.ConvertThroughputUnits (0 ms) [1711.306] 05007.05027> [ RUN ] CatapultConverter.ConvertBytesUnit [1711.306] 05007.05027> [ OK ] CatapultConverter.ConvertBytesUnit (0 ms) [1711.306] 05007.05027> [ RUN ] CatapultConverter.ZeroValues [1711.306] 05007.05027> [ OK ] CatapultConverter.ZeroValues (0 ms) [1711.306] 05007.05027> [ RUN ] CatapultConverter.NegativeValues [1711.306] 05007.05027> [ OK ] CatapultConverter.NegativeValues (1 ms) [1711.306] 05007.05027> [ RUN ] CatapultConverter.ConverterMain [1711.306] 05007.05027> [ OK ] CatapultConverter.ConverterMain (0 ms) [1711.306] 05007.05027> [----------] 7 tests from CatapultConverter (2 ms total) [1711.306] 05007.05027> [1711.306] 05007.05027> [----------] Global test environment tear-down [1711.306] 05007.05027> [==========] 8 tests from 2 test cases ran. (2 ms total) [1711.306] 05007.05027> [ PASSED ] 8 tests. [1711.307] 05007.05027> PASSED: /pkgfs/packages/catapult_converter/0/test/catapult_converter_test passed [1711.307] 05007.05027> [1711.307] 05007.05027> ------------------------------------------------ [1711.307] 05007.05027> RUNNING TEST: /pkgfs/packages/dart_zircon_test/0/test/dart_zircon_test [1711.307] 05007.05027> [1711.325] 01103.04284> devmgr: adding system driver 'btintel' '/system/driver/btintel.so' [1711.326] 01103.01116> devcoord: fallback driver 'usb_composite' is available [1711.326] 01103.01116> devcoord: fallback driver 'intel_disp' is available [1711.326] 01103.01116> devcoord: driver 'btintel' added [1711.326] 01103.01116> devcoord: driver 'wlan' added [1711.326] 01103.01116> devcoord: driver 'rtl88xx' added [1711.326] 01103.01116> devcoord: driver 'qmi_usb' added [1711.326] 01103.01116> devcoord: driver 'bthci_fake' added [1711.326] 01103.01116> devcoord: driver 'wlantapctl' added [1711.326] 01103.01116> devcoord: driver 'ralink' added [1711.326] 01103.01116> devcoord: driver 'bthog' added [1711.326] 01103.01116> devcoord: driver 'usb_video' added [1711.326] 01103.01116> devcoord: driver 'wlan' added [1711.326] 01103.01116> devcoord: driver 'bthost' added [1711.326] 01103.01116> devcoord: driver 'ath10k_pci' added [1711.326] 01103.01116> devcoord: driver 'wlan' added [1711.326] 01103.01116> devcoord: driver 'gpu' added [1711.326] 01103.01116> devcoord: drv='gpu' bindable to dev='intel-gpu-core' [1711.326] 01103.01116> devcoord: driver 'brcmfmac' added [1711.326] 01103.01116> devcoord: driver 'bt_passthrough_hci' added [1711.326] 01103.01116> devcoord: driver 'usb_composite' added [1711.326] 01103.01116> devcoord: drv='usb_composite' bindable to dev='001' [1711.326] 01103.01116> devcoord: drv='usb_composite' bindable to dev='002' [1711.326] 01103.01116> devcoord: driver 'intel_disp' added [1711.329] 02724.02756> UMS:Max lun is: 0 [1711.329] 02724.02756> devhost[00:14.0/xhci/usb/002/002/ifc-001] bind driver '/boot/driver/bt-transport-usb.so' failed: -2 [1711.329] 01103.01116> devcoord: rpc: bind-driver 'ifc-001' status -2 [1711.386] 02724.05874> btintel: already loaded [1711.386] 02724.05890> hci_read_thread: failed to read from command channel ZX_ERR_PEER_CLOSED [1711.386] 02724.05890> hci_read_thread: failed to read from ACL channel ZX_ERR_PEER_CLOSED [1711.386] 02724.05890> bt-transport-usb: all channels closed - exiting [1711.388] 02724.06260> [INFO:command_channel.cc(150)] hci: initialized [1711.409] 02724.06260> [INFO:acl_data_channel.cc(91)] hci: initialized [1711.552] 07125.07137> netstack: main.go:40: started [1711.553] 07125.07137> netstack: main.go:61: socket server started [1711.553] 07125.07347> netstack: main.go:117: OnInterfacesChanged failed: ErrBadHandle: zx.Channel.Write [1711.638] 07563.07575> [INFO:cobalt_main.cc(102)] Cobalt client schedule params: schedule_interval=3600 seconds, min_interval=10 seconds, initial_interval=60 seconds. [1711.679] 07992.08004> netcfg: started [1711.766] 07125.07347> netstack: netstack.go:553: NIC ethp001f6 added [1711.767] 07125.07347> netstack: netstack.go:568: NIC ethp001f6: link-local IPv6: fe80::d65d:dfff:fe07:5e24 [1711.767] 07125.07398> netstack: netstack.go:251: NIC ethp001f6: stopped [1711.806] 09542.09554> [INFO:main.cc(35)] Trace Manager starting with config: /pkg/data/tracing.config [1711.850] 02724.06260> [WARN:bredr_discovery_manager.cc(470)] gap-bredr: write inquiry scan activity failed: [status: invalid HCI command parameters (HCI 0x12)] [1712.010] 10182.10776> Observatory server failed to start after 1 tries [1712.054] 10991.11009> wlanstack2 [I]: Starting [1712.075] 10182.10534> [INFO:dart_component_controller.cc(109)] fuchsia-pkg://fuchsia.com/dart_zircon_test#meta/dart_zircon_test.cmx is running from kernel [1712.134] 10182.10534> 00:00 +0: fromFile [1712.170] 10182.10534> 00:00 +1: create socket [1712.173] 10182.10534> 00:00 +2: close socket [1712.176] 10182.10534> 00:00 +3: read write socket [1712.178] 10182.10534> 00:00 +4: partial read socket [1712.180] 10182.10534> 00:00 +5: partial write socket [1712.182] 10182.10534> 00:00 +6: async wait socket read [1712.185] 10182.10534> 00:00 +7: async wait socket closed [1712.187] 10182.10534> 00:00 +8: create channel [1712.189] 10182.10534> 00:00 +9: close channel [1712.191] 10182.10534> 00:00 +10: channel bytes [1712.193] 10182.10534> 00:00 +11: channel handles [1712.195] 10182.10534> 00:00 +12: async wait channel read [1712.197] 10182.10534> 00:00 +13: async wait channel closed [1712.207] 10182.10534> 00:00 +14: All tests passed! [1712.210] 05007.05027> PASSED: /pkgfs/packages/dart_zircon_test/0/test/dart_zircon_test passed [1712.211] 05007.05027> [1712.211] 05007.05027> ------------------------------------------------ [1712.211] 05007.05027> RUNNING TEST: /pkgfs/packages/deprecated_loop_tests/0/test/deprecated_loop_unittests [1712.211] 05007.05027> [1712.223] 05007.05027> [==========] Running 15 tests from 2 test cases. [1712.223] 05007.05027> [----------] Global test environment set-up. [1712.223] 05007.05027> [----------] 14 tests from MessageLoop [1712.223] 05007.05027> [ RUN ] MessageLoop.Current [1712.223] 05007.05027> [ OK ] MessageLoop.Current (0 ms) [1712.223] 05007.05027> [ RUN ] MessageLoop.RunsTasksOnCurrentThread [1712.223] 05007.05027> [ OK ] MessageLoop.RunsTasksOnCurrentThread (1 ms) [1712.223] 05007.05027> [ RUN ] MessageLoop.CanRunTasks [1712.223] 05007.05027> [ OK ] MessageLoop.CanRunTasks (0 ms) [1712.223] 05007.05027> [ RUN ] MessageLoop.CanPostTasksFromTasks [1712.223] 05007.05027> [ OK ] MessageLoop.CanPostTasksFromTasks (0 ms) [1712.223] 05007.05027> [ RUN ] MessageLoop.TriplyNestedTasks [1712.223] 05007.05027> [ OK ] MessageLoop.TriplyNestedTasks (0 ms) [1712.223] 05007.05027> [ RUN ] MessageLoop.CanRunTasksInOrder [1712.223] 05007.05027> [ OK ] MessageLoop.CanRunTasksInOrder (0 ms) [1712.223] 05007.05027> [ RUN ] MessageLoop.CanPreloadTasks [1712.223] 05007.05027> [ OK ] MessageLoop.CanPreloadTasks (0 ms) [1712.223] 05007.05027> [ RUN ] MessageLoop.AfterTaskCallbacks [1712.223] 05007.05027> [ OK ] MessageLoop.AfterTaskCallbacks (0 ms) [1712.223] 05007.05027> [ RUN ] MessageLoop.RemoveAfterTaskCallbacksDuringCallback [1712.223] 05007.05027> [ OK ] MessageLoop.RemoveAfterTaskCallbacksDuringCallback (0 ms) [1712.224] 05007.05027> [ RUN ] MessageLoop.TaskDestructionTime [1712.224] 05007.05027> [ OK ] MessageLoop.TaskDestructionTime (0 ms) [1712.224] 05007.05027> [ RUN ] MessageLoop.CanQuitCurrent [1712.224] 05007.05027> [ OK ] MessageLoop.CanQuitCurrent (0 ms) [1712.224] 05007.05027> [ RUN ] MessageLoop.CanQuitManyTimes [1712.224] 05007.05027> [ OK ] MessageLoop.CanQuitManyTimes (0 ms) [1712.224] 05007.05027> [ RUN ] MessageLoop.FDWaiter [1712.224] 05007.05027> [ OK ] MessageLoop.FDWaiter (0 ms) [1712.224] 05007.05027> [ RUN ] MessageLoop.TaskRunnerAvailableDuringLoopDestruction [1712.224] 05007.05027> [ OK ] MessageLoop.TaskRunnerAvailableDuringLoopDestruction (0 ms) [1712.224] 05007.05027> [----------] 14 tests from MessageLoop (1 ms total) [1712.224] 05007.05027> [1712.224] 05007.05027> [----------] 1 test from Thread [1712.224] 05007.05027> [ RUN ] Thread.Control [1712.224] 05007.05027> [ OK ] Thread.Control (1 ms) [1712.224] 05007.05027> [----------] 1 test from Thread (1 ms total) [1712.224] 05007.05027> [1712.224] 05007.05027> [----------] Global test environment tear-down [1712.224] 05007.05027> [==========] 15 tests from 2 test cases ran. (2 ms total) [1712.224] 05007.05027> [ PASSED ] 15 tests. [1712.225] 05007.05027> PASSED: /pkgfs/packages/deprecated_loop_tests/0/test/deprecated_loop_unittests passed [1712.225] 05007.05027> [1712.225] 05007.05027> ------------------------------------------------ [1712.225] 05007.05027> RUNNING TEST: /pkgfs/packages/fidl_bindings_test/0/test/fidl_bindings_test [1712.225] 05007.05027> [1712.330] 02724.05563> UMS: block size is: 0x00000200 [1712.330] 02724.05563> UMS: total blocks is: 30031872 [1712.330] 02724.05563> UMS: total size is: 15376318464 [1712.330] 02724.05563> UMS: read-only: 0 removable: 1 [1712.331] 01179.01199> devmgr: /dev/class/block/006: MBR? [1712.331] 01103.01116> devcoord: dc_bind_device() '/boot/driver/mbr.so' [1712.334] 01179.01199> devmgr: /dev/class/block/008: MBR? [1712.334] 01103.01116> devcoord: dc_bind_device() '/boot/driver/mbr.so' [1712.380] 11899.12322> Observatory server failed to start after 1 tries [1712.425] 11899.12182> [INFO:dart_component_controller.cc(109)] fuchsia-pkg://fuchsia.com/fidl_bindings_test#meta/fidl_bindings_test.cmx is running from kernel [1712.459] 11899.12182> clone-test [1712.496] 11899.12182> 00:00 +0: two way (setUpAll) [1712.535] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_bindings_test_server was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_bindings_test_server#meta/fidl_bindings_test_server [1712.535] 05721.05788> .cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1712.544] 11899.12182> 00:00 +0: two way no args [1712.563] 11899.12592> [INFO:dart_component_controller.cc(109)] fuchsia-pkg://fuchsia.com/fidl_bindings_test_server is running from kernel [1712.604] 11899.12182> 00:00 +1: two way string arg [1712.611] 11899.12182> 00:00 +2: two way three args [1712.618] 07125.07392> netstack: netstack.go:276: NIC ethp001f6: restarting [1712.625] 11899.12182> 00:00 +3: two way (tearDownAll) [1712.635] 11899.12182> Proxy error: Peer unexpectedly closed [1712.636] 11899.12182> 00:00 +3: proxy state initial [1712.636] 11899.12182> Proxy error: Peer unexpectedly closed [1712.638] 11899.12182> 00:00 +4: proxy state request [1712.641] 11899.12182> 00:00 +5: proxy state bind [1712.644] 11899.12182> Proxy error: Peer unexpectedly closed [1712.645] 11899.12182> 00:00 +6: proxy state after bind [1712.648] 11899.12182> 00:00 +7: proxy state unbind [1712.651] 11899.12182> 00:00 +8: proxy state close [1712.654] 11899.12182> 00:00 +9: proxy state after close [1712.660] 11899.12182> 00:00 +10: hash and equality enums simple [1712.662] 11899.12182> 00:00 +11: hash and equality enums constructed [1712.664] 11899.12182> 00:00 +12: hash and equality enums different types [1712.666] 11899.12182> 00:00 +13: hash and equality enums map [1712.668] 11899.12182> 00:00 +14: hash and equality structs no handles [1712.671] 11899.12182> 00:00 +15: hash and equality structs handles [1712.673] 11899.12182> 00:00 +16: hash and equality unions equality [1712.677] 11899.12182> 00:00 +17: hash and equality unions hash [1712.679] 11899.12182> 00:00 +18: one way (setUpAll) [1712.681] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_bindings_test_server was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_bindings_test_server#meta/fidl_bindings_test_server [1712.681] 05721.05788> .cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1712.682] 11899.12182> 00:00 +18: one way no args [1712.703] 11899.12806> [INFO:dart_component_controller.cc(109)] fuchsia-pkg://fuchsia.com/fidl_bindings_test_server is running from kernel [1712.739] 11899.12182> 00:00 +19: one way string arg [1712.745] 11899.12182> 00:00 +20: one way three args [1712.755] 11899.12182> Proxy error: Peer unexpectedly closed [1712.755] 11899.12182> Proxy error: Peer unexpectedly closed [1712.755] 11899.12182> 00:00 +21: one way table [1712.766] 11899.12182> 00:00 +22: one way partial table [1712.769] 11899.12182> 00:00 +23: one way empty table [1712.771] 11899.12182> 00:00 +24: one way (tearDownAll) [1712.774] 11899.12182> 00:00 +24: clone exact [1712.776] 11899.12182> Proxy error: Peer unexpectedly closed [1712.776] 11899.12182> 00:00 +25: clone modify field [1712.777] 11899.12182> Proxy error: Peer unexpectedly closed [1712.778] 11899.12182> 00:00 +26: clone set field [1712.780] 11899.12182> 00:00 +27: clone unset field [1712.783] 11899.12182> 00:00 +28: failure remote close during call [1712.785] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_bindings_test_server was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_bindings_test_server#meta/fidl_bindings_test_server [1712.785] 05721.05788> .cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1712.807] 11899.12982> [INFO:dart_component_controller.cc(109)] fuchsia-pkg://fuchsia.com/fidl_bindings_test_server is running from kernel [1712.942] 11899.12182> Proxy error: Peer unexpectedly closed [1712.945] 11899.12182> Proxy error: Peer unexpectedly closed [1712.946] 11899.12182> 00:00 +29: failure local close during call [1712.946] 11899.12182> Proxy error: Peer unexpectedly closed [1712.948] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_bindings_test_server was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_bindings_test_server#meta/fidl_bindings_test_server [1712.948] 05721.05788> .cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1712.952] 11899.12182> 00:00 +30: failure server killed during call [1712.955] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_bindings_test_server was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_bindings_test_server#meta/fidl_bindings_test_server [1712.955] 05721.05788> .cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1712.974] 11899.13151> [INFO:dart_component_controller.cc(109)] fuchsia-pkg://fuchsia.com/fidl_bindings_test_server is running from kernel [1712.974] 11899.12182> Proxy error: Peer unexpectedly closed [1712.975] 11899.12182> Proxy error: Peer unexpectedly closed [1712.980] 11899.13249> [INFO:dart_component_controller.cc(109)] fuchsia-pkg://fuchsia.com/fidl_bindings_test_server is running from kernel [1712.980] 11899.12182> Proxy error: Peer unexpectedly closed [1712.980] 11899.12182> Proxy error: Peer unexpectedly closed [1712.983] 11899.12182> Proxy error: AsyncProxyController<ComponentController> failed to write to channel: Channel(Handle(1976857377)) (status: -24) [1712.984] 11899.12182> 00:00 +31: failure one-way call on closed proxy [1712.986] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_bindings_test_server was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_bindings_test_server#meta/fidl_bindings_test_server [1712.986] 05721.05788> .cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1712.989] 11899.12182> 00:00 +32: failure two-way call on closed proxy [1712.991] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_bindings_test_server was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_bindings_test_server#meta/fidl_bindings_test_server [1712.991] 05721.05788> .cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1712.994] 11899.12182> 00:00 +33: failure listen for events on a closed proxy [1712.997] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_bindings_test_server was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_bindings_test_server#meta/fidl_bindings_test_server [1712.997] 05721.05788> .cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1713.007] 11899.12182> 00:00 +34: failure proxy closes while listening for events [1713.013] 11899.12182> 00:00 +35: failure binding closes immediately after sending response [1713.013] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_bindings_test_server was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_bindings_test_server#meta/fidl_bindings_test_server [1713.013] 05721.05788> .cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1713.024] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_bindings_test_server was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_bindings_test_server#meta/fidl_bindings_test_server [1713.024] 05721.05788> .cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1713.025] 11899.12182> Proxy error: Peer unexpectedly closed [1713.027] 11899.12182> 00:00 +36: unbound one-way call on unbound proxy [1713.029] 11899.12182> 00:00 +37: unbound two-way call on unbound proxy [1713.033] 11899.13534> [INFO:dart_component_controller.cc(109)] fuchsia-pkg://fuchsia.com/fidl_bindings_test_server is running from kernel [1713.037] 11899.12182> Proxy error: Peer unexpectedly closed [1713.037] 11899.12182> 00:00 +38: unbound event listen on unbound proxy [1713.038] 11899.12182> Proxy error: Peer unexpectedly closed [1713.039] 11899.13444> [INFO:dart_component_controller.cc(109)] fuchsia-pkg://fuchsia.com/fidl_bindings_test_server is running from kernel [1713.041] 11899.12182> Proxy error: Peer unexpectedly closed [1713.042] 11899.12182> Proxy error: Peer unexpectedly closed [1713.046] 11899.12182> 00:00 +39: events (setUpAll) [1713.051] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_bindings_test_server was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_bindings_test_server#meta/fidl_bindings_test_server [1713.051] 05721.05788> .cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1713.053] 11899.12182> 00:00 +39: events empty [1713.060] 11899.13655> [INFO:dart_component_controller.cc(109)] fuchsia-pkg://fuchsia.com/fidl_bindings_test_server is running from kernel [1713.061] 11899.12182> Proxy error: Peer unexpectedly closed [1713.064] 11899.12182> Proxy error: Peer unexpectedly closed [1713.069] 11899.13783> [INFO:dart_component_controller.cc(109)] fuchsia-pkg://fuchsia.com/fidl_bindings_test_server is running from kernel [1713.070] 11899.12182> Proxy error: Peer unexpectedly closed [1713.070] 11899.12182> Proxy error: Peer unexpectedly closed [1713.073] 11899.13888> [INFO:dart_component_controller.cc(109)] fuchsia-pkg://fuchsia.com/fidl_bindings_test_server is running from kernel [1713.074] 11899.12182> Proxy error: Peer unexpectedly closed [1713.075] 11899.12182> Proxy error: Peer unexpectedly closed [1713.086] 11899.14085> [INFO:dart_component_controller.cc(109)] fuchsia-pkg://fuchsia.com/fidl_bindings_test_server is running from kernel [1713.122] 11899.12182> 00:00 +40: events string arg [1713.127] 11899.12182> 00:00 +41: events three args [1713.135] 11899.12182> 00:00 +42: events multiple events [1713.255] 11899.12182> 00:00 +43: events (tearDownAll) [1713.265] 11899.12182> 00:00 +43: All tests passed! [1713.268] 05007.05027> PASSED: /pkgfs/packages/fidl_bindings_test/0/test/fidl_bindings_test passed [1713.268] 05007.05027> [1713.268] 05007.05027> ------------------------------------------------ [1713.268] 05007.05027> RUNNING TEST: /pkgfs/packages/fidl_compatibility_test/0/test/run_fidl_compatibility_test_topaz.sh [1713.268] 05007.05027> [1713.287] 05007.05027> [==========] Running 32 tests from 1 test case. [1713.287] 05007.05027> [----------] Global test environment set-up. [1713.287] 05007.05027> [----------] 32 tests from CompatibilityTest/CompatibilityTest [1713.287] 05007.05027> [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/0 [1713.288] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_cpp was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_cpp#meta/fidl_co [1713.288] 05721.05788> mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1713.302] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_cpp was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_cpp#meta/fidl_co [1713.302] 05721.05788> mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1713.311] 05007.05027> [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/0 (24 ms) [1713.311] 05007.05027> [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/1 [1713.311] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_cpp was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_cpp#meta/fidl_co [1713.311] 05721.05788> mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1713.326] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_dart was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart#meta/fidl_ [1713.326] 05721.05788> compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1713.482] 15284.15746> Observatory listening on http://127.0.0.1:26913/ [1713.484] 15284.15746> Creating /tmp/dart.services/26913 [1713.567] 15284.15512> [INFO:dart_component_controller.cc(109)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [1713.645] 05007.05027> [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/1 (334 ms) [1713.645] 05007.05027> [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/2 [1713.646] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_cpp was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_cpp#meta/fidl_co [1713.646] 05721.05788> mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1713.661] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_go was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_go#meta/fidl_comp [1713.661] 05721.05788> atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1713.688] 05007.05027> [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/2 (44 ms) [1713.688] 05007.05027> [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/3 [1713.690] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_cpp was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_cpp#meta/fidl_co [1713.690] 05721.05788> mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1713.704] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_rust was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_rust#meta/fidl_ [1713.704] 05721.05788> compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1713.730] 05007.05027> [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/3 (41 ms) [1713.730] 05007.05027> [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/4 [1713.730] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_dart was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart#meta/fidl_ [1713.730] 05721.05788> compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1713.757] 15284.16945> [INFO:dart_component_controller.cc(109)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [1713.820] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_cpp was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_cpp#meta/fidl_co [1713.820] 05721.05788> mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1713.845] 05007.05027> [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/4 (116 ms) [1713.845] 05007.05027> [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/5 [1713.846] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_dart was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart#meta/fidl_ [1713.846] 05721.05788> compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1713.874] 15284.17358> [INFO:dart_component_controller.cc(109)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [1713.937] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_dart was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart#meta/fidl_ [1713.937] 05721.05788> compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1713.957] 15284.17539> [INFO:dart_component_controller.cc(109)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [1714.038] 05007.05027> [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/5 (193 ms) [1714.038] 05007.05027> [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/6 [1714.039] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_dart was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart#meta/fidl_ [1714.039] 05721.05788> compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1714.066] 15284.17767> [INFO:dart_component_controller.cc(109)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [1714.128] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_go was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_go#meta/fidl_comp [1714.128] 05721.05788> atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1714.161] 05007.05027> [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/6 (123 ms) [1714.161] 05007.05027> [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/7 [1714.162] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_dart was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart#meta/fidl_ [1714.162] 05721.05788> compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1714.189] 15284.18271> [INFO:dart_component_controller.cc(109)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [1714.252] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_rust was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_rust#meta/fidl_ [1714.252] 05721.05788> compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1714.281] 05007.05027> [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/7 (120 ms) [1714.281] 05007.05027> [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/8 [1714.282] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_go was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_go#meta/fidl_comp [1714.282] 05721.05788> atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1714.309] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_cpp was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_cpp#meta/fidl_co [1714.309] 05721.05788> mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1714.325] 05007.05027> [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/8 (44 ms) [1714.325] 05007.05027> [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/9 [1714.326] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_go was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_go#meta/fidl_comp [1714.326] 05721.05788> atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1714.353] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_dart was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart#meta/fidl_ [1714.353] 05721.05788> compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1714.379] 15284.19375> [INFO:dart_component_controller.cc(109)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [1714.455] 05007.05027> [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/9 (129 ms) [1714.455] 05007.05027> [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/10 [1714.456] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_go was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_go#meta/fidl_comp [1714.456] 05721.05788> atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1714.483] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_go was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_go#meta/fidl_comp [1714.483] 05721.05788> atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1714.493] 05007.05027> [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/10 (39 ms) [1714.493] 05007.05027> [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/11 [1714.494] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_go was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_go#meta/fidl_comp [1714.494] 05721.05788> atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1714.520] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_rust was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_rust#meta/fidl_ [1714.520] 05721.05788> compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1714.545] 05007.05027> [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/11 (52 ms) [1714.545] 05007.05027> [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/12 [1714.546] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_rust was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_rust#meta/fidl_ [1714.546] 05721.05788> compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1714.569] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_cpp was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_cpp#meta/fidl_co [1714.569] 05721.05788> mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1714.580] 01537.01621> netsvc: handling tftp request failed (file might not exist) [1714.580] 01537.01621> netsvc: close, but no open file [1714.586] 05007.05027> [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/12 (41 ms) [1714.586] 05007.05027> [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/13 [1714.587] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_rust was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_rust#meta/fidl_ [1714.587] 05721.05788> compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1714.611] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_dart was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart#meta/fidl_ [1714.611] 05721.05788> compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1714.638] 15284.21064> [INFO:dart_component_controller.cc(109)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [1714.715] 05007.05027> [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/13 (128 ms) [1714.715] 05007.05027> [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/14 [1714.716] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_rust was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_rust#meta/fidl_ [1714.716] 05721.05788> compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1714.740] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_go was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_go#meta/fidl_comp [1714.740] 05721.05788> atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1714.768] 05007.05027> [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/14 (53 ms) [1714.768] 05007.05027> [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/15 [1714.769] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_rust was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_rust#meta/fidl_ [1714.769] 05721.05788> compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1714.793] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_rust was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_rust#meta/fidl_ [1714.793] 05721.05788> compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1714.802] 05007.05027> [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/15 (33 ms) [1714.802] 05007.05027> [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/0 [1714.803] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_cpp was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_cpp#meta/fidl_co [1714.803] 05721.05788> mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1714.817] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_cpp was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_cpp#meta/fidl_co [1714.817] 05721.05788> mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1714.825] 05007.05027> [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/0 (24 ms) [1714.825] 05007.05027> [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/1 [1714.827] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_cpp was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_cpp#meta/fidl_co [1714.827] 05721.05788> mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1714.842] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_dart was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart#meta/fidl_ [1714.842] 05721.05788> compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1714.868] 15284.22550> [INFO:dart_component_controller.cc(109)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [1714.945] 05007.05027> [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/1 (119 ms) [1714.945] 05007.05027> [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/2 [1714.946] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_cpp was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_cpp#meta/fidl_co [1714.946] 05721.05788> mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1714.961] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_go was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_go#meta/fidl_comp [1714.961] 05721.05788> atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1714.989] 05007.05027> [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/2 (44 ms) [1714.989] 05007.05027> [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/3 [1714.990] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_cpp was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_cpp#meta/fidl_co [1714.990] 05721.05788> mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1715.004] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_rust was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_rust#meta/fidl_ [1715.004] 05721.05788> compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1715.029] 05007.05027> [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/3 (41 ms) [1715.029] 05007.05027> [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/4 [1715.031] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_dart was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart#meta/fidl_ [1715.031] 05721.05788> compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1715.058] 15284.23622> [INFO:dart_component_controller.cc(109)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [1715.120] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_cpp was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_cpp#meta/fidl_co [1715.120] 05721.05788> mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1715.146] 05007.05027> [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/4 (116 ms) [1715.146] 05007.05027> [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/5 [1715.147] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_dart was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart#meta/fidl_ [1715.147] 05721.05788> compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1715.174] 15284.24036> [INFO:dart_component_controller.cc(109)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [1715.236] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_dart was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart#meta/fidl_ [1715.236] 05721.05788> compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1715.255] 15284.24211> [INFO:dart_component_controller.cc(109)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [1715.340] 05007.05027> [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/5 (194 ms) [1715.340] 05007.05027> [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/6 [1715.340] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_dart was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart#meta/fidl_ [1715.340] 05721.05788> compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1715.367] 15284.24421> [INFO:dart_component_controller.cc(109)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [1715.430] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_go was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_go#meta/fidl_comp [1715.430] 05721.05788> atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1715.467] 05007.05027> [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/6 (128 ms) [1715.467] 05007.05027> [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/7 [1715.468] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_dart was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart#meta/fidl_ [1715.468] 05721.05788> compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1715.496] 15284.24922> [INFO:dart_component_controller.cc(109)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [1715.558] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_rust was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_rust#meta/fidl_ [1715.558] 05721.05788> compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1715.588] 05007.05027> [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/7 (120 ms) [1715.588] 05007.05027> [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/8 [1715.589] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_go was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_go#meta/fidl_comp [1715.589] 05721.05788> atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1715.616] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_cpp was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_cpp#meta/fidl_co [1715.616] 05721.05788> mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1715.632] 05007.05027> [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/8 (45 ms) [1715.632] 05007.05027> [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/9 [1715.633] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_go was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_go#meta/fidl_comp [1715.633] 05721.05788> atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1715.660] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_dart was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart#meta/fidl_ [1715.660] 05721.05788> compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1715.687] 15284.26024> [INFO:dart_component_controller.cc(109)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [1715.763] 05007.05027> [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/9 (131 ms) [1715.763] 05007.05027> [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/10 [1715.765] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_go was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_go#meta/fidl_comp [1715.765] 05721.05788> atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1715.791] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_go was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_go#meta/fidl_comp [1715.791] 05721.05788> atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1715.801] 05007.05027> [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/10 (38 ms) [1715.801] 05007.05027> [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/11 [1715.803] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_go was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_go#meta/fidl_comp [1715.803] 05721.05788> atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1715.829] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_rust was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_rust#meta/fidl_ [1715.829] 05721.05788> compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1715.854] 05007.05027> [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/11 (53 ms) [1715.854] 05007.05027> [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/12 [1715.855] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_rust was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_rust#meta/fidl_ [1715.855] 05721.05788> compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1715.878] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_cpp was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_cpp#meta/fidl_co [1715.878] 05721.05788> mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1715.895] 05007.05027> [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/12 (41 ms) [1715.895] 05007.05027> [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/13 [1715.896] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_rust was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_rust#meta/fidl_ [1715.896] 05721.05788> compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1715.919] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_dart was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart#meta/fidl_ [1715.919] 05721.05788> compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1715.946] 15284.27711> [INFO:dart_component_controller.cc(109)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [1716.023] 05007.05027> [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/13 (128 ms) [1716.023] 05007.05027> [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/14 [1716.024] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_rust was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_rust#meta/fidl_ [1716.024] 05721.05788> compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1716.048] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_go was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_go#meta/fidl_comp [1716.048] 05721.05788> atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1716.076] 05007.05027> [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/14 (53 ms) [1716.076] 05007.05027> [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/15 [1716.077] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_rust was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_rust#meta/fidl_ [1716.077] 05721.05788> compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1716.101] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://fidl_compatibility_test_server_rust was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_rust#meta/fidl_ [1716.101] 05721.05788> compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1716.110] 05007.05027> [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/15 (34 ms) [1716.111] 05007.05027> [----------] 32 tests from CompatibilityTest/CompatibilityTest (2824 ms total) [1716.111] 05007.05027> [1716.111] 05007.05027> [----------] Global test environment tear-down [1716.111] 05007.05027> [==========] 32 tests from 1 test case ran. (2824 ms total) [1716.111] 05007.05027> [ PASSED ] 32 tests. [1716.111] 05007.05027> PASSED: /pkgfs/packages/fidl_compatibility_test/0/test/run_fidl_compatibility_test_topaz.sh passed [1716.111] 05007.05027> [1716.111] 05007.05027> ------------------------------------------------ [1716.111] 05007.05027> RUNNING TEST: /pkgfs/packages/flutter_runner_tests/0/test/flutter_runner_unittests [1716.111] 05007.05027> [1716.208] 05007.05027> Running main() from gtest_main.cc [1716.208] 05007.05027> [==========] Running 10 tests from 1 test case. [1716.208] 05007.05027> [----------] Global test environment set-up. [1716.208] 05007.05027> [----------] 10 tests from FuchsiaFontManagerTest [1716.208] 05007.05027> [ RUN ] FuchsiaFontManagerTest.ValidResponseWhenCharacterFound [1716.298] 05007.05027> [ OK ] FuchsiaFontManagerTest.ValidResponseWhenCharacterFound (90 ms) [1716.298] 05007.05027> [ RUN ] FuchsiaFontManagerTest.EmptyResponseWhenCharacterNotFound [1716.387] 05007.05027> [ OK ] FuchsiaFontManagerTest.EmptyResponseWhenCharacterNotFound (88 ms) [1716.387] 05007.05027> [ RUN ] FuchsiaFontManagerTest.Caching [1716.478] 05007.05027> [ OK ] FuchsiaFontManagerTest.Caching (91 ms) [1716.478] 05007.05027> [ RUN ] FuchsiaFontManagerTest.TypefaceOutlivesManager [1716.565] 05007.05027> [ OK ] FuchsiaFontManagerTest.TypefaceOutlivesManager (87 ms) [1716.565] 05007.05027> [ RUN ] FuchsiaFontManagerTest.ReleaseThenCreateAgain [1716.653] 05007.05027> [ OK ] FuchsiaFontManagerTest.ReleaseThenCreateAgain (88 ms) [1716.653] 05007.05027> [ RUN ] FuchsiaFontManagerTest.ReleasedTypefaceIsPurged [1716.684] 07125.07361> netstack: netstack.go:209: NIC ethp001f6: DHCP acquired IP 192.168.42.102 for 240h0m0s [1716.684] 07125.07361> netstack: netstack.go:210: NIC ethp001f6: Adding DNS servers: [8.8.8.8] [1716.742] 05007.05027> [ OK ] FuchsiaFontManagerTest.ReleasedTypefaceIsPurged (88 ms) [1716.742] 05007.05027> [ RUN ] FuchsiaFontManagerTest.MatchUnknownFamily [1716.829] 05007.05027> [ OK ] FuchsiaFontManagerTest.MatchUnknownFamily (86 ms) [1716.829] 05007.05027> [ RUN ] FuchsiaFontManagerTest.MatchKnownFamily [1716.915] 05007.05027> [ OK ] FuchsiaFontManagerTest.MatchKnownFamily (87 ms) [1716.915] 05007.05027> [ RUN ] FuchsiaFontManagerTest.FontFamilyGetStyle [1717.002] 05007.05027> [ OK ] FuchsiaFontManagerTest.FontFamilyGetStyle (87 ms) [1717.002] 05007.05027> [ RUN ] FuchsiaFontManagerTest.FontFamilyCreateTypeface [1717.092] 05007.05027> [ OK ] FuchsiaFontManagerTest.FontFamilyCreateTypeface (90 ms) [1717.092] 05007.05027> [----------] 10 tests from FuchsiaFontManagerTest (884 ms total) [1717.092] 05007.05027> [1717.092] 05007.05027> [----------] Global test environment tear-down [1717.092] 05007.05027> [==========] 10 tests from 1 test case ran. (884 ms total) [1717.092] 05007.05027> [ PASSED ] 10 tests. [1717.093] 05007.05027> PASSED: /pkgfs/packages/flutter_runner_tests/0/test/flutter_runner_unittests passed [1717.093] 05007.05027> [1717.093] 05007.05027> ------------------------------------------------ [1717.093] 05007.05027> RUNNING TEST: /pkgfs/packages/flutter_screencap_test/0/test/run_flutter_screencap_test.sh [1717.093] 05007.05027> [1717.098] 05007.05027> no tasks found [1717.099] 05007.05027> no tasks found [1717.100] 05007.05027> no tasks found [1717.114] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://basemgr was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/basemgr#meta/basemgr.cmx instead. See https://fuchsia.googlesource.com/doc [1717.114] 05721.05788> s/+/master/glossary.md#fuchsia_pkg-url for more information. [1717.150] 32892.32904> [ERROR:peridot/lib/session_shell_settings/session_shell_settings.cc(163)] /system/data/sysui/base_shell_config.json: read failed [1717.185] 32892.32904> [INFO:basemgr_impl.cc(247)] Initialzing token_manager_factory_app() [1717.195] 32892.32904> [INFO:basemgr_impl.cc(485)] Setting shadow technique to 0 [1717.195] 32892.32904> [ERROR:peridot/bin/basemgr/basemgr_impl.cc(107)] Active session shell index is 0, but only 0 session shell settings exist. [1717.199] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://token_manager_factory was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/token_manager_factory#meta/token_manager_factory.cmx instead [1717.199] 05721.05788> . See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1717.200] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://token_manager_factory was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/token_manager_factory#meta/token_manager_factory.cmx instead [1717.200] 05721.05788> . See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1717.201] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://dev_base_shell was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/dev_base_shell#meta/dev_base_shell.cmx instead. See https://fuchsia [1717.201] 05721.05788> .googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1717.204] 33364.33376> [INFO:fdio_hid_decoder.cc(280)] hid-parser succesful for 000 with usage page 1 and usage 128 [1717.225] 33639.33651> No service found for path fuchsia.modular.Lifecycle [1717.229] 33729.33741> No service found for path fuchsia.modular.auth.AccountProvider [1717.256] 33364.33376> [INFO:presentation1.cc(184)] Disconnected from a11y toggle broadcaster. [1717.261] 32892.32904> [INFO:user_provider_impl.cc(191)] fuchsia::modular::UserProvider::Login() Incognito mode [1717.261] 32892.32904> [INFO:user_provider_impl.cc(562)] Login() User:1014568570 [1717.261] 32892.32904> [INFO:user_controller_impl.cc(54)] SESSIONMGR DATA ORIGIN IS /data/modular/USER_GUEST_3278463660 [1717.262] 32892.32904> [INFO:basemgr_impl.cc(355)] Stopping base shell due to login [1717.263] 32892.32904> [INFO:basemgr_impl.cc(485)] Setting shadow technique to 0 [1717.263] 32892.32904> [ERROR:peridot/bin/basemgr/basemgr_impl.cc(107)] Active session shell index is 0, but only 0 session shell settings exist. [1717.263] 32892.32904> [WARNING:peridot/bin/basemgr/user_provider_impl.cc(137)] AuthenticationContextProvider disconnected. [1717.338] 32892.32904> [INFO:basemgr_impl.cc(178)] - fuchsia::modular::BaseShell down [1717.422] 33364.33376> [INFO:presentation1.cc(184)] Disconnected from a11y toggle broadcaster. [1717.424] 34169.34181> [INFO:display_watcher.cc(46)] Scenic: Acquired display controller /dev/class/display-controller/000.(000) [1717.430] 33364.33376> [ERROR:garnet/bin/ui/root_presenter/presentation1.cc(822)] Root presenter: Content view terminated unexpectedly. [1717.489] 34621.34633> [INFO:device_info.cc(61)] device_info: syncing device id for user: GUEST set to: dc29a030-5e1c-44ef-919e-8c78f6525ea7 [1717.508] 34621.34633> [INFO:user_intelligence_provider_impl.cc(113)] Starting session_agents: [1717.508] 34621.34633> [INFO:user_intelligence_provider_impl.cc(119)] Starting startup_agents: [1717.639] 35602.35618> [WARNING:peridot/bin/ledger/app/ledger_repository_factory_impl.cc(305)] No cloud provider - Ledger will work locally but not sync. (running in Guest mode?) [1717.640] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://dev_session_shell was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/dev_session_shell#meta/dev_session_shell.cmx instead. See https: [1717.640] 05721.05788> //fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1717.681] 34621.34633> [INFO:device_map_impl.cc(155)] Updated Device: Device/dc29a030-5e1c-44ef-919e-8c78f6525ea7 value={"@version":3,"name":"upper-swoop-clock-grope","device_id":"dc29a030-5e1c-44ef-919e-8c78f6525ea7","profile":"{}","hostname":"up [1717.681] 34621.34633> per-swoop-clock-grope","last_change_timestamp":1544753008} [1717.762] 37195.37210> [WARNING:peridot/bin/module_resolver/local_module_resolver.cc(634)] Still waiting on source: firebase_mods [1717.853] 37929.37946> [INFO:dev_session_shell.cc(103)] DevSessionShell START flutter_screencap_test_app [1718.657] 34169.34181> [INFO:input_system.cc(236)] Scenic input system initialized. [1718.657] 33364.33376> [WARNING:garnet/bin/ui/root_presenter/displays/display_configuration.cc(76)] SceneManager: unrecognized display. [1718.657] 34169.34181> [INFO:input_system.cc(576)] Scenic: Parallel dispatch is turned OFF [1718.657] 33364.33376> [INFO:display_configuration.cc(122)] SceneManager: Display metrics: width_in_px=1920, height_in_px=1080, width_in_pp=1117.09, height_in_pp=628.364, width_in_mm=213.333, height_in_mm=120, x_scale_in_px_per_pp=1.71875, y_scale [1718.657] 33364.33376> _in_px_per_pp=1.71875, x_scale_in_pp_per_px=0.581818, y_scale_in_pp_per_px=0.581818, density_in_pp_per_mm=5.23636, density_in_mm_per_pp=0.190972 [1721.754] 09037.09049> Starting mDNS on interface ethp001f6 192.168.42.102 [1721.754] 07125.10112> netstack: socket_conv.go:50: convSockOpt: TODO SOL_SOCKET optname=15 [1721.754] 07125.07392> netstack: socket_conv.go:86: convSockOpt: TODO IPPROTO_IP optname=32 [1721.754] 07125.07392> netstack: socket_conv.go:86: convSockOpt: TODO IPPROTO_IP optname=2 [1721.754] 09037.09049> Starting mDNS on interface ethp001f6 [80fe::5dd6:ffdf:7fe:245e] [1721.754] 07125.07353> netstack: socket_conv.go:50: convSockOpt: TODO SOL_SOCKET optname=15 [1721.754] 09037.09049> [ERROR:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(102)] Failed to bind socket to V6 address, errno 98 [1721.754] 09037.09049> [ERROR:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(105)] (EADDRINUSE) This is probably due to NET-1809. [1721.754] 09037.09049> mDNS: Verifying uniqueness of host name upper-swoop-clock-grope.local. [1722.509] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://dev_story_shell was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/dev_story_shell#meta/dev_story_shell.cmx instead. See https://fuch [1722.509] 05721.05788> sia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1722.671] 09037.09049> mDNS: Using unique host name upper-swoop-clock-grope.local. [1727.762] 37195.37210> [WARNING:peridot/bin/module_resolver/local_module_resolver.cc(634)] Still waiting on source: firebase_mods [1736.055] 37195.37210> [ERROR:peridot/lib/firebase/firebase_impl.cc(238)] https://cloud-mods.firebaseio.com//manifests.json error http::HTTP_ERR_NAME_NOT_RESOLVED [1736.055] 37195.37210> [INFO:firebase_source.cc(102)] Reconnecting to Firebase in 1 seconds. [1736.055] 37195.37210> [INFO:local_module_resolver.cc(109)] No manifest found for handler=flutter_screencap_test_app [1736.056] 34621.34633> [INFO:add_mod_call.cc(92)] Module Resolver does not know about module 'flutter_screencap_test_app' with action = 'action'. Going to try using it anyway.. [1736.056] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://flutter_screencap_test_app was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/flutter_screencap_test_app#meta/flutter_screencap_test_ [1736.056] 05721.05788> app.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1736.060] 34621.34633> [INFO:module_facet_reader_impl.cc(69)] No module facet declared for module=fuchsia-pkg://fuchsia.com/flutter_screencap_test_app [1736.063] 37929.37946> [INFO:dev_session_shell.cc(184)] DevSessionShell Starting story with id: story [1736.063] 37929.37946> [INFO:dev_session_shell.cc(210)] DevSessionShell State 3 [1736.064] 37929.37946> [INFO:dev_session_shell.cc(210)] DevSessionShell State 1 [1736.064] 34621.34633> [INFO:story_controller_impl.cc(173)] StoryControllerImpl::LaunchModule() flutter_screencap_test_app root [1736.070] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://flutter_screencap_test_app was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/flutter_screencap_test_app#meta/flutter_screencap_test_ [1736.070] 05721.05788> app.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1736.224] 40945.40957> [INFO:main.cc(17)] Flutter application services initialized. [1736.225] 40945.41131> [INFO:component.cc(201)] Found pkg/data/enable_interpreter. Passing --enable_interpreter [1736.229] 40945.41131> [INFO:vulkan_proc_table.cc(94)] Could not acquire proc: vkCreateMagmaSurfaceKHR [1736.229] 40945.41131> [INFO:vulkan_application.cc(116)] Debug reporting is enabled. [1736.243] 40945.41131> [INFO:vulkan_surface_producer.cc(32)] Flutter engine: Vulkan surface producer initialization: Successful [1736.281] 40945.41131> [INFO:dart_vm.cc(296)] Attempting Dart VM launch for mode: Interpreter [1736.283] 40945.41131> [WARNING:third_party/flutter/shell/common/shell.cc(199)] Skipping ICU initialization in the shell. [1736.284] 40945.41163> [WARNING:third_party/flutter/shell/common/platform_view.cc(71)] This platform does not setup the resource context on the IO thread for async texture uploads. [1736.284] 40945.41163> [WARNING:third_party/flutter/shell/common/io_manager.cc(56)] The IO manager was initialized without a resource context. Async texture uploads will be disabled. Expect performance degradation. [1736.296] 40945.41131> [WARNING:third_party/flutter/shell/common/persistent_cache.cc(46)] Could not acquire the persistent cache directory. Caching of GPU resources on disk is disabled. [1736.299] 40945.41131> [INFO:platform_view.cc(126)] Flutter, input comes from: Scenic [1736.299] 40945.41156> [WARNING:third_party/flutter/assets/asset_manager.cc(46)] Could not find asset: FontManifest.json [1736.299] 40945.41156> [WARNING:third_party/flutter/lib/ui/text/font_collection.cc(75)] Could not find the font manifest in the asset store. [1736.341] 40945.41677> flutter_screencap_test_app(flutter): Observatory listening on http://127.0.0.1:58135/ [1736.344] 40945.41677> flutter_screencap_test_app(flutter): Creating /tmp/dart.services/58135 [1736.522] 40945.41156> [INFO:dart_isolate.cc(430)] New isolate is in the running state. [1736.523] 40945.41156> [INFO:engine.cc(351)] Main isolate for engine 'flutter_screencap_test_app' was started. [1736.550] 40945.41156> flutter_screencap_test_app(flutter): [INFO:flutter_screencap_test:main.dart] starting flutter screencap test [1736.683] 40945.41156> flutter_screencap_test_app(flutter): [WARNING:flutter_screencap_test:main.dart] _reportTestResultsIfInTestHarness() [1736.687] 40945.41156> flutter_screencap_test_app(flutter): [INFO:flutter_screencap_test:main.dart] runTestIterations() [1736.716] 40945.41156> [INFO:paragraph.cc(291)] Could not find font collection for family "". [1741.063] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://dev_story_shell was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/dev_story_shell#meta/dev_story_shell.cmx instead. See https://fuch [1741.063] 05721.05788> sia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1743.691] 40945.41156> flutter_screencap_test_app(flutter): [INFO:flutter_screencap_test:main.dart] attempting to launch screencap [1743.693] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://screencap was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/screencap#meta/screencap.cmx instead. See https://fuchsia.googlesource.c [1743.693] 05721.05788> om/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1743.695] 40945.41156> flutter_screencap_test_app(flutter): [INFO:flutter_screencap_test:main.dart] waiting for launch response [1743.710] 42819.42831> [INFO:main.cc(137)] starting screen capture [1743.710] 42819.42831> [INFO:main.cc(148)] in histogram mode [1743.710] 42819.42831> [INFO:main.cc(158)] setting up event loop [1743.710] 42819.42831> [INFO:main.cc(163)] starting taker [1743.710] 42819.42831> [INFO:main.cc(47)] start TakeScreenshot [1743.710] 42819.42831> [INFO:main.cc(166)] starting Run() [1743.711] 42819.42831> [INFO:main.cc(58)] start TakeScreenshotInternal [1743.810] 42819.42831> [INFO:main.cc(61)] start pixel capture [1743.950] 42819.42831> [INFO:main.cc(78)] capturing pixels [1746.123] 42819.42831> [INFO:main.cc(100)] success [1746.123] 42819.42831> success [1746.237] 42819.42831> [INFO:main.cc(169)] returning result [1746.240] 40945.41156> flutter_screencap_test_app(flutter): [INFO:flutter_screencap_test:main.dart] sending success test result [1746.242] 05007.05027> flutter_screencap ...flutter_screencap OK [1746.242] 05007.05027> Succeeded tests: [1746.242] 05007.05027> flutter_screencap [1746.251] 33364.33376> [ERROR:garnet/bin/ui/root_presenter/presentation1.cc(822)] Root presenter: Content view terminated unexpectedly. [1746.258] 05007.05027> PASSED: /pkgfs/packages/flutter_screencap_test/0/test/run_flutter_screencap_test.sh passed [1746.259] 05007.05027> [1746.259] 05007.05027> ------------------------------------------------ [1746.259] 05007.05027> RUNNING TEST: /pkgfs/packages/flutter_screencap_test/0/test/run_flutter_screencap_test.sh [1746.259] 05007.05027> [1746.267] 05007.05027> Killed 33128 basemgr_monitor.cmx [1746.268] 05721.05788> [ERROR:garnet/bin/sysmgr/app.cc(154)] Singleton fuchsia-pkg://fuchsia.com/basemgr_monitor#meta/basemgr_monitor.cmx died [1746.269] 05007.05027> Killed 33364 root_presenter.cmx [1746.270] 05721.05788> [ERROR:garnet/bin/sysmgr/app.cc(154)] Singleton fuchsia-pkg://fuchsia.com/root_presenter#meta/root_presenter.cmx died [1746.271] 05007.05027> Killed 34169 scenic.cmx [1746.271] 34322.34334> [ERROR:garnet/bin/ui/view_manager/view_registry.cc(144)] Exiting due to session connection error. [1746.275] 02677.03438> Failed to send vsync event -24 [1746.275] 05721.05788> [ERROR:garnet/bin/sysmgr/app.cc(154)] Singleton fuchsia-pkg://fuchsia.com/view_manager#meta/view_manager.cmx died [1746.276] 02677.03715> Failed to send vsync event -24 [1746.281] 05721.05788> [ERROR:garnet/bin/sysmgr/app.cc(154)] Singleton fuchsia-pkg://fuchsia.com/scenic#meta/scenic.cmx died [1746.292] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://basemgr was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/basemgr#meta/basemgr.cmx instead. See https://fuchsia.googlesource.com/doc [1746.292] 05721.05788> s/+/master/glossary.md#fuchsia_pkg-url for more information. [1746.328] 43599.43611> [ERROR:peridot/lib/session_shell_settings/session_shell_settings.cc(163)] /system/data/sysui/base_shell_config.json: read failed [1746.363] 43599.43611> [INFO:basemgr_impl.cc(247)] Initialzing token_manager_factory_app() [1746.369] 43599.43611> [INFO:basemgr_impl.cc(485)] Setting shadow technique to 0 [1746.369] 43599.43611> [ERROR:peridot/bin/basemgr/basemgr_impl.cc(107)] Active session shell index is 0, but only 0 session shell settings exist. [1746.377] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://token_manager_factory was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/token_manager_factory#meta/token_manager_factory.cmx instead [1746.377] 05721.05788> . See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1746.378] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://token_manager_factory was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/token_manager_factory#meta/token_manager_factory.cmx instead [1746.378] 05721.05788> . See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1746.379] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://dev_base_shell was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/dev_base_shell#meta/dev_base_shell.cmx instead. See https://fuchsia [1746.379] 05721.05788> .googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1746.383] 44053.44065> [INFO:fdio_hid_decoder.cc(280)] hid-parser succesful for 000 with usage page 1 and usage 128 [1746.403] 44324.44336> No service found for path fuchsia.modular.Lifecycle [1746.406] 44414.44426> No service found for path fuchsia.modular.auth.AccountProvider [1746.434] 44053.44065> [INFO:presentation1.cc(184)] Disconnected from a11y toggle broadcaster. [1746.439] 43599.43611> [INFO:user_provider_impl.cc(191)] fuchsia::modular::UserProvider::Login() Incognito mode [1746.439] 43599.43611> [INFO:user_provider_impl.cc(562)] Login() User:123878200 [1746.439] 43599.43611> [INFO:user_controller_impl.cc(54)] SESSIONMGR DATA ORIGIN IS /data/modular/USER_GUEST_4271615330 [1746.440] 43599.43611> [INFO:basemgr_impl.cc(355)] Stopping base shell due to login [1746.441] 43599.43611> [INFO:basemgr_impl.cc(485)] Setting shadow technique to 0 [1746.441] 43599.43611> [ERROR:peridot/bin/basemgr/basemgr_impl.cc(107)] Active session shell index is 0, but only 0 session shell settings exist. [1746.441] 43599.43611> [WARNING:peridot/bin/basemgr/user_provider_impl.cc(137)] AuthenticationContextProvider disconnected. [1746.529] 43599.43611> [INFO:basemgr_impl.cc(178)] - fuchsia::modular::BaseShell down [1746.613] 44053.44065> [INFO:presentation1.cc(184)] Disconnected from a11y toggle broadcaster. [1746.615] 44844.44856> [INFO:display_watcher.cc(46)] Scenic: Acquired display controller /dev/class/display-controller/000.(000) [1746.677] 44053.44065> [ERROR:garnet/bin/ui/root_presenter/presentation1.cc(822)] Root presenter: Content view terminated unexpectedly. [1746.680] 45155.45173> [INFO:device_info.cc(61)] device_info: syncing device id for user: GUEST set to: b90b6947-cd68-46c8-beaa-a274df6396b2 [1746.695] 45155.45173> [INFO:user_intelligence_provider_impl.cc(113)] Starting session_agents: [1746.695] 45155.45173> [INFO:user_intelligence_provider_impl.cc(119)] Starting startup_agents: [1746.768] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://dev_session_shell was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/dev_session_shell#meta/dev_session_shell.cmx instead. See https: [1746.768] 05721.05788> //fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1746.802] 46109.46123> [WARNING:peridot/bin/ledger/app/ledger_repository_factory_impl.cc(305)] No cloud provider - Ledger will work locally but not sync. (running in Guest mode?) [1746.841] 45155.45173> [INFO:device_map_impl.cc(155)] Updated Device: Device/b90b6947-cd68-46c8-beaa-a274df6396b2 value={"@version":3,"name":"upper-swoop-clock-grope","device_id":"b90b6947-cd68-46c8-beaa-a274df6396b2","profile":"{}","hostname":"up [1746.841] 45155.45173> per-swoop-clock-grope","last_change_timestamp":1544753037} [1746.979] 48030.48255> [WARNING:peridot/bin/module_resolver/local_module_resolver.cc(634)] Still waiting on source: firebase_mods [1747.029] 48491.48503> [INFO:dev_session_shell.cc(103)] DevSessionShell START flutter_screencap_test_app [1747.831] 44844.44856> [INFO:input_system.cc(236)] Scenic input system initialized. [1747.832] 44053.44065> [WARNING:garnet/bin/ui/root_presenter/displays/display_configuration.cc(76)] SceneManager: unrecognized display. [1747.832] 44844.44856> [INFO:input_system.cc(576)] Scenic: Parallel dispatch is turned OFF [1747.832] 44053.44065> [INFO:display_configuration.cc(122)] SceneManager: Display metrics: width_in_px=1920, height_in_px=1080, width_in_pp=1117.09, height_in_pp=628.364, width_in_mm=213.333, height_in_mm=120, x_scale_in_px_per_pp=1.71875, y_scale [1747.832] 44053.44065> _in_px_per_pp=1.71875, x_scale_in_pp_per_px=0.581818, y_scale_in_pp_per_px=0.581818, density_in_pp_per_mm=5.23636, density_in_mm_per_pp=0.190972 [1751.696] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://dev_story_shell was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/dev_story_shell#meta/dev_story_shell.cmx instead. See https://fuch [1751.696] 05721.05788> sia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1756.979] 48030.48255> [WARNING:peridot/bin/module_resolver/local_module_resolver.cc(634)] Still waiting on source: firebase_mods [1766.979] 48030.48255> [WARNING:peridot/bin/module_resolver/local_module_resolver.cc(634)] Still waiting on source: firebase_mods [1773.096] 48030.48255> [ERROR:peridot/lib/firebase/firebase_impl.cc(238)] https://cloud-mods.firebaseio.com//manifests.json error http::HTTP_ERR_NAME_NOT_RESOLVED [1773.096] 48030.48255> [INFO:firebase_source.cc(102)] Reconnecting to Firebase in 1 seconds. [1773.096] 48030.48255> [INFO:local_module_resolver.cc(109)] No manifest found for handler=flutter_screencap_test_app [1773.096] 45155.45173> [INFO:add_mod_call.cc(92)] Module Resolver does not know about module 'flutter_screencap_test_app' with action = 'action'. Going to try using it anyway.. [1773.097] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://flutter_screencap_test_app was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/flutter_screencap_test_app#meta/flutter_screencap_test_ [1773.097] 05721.05788> app.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1773.100] 45155.45173> [INFO:module_facet_reader_impl.cc(69)] No module facet declared for module=fuchsia-pkg://fuchsia.com/flutter_screencap_test_app [1773.103] 48491.48503> [INFO:dev_session_shell.cc(184)] DevSessionShell Starting story with id: story [1773.103] 48491.48503> [INFO:dev_session_shell.cc(210)] DevSessionShell State 3 [1773.104] 48491.48503> [INFO:dev_session_shell.cc(210)] DevSessionShell State 1 [1773.104] 45155.45173> [INFO:story_controller_impl.cc(173)] StoryControllerImpl::LaunchModule() flutter_screencap_test_app root [1773.110] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://flutter_screencap_test_app was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/flutter_screencap_test_app#meta/flutter_screencap_test_ [1773.110] 05721.05788> app.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1773.272] 51583.51595> [INFO:main.cc(17)] Flutter application services initialized. [1773.273] 51583.51764> [INFO:component.cc(201)] Found pkg/data/enable_interpreter. Passing --enable_interpreter [1773.277] 51583.51764> [INFO:vulkan_proc_table.cc(94)] Could not acquire proc: vkCreateMagmaSurfaceKHR [1773.278] 51583.51764> [INFO:vulkan_application.cc(116)] Debug reporting is enabled. [1773.291] 51583.51764> [INFO:vulkan_surface_producer.cc(32)] Flutter engine: Vulkan surface producer initialization: Successful [1773.329] 51583.51764> [INFO:dart_vm.cc(296)] Attempting Dart VM launch for mode: Interpreter [1773.331] 51583.51764> [WARNING:third_party/flutter/shell/common/shell.cc(199)] Skipping ICU initialization in the shell. [1773.332] 51583.51796> [WARNING:third_party/flutter/shell/common/platform_view.cc(71)] This platform does not setup the resource context on the IO thread for async texture uploads. [1773.332] 51583.51796> [WARNING:third_party/flutter/shell/common/io_manager.cc(56)] The IO manager was initialized without a resource context. Async texture uploads will be disabled. Expect performance degradation. [1773.345] 51583.51764> [WARNING:third_party/flutter/shell/common/persistent_cache.cc(46)] Could not acquire the persistent cache directory. Caching of GPU resources on disk is disabled. [1773.346] 51583.51764> [INFO:platform_view.cc(126)] Flutter, input comes from: Scenic [1773.353] 51583.51789> [WARNING:third_party/flutter/assets/asset_manager.cc(46)] Could not find asset: FontManifest.json [1773.353] 51583.51789> [WARNING:third_party/flutter/lib/ui/text/font_collection.cc(75)] Could not find the font manifest in the asset store. [1773.379] 51583.52285> flutter_screencap_test_app(flutter): Observatory listening on http://127.0.0.1:61740/ [1773.382] 51583.52285> flutter_screencap_test_app(flutter): Creating /tmp/dart.services/61740 [1773.550] 51583.51789> [INFO:dart_isolate.cc(430)] New isolate is in the running state. [1773.550] 51583.51789> [INFO:engine.cc(351)] Main isolate for engine 'flutter_screencap_test_app' was started. [1773.573] 51583.51789> flutter_screencap_test_app(flutter): [INFO:flutter_screencap_test:main.dart] starting flutter screencap test [1773.707] 51583.51789> flutter_screencap_test_app(flutter): [WARNING:flutter_screencap_test:main.dart] _reportTestResultsIfInTestHarness() [1773.711] 51583.51789> flutter_screencap_test_app(flutter): [INFO:flutter_screencap_test:main.dart] runTestIterations() [1773.740] 51583.51789> [INFO:paragraph.cc(291)] Could not find font collection for family "". [1774.581] 01537.01621> netsvc: handling tftp request failed (file might not exist) [1774.581] 01537.01621> netsvc: close, but no open file [1778.103] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://dev_story_shell was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/dev_story_shell#meta/dev_story_shell.cmx instead. See https://fuch [1778.103] 05721.05788> sia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1780.714] 51583.51789> flutter_screencap_test_app(flutter): [INFO:flutter_screencap_test:main.dart] attempting to launch screencap [1780.716] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://screencap was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/screencap#meta/screencap.cmx instead. See https://fuchsia.googlesource.c [1780.716] 05721.05788> om/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1780.718] 51583.51789> flutter_screencap_test_app(flutter): [INFO:flutter_screencap_test:main.dart] waiting for launch response [1780.733] 53146.53158> [INFO:main.cc(137)] starting screen capture [1780.733] 53146.53158> [INFO:main.cc(148)] in histogram mode [1780.733] 53146.53158> [INFO:main.cc(158)] setting up event loop [1780.733] 53146.53158> [INFO:main.cc(163)] starting taker [1780.733] 53146.53158> [INFO:main.cc(47)] start TakeScreenshot [1780.733] 53146.53158> [INFO:main.cc(166)] starting Run() [1780.733] 53146.53158> [INFO:main.cc(58)] start TakeScreenshotInternal [1780.832] 53146.53158> [INFO:main.cc(61)] start pixel capture [1780.973] 53146.53158> [INFO:main.cc(78)] capturing pixels [1783.146] 53146.53158> [INFO:main.cc(100)] success [1783.146] 53146.53158> success [1783.261] 53146.53158> [INFO:main.cc(169)] returning result [1783.264] 51583.51789> flutter_screencap_test_app(flutter): [INFO:flutter_screencap_test:main.dart] sending success test result [1783.265] 05007.05027> flutter_screencap ...flutter_screencap OK [1783.265] 05007.05027> Succeeded tests: [1783.265] 05007.05027> flutter_screencap [1783.267] 44986.45003> fidl channel writing error at ../../garnet/public/lib/fidl/cpp/internal/proxy_controller.cc:59: zx_status_t -11, type fuchsia.ui.viewsv1/ViewContainerListenerOnChildUnavailableRequest, 24 bytes, 0 handles [1783.275] 44053.44065> [ERROR:garnet/bin/ui/root_presenter/presentation1.cc(822)] Root presenter: Content view terminated unexpectedly. [1783.282] 05007.05027> PASSED: /pkgfs/packages/flutter_screencap_test/0/test/run_flutter_screencap_test.sh passed [1783.284] 05007.05027> [1783.284] 05007.05027> ------------------------------------------------ [1783.284] 05007.05027> RUNNING TEST: /pkgfs/packages/fuchsia_modular_package_integration_tests/0/test/fuchsia_modular_package_integration_tests [1783.284] 05007.05027> [1783.369] 53788.54117> Observatory server failed to start after 1 tries [1783.466] 53788.53986> [INFO:dart_component_controller.cc(109)] fuchsia-pkg://fuchsia.com/fuchsia_modular_package_integration_tests#meta/fuchsia_modular_package_integration_tests.cmx is running from kernel [1783.571] 53788.53986> 00:00 +0: calls the set onAccept function [1783.600] 53788.53986> 00:00 +1: sets values on display [1783.606] 53788.53986> 00:00 +2: addModuleAffinity [1783.609] 53788.53986> 00:00 +3: addStoryAffinity [1783.611] 53788.53986> 00:00 +4: calls the callback when proposal accepted [1783.613] 53788.53986> 00:00 +5: factory should return same instance [1783.616] 53788.53986> 00:00 +6: startupContext [1783.630] 53788.53986> 00:00 +7: verify Lifecycle init during the construction of ModuleImpl [1783.632] 53788.53986> 00:00 +8: verify exposeService arguments [1783.637] 53788.53986> 00:00 +9: verify exposeServiceProvider arguments [1783.639] 53788.53986> 00:00 +10: service bindings tests verify exposeService binds the correct service on connect request [1783.646] 53788.53986> 00:00 +11: service bindings tests verify exposeService waits for all futures and binds the correct service on connect request [1783.651] 53788.53986> 00:00 +12: service bindings tests verify exposeServiceProvider waits for all futures and binds the correct service on connect request [1783.655] 53788.53986> 00:00 +13: service bindings tests verify exposeServiceProvider waits binds the correct service on connect request [1783.657] 53788.53986> 00:00 +14: verify getTokenManager should call context.getTokenManager [1783.661] 53788.53986> 00:00 +15: Agent Tasks: verify calling scheduleTask with null task throws [1783.663] 53788.53986> 00:00 +16: Agent Tasks: verify calling scheduleTask without handler throws [1783.666] 53788.53986> 00:00 +17: Agent Tasks: verify scheduleTask should call context.scheduleTask [1783.669] 53788.53986> 00:00 +18: Agent Tasks: verify calling deleteTask with null task throws [1783.671] 53788.53986> 00:00 +19: Agent Tasks: verify deleteTask should call context.deleteTask [1783.673] 53788.53986> 00:00 +20: Agent Tasks: verify calling registerTaskHandler with null task throws [1783.675] 53788.53986> 00:00 +21: Agent Tasks: verify calling registerTaskHandler twice should throw [1783.677] 53788.53986> 00:00 +22: Agent Tasks: verify runTask invokes registered taskHandler [1783.680] 53788.53986> 00:00 +23: Agent Tasks: verify out of band runTasks are queued up and run after task handler is registered [1783.684] 53788.53986> [WARNING:null] Attempting to run a task [1] before a task handler was registered. Queuing up this task to be executed as soon a task handler is registered. [1783.685] 53788.53986> [WARNING:null] Attempting to run a task [2] before a task handler was registered. Queuing up this task to be executed as soon a task handler is registered. [1783.685] 53788.53986> [WARNING:null] Attempting to run a task [3] before a task handler was registered. Queuing up this task to be executed as soon a task handler is registered. [1783.687] 53788.53986> 00:00 +24: module tests factory returns same instance [1783.689] 53788.53986> 00:00 +25: no-op intent handler calling handle intent does nothing [1783.691] 53788.53986> 00:00 +26: intent constructors intent sets the action [1783.694] 53788.53986> 00:00 +27: intent constructors intent with handler sets the handler [1783.696] 53788.53986> 00:00 +28: intent parameters addParameterFromEntityReference adds it to the list [1783.698] 53788.53986> 00:00 +29: intent parameters getEntity throws for missing name [1783.701] 53788.53986> 00:00 +30: intent parameters getEntity returns valid entity for link entity [1783.703] 53788.53986> 00:00 +31: addModuleToStory should return ModuleController upon success [1783.711] 53788.53986> 00:00 +32: addModuleToStory should throw ModuleResolutionException if no module found [1783.719] 53788.53986> 00:00 +33: addModuleToStory should throw ModuleStateException for unknown errors [1783.722] 53788.53986> 00:00 +34: embedModule should throw ModuleResolutionException if no module found [1783.726] 53788.53986> 00:00 +35: embedModule should throw ModuleStateException for unknown errors [1783.728] 53788.53986> 00:00 +36: embedModule should throw ArgumentError for invalid name [1783.731] 53788.53986> 00:00 +37: intent handling throws when registerIntentHandler called twice [1783.733] 53788.53986> 00:00 +38: intent handling throws when no intent handler registered [1783.736] 53788.53986> 00:00 +39: intent handling module proxies intents to handler [1783.738] 53788.53986> 00:00 +40: verify Lifecycle init during the construction of ModuleImpl [1783.741] 53788.53986> 00:00 +41: embedModule throws for empty name [1783.743] 53788.53986> 00:00 +42: embedModule throws for null intent [1783.745] 53788.53986> 00:00 +43: addModuleToStory throws for empty name [1783.747] 53788.53986> 00:00 +44: addModuleToStory throws for null intent [1783.748] 53788.53986> 00:00 +45: verify requestFocus should call context.requestFocus [1783.751] 53788.53986> 00:00 +46: verify removeSelfFromStory should call context.removeSelfFromStory [1783.753] 53788.53986> 00:00 +47: intent handler impl handleIntent calls registered handler [1783.755] 53788.53986> 00:00 +48: intent handler impl handleIntent passes along the correct action [1783.757] 53788.53986> 00:00 +49: intent transformers convertFidlIntentToIntent clones correct fields [1783.758] 53788.53986> 00:00 +50: intent transformers convertFidlIntentToIntent handles null fidl intent parametsrs [1783.760] 53788.53986> 00:00 +51: intent transformers Intents with const parameter lists can still be modified [1783.762] 53788.53986> 00:00 +52: ComponentContext: getComponentContext does not return null instance [1783.765] 04300.04532> [WARNING:garnet/bin/appmgr/service_provider_dir_impl.cc(87)] Component fuchsia-pkg://fuchsia.com/fuchsia_modular_package_integration_tests#meta/fuchsia_modular_package_integration_tests.cmx is not allowed to connect to fuchs [1783.765] 04300.04532> ia.modular.ComponentContext because this service is not present in the component's sandbox. [1783.765] 04300.04532> Refer to https://fuchsia.googlesource.com/docs/+/master/the-book/sandboxing.md#services for more information. [1783.767] 53788.53986> Proxy error: Peer unexpectedly closed [1783.768] 53788.53986> 00:00 +53: ComponentContext: getComponentContext returns the same instance [1783.769] 53788.53986> 00:00 +54: connectToAgentService:= throws for null or empty agent url [1783.772] 53788.53986> 00:00 +55: connectToAgentService:= throws if serviceProxy is null [1783.773] 53788.53986> 00:00 +56: factory should return same instance [1783.775] 53788.53986> 00:00 +57: addTerminateListener throws for null listener [1783.777] 53788.53986> 00:00 +58: addTerminateListener should return false when adding same handler [1783.778] 53788.53986> 00:00 +59: addTerminateListener successful add [1783.780] 53788.53986> 00:00 +60: failing terminate handler should error [1783.782] 53788.53986> testing 1 [1783.786] 53788.53986> terminateListener1 [1783.787] 53788.53986> throwingTerminateListener [1783.789] 53788.53986> 00:00 +61: terminate should trigger all added listeners to execute [1783.789] 53788.53986> Skip: this test will cause other tests to not run after it is invoked since it calls exit() [1783.797] 53788.53986> 00:00 +61 ~1: All tests passed! [1783.801] 05007.05027> PASSED: /pkgfs/packages/fuchsia_modular_package_integration_tests/0/test/fuchsia_modular_package_integration_tests passed [1783.802] 05007.05027> [1783.802] 05007.05027> ------------------------------------------------ [1783.802] 05007.05027> RUNNING TEST: /pkgfs/packages/fuchsia_services_package_integration_tests/0/test/fuchsia_services_package_integration_tests [1783.802] 05007.05027> [1783.867] 54926.55256> Observatory server failed to start after 1 tries [1783.898] 54926.55125> [INFO:dart_component_controller.cc(109)] fuchsia-pkg://fuchsia.com/fuchsia_services_package_integration_tests#meta/fuchsia_services_package_integration_tests.cmx is running from kernel [1783.959] 54926.55125> 00:00 +0: StartupContext fromStartupInfo does not return null instance [1783.998] 54926.55125> 00:00 +1: StartupContext fromStartupInfo returns the same instance [1784.001] 54926.55125> 00:00 +2: connectToEnvironmentService throws if serviceProxy is null [1784.005] 54926.55125> 00:00 +3: service provider impl connect to service calls correct thunk [1784.017] 54926.55125> 00:00 +4: All tests passed! [1784.021] 05007.05027> PASSED: /pkgfs/packages/fuchsia_services_package_integration_tests/0/test/fuchsia_services_package_integration_tests passed [1784.021] 05007.05027> [1784.021] 05007.05027> ------------------------------------------------ [1784.021] 05007.05027> RUNNING TEST: /pkgfs/packages/goodbye_dart_test/0/test/goodbye_dart_test [1784.021] 05007.05027> [1784.077] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://goodbye_dart_aot was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/goodbye_dart_aot#meta/goodbye_dart_aot.cmx instead. See https://f [1784.077] 05721.05788> uchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1784.191] 56163.56356> [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/goodbye_dart_aot is running from an app snapshot [1784.191] 56163.56356> Hello, Dart! [1784.191] 56163.56356> Goodbye now! [1784.192] 56163.56469> Observatory server failed to start after 1 tries [1784.203] 05721.05788> [WARNING:garnet/lib/loader/package_loader.cc(53)] Component file://goodbye_dart_aot was launched without using fuchsia-pkg URLs! Use fuchsia-pkg://fuchsia.com/goodbye_dart_aot#meta/goodbye_dart_aot.cmx instead. See https://f [1784.203] 05721.05788> uchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [1784.237] 56163.56680> [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/goodbye_dart_aot is running from an app snapshot [1784.237] 56163.56680> Hello, Dart! [1785.194] 56163.56734> Observatory server failed to start after 2 tries [1785.238] 56163.56680> Goodbye, Dart! [1785.239] 05007.05027> PASSED: /pkgfs/packages/goodbye_dart_test/0/test/goodbye_dart_test passed [1785.240] 05007.05027> [1785.240] 05007.05027> ------------------------------------------------ [1785.240] 05007.05027> RUNNING TEST: /pkgfs/packages/run_test_component_test/0/test/run_test_component_test [1785.240] 05007.05027> [1785.288] 05007.05027> [==========] Running 1 test from 1 test case. [1785.288] 05007.05027> [----------] Global test environment set-up. [1785.288] 05007.05027> [----------] 1 test from Run [1785.288] 05007.05027> [ RUN ] Run.TestHermeticEnv [1785.288] 05007.05027> [ OK ] Run.TestHermeticEnv (1 ms) [1785.288] 05007.05027> [----------] 1 test from Run (1 ms total) [1785.288] 05007.05027> [1785.288] 05007.05027> [----------] Global test environment tear-down [1785.288] 05007.05027> [==========] 1 test from 1 test case ran. (1 ms total) [1785.288] 05007.05027> [ PASSED ] 1 test. [1785.289] 05007.05027> PASSED: /pkgfs/packages/run_test_component_test/0/test/run_test_component_test passed [1785.289] 05007.05027> [1785.289] 05007.05027> ------------------------------------------------ [1785.289] 05007.05027> RUNNING TEST: /pkgfs/packages/run_test_component_unittests/0/test/run_test_component_unittests [1785.289] 05007.05027> [1785.339] 05007.05027> [==========] Running 19 tests from 4 test cases. [1785.339] 05007.05027> [----------] Global test environment set-up. [1785.339] 05007.05027> [----------] 5 tests from EnvironmentConfigTest [1785.339] 05007.05027> [ RUN ] EnvironmentConfigTest.InvalidJson [1785.339] 05007.05027> [ OK ] EnvironmentConfigTest.InvalidJson (1 ms) [1785.339] 05007.05027> [ RUN ] EnvironmentConfigTest.NoRootElement [1785.340] 05007.05027> [ OK ] EnvironmentConfigTest.NoRootElement (0 ms) [1785.340] 05007.05027> [ RUN ] EnvironmentConfigTest.NoSysElement [1785.340] 05007.05027> [ OK ] EnvironmentConfigTest.NoSysElement (1 ms) [1785.340] 05007.05027> [ RUN ] EnvironmentConfigTest.InvalidSection [1785.341] 05007.05027> [ OK ] EnvironmentConfigTest.InvalidSection (0 ms) [1785.341] 05007.05027> [ RUN ] EnvironmentConfigTest.ValidConfig [1785.341] 05007.05027> [ OK ] EnvironmentConfigTest.ValidConfig (1 ms) [1785.341] 05007.05027> [----------] 5 tests from EnvironmentConfigTest (3 ms total) [1785.341] 05007.05027> [1785.341] 05007.05027> [----------] 2 tests from Url [1785.341] 05007.05027> [ RUN ] Url.ParseURL [1785.342] 05007.05027> [ OK ] Url.ParseURL (0 ms) [1785.342] 05007.05027> [ RUN ] Url.GenerateComponentUrl [1785.342] 05007.05027> [ OK ] Url.GenerateComponentUrl (0 ms) [1785.342] 05007.05027> [----------] 2 tests from Url (0 ms total) [1785.342] 05007.05027> [1785.342] 05007.05027> [----------] 1 test from RunTest [1785.342] 05007.05027> [ RUN ] RunTest.ParseArgs [1785.344] 05007.05027> [ OK ] RunTest.ParseArgs (1 ms) [1785.344] 05007.05027> [----------] 1 test from RunTest (1 ms total) [1785.344] 05007.05027> [1785.344] 05007.05027> [----------] 11 tests from TestMetadataTest [1785.344] 05007.05027> [ RUN ] TestMetadataTest.InvalidJson [1785.344] 05007.05027> [ OK ] TestMetadataTest.InvalidJson (0 ms) [1785.344] 05007.05027> [ RUN ] TestMetadataTest.NoFacet [1785.344] 05007.05027> [ OK ] TestMetadataTest.NoFacet (1 ms) [1785.344] 05007.05027> [ RUN ] TestMetadataTest.NoFuchsiaTestFacet [1785.345] 05007.05027> [ OK ] TestMetadataTest.NoFuchsiaTestFacet (0 ms) [1785.345] 05007.05027> [ RUN ] TestMetadataTest.NoServices [1785.345] 05007.05027> [ OK ] TestMetadataTest.NoServices (1 ms) [1785.345] 05007.05027> [ RUN ] TestMetadataTest.InvalidTestFacet [1785.346] 05007.05027> [ OK ] TestMetadataTest.InvalidTestFacet (0 ms) [1785.346] 05007.05027> [ RUN ] TestMetadataTest.InvalidServicesType [1785.346] 05007.05027> [ OK ] TestMetadataTest.InvalidServicesType (1 ms) [1785.346] 05007.05027> [ RUN ] TestMetadataTest.InvalidSystemServicesType [1785.347] 05007.05027> [ OK ] TestMetadataTest.InvalidSystemServicesType (1 ms) [1785.347] 05007.05027> [ RUN ] TestMetadataTest.InvalidServices [1785.348] 05007.05027> [ OK ] TestMetadataTest.InvalidServices (1 ms) [1785.348] 05007.05027> [ RUN ] TestMetadataTest.EmptyServices [1785.349] 05007.05027> [ OK ] TestMetadataTest.EmptyServices (0 ms) [1785.349] 05007.05027> [ RUN ] TestMetadataTest.ValidServices [1785.349] 05007.05027> [ OK ] TestMetadataTest.ValidServices (1 ms) [1785.349] 05007.05027> [ RUN ] TestMetadataTest.ValidSystemServices [1785.350] 05007.05027> [ OK ] TestMetadataTest.ValidSystemServices (0 ms) [1785.350] 05007.05027> [----------] 11 tests from TestMetadataTest (6 ms total) [1785.350] 05007.05027> [1785.350] 05007.05027> [----------] Global test environment tear-down [1785.350] 05007.05027> [==========] 19 tests from 4 test cases ran. (11 ms total) [1785.350] 05007.05027> [ PASSED ] 19 tests. [1785.351] 05007.05027> PASSED: /pkgfs/packages/run_test_component_unittests/0/test/run_test_component_unittests passed [1785.351] 05007.05027> [1785.351] 05007.05027> ------------------------------------------------ [1785.351] 05007.05027> RUNNING TEST: /pkgfs/packages/scenic_tests/0/test/geometry_util_unittests [1785.351] 05007.05027> [1785.359] 05007.05027> Running main() from gtest_main.cc [1785.359] 05007.05027> [==========] Running 10 tests from 4 test cases. [1785.359] 05007.05027> [----------] Global test environment set-up. [1785.359] 05007.05027> [----------] 1 test from RectTest [1785.359] 05007.05027> [ RUN ] RectTest.Comparisons [1785.359] 05007.05027> [ OK ] RectTest.Comparisons (0 ms) [1785.359] 05007.05027> [----------] 1 test from RectTest (0 ms total) [1785.359] 05007.05027> [1785.359] 05007.05027> [----------] 1 test from SizeTest [1785.359] 05007.05027> [ RUN ] SizeTest.Comparisons [1785.359] 05007.05027> [ OK ] SizeTest.Comparisons (0 ms) [1785.359] 05007.05027> [----------] 1 test from SizeTest (0 ms total) [1785.359] 05007.05027> [1785.359] 05007.05027> [----------] 1 test from PointTest [1785.359] 05007.05027> [ RUN ] PointTest.Comparisons [1785.359] 05007.05027> [ OK ] PointTest.Comparisons (0 ms) [1785.359] 05007.05027> [----------] 1 test from PointTest (0 ms total) [1785.359] 05007.05027> [1785.359] 05007.05027> [----------] 7 tests from TransformFunctionsTest [1785.359] 05007.05027> [ RUN ] TransformFunctionsTest.SetIdentityTransform [1785.359] 05007.05027> [ OK ] TransformFunctionsTest.SetIdentityTransform (0 ms) [1785.359] 05007.05027> [ RUN ] TransformFunctionsTest.SetTranslationTransform [1785.359] 05007.05027> [ OK ] TransformFunctionsTest.SetTranslationTransform (0 ms) [1785.359] 05007.05027> [ RUN ] TransformFunctionsTest.Translate [1785.359] 05007.05027> [ OK ] TransformFunctionsTest.Translate (0 ms) [1785.360] 05007.05027> [ RUN ] TransformFunctionsTest.Scale [1785.360] 05007.05027> [ OK ] TransformFunctionsTest.Scale (0 ms) [1785.360] 05007.05027> [ RUN ] TransformFunctionsTest.CreateIdentityTransform [1785.360] 05007.05027> [ OK ] TransformFunctionsTest.CreateIdentityTransform (0 ms) [1785.360] 05007.05027> [ RUN ] TransformFunctionsTest.CreateTranslationTransform [1785.360] 05007.05027> [ OK ] TransformFunctionsTest.CreateTranslationTransform (0 ms) [1785.360] 05007.05027> [ RUN ] TransformFunctionsTest.CreateScaleTransform [1785.360] 05007.05027> [ OK ] TransformFunctionsTest.CreateScaleTransform (0 ms) [1785.360] 05007.05027> [----------] 7 tests from TransformFunctionsTest (0 ms total) [1785.360] 05007.05027> [1785.360] 05007.05027> [----------] Global test environment tear-down [1785.360] 05007.05027> [==========] 10 tests from 4 test cases ran. (0 ms total) [1785.360] 05007.05027> [ PASSED ] 10 tests. [1785.360] 05007.05027> PASSED: /pkgfs/packages/scenic_tests/0/test/geometry_util_unittests passed [1785.360] 05007.05027> [1785.360] 05007.05027> ------------------------------------------------ [1785.360] 05007.05027> RUNNING TEST: /pkgfs/packages/scenic_tests/0/test/gfx_apptests [1785.360] 05007.05027> [1785.439] 05007.05027> Running main() from gtest_main.cc [1785.439] 05007.05027> [==========] Running 0 tests from 0 test cases. [1785.439] 05007.05027> [==========] 0 tests from 0 test cases ran. (0 ms total) [1785.439] 05007.05027> [ PASSED ] 0 tests. [1785.439] 05007.05027> [1785.439] 05007.05027> YOU HAVE 4 DISABLED TESTS [1785.439] 05007.05027> [1785.440] 05007.05027> PASSED: /pkgfs/packages/scenic_tests/0/test/gfx_apptests passed [1785.440] 05007.05027> [1785.440] 05007.05027> ------------------------------------------------ [1785.440] 05007.05027> RUNNING TEST: /pkgfs/packages/scenic_tests/0/test/gfx_unittests [1785.440] 05007.05027> [1785.526] 05007.05027> [==========] Running 113 tests from 13 test cases. [1785.526] 05007.05027> [----------] Global test environment set-up. [1785.526] 05007.05027> [----------] 5 tests from HLATest [1785.526] 05007.05027> [ RUN ] HLATest.HasHardwareLayerAssignment [1785.526] 05007.05027> [1785.526] 05007.05027> TEST_LOOP_RANDOM_SEED="1491876354" [1785.526] 05007.05027> [ OK ] HLATest.HasHardwareLayerAssignment (0 ms) [1785.526] 05007.05027> [ RUN ] HLATest.HardwareLayerAssignmentMissingSwapchain [1785.526] 05007.05027> [1785.526] 05007.05027> TEST_LOOP_RANDOM_SEED="2781616209" [1785.526] 05007.05027> [WARNING:garnet/lib/ui/gfx/engine/hardware_layer_assignment.cc(16)] Invalid HardwareLayerAssignment: no swapchain. [1785.526] 05007.05027> [ OK ] HLATest.HardwareLayerAssignmentMissingSwapchain (0 ms) [1785.526] 05007.05027> [ RUN ] HLATest.HardwareLayerAssignmentMissingLayers [1785.526] 05007.05027> [1785.526] 05007.05027> TEST_LOOP_RANDOM_SEED="2347705820" [1785.526] 05007.05027> [WARNING:garnet/lib/ui/gfx/engine/hardware_layer_assignment.cc(26)] Invalid HardwareLayerAssignment: item with no layers. [1785.526] 05007.05027> [ OK ] HLATest.HardwareLayerAssignmentMissingLayers (0 ms) [1785.526] 05007.05027> [ RUN ] HLATest.HardwareLayerAssignmentMissingItems [1785.526] 05007.05027> [1785.526] 05007.05027> TEST_LOOP_RANDOM_SEED="3146125077" [1785.526] 05007.05027> [WARNING:garnet/lib/ui/gfx/engine/hardware_layer_assignment.cc(19)] Invalid HardwareLayerAssignment: no items. [1785.526] 05007.05027> [ OK ] HLATest.HardwareLayerAssignmentMissingItems (0 ms) [1785.526] 05007.05027> [ RUN ] HLATest.HardwareLayerAssignmentDuplicateLayerIDs [1785.526] 05007.05027> [1785.526] 05007.05027> TEST_LOOP_RANDOM_SEED="3712179301" [1785.526] 05007.05027> [WARNING:garnet/lib/ui/gfx/engine/hardware_layer_assignment.cc(32)] Invalid HardwareLayerAssignment: duplicate layer ID [1785.526] 05007.05027> [ OK ] HLATest.HardwareLayerAssignmentDuplicateLayerIDs (0 ms) [1785.526] 05007.05027> [----------] 5 tests from HLATest (0 ms total) [1785.526] 05007.05027> [1785.526] 05007.05027> [----------] 1 test from MultiSessionHitTestTest [1785.526] 05007.05027> [ RUN ] MultiSessionHitTestTest.GlobalHits [1785.527] 05007.05027> [1785.527] 05007.05027> TEST_LOOP_RANDOM_SEED="3717505633" [1785.527] 05007.05027> [WARNING:garnet/lib/ui/scenic/event_reporter.cc(23)] EventReporter not set up, dropped event: ViewConnectedEvent(view_id=1008) [1785.527] 05007.05027> [WARNING:garnet/lib/ui/scenic/event_reporter.cc(23)] EventReporter not set up, dropped event: ViewAttachedToSceneEvent(view_id=2001, properties=<TBD> [1785.527] 05007.05027> [WARNING:garnet/lib/ui/scenic/event_reporter.cc(23)] EventReporter not set up, dropped event: ViewPropertiesChangedEvent(view_id=2001, properties=<TBD>) [1785.527] 05007.05027> [WARNING:garnet/lib/ui/scenic/event_reporter.cc(23)] EventReporter not set up, dropped event: ViewConnectedEvent(view_id=1009) [1785.527] 05007.05027> [WARNING:garnet/lib/ui/scenic/event_reporter.cc(23)] EventReporter not set up, dropped event: ViewAttachedToSceneEvent(view_id=3001, properties=<TBD> [1785.527] 05007.05027> [WARNING:garnet/lib/ui/scenic/event_reporter.cc(23)] EventReporter not set up, dropped event: ViewPropertiesChangedEvent(view_id=3001, properties=<TBD>) [1785.527] 05007.05027> [WARNING:garnet/lib/ui/scenic/event_reporter.cc(23)] EventReporter not set up, dropped event: ViewDisconnectedEvent(view_id=1009) [1785.527] 05007.05027> [WARNING:garnet/lib/ui/scenic/event_reporter.cc(23)] EventReporter not set up, dropped event: ViewDisconnectedEvent(view_id=1008) [1785.527] 05007.05027> [ OK ] MultiSessionHitTestTest.GlobalHits (1 ms) [1785.527] 05007.05027> [----------] 1 test from MultiSessionHitTestTest (1 ms total) [1785.527] 05007.05027> [1785.527] 05007.05027> [----------] 17 tests from HitTestTest [1785.527] 05007.05027> [ RUN ] HitTestTest.InvalidNodeId [1785.527] 05007.05027> [1785.527] 05007.05027> TEST_LOOP_RANDOM_SEED="429304738" [1785.528] 05007.05027> [ OK ] HitTestTest.InvalidNodeId (0 ms) [1785.528] 05007.05027> [ RUN ] HitTestTest.RayBelowScenePointingDown [1785.528] 05007.05027> [1785.528] 05007.05027> TEST_LOOP_RANDOM_SEED="131416712" [1785.528] 05007.05027> [ OK ] HitTestTest.RayBelowScenePointingDown (0 ms) [1785.528] 05007.05027> [ RUN ] HitTestTest.RayBelowScenePointingUp [1785.528] 05007.05027> [1785.528] 05007.05027> TEST_LOOP_RANDOM_SEED="673282619" [1785.528] 05007.05027> [ OK ] HitTestTest.RayBelowScenePointingUp (1 ms) [1785.528] 05007.05027> [ RUN ] HitTestTest.RayAboveScenePointingUp [1785.528] 05007.05027> [1785.528] 05007.05027> TEST_LOOP_RANDOM_SEED="2935454954" [1785.528] 05007.05027> [ OK ] HitTestTest.RayAboveScenePointingUp (0 ms) [1785.528] 05007.05027> [ RUN ] HitTestTest.Hit10InTopLeftCornerFromNode1 [1785.528] 05007.05027> [1785.528] 05007.05027> TEST_LOOP_RANDOM_SEED="3246270083" [1785.529] 05007.05027> [ OK ] HitTestTest.Hit10InTopLeftCornerFromNode1 (0 ms) [1785.529] 05007.05027> [ RUN ] HitTestTest.Hit10InTopLeftCornerFromNode2 [1785.529] 05007.05027> [1785.529] 05007.05027> TEST_LOOP_RANDOM_SEED="1977064416" [1785.529] 05007.05027> [ OK ] HitTestTest.Hit10InTopLeftCornerFromNode2 (0 ms) [1785.529] 05007.05027> [ RUN ] HitTestTest.Hit10InTopLeftCornerFromNode3 [1785.529] 05007.05027> [1785.529] 05007.05027> TEST_LOOP_RANDOM_SEED="287557633" [1785.529] 05007.05027> [ OK ] HitTestTest.Hit10InTopLeftCornerFromNode3 (1 ms) [1785.529] 05007.05027> [ RUN ] HitTestTest.Hit10InTopLeftCornerFromNode4 [1785.529] 05007.05027> [1785.529] 05007.05027> TEST_LOOP_RANDOM_SEED="1290376222" [1785.529] 05007.05027> [ OK ] HitTestTest.Hit10InTopLeftCornerFromNode4 (0 ms) [1785.529] 05007.05027> [ RUN ] HitTestTest.Hit20InMiddleFromNode1 [1785.529] 05007.05027> [1785.529] 05007.05027> TEST_LOOP_RANDOM_SEED="991825242" [1785.529] 05007.05027> [ OK ] HitTestTest.Hit20InMiddleFromNode1 (0 ms) [1785.529] 05007.05027> [ RUN ] HitTestTest.Hit20InMiddleFromNode2 [1785.529] 05007.05027> [1785.529] 05007.05027> TEST_LOOP_RANDOM_SEED="1694392510" [1785.530] 05007.05027> [ OK ] HitTestTest.Hit20InMiddleFromNode2 (0 ms) [1785.530] 05007.05027> [ RUN ] HitTestTest.Hit20InMiddleFromNode3 [1785.530] 05007.05027> [1785.530] 05007.05027> TEST_LOOP_RANDOM_SEED="3292556770" [1785.530] 05007.05027> [ OK ] HitTestTest.Hit20InMiddleFromNode3 (0 ms) [1785.530] 05007.05027> [ RUN ] HitTestTest.Hit20InMiddleFromNode5 [1785.530] 05007.05027> [1785.530] 05007.05027> TEST_LOOP_RANDOM_SEED="2677309928" [1785.530] 05007.05027> [ OK ] HitTestTest.Hit20InMiddleFromNode5 (1 ms) [1785.530] 05007.05027> [ RUN ] HitTestTest.Hit20InMiddleFromNode6 [1785.530] 05007.05027> [1785.530] 05007.05027> TEST_LOOP_RANDOM_SEED="4143669032" [1785.530] 05007.05027> [ OK ] HitTestTest.Hit20InMiddleFromNode6 (0 ms) [1785.530] 05007.05027> [ RUN ] HitTestTest.HitBoth10And20FromNode1 [1785.530] 05007.05027> [1785.530] 05007.05027> TEST_LOOP_RANDOM_SEED="4182800007" [1785.530] 05007.05027> [ OK ] HitTestTest.HitBoth10And20FromNode1 (0 ms) [1785.530] 05007.05027> [ RUN ] HitTestTest.HitBoth20And30FromNode1 [1785.530] 05007.05027> [1785.530] 05007.05027> TEST_LOOP_RANDOM_SEED="101851235" [1785.531] 05007.05027> [ OK ] HitTestTest.HitBoth20And30FromNode1 (0 ms) [1785.531] 05007.05027> [ RUN ] HitTestTest.SuppressNode25FromNode1 [1785.531] 05007.05027> [1785.531] 05007.05027> TEST_LOOP_RANDOM_SEED="2343558441" [1785.531] 05007.05027> [ OK ] HitTestTest.SuppressNode25FromNode1 (0 ms) [1785.531] 05007.05027> [ RUN ] HitTestTest.Clipping [1785.531] 05007.05027> [1785.531] 05007.05027> TEST_LOOP_RANDOM_SEED="3938814817" [1785.531] 05007.05027> [ OK ] HitTestTest.Clipping (1 ms) [1785.531] 05007.05027> [----------] 17 tests from HitTestTest (4 ms total) [1785.531] 05007.05027> [1785.531] 05007.05027> [----------] 7 tests from ImagePipeTest [1785.531] 05007.05027> [ RUN ] ImagePipeTest.ImagePipeImageIdMustNotBeZero [1785.531] 05007.05027> [1785.531] 05007.05027> TEST_LOOP_RANDOM_SEED="3895270360" [1785.531] 05007.05027> [WARNING:garnet/lib/ui/gfx/engine/engine.cc(138)] No FrameScheduler available; applying update immediately [1785.531] 05007.05027> [ OK ] ImagePipeTest.ImagePipeImageIdMustNotBeZero (0 ms) [1785.532] 05007.05027> [ RUN ] ImagePipeTest.PresentImagesOutOfOrder [1785.532] 05007.05027> [1785.532] 05007.05027> TEST_LOOP_RANDOM_SEED="1093380960" [1785.532] 05007.05027> [WARNING:garnet/lib/ui/gfx/engine/engine.cc(138)] No FrameScheduler available; applying update immediately [1785.532] 05007.05027> [INFO:image.cc(62)] Destroying Image with unowned VkImage (perhaps a swapchain image?) [1785.532] 05007.05027> [ OK ] ImagePipeTest.PresentImagesOutOfOrder (0 ms) [1785.532] 05007.05027> [ RUN ] ImagePipeTest.PresentImagesInOrder [1785.532] 05007.05027> [1785.532] 05007.05027> TEST_LOOP_RANDOM_SEED="3812439383" [1785.532] 05007.05027> [INFO:image.cc(62)] Destroying Image with unowned VkImage (perhaps a swapchain image?) [1785.532] 05007.05027> [ OK ] ImagePipeTest.PresentImagesInOrder (1 ms) [1785.532] 05007.05027> [ RUN ] ImagePipeTest.PresentImagesWithOffset [1785.532] 05007.05027> [1785.532] 05007.05027> TEST_LOOP_RANDOM_SEED="1474511164" [1785.532] 05007.05027> [INFO:image.cc(62)] Destroying Image with unowned VkImage (perhaps a swapchain image?) [1785.532] 05007.05027> [ OK ] ImagePipeTest.PresentImagesWithOffset (0 ms) [1785.532] 05007.05027> [ RUN ] ImagePipeTest.ImagePipePresentTwoFrames [1785.532] 05007.05027> [1785.532] 05007.05027> TEST_LOOP_RANDOM_SEED="28019751" [1785.533] 05007.05027> [WARNING:garnet/lib/ui/gfx/engine/engine.cc(138)] No FrameScheduler available; applying update immediately [1785.533] 05007.05027> [WARNING:garnet/lib/ui/gfx/engine/engine.cc(138)] No FrameScheduler available; applying update immediately [1785.533] 05007.05027> [INFO:image.cc(62)] Destroying Image with unowned VkImage (perhaps a swapchain image?) [1785.533] 05007.05027> [INFO:image.cc(62)] Destroying Image with unowned VkImage (perhaps a swapchain image?) [1785.533] 05007.05027> [ OK ] ImagePipeTest.ImagePipePresentTwoFrames (0 ms) [1785.533] 05007.05027> [ RUN ] ImagePipeTest.ImagePipeUpdateTwoFrames [1785.533] 05007.05027> [1785.533] 05007.05027> TEST_LOOP_RANDOM_SEED="2127607153" [1785.533] 05007.05027> [WARNING:garnet/lib/ui/gfx/engine/engine.cc(138)] No FrameScheduler available; applying update immediately [1785.533] 05007.05027> [WARNING:garnet/lib/ui/gfx/engine/engine.cc(138)] No FrameScheduler available; applying update immediately [1785.533] 05007.05027> [WARNING:garnet/lib/ui/gfx/engine/engine.cc(138)] No FrameScheduler available; applying update immediately [1785.533] 05007.05027> [WARNING:garnet/lib/ui/gfx/engine/engine.cc(138)] No FrameScheduler available; applying update immediately [1785.533] 05007.05027> [INFO:image.cc(62)] Destroying Image with unowned VkImage (perhaps a swapchain image?) [1785.533] 05007.05027> [INFO:image.cc(62)] Destroying Image with unowned VkImage (perhaps a swapchain image?) [1785.533] 05007.05027> [ OK ] ImagePipeTest.ImagePipeUpdateTwoFrames (1 ms) [1785.533] 05007.05027> [ RUN ] ImagePipeTest.ImagePipeRemoveImageThatIsPendingPresent [1785.533] 05007.05027> [1785.533] 05007.05027> TEST_LOOP_RANDOM_SEED="4210772777" [1785.533] 05007.05027> [WARNING:garnet/lib/ui/gfx/engine/engine.cc(138)] No FrameScheduler available; applying update immediately [1785.533] 05007.05027> [WARNING:garnet/lib/ui/gfx/engine/engine.cc(138)] No FrameScheduler available; applying update immediately [1785.533] 05007.05027> [INFO:image.cc(62)] Destroying Image with unowned VkImage (perhaps a swapchain image?) [1785.533] 05007.05027> [INFO:image.cc(62)] Destroying Image with unowned VkImage (perhaps a swapchain image?) [1785.534] 05007.05027> [ OK ] ImagePipeTest.ImagePipeRemoveImageThatIsPendingPresent (0 ms) [1785.534] 05007.05027> [----------] 7 tests from ImagePipeTest (2 ms total) [1785.534] 05007.05027> [1785.534] 05007.05027> [----------] 16 tests from ImportTest [1785.534] 05007.05027> [ RUN ] ImportTest.ExportsResourceViaCmd [1785.534] 05007.05027> [1785.534] 05007.05027> TEST_LOOP_RANDOM_SEED="781712353" [1785.534] 05007.05027> [ OK ] ImportTest.ExportsResourceViaCmd (0 ms) [1785.534] 05007.05027> [ RUN ] ImportTest.ImportsUnlinkedImportViaCmd [1785.534] 05007.05027> [1785.534] 05007.05027> TEST_LOOP_RANDOM_SEED="2705318942" [1785.534] 05007.05027> [ OK ] ImportTest.ImportsUnlinkedImportViaCmd (0 ms) [1785.534] 05007.05027> [ RUN ] ImportTest.PerformsFullLinking [1785.534] 05007.05027> [1785.534] 05007.05027> TEST_LOOP_RANDOM_SEED="3590515456" [1785.534] 05007.05027> [ OK ] ImportTest.PerformsFullLinking (0 ms) [1785.534] 05007.05027> [ RUN ] ImportTest.HandlesDeadSourceHandle [1785.534] 05007.05027> [1785.534] 05007.05027> TEST_LOOP_RANDOM_SEED="2599797327" [1785.534] 05007.05027> [ OK ] ImportTest.HandlesDeadSourceHandle (0 ms) [1785.534] 05007.05027> [ RUN ] ImportTest.HandlesDeadDestinationHandle [1785.534] 05007.05027> [1785.534] 05007.05027> TEST_LOOP_RANDOM_SEED="1200102577" [1785.534] 05007.05027> [ OK ] ImportTest.HandlesDeadDestinationHandle (0 ms) [1785.534] 05007.05027> [ RUN ] ImportTest.DestroyingExportedResourceSendsEvent [1785.534] 05007.05027> [1785.534] 05007.05027> TEST_LOOP_RANDOM_SEED="3535344758" [1785.534] 05007.05027> [ OK ] ImportTest.DestroyingExportedResourceSendsEvent (0 ms) [1785.534] 05007.05027> [ RUN ] ImportTest.ImportingNodeAfterDestroyingExportedResourceSendsEvent [1785.534] 05007.05027> [1785.534] 05007.05027> TEST_LOOP_RANDOM_SEED="1736916371" [1785.535] 05007.05027> [ OK ] ImportTest.ImportingNodeAfterDestroyingExportedResourceSendsEvent (0 ms) [1785.535] 05007.05027> [ RUN ] ImportTest.KillingImportedResourceEvictsFromResourceLinker [1785.535] 05007.05027> [1785.535] 05007.05027> TEST_LOOP_RANDOM_SEED="3440833005" [1785.535] 05007.05027> [ OK ] ImportTest.KillingImportedResourceEvictsFromResourceLinker (0 ms) [1785.535] 05007.05027> [ RUN ] ImportTest.ResourceUnexportedAfterImportsAndImportHandlesDie1 [1785.535] 05007.05027> [1785.535] 05007.05027> TEST_LOOP_RANDOM_SEED="335119006" [1785.535] 05007.05027> [ OK ] ImportTest.ResourceUnexportedAfterImportsAndImportHandlesDie1 (1 ms) [1785.535] 05007.05027> [ RUN ] ImportTest.ResourceUnexportedAfterImportsAndImportHandlesDie2 [1785.535] 05007.05027> [1785.535] 05007.05027> TEST_LOOP_RANDOM_SEED="884360294" [1785.535] 05007.05027> [ OK ] ImportTest.ResourceUnexportedAfterImportsAndImportHandlesDie2 (0 ms) [1785.535] 05007.05027> [ RUN ] ImportTest.ResourceUnexportedAfterImportsAndImportHandlesDie3 [1785.535] 05007.05027> [1785.535] 05007.05027> TEST_LOOP_RANDOM_SEED="537966728" [1785.535] 05007.05027> [ OK ] ImportTest.ResourceUnexportedAfterImportsAndImportHandlesDie3 (0 ms) [1785.535] 05007.05027> [ RUN ] ImportTest.ResourceUnexportedAfterImportsAndImportHandlesDie4 [1785.535] 05007.05027> [1785.535] 05007.05027> TEST_LOOP_RANDOM_SEED="3876518754" [1785.536] 05007.05027> [ OK ] ImportTest.ResourceUnexportedAfterImportsAndImportHandlesDie4 (0 ms) [1785.536] 05007.05027> [ RUN ] ImportTest.ProxiesCanBeFoundByTheirContainerOrTheirUnderlyingEntityType [1785.536] 05007.05027> [1785.536] 05007.05027> TEST_LOOP_RANDOM_SEED="3088782710" [1785.536] 05007.05027> [ OK ] ImportTest.ProxiesCanBeFoundByTheirContainerOrTheirUnderlyingEntityType (0 ms) [1785.536] 05007.05027> [ RUN ] ImportTest.UnlinkedImportedResourceCanAcceptCommands [1785.536] 05007.05027> [1785.536] 05007.05027> TEST_LOOP_RANDOM_SEED="728000909" [1785.536] 05007.05027> [ OK ] ImportTest.UnlinkedImportedResourceCanAcceptCommands (0 ms) [1785.536] 05007.05027> [ RUN ] ImportTest.LinkedResourceShouldBeAbleToAcceptCommands [1785.536] 05007.05027> [1785.536] 05007.05027> TEST_LOOP_RANDOM_SEED="4115285924" [1785.536] 05007.05027> [ OK ] ImportTest.LinkedResourceShouldBeAbleToAcceptCommands (1 ms) [1785.536] 05007.05027> [ RUN ] ImportTest.EmbedderCanEmbedNodesFromElsewhere [1785.536] 05007.05027> [1785.536] 05007.05027> TEST_LOOP_RANDOM_SEED="3723810598" [1785.536] 05007.05027> [ OK ] ImportTest.EmbedderCanEmbedNodesFromElsewhere (0 ms) [1785.536] 05007.05027> [----------] 16 tests from ImportTest (3 ms total) [1785.536] 05007.05027> [1785.536] 05007.05027> [----------] 1 test from MemoryTest [1785.536] 05007.05027> [ RUN ] MemoryTest.MemoryAllocationSizeValidation [1785.536] 05007.05027> [1785.536] 05007.05027> TEST_LOOP_RANDOM_SEED="364631276" [1785.536] 05007.05027> [ OK ] MemoryTest.MemoryAllocationSizeValidation (0 ms) [1785.536] 05007.05027> [----------] 1 test from MemoryTest (0 ms total) [1785.536] 05007.05027> [1785.536] 05007.05027> [----------] 4 tests from NodeTest [1785.536] 05007.05027> [ RUN ] NodeTest.Tagging [1785.536] 05007.05027> [1785.536] 05007.05027> TEST_LOOP_RANDOM_SEED="1397868834" [1785.537] 05007.05027> [ OK ] NodeTest.Tagging (0 ms) [1785.537] 05007.05027> [ RUN ] NodeTest.ShapeNodeMaterialAndShape [1785.537] 05007.05027> [1785.537] 05007.05027> TEST_LOOP_RANDOM_SEED="1484613919" [1785.537] 05007.05027> [ OK ] NodeTest.ShapeNodeMaterialAndShape (0 ms) [1785.537] 05007.05027> [ RUN ] NodeTest.NodesWithChildren [1785.537] 05007.05027> [1785.537] 05007.05027> TEST_LOOP_RANDOM_SEED="3976590809" [1785.537] 05007.05027> [ OK ] NodeTest.NodesWithChildren (0 ms) [1785.537] 05007.05027> [ RUN ] NodeTest.SettingHitTestBehavior [1785.537] 05007.05027> [1785.537] 05007.05027> TEST_LOOP_RANDOM_SEED="278455058" [1785.537] 05007.05027> [ OK ] NodeTest.SettingHitTestBehavior (1 ms) [1785.537] 05007.05027> [----------] 4 tests from NodeTest (1 ms total) [1785.537] 05007.05027> [1785.537] 05007.05027> [----------] 21 tests from ObjectLinkerTest [1785.537] 05007.05027> [ RUN ] ObjectLinkerTest.InitialState [1785.537] 05007.05027> [1785.537] 05007.05027> TEST_LOOP_RANDOM_SEED="2376379301" [1785.537] 05007.05027> [ OK ] ObjectLinkerTest.InitialState (0 ms) [1785.537] 05007.05027> [ RUN ] ObjectLinkerTest.AllowsExport [1785.537] 05007.05027> [1785.537] 05007.05027> TEST_LOOP_RANDOM_SEED="512968031" [1785.537] 05007.05027> [ OK ] ObjectLinkerTest.AllowsExport (0 ms) [1785.537] 05007.05027> [ RUN ] ObjectLinkerTest.CannotExportInvalidToken [1785.537] 05007.05027> [1785.537] 05007.05027> TEST_LOOP_RANDOM_SEED="1408149031" [1785.537] 05007.05027> [ OK ] ObjectLinkerTest.CannotExportInvalidToken (0 ms) [1785.537] 05007.05027> [ RUN ] ObjectLinkerTest.CannotExportWithDeadExportToken [1785.537] 05007.05027> [1785.537] 05007.05027> TEST_LOOP_RANDOM_SEED="1471077047" [1785.537] 05007.05027> [ OK ] ObjectLinkerTest.CannotExportWithDeadExportToken (0 ms) [1785.537] 05007.05027> [ RUN ] ObjectLinkerTest.CanExportWithDeadImportToken [1785.537] 05007.05027> [1785.537] 05007.05027> TEST_LOOP_RANDOM_SEED="2843573291" [1785.537] 05007.05027> [ OK ] ObjectLinkerTest.CanExportWithDeadImportToken (0 ms) [1785.537] 05007.05027> [ RUN ] ObjectLinkerTest.CannotExportSameTokenTwice [1785.537] 05007.05027> [1785.537] 05007.05027> TEST_LOOP_RANDOM_SEED="3477541865" [1785.537] 05007.05027> [ OK ] ObjectLinkerTest.CannotExportSameTokenTwice (0 ms) [1785.537] 05007.05027> [ RUN ] ObjectLinkerTest.LinkDeathRemovesExport [1785.537] 05007.05027> [1785.537] 05007.05027> TEST_LOOP_RANDOM_SEED="4253188755" [1785.537] 05007.05027> [ OK ] ObjectLinkerTest.LinkDeathRemovesExport (0 ms) [1785.537] 05007.05027> [ RUN ] ObjectLinkerTest.AllowsImport [1785.537] 05007.05027> [1785.537] 05007.05027> TEST_LOOP_RANDOM_SEED="1662460656" [1785.537] 05007.05027> [ OK ] ObjectLinkerTest.AllowsImport (0 ms) [1785.537] 05007.05027> [ RUN ] ObjectLinkerTest.CannotImportInvalidToken [1785.537] 05007.05027> [1785.537] 05007.05027> TEST_LOOP_RANDOM_SEED="3872531366" [1785.537] 05007.05027> [ OK ] ObjectLinkerTest.CannotImportInvalidToken (0 ms) [1785.537] 05007.05027> [ RUN ] ObjectLinkerTest.CannotImportWithDeadImportToken [1785.537] 05007.05027> [1785.537] 05007.05027> TEST_LOOP_RANDOM_SEED="2688239930" [1785.537] 05007.05027> [ OK ] ObjectLinkerTest.CannotImportWithDeadImportToken (0 ms) [1785.537] 05007.05027> [ RUN ] ObjectLinkerTest.CanImportWithDeadExportToken [1785.537] 05007.05027> [1785.537] 05007.05027> TEST_LOOP_RANDOM_SEED="154002080" [1785.537] 05007.05027> [ OK ] ObjectLinkerTest.CanImportWithDeadExportToken (0 ms) [1785.537] 05007.05027> [ RUN ] ObjectLinkerTest.CannotImportSameTokenTwice [1785.537] 05007.05027> [1785.537] 05007.05027> TEST_LOOP_RANDOM_SEED="3323006116" [1785.537] 05007.05027> [ OK ] ObjectLinkerTest.CannotImportSameTokenTwice (0 ms) [1785.537] 05007.05027> [ RUN ] ObjectLinkerTest.LinkDeathRemovesImport [1785.537] 05007.05027> [1785.537] 05007.05027> TEST_LOOP_RANDOM_SEED="3296137715" [1785.537] 05007.05027> [ OK ] ObjectLinkerTest.LinkDeathRemovesImport (0 ms) [1785.537] 05007.05027> [ RUN ] ObjectLinkerTest.InitializingLinkTwiceCausesDeath [1785.537] 05007.05027> [1785.537] 05007.05027> TEST_LOOP_RANDOM_SEED="1461409501" [1785.537] 05007.05027> [1785.537] 05007.05027> [WARNING] ../../garnet/lib/ui/gfx/tests/object_linker_unittest.cc:264:: Death tests are not supported on this platform. [1785.538] 05007.05027> Statement 'export_link.Initialize(&export_obj2, std::bind( []() { switch (0) case 0: default: if (const ::testing::AssertionResult gtest_ar_ = ::testing::AssertionResult(false)) ; else ::testing::internal::AssertHelper(::tes [1785.538] 05007.05027> ting::TestPartResult::kNonFatalFailure, "../../garnet/lib/ui/gfx/tests/object_linker_unittest.cc", 262, ::testing::internal::GetBoolAssertionFailureMessage( gtest_ar_, "false", "false", "true").c_str()) = ::testing::Message( [1785.538] 05007.05027> ) << "Delegate called unexpectedly: " << "export.link_resolved"; }), std::bind( []() { switch (0) case 0: default: if (const ::testing::AssertionResult gtest_ar_ = ::testing::AssertionResult(false)) ; else ::testing::interna [1785.538] 05007.05027> l::AssertHelper(::testing::TestPartResult::kNonFatalFailure, "../../garnet/lib/ui/gfx/tests/object_linker_unittest.cc", 263, ::testing::internal::GetBoolAssertionFailureMessage( gtest_ar_, "false", "false", "true").c_str()) [1785.538] 05007.05027> = ::testing::Message() << "Delegate called unexpectedly: " << "export.link_disconnected"; }))' cannot be verified. [1785.538] 05007.05027> [ OK ] ObjectLinkerTest.InitializingLinkTwiceCausesDeath (0 ms) [1785.538] 05007.05027> [ RUN ] ObjectLinkerTest.InitializeLinksMatchingPeers [1785.538] 05007.05027> [1785.538] 05007.05027> TEST_LOOP_RANDOM_SEED="2708763457" [1785.538] 05007.05027> [ OK ] ObjectLinkerTest.InitializeLinksMatchingPeers (0 ms) [1785.538] 05007.05027> [ RUN ] ObjectLinkerTest.InitializeLinksMatchingPeersWithImportBeforeExport [1785.538] 05007.05027> [1785.538] 05007.05027> TEST_LOOP_RANDOM_SEED="2549334560" [1785.538] 05007.05027> [ OK ] ObjectLinkerTest.InitializeLinksMatchingPeersWithImportBeforeExport (0 ms) [1785.538] 05007.05027> [ RUN ] ObjectLinkerTest.InitializeDoesNotLinkNonMatchingPeers [1785.538] 05007.05027> [1785.538] 05007.05027> TEST_LOOP_RANDOM_SEED="175881532" [1785.538] 05007.05027> [ OK ] ObjectLinkerTest.InitializeDoesNotLinkNonMatchingPeers (0 ms) [1785.538] 05007.05027> [ RUN ] ObjectLinkerTest.EarlyImportTokenDeathCausesExportDisconnection [1785.538] 05007.05027> [1785.538] 05007.05027> TEST_LOOP_RANDOM_SEED="1106015771" [1785.538] 05007.05027> [ OK ] ObjectLinkerTest.EarlyImportTokenDeathCausesExportDisconnection (0 ms) [1785.538] 05007.05027> [ RUN ] ObjectLinkerTest.ImportTokenDeathCausesExportDisconnection [1785.538] 05007.05027> [1785.538] 05007.05027> TEST_LOOP_RANDOM_SEED="2930789577" [1785.538] 05007.05027> [ OK ] ObjectLinkerTest.ImportTokenDeathCausesExportDisconnection (0 ms) [1785.538] 05007.05027> [ RUN ] ObjectLinkerTest.EarlyExportTokenDeathCausesImportDisconnection [1785.538] 05007.05027> [1785.538] 05007.05027> TEST_LOOP_RANDOM_SEED="494436602" [1785.538] 05007.05027> [ OK ] ObjectLinkerTest.EarlyExportTokenDeathCausesImportDisconnection (0 ms) [1785.538] 05007.05027> [ RUN ] ObjectLinkerTest.ExportTokenDeathCausesImportDisconnection [1785.538] 05007.05027> [1785.538] 05007.05027> TEST_LOOP_RANDOM_SEED="3281727107" [1785.538] 05007.05027> [ OK ] ObjectLinkerTest.ExportTokenDeathCausesImportDisconnection (0 ms) [1785.538] 05007.05027> [----------] 21 tests from ObjectLinkerTest (0 ms total) [1785.538] 05007.05027> [1785.538] 05007.05027> [----------] 15 tests from ResourceLinkerTest [1785.538] 05007.05027> [ RUN ] ResourceLinkerTest.AllowsExport [1785.538] 05007.05027> [1785.538] 05007.05027> TEST_LOOP_RANDOM_SEED="494895474" [1785.538] 05007.05027> [ OK ] ResourceLinkerTest.AllowsExport (1 ms) [1785.538] 05007.05027> [ RUN ] ResourceLinkerTest.AllowsImport [1785.538] 05007.05027> [1785.538] 05007.05027> TEST_LOOP_RANDOM_SEED="2060719848" [1785.538] 05007.05027> [ OK ] ResourceLinkerTest.AllowsImport (0 ms) [1785.538] 05007.05027> [ RUN ] ResourceLinkerTest.CannotImportWithDeadSourceAndDestinationHandles [1785.538] 05007.05027> [1785.538] 05007.05027> TEST_LOOP_RANDOM_SEED="1099032555" [1785.540] 05007.05027> [ OK ] ResourceLinkerTest.CannotImportWithDeadSourceAndDestinationHandles (1 ms) [1785.540] 05007.05027> [ RUN ] ResourceLinkerTest.CannotImportWithDeadDestinationHandles [1785.540] 05007.05027> [1785.540] 05007.05027> TEST_LOOP_RANDOM_SEED="2403170343" [1785.540] 05007.05027> [ OK ] ResourceLinkerTest.CannotImportWithDeadDestinationHandles (1 ms) [1785.540] 05007.05027> [ RUN ] ResourceLinkerTest.CanImportWithDeadSourceHandle [1785.540] 05007.05027> [1785.540] 05007.05027> TEST_LOOP_RANDOM_SEED="1497403701" [1785.540] 05007.05027> [ OK ] ResourceLinkerTest.CanImportWithDeadSourceHandle (0 ms) [1785.540] 05007.05027> [ RUN ] ResourceLinkerTest.CannotExportWithDeadSourceAndDestinationHandles [1785.540] 05007.05027> [1785.540] 05007.05027> TEST_LOOP_RANDOM_SEED="2147959370" [1785.540] 05007.05027> [ OK ] ResourceLinkerTest.CannotExportWithDeadSourceAndDestinationHandles (0 ms) [1785.540] 05007.05027> [ RUN ] ResourceLinkerTest.CannotExportWithDeadSourceHandle [1785.540] 05007.05027> [1785.540] 05007.05027> TEST_LOOP_RANDOM_SEED="1294210124" [1785.540] 05007.05027> [ OK ] ResourceLinkerTest.CannotExportWithDeadSourceHandle (0 ms) [1785.540] 05007.05027> [ RUN ] ResourceLinkerTest.CanExportWithDeadDestinationHandle [1785.540] 05007.05027> [1785.540] 05007.05027> TEST_LOOP_RANDOM_SEED="1672277556" [1785.541] 05007.05027> [ OK ] ResourceLinkerTest.CanExportWithDeadDestinationHandle (0 ms) [1785.541] 05007.05027> [ RUN ] ResourceLinkerTest.DestinationHandleDeathAutomaticallyCleansUpResourceExport [1785.541] 05007.05027> [1785.541] 05007.05027> TEST_LOOP_RANDOM_SEED="1236318552" [1785.541] 05007.05027> [ OK ] ResourceLinkerTest.DestinationHandleDeathAutomaticallyCleansUpResourceExport (0 ms) [1785.541] 05007.05027> [ RUN ] ResourceLinkerTest.SourceHandleDeathAutomaticallyCleansUpUnresolvedImports [1785.541] 05007.05027> [1785.541] 05007.05027> TEST_LOOP_RANDOM_SEED="2812489515" [1785.541] 05007.05027> [ OK ] ResourceLinkerTest.SourceHandleDeathAutomaticallyCleansUpUnresolvedImports (0 ms) [1785.541] 05007.05027> [ RUN ] ResourceLinkerTest.ResourceDeathAutomaticallyCleansUpResourceExport [1785.541] 05007.05027> [1785.541] 05007.05027> TEST_LOOP_RANDOM_SEED="1949160491" [1785.541] 05007.05027> [ OK ] ResourceLinkerTest.ResourceDeathAutomaticallyCleansUpResourceExport (1 ms) [1785.541] 05007.05027> [ RUN ] ResourceLinkerTest.ImportsBeforeExportsAreServiced [1785.541] 05007.05027> [1785.541] 05007.05027> TEST_LOOP_RANDOM_SEED="538764092" [1785.541] 05007.05027> [ OK ] ResourceLinkerTest.ImportsBeforeExportsAreServiced (0 ms) [1785.541] 05007.05027> [ RUN ] ResourceLinkerTest.ImportAfterReleasedExportedResourceFails [1785.541] 05007.05027> [1785.541] 05007.05027> TEST_LOOP_RANDOM_SEED="4174776623" [1785.541] 05007.05027> [ OK ] ResourceLinkerTest.ImportAfterReleasedExportedResourceFails (0 ms) [1785.541] 05007.05027> [ RUN ] ResourceLinkerTest.DuplicatedDestinationHandlesAllowMultipleImports [1785.541] 05007.05027> [1785.541] 05007.05027> TEST_LOOP_RANDOM_SEED="1132480739" [1785.542] 05007.05027> [ OK ] ResourceLinkerTest.DuplicatedDestinationHandlesAllowMultipleImports (1 ms) [1785.542] 05007.05027> [ RUN ] ResourceLinkerTest.UnresolvedImportIsRemovedIfDestroyed [1785.542] 05007.05027> [1785.542] 05007.05027> TEST_LOOP_RANDOM_SEED="3466082327" [1785.543] 05007.05027> [ OK ] ResourceLinkerTest.UnresolvedImportIsRemovedIfDestroyed (0 ms) [1785.543] 05007.05027> [----------] 15 tests from ResourceLinkerTest (5 ms total) [1785.543] 05007.05027> [1785.543] 05007.05027> [----------] 6 tests from SessionTest [1785.543] 05007.05027> [ RUN ] SessionTest.ScheduleUpdateOutOfOrder [1785.543] 05007.05027> [1785.543] 05007.05027> TEST_LOOP_RANDOM_SEED="2151050919" [1785.543] 05007.05027> [ OK ] SessionTest.ScheduleUpdateOutOfOrder (0 ms) [1785.543] 05007.05027> [ RUN ] SessionTest.ScheduleUpdateInOrder [1785.543] 05007.05027> [1785.543] 05007.05027> TEST_LOOP_RANDOM_SEED="4263965160" [1785.543] 05007.05027> [ OK ] SessionTest.ScheduleUpdateInOrder (0 ms) [1785.543] 05007.05027> [ RUN ] SessionTest.ResourceIdAlreadyUsed [1785.543] 05007.05027> [1785.543] 05007.05027> TEST_LOOP_RANDOM_SEED="3917375983" [1785.543] 05007.05027> [ OK ] SessionTest.ResourceIdAlreadyUsed (1 ms) [1785.543] 05007.05027> [ RUN ] SessionTest.AddAndRemoveResource [1785.543] 05007.05027> [1785.543] 05007.05027> TEST_LOOP_RANDOM_SEED="4107698547" [1785.543] 05007.05027> [ OK ] SessionTest.AddAndRemoveResource (0 ms) [1785.543] 05007.05027> [ RUN ] SessionTest.Labeling [1785.543] 05007.05027> [1785.543] 05007.05027> TEST_LOOP_RANDOM_SEED="1861682671" [1785.543] 05007.05027> [ OK ] SessionTest.Labeling (0 ms) [1785.543] 05007.05027> [ RUN ] SessionTest.SendingSizeChangeEventWorks [1785.543] 05007.05027> [1785.543] 05007.05027> TEST_LOOP_RANDOM_SEED="3557799079" [1785.543] 05007.05027> [ OK ] SessionTest.SendingSizeChangeEventWorks (0 ms) [1785.543] 05007.05027> [----------] 6 tests from SessionTest (1 ms total) [1785.543] 05007.05027> [1785.543] 05007.05027> [----------] 2 tests from ShapeTest [1785.543] 05007.05027> [ RUN ] ShapeTest.Circle [1785.543] 05007.05027> [1785.543] 05007.05027> TEST_LOOP_RANDOM_SEED="1329283743" [1785.544] 05007.05027> [ OK ] ShapeTest.Circle (0 ms) [1785.544] 05007.05027> [ RUN ] ShapeTest.Rectangle [1785.544] 05007.05027> [1785.544] 05007.05027> TEST_LOOP_RANDOM_SEED="756215055" [1785.544] 05007.05027> [ OK ] ShapeTest.Rectangle (0 ms) [1785.544] 05007.05027> [----------] 2 tests from ShapeTest (0 ms total) [1785.544] 05007.05027> [1785.544] 05007.05027> [----------] 1 test from StereoCameraTest [1785.544] 05007.05027> [ RUN ] StereoCameraTest.Basic [1785.544] 05007.05027> [1785.544] 05007.05027> TEST_LOOP_RANDOM_SEED="3945202863" [1785.544] 05007.05027> [ OK ] StereoCameraTest.Basic (0 ms) [1785.544] 05007.05027> [----------] 1 test from StereoCameraTest (1 ms total) [1785.544] 05007.05027> [1785.544] 05007.05027> [----------] 17 tests from ViewTest [1785.544] 05007.05027> [ RUN ] ViewTest.CreateViewWithBadTokenDies [1785.544] 05007.05027> [1785.544] 05007.05027> TEST_LOOP_RANDOM_SEED="619132320" [1785.544] 05007.05027> [1785.544] 05007.05027> [WARNING] ../../garnet/lib/ui/gfx/tests/view_unittest.cc:25:: Death tests are not supported on this platform. [1785.544] 05007.05027> Statement 'Apply(scenic::NewCreateViewCmd(1, zx::eventpair(), ""))' cannot be verified. [1785.544] 05007.05027> [1785.544] 05007.05027> [WARNING] ../../garnet/lib/ui/gfx/tests/view_unittest.cc:27:: Death tests are not supported on this platform. [1785.544] 05007.05027> Statement 'Apply(scenic::NewCreateViewHolderCmd(2, zx::eventpair(), ""))' cannot be verified. [1785.544] 05007.05027> [ OK ] ViewTest.CreateViewWithBadTokenDies (0 ms) [1785.544] 05007.05027> [ RUN ] ViewTest.Children [1785.544] 05007.05027> [1785.544] 05007.05027> TEST_LOOP_RANDOM_SEED="851935474" [1785.544] 05007.05027> [ OK ] ViewTest.Children (0 ms) [1785.544] 05007.05027> [ RUN ] ViewTest.ExportsViewHolderViaCmd [1785.544] 05007.05027> [1785.544] 05007.05027> TEST_LOOP_RANDOM_SEED="2106685293" [1785.544] 05007.05027> [ OK ] ViewTest.ExportsViewHolderViaCmd (0 ms) [1785.544] 05007.05027> [ RUN ] ViewTest.ImportsViewViaCmd [1785.544] 05007.05027> [1785.544] 05007.05027> TEST_LOOP_RANDOM_SEED="3244485776" [1785.545] 05007.05027> [ OK ] ViewTest.ImportsViewViaCmd (0 ms) [1785.545] 05007.05027> [ RUN ] ViewTest.PairedViewAndHolderAreLinked [1785.545] 05007.05027> [1785.545] 05007.05027> TEST_LOOP_RANDOM_SEED="3192289268" [1785.545] 05007.05027> [ OK ] ViewTest.PairedViewAndHolderAreLinked (0 ms) [1785.545] 05007.05027> [ RUN ] ViewTest.ExportViewHolderWithDeadHandleFails [1785.545] 05007.05027> [1785.545] 05007.05027> TEST_LOOP_RANDOM_SEED="171823570" [1785.545] 05007.05027> [ OK ] ViewTest.ExportViewHolderWithDeadHandleFails (1 ms) [1785.545] 05007.05027> [ RUN ] ViewTest.ViewHolderDestroyedBeforeView [1785.545] 05007.05027> [1785.545] 05007.05027> TEST_LOOP_RANDOM_SEED="4211283889" [1785.545] 05007.05027> [ OK ] ViewTest.ViewHolderDestroyedBeforeView (0 ms) [1785.545] 05007.05027> [ RUN ] ViewTest.ViewDestroyedBeforeViewHolder [1785.545] 05007.05027> [1785.545] 05007.05027> TEST_LOOP_RANDOM_SEED="1825182975" [1785.545] 05007.05027> [ OK ] ViewTest.ViewDestroyedBeforeViewHolder (0 ms) [1785.545] 05007.05027> [ RUN ] ViewTest.ViewHolderConnectsToScene [1785.545] 05007.05027> [1785.545] 05007.05027> TEST_LOOP_RANDOM_SEED="3152253381" [1785.545] 05007.05027> [ OK ] ViewTest.ViewHolderConnectsToScene (0 ms) [1785.545] 05007.05027> [ RUN ] ViewTest.ViewHolderGrandchildGetsSceneRefreshed [1785.545] 05007.05027> [1785.545] 05007.05027> TEST_LOOP_RANDOM_SEED="1808356581" [1785.546] 05007.05027> [ OK ] ViewTest.ViewHolderGrandchildGetsSceneRefreshed (0 ms) [1785.546] 05007.05027> [ RUN ] ViewTest.ViewLinksAfterViewHolderConnectsToScene [1785.546] 05007.05027> [1785.546] 05007.05027> TEST_LOOP_RANDOM_SEED="2723453963" [1785.546] 05007.05027> [ OK ] ViewTest.ViewLinksAfterViewHolderConnectsToScene (0 ms) [1785.546] 05007.05027> [ RUN ] ViewTest.ViewStateChangeNotifiesViewHolder [1785.546] 05007.05027> [1785.546] 05007.05027> TEST_LOOP_RANDOM_SEED="1753499177" [1785.546] 05007.05027> [ OK ] ViewTest.ViewStateChangeNotifiesViewHolder (0 ms) [1785.546] 05007.05027> [ RUN ] ViewTest.RenderStateAcrossManyFrames [1785.546] 05007.05027> [1785.546] 05007.05027> TEST_LOOP_RANDOM_SEED="3357556215" [1785.546] 05007.05027> [ OK ] ViewTest.RenderStateAcrossManyFrames (1 ms) [1785.546] 05007.05027> [ RUN ] ViewTest.RenderStateFalseWhenViewDisconnects [1785.546] 05007.05027> [1785.546] 05007.05027> TEST_LOOP_RANDOM_SEED="2287407876" [1785.546] 05007.05027> [ OK ] ViewTest.RenderStateFalseWhenViewDisconnects (0 ms) [1785.546] 05007.05027> [ RUN ] ViewTest.ViewHolderRenderWaitClearedWhenViewDestroyed [1785.546] 05007.05027> [1785.546] 05007.05027> TEST_LOOP_RANDOM_SEED="3871744979" [1785.546] 05007.05027> [ OK ] ViewTest.ViewHolderRenderWaitClearedWhenViewDestroyed (0 ms) [1785.546] 05007.05027> [ RUN ] ViewTest.RenderSignalDoesntCrashWhenViewHolderDestroyed [1785.546] 05007.05027> [1785.546] 05007.05027> TEST_LOOP_RANDOM_SEED="1569274248" [1785.547] 05007.05027> [ OK ] ViewTest.RenderSignalDoesntCrashWhenViewHolderDestroyed (0 ms) [1785.547] 05007.05027> [ RUN ] ViewTest.RenderStateFalseWhenViewHolderDisconnectsFromScene [1785.547] 05007.05027> [1785.547] 05007.05027> TEST_LOOP_RANDOM_SEED="2406289670" [1785.547] 05007.05027> [ OK ] ViewTest.RenderStateFalseWhenViewHolderDisconnectsFromScene (0 ms) [1785.547] 05007.05027> [----------] 17 tests from ViewTest (2 ms total) [1785.547] 05007.05027> [1785.547] 05007.05027> [----------] Global test environment tear-down [1785.547] 05007.05027> [==========] 113 tests from 13 test cases ran. (20 ms total) [1785.547] 05007.05027> [ PASSED ] 113 tests. [1785.547] 05007.05027> [1785.547] 05007.05027> YOU HAVE 2 DISABLED TESTS [1785.547] 05007.05027> [1785.548] 05007.05027> PASSED: /pkgfs/packages/scenic_tests/0/test/gfx_unittests passed [1785.548] 05007.05027> [1785.548] 05007.05027> ------------------------------------------------ [1785.548] 05007.05027> RUNNING TEST: /pkgfs/packages/scenic_tests/0/test/input_apptests [1785.548] 05007.05027> [1785.566] 05007.05027> Running main() from gtest_main.cc [1785.566] 05007.05027> [==========] Running 6 tests from 3 test cases. [1785.566] 05007.05027> [----------] Global test environment set-up. [1785.566] 05007.05027> [----------] 2 tests from InputInterfaceTest [1785.566] 05007.05027> [ RUN ] InputInterfaceTest.RegisterKeyboardTest [1785.566] 05007.05027> [1785.566] 05007.05027> TEST_LOOP_RANDOM_SEED="473433746" [1785.566] 05007.05027> [ OK ] InputInterfaceTest.RegisterKeyboardTest (0 ms) [1785.566] 05007.05027> [ RUN ] InputInterfaceTest.InputKeyboardTest [1785.566] 05007.05027> [1785.566] 05007.05027> TEST_LOOP_RANDOM_SEED="581814238" [1785.566] 05007.05027> [ OK ] InputInterfaceTest.InputKeyboardTest (0 ms) [1785.566] 05007.05027> [----------] 2 tests from InputInterfaceTest (0 ms total) [1785.566] 05007.05027> [1785.566] 05007.05027> [----------] 2 tests from ReaderInterpreterTest [1785.566] 05007.05027> [ RUN ] ReaderInterpreterTest.RegisterKeyboardTest [1785.566] 05007.05027> [1785.566] 05007.05027> TEST_LOOP_RANDOM_SEED="4088266615" [1785.567] 05007.05027> [ OK ] ReaderInterpreterTest.RegisterKeyboardTest (0 ms) [1785.567] 05007.05027> [ RUN ] ReaderInterpreterTest.RemoveKeyboardTest [1785.567] 05007.05027> [1785.567] 05007.05027> TEST_LOOP_RANDOM_SEED="2934619667" [1785.567] 05007.05027> [ERROR:garnet/bin/ui/input_reader/input_interpreter.cc(490)] Failed to read from input: -1 for MockHidDecoder [1785.567] 05007.05027> [ OK ] ReaderInterpreterTest.RemoveKeyboardTest (0 ms) [1785.567] 05007.05027> [----------] 2 tests from ReaderInterpreterTest (0 ms total) [1785.567] 05007.05027> [1785.567] 05007.05027> [----------] 2 tests from ReaderInterpreterInputTest [1785.567] 05007.05027> [ RUN ] ReaderInterpreterInputTest.KeyboardTest [1785.567] 05007.05027> [1785.567] 05007.05027> TEST_LOOP_RANDOM_SEED="1852260944" [1785.567] 05007.05027> [ OK ] ReaderInterpreterInputTest.KeyboardTest (0 ms) [1785.567] 05007.05027> [ RUN ] ReaderInterpreterInputTest.LightSensorTest [1785.567] 05007.05027> [1785.567] 05007.05027> TEST_LOOP_RANDOM_SEED="2073240870" [1785.567] 05007.05027> [ OK ] ReaderInterpreterInputTest.LightSensorTest (0 ms) [1785.567] 05007.05027> [----------] 2 tests from ReaderInterpreterInputTest (1 ms total) [1785.567] 05007.05027> [1785.567] 05007.05027> [----------] Global test environment tear-down [1785.567] 05007.05027> [==========] 6 tests from 3 test cases ran. (1 ms total) [1785.567] 05007.05027> [ PASSED ] 6 tests. [1785.568] 05007.05027> PASSED: /pkgfs/packages/scenic_tests/0/test/input_apptests passed [1785.568] 05007.05027> [1785.568] 05007.05027> ------------------------------------------------ [1785.568] 05007.05027> RUNNING TEST: /pkgfs/packages/scenic_tests/0/test/input_tool_unittests [1785.568] 05007.05027> [1785.576] 05007.05027> Running main() from gtest_main.cc [1785.576] 05007.05027> [==========] Running 5 tests from 1 test case. [1785.576] 05007.05027> [----------] Global test environment set-up. [1785.576] 05007.05027> [----------] 5 tests from InverseKeymapTest [1785.576] 05007.05027> [ RUN ] InverseKeymapTest.PlainKey [1785.576] 05007.05027> [ OK ] InverseKeymapTest.PlainKey (0 ms) [1785.576] 05007.05027> [ RUN ] InverseKeymapTest.ShiftKey [1785.576] 05007.05027> [ OK ] InverseKeymapTest.ShiftKey (0 ms) [1785.576] 05007.05027> [ RUN ] InverseKeymapTest.Lowercase [1785.577] 05007.05027> [ OK ] InverseKeymapTest.Lowercase (0 ms) [1785.577] 05007.05027> [ RUN ] InverseKeymapTest.Sentence [1785.577] 05007.05027> [ OK ] InverseKeymapTest.Sentence (0 ms) [1785.577] 05007.05027> [ RUN ] InverseKeymapTest.HoldShift [1785.577] 05007.05027> [ OK ] InverseKeymapTest.HoldShift (0 ms) [1785.577] 05007.05027> [----------] 5 tests from InverseKeymapTest (0 ms total) [1785.577] 05007.05027> [1785.577] 05007.05027> [----------] Global test environment tear-down [1785.577] 05007.05027> [==========] 5 tests from 1 test case ran. (1 ms total) [1785.577] 05007.05027> [ PASSED ] 5 tests. [1785.577] 05007.05027> PASSED: /pkgfs/packages/scenic_tests/0/test/input_tool_unittests passed [1785.577] 05007.05027> [1785.577] 05007.05027> ------------------------------------------------ [1785.577] 05007.05027> RUNNING TEST: /pkgfs/packages/scenic_tests/0/test/input_unittests [1785.577] 05007.05027> [1785.658] 05007.05027> [==========] Running 7 tests from 5 test cases. [1785.658] 05007.05027> [----------] Global test environment set-up. [1785.658] 05007.05027> [----------] 1 test from CoordinateTransformTest [1785.658] 05007.05027> [ RUN ] CoordinateTransformTest.CoordinateTransform [1785.658] 05007.05027> [1785.658] 05007.05027> TEST_LOOP_RANDOM_SEED="1593661479" [1785.658] 05007.05027> [INFO:gfx_system.cc(176)] No Vulkan found, but using a test-only "display". [1785.659] 05007.05027> [INFO:input_system.cc(236)] Scenic input system initialized. [1785.659] 05007.05027> [ERROR:garnet/lib/ui/input/input_system.cc(233)] Scenic lost connection to TextSync [1785.662] 05007.05027> [ OK ] CoordinateTransformTest.CoordinateTransform (3 ms) [1785.662] 05007.05027> [----------] 1 test from CoordinateTransformTest (3 ms total) [1785.662] 05007.05027> [1785.662] 05007.05027> [----------] 2 tests from FocusAvoidanceTest [1785.662] 05007.05027> [ RUN ] FocusAvoidanceTest.ViewHierarchyByViewManager [1785.662] 05007.05027> [1785.662] 05007.05027> TEST_LOOP_RANDOM_SEED="1082073049" [1785.662] 05007.05027> [INFO:gfx_system.cc(176)] No Vulkan found, but using a test-only "display". [1785.662] 05007.05027> [INFO:input_system.cc(236)] Scenic input system initialized. [1785.662] 05007.05027> [ERROR:garnet/lib/ui/input/input_system.cc(233)] Scenic lost connection to TextSync [1785.665] 05007.05027> [ OK ] FocusAvoidanceTest.ViewHierarchyByViewManager (4 ms) [1785.665] 05007.05027> [ RUN ] FocusAvoidanceTest.ViewHierarchyByScenic [1785.665] 05007.05027> [1785.665] 05007.05027> TEST_LOOP_RANDOM_SEED="2530589321" [1785.666] 05007.05027> [INFO:gfx_system.cc(176)] No Vulkan found, but using a test-only "display". [1785.666] 05007.05027> [INFO:input_system.cc(236)] Scenic input system initialized. [1785.666] 05007.05027> [ERROR:garnet/lib/ui/input/input_system.cc(233)] Scenic lost connection to TextSync [1785.668] 05007.05027> [ OK ] FocusAvoidanceTest.ViewHierarchyByScenic (2 ms) [1785.668] 05007.05027> [----------] 2 tests from FocusAvoidanceTest (6 ms total) [1785.668] 05007.05027> [1785.668] 05007.05027> [----------] 1 test from HardKeyboardDeliveryTest [1785.668] 05007.05027> [ RUN ] HardKeyboardDeliveryTest.Test [1785.668] 05007.05027> [1785.668] 05007.05027> TEST_LOOP_RANDOM_SEED="583751752" [1785.668] 05007.05027> [INFO:gfx_system.cc(176)] No Vulkan found, but using a test-only "display". [1785.668] 05007.05027> [INFO:input_system.cc(236)] Scenic input system initialized. [1785.668] 05007.05027> [ERROR:garnet/lib/ui/input/input_system.cc(233)] Scenic lost connection to TextSync [1785.670] 05007.05027> [ OK ] HardKeyboardDeliveryTest.Test (2 ms) [1785.670] 05007.05027> [----------] 1 test from HardKeyboardDeliveryTest (2 ms total) [1785.670] 05007.05027> [1785.670] 05007.05027> [----------] 1 test from ImportNodeTest [1785.670] 05007.05027> [ RUN ] ImportNodeTest.ImportNodeEventDelivery [1785.670] 05007.05027> [1785.670] 05007.05027> TEST_LOOP_RANDOM_SEED="1000897515" [1785.670] 05007.05027> [INFO:gfx_system.cc(176)] No Vulkan found, but using a test-only "display". [1785.670] 05007.05027> [INFO:input_system.cc(236)] Scenic input system initialized. [1785.670] 05007.05027> [ERROR:garnet/lib/ui/input/input_system.cc(233)] Scenic lost connection to TextSync [1785.671] 05007.05027> [ OK ] ImportNodeTest.ImportNodeEventDelivery (2 ms) [1785.671] 05007.05027> [----------] 1 test from ImportNodeTest (2 ms total) [1785.671] 05007.05027> [1785.671] 05007.05027> [----------] 2 tests from MouseDeliveryTest [1785.671] 05007.05027> [ RUN ] MouseDeliveryTest.StandardTest [1785.671] 05007.05027> [1785.671] 05007.05027> TEST_LOOP_RANDOM_SEED="1727486847" [1785.671] 05007.05027> [INFO:gfx_system.cc(176)] No Vulkan found, but using a test-only "display". [1785.671] 05007.05027> [INFO:input_system.cc(236)] Scenic input system initialized. [1785.671] 05007.05027> [ERROR:garnet/lib/ui/input/input_system.cc(233)] Scenic lost connection to TextSync [1785.675] 05007.05027> [ OK ] MouseDeliveryTest.StandardTest (4 ms) [1785.675] 05007.05027> [ RUN ] MouseDeliveryTest.NoFocusTest [1785.675] 05007.05027> [1785.675] 05007.05027> TEST_LOOP_RANDOM_SEED="604794236" [1785.675] 05007.05027> [INFO:gfx_system.cc(176)] No Vulkan found, but using a test-only "display". [1785.675] 05007.05027> [INFO:input_system.cc(236)] Scenic input system initialized. [1785.675] 05007.05027> [ERROR:garnet/lib/ui/input/input_system.cc(233)] Scenic lost connection to TextSync [1785.679] 05007.05027> [ OK ] MouseDeliveryTest.NoFocusTest (3 ms) [1785.679] 05007.05027> [----------] 2 tests from MouseDeliveryTest (7 ms total) [1785.679] 05007.05027> [1785.679] 05007.05027> [----------] Global test environment tear-down [1785.679] 05007.05027> [==========] 7 tests from 5 test cases ran. (20 ms total) [1785.679] 05007.05027> [ PASSED ] 7 tests. [1785.680] 05007.05027> PASSED: /pkgfs/packages/scenic_tests/0/test/input_unittests passed [1785.680] 05007.05027> [1785.680] 05007.05027> ------------------------------------------------ [1785.680] 05007.05027> RUNNING TEST: /pkgfs/packages/scenic_tests/0/test/presentation_mode_unittests [1785.680] 05007.05027> [1785.691] 05007.05027> Running main() from gtest_main.cc [1785.691] 05007.05027> [==========] Running 7 tests from 3 test cases. [1785.691] 05007.05027> [----------] Global test environment set-up. [1785.691] 05007.05027> [----------] 1 test from PositiveData [1785.691] 05007.05027> [ RUN ] PositiveData.MovingAverage [1785.691] 05007.05027> [ OK ] PositiveData.MovingAverage (0 ms) [1785.691] 05007.05027> [----------] 1 test from PositiveData (0 ms total) [1785.691] 05007.05027> [1785.691] 05007.05027> [----------] 1 test from NegativeData [1785.691] 05007.05027> [ RUN ] NegativeData.MovingAverage [1785.691] 05007.05027> [ OK ] NegativeData.MovingAverage (0 ms) [1785.691] 05007.05027> [----------] 1 test from NegativeData (0 ms total) [1785.691] 05007.05027> [1785.691] 05007.05027> [----------] 5 tests from Detector [1785.691] 05007.05027> [ RUN ] Detector.Closed [1785.691] 05007.05027> [ OK ] Detector.Closed (0 ms) [1785.691] 05007.05027> [ RUN ] Detector.Laptop [1785.691] 05007.05027> [ OK ] Detector.Laptop (0 ms) [1785.691] 05007.05027> [ RUN ] Detector.Tablet [1785.691] 05007.05027> [ OK ] Detector.Tablet (0 ms) [1785.691] 05007.05027> [ RUN ] Detector.Tent [1785.691] 05007.05027> [ OK ] Detector.Tent (0 ms) [1785.691] 05007.05027> [ RUN ] Detector.NonAccelerometer [1785.691] 05007.05027> [ OK ] Detector.NonAccelerometer (0 ms) [1785.691] 05007.05027> [----------] 5 tests from Detector (1 ms total) [1785.691] 05007.05027> [1785.691] 05007.05027> [----------] Global test environment tear-down [1785.691] 05007.05027> [==========] 7 tests from 3 test cases ran. (1 ms total) [1785.691] 05007.05027> [ PASSED ] 7 tests. [1785.691] 05007.05027> PASSED: /pkgfs/packages/scenic_tests/0/test/presentation_mode_unittests passed [1785.692] 05007.05027> [1785.692] 05007.05027> ------------------------------------------------ [1785.692] 05007.05027> RUNNING TEST: /pkgfs/packages/scenic_tests/0/test/scenic_unittests [1785.692] 05007.05027> [1785.708] 05007.05027> Running main() from gtest_main.cc [1785.708] 05007.05027> [==========] Running 1 test from 1 test case. [1785.708] 05007.05027> [----------] Global test environment set-up. [1785.708] 05007.05027> [----------] 1 test from ScenicTest [1785.708] 05007.05027> [ RUN ] ScenicTest.SessionCreatedAfterAllSystemsInitialized [1785.708] 05007.05027> [1785.708] 05007.05027> TEST_LOOP_RANDOM_SEED="2793434283" [1785.708] 05007.05027> [ OK ] ScenicTest.SessionCreatedAfterAllSystemsInitialized (0 ms) [1785.708] 05007.05027> [----------] 1 test from ScenicTest (0 ms total) [1785.708] 05007.05027> [1785.708] 05007.05027> [----------] Global test environment tear-down [1785.708] 05007.05027> [==========] 1 test from 1 test case ran. (0 ms total) [1785.708] 05007.05027> [ PASSED ] 1 test. [1785.709] 05007.05027> PASSED: /pkgfs/packages/scenic_tests/0/test/scenic_unittests passed [1785.709] 05007.05027> [1785.709] 05007.05027> ------------------------------------------------ [1785.709] 05007.05027> RUNNING TEST: /pkgfs/packages/scenic_tests/0/test/view_manager_apptests [1785.709] 05007.05027> [1785.791] 05007.05027> Running main() from gtest_main.cc [1785.791] 05007.05027> [==========] Running 0 tests from 0 test cases. [1785.791] 05007.05027> [==========] 0 tests from 0 test cases ran. (0 ms total) [1785.791] 05007.05027> [ PASSED ] 0 tests. [1785.791] 05007.05027> PASSED: /pkgfs/packages/scenic_tests/0/test/view_manager_apptests passed [1785.792] 05007.05027> [1785.792] 05007.05027> ------------------------------------------------ [1785.792] 05007.05027> RUNNING TEST: /pkgfs/packages/topaz_modular_integration_tests/0/test/run_topaz_modular_integration_tests.sh [1785.792] 05007.05027> [1785.796] 05007.05027> Killed 43830 basemgr_monitor.cmx [1785.797] 05721.05788> [ERROR:garnet/bin/sysmgr/app.cc(154)] Singleton fuchsia-pkg://fuchsia.com/basemgr_monitor#meta/basemgr_monitor.cmx died [1785.798] 05007.05027> Killed 44053 root_presenter.cmx [1785.799] 05721.05788> [ERROR:garnet/bin/sysmgr/app.cc(154)] Singleton fuchsia-pkg://fuchsia.com/root_presenter#meta/root_presenter.cmx died [1785.801] 05007.05027> Killed 44844 scenic.cmx [1785.801] 44986.45003> [ERROR:garnet/bin/ui/view_manager/view_registry.cc(144)] Exiting due to session connection error. [1785.805] 02677.03715> Failed to send vsync event -24 [1785.805] 05721.05788> [ERROR:garnet/bin/sysmgr/app.cc(154)] Singleton fuchsia-pkg://fuchsia.com/view_manager#meta/view_manager.cmx died [1785.807] 05721.05788> [ERROR:garnet/bin/sysmgr/app.cc(154)] Singleton fuchsia-pkg://fuchsia.com/scenic#meta/scenic.cmx died [1785.817] 05007.05027> PASSED: /pkgfs/packages/topaz_modular_integration_tests/0/test/run_topaz_modular_integration_tests.sh passed [1785.817] 05007.05027> [1785.817] 05007.05027> ------------------------------------------------ [1785.817] 05007.05027> RUNNING TEST: /pkgfs/packages/topaz_modular_integration_tests/0/test/run_topaz_modular_integration_tests.sh [1785.817] 05007.05027> [1785.822] 05007.05027> no tasks found [1785.823] 05007.05027> no tasks found [1785.824] 05007.05027> no tasks found [1785.837] 05007.05027> PASSED: /pkgfs/packages/topaz_modular_integration_tests/0/test/run_topaz_modular_integration_tests.sh passed [1785.837] 05007.05027> [1785.837] 05007.05027> ------------------------------------------------ [1785.837] 05007.05027> RUNNING TEST: /pkgfs/packages/web_runner_tests/0/test/web_runner_unittests [1785.837] 05007.05027> [1785.853] 05007.05027> Running main() from gtest_main.cc [1785.853] 05007.05027> [==========] Running 2 tests from 1 test case. [1785.853] 05007.05027> [----------] Global test environment set-up. [1785.853] 05007.05027> [----------] 2 tests from RunnerTest [1785.853] 05007.05027> [ RUN ] RunnerTest.Trivial [1785.853] 05007.05027> [1785.853] 05007.05027> TEST_LOOP_RANDOM_SEED="3617510197" [1785.854] 05007.05027> [ OK ] RunnerTest.Trivial (0 ms) [1785.854] 05007.05027> [ RUN ] RunnerTest.CreatesWebView [1785.854] 05007.05027> [1785.854] 05007.05027> TEST_LOOP_RANDOM_SEED="1168092480" [1785.854] 05007.05027> [ OK ] RunnerTest.CreatesWebView (1 ms) [1785.854] 05007.05027> [----------] 2 tests from RunnerTest (1 ms total) [1785.854] 05007.05027> [1785.854] 05007.05027> [----------] Global test environment tear-down [1785.854] 05007.05027> [==========] 2 tests from 1 test case ran. (1 ms total) [1785.854] 05007.05027> [ PASSED ] 2 tests. [1785.855] 05007.05027> PASSED: /pkgfs/packages/web_runner_tests/0/test/web_runner_unittests passed [1785.855] 05007.05027> [1785.855] 05007.05027> ------------------------------------------------ [1785.855] 05007.05027> RUNNING TEST: /system/test/auth_provider_unittests [1785.855] 05007.05027> [1785.876] 05007.05027> Running main() from gtest_main.cc [1785.876] 05007.05027> [==========] Running 27 tests from 6 test cases. [1785.876] 05007.05027> [----------] Global test environment set-up. [1785.876] 05007.05027> [----------] 1 test from GoogleFactoryImplTest [1785.876] 05007.05027> [ RUN ] GoogleFactoryImplTest.GetAuthProvider [1785.876] 05007.05027> [1785.876] 05007.05027> TEST_LOOP_RANDOM_SEED="3245845737" [1785.877] 05007.05027> [ OK ] GoogleFactoryImplTest.GetAuthProvider (0 ms) [1785.877] 05007.05027> [----------] 1 test from GoogleFactoryImplTest (0 ms total) [1785.877] 05007.05027> [1785.877] 05007.05027> [----------] 12 tests from GoogleAuthProviderImplTest [1785.877] 05007.05027> [ RUN ] GoogleAuthProviderImplTest.EmptyWhenClientDisconnected [1785.877] 05007.05027> [1785.877] 05007.05027> TEST_LOOP_RANDOM_SEED="4015381170" [1785.877] 05007.05027> [ OK ] GoogleAuthProviderImplTest.EmptyWhenClientDisconnected (0 ms) [1785.877] 05007.05027> [ RUN ] GoogleAuthProviderImplTest.GetAppAccessTokenSuccess [1785.877] 05007.05027> [1785.877] 05007.05027> TEST_LOOP_RANDOM_SEED="4195509376" [1785.879] 05007.05027> [ OK ] GoogleAuthProviderImplTest.GetAppAccessTokenSuccess (2 ms) [1785.879] 05007.05027> [ RUN ] GoogleAuthProviderImplTest.GetAppAccessTokenBadRequestError [1785.879] 05007.05027> [1785.879] 05007.05027> TEST_LOOP_RANDOM_SEED="256520323" [1785.879] 05007.05027> [ OK ] GoogleAuthProviderImplTest.GetAppAccessTokenBadRequestError (1 ms) [1785.879] 05007.05027> [ RUN ] GoogleAuthProviderImplTest.GetAppAccessTokenInvalidClientError [1785.879] 05007.05027> [1785.879] 05007.05027> TEST_LOOP_RANDOM_SEED="831302672" [1785.881] 05007.05027> [WARNING:topaz/auth_providers/google/google_auth_provider_impl.cc(196)] Got error: OAuth backend returned error: 401 [1785.881] 05007.05027> [WARNING:topaz/auth_providers/google/google_auth_provider_impl.cc(197)] Got response: { [1785.881] 05007.05027> "error": "invalid_client" [1785.881] 05007.05027> } [1785.881] 05007.05027> [ OK ] GoogleAuthProviderImplTest.GetAppAccessTokenInvalidClientError (2 ms) [1785.881] 05007.05027> [ RUN ] GoogleAuthProviderImplTest.GetAppAccessTokenInvalidUserError [1785.881] 05007.05027> [1785.881] 05007.05027> TEST_LOOP_RANDOM_SEED="2190551411" [1785.883] 05007.05027> [WARNING:topaz/auth_providers/google/google_auth_provider_impl.cc(196)] Got error: OAuth backend returned error: 401 [1785.883] 05007.05027> [WARNING:topaz/auth_providers/google/google_auth_provider_impl.cc(197)] Got response: { [1785.883] 05007.05027> "error": "invalid_credential" [1785.883] 05007.05027> } [1785.883] 05007.05027> [ OK ] GoogleAuthProviderImplTest.GetAppAccessTokenInvalidUserError (2 ms) [1785.883] 05007.05027> [ RUN ] GoogleAuthProviderImplTest.GetAppIdTokenSuccess [1785.883] 05007.05027> [1785.883] 05007.05027> TEST_LOOP_RANDOM_SEED="2056113578" [1785.885] 05007.05027> [ OK ] GoogleAuthProviderImplTest.GetAppIdTokenSuccess (2 ms) [1785.885] 05007.05027> [ RUN ] GoogleAuthProviderImplTest.GetAppIdTokenBadRequestError [1785.885] 05007.05027> [1785.885] 05007.05027> TEST_LOOP_RANDOM_SEED="3579219094" [1785.885] 05007.05027> [ OK ] GoogleAuthProviderImplTest.GetAppIdTokenBadRequestError (0 ms) [1785.885] 05007.05027> [ RUN ] GoogleAuthProviderImplTest.GetAppIdTokenInvalidAudienceError [1785.885] 05007.05027> [1785.885] 05007.05027> TEST_LOOP_RANDOM_SEED="580918789" [1785.887] 05007.05027> [WARNING:topaz/auth_providers/google/google_auth_provider_impl.cc(234)] Got error: OAuth backend returned error: 401 [1785.887] 05007.05027> [WARNING:topaz/auth_providers/google/google_auth_provider_impl.cc(235)] Got response: { [1785.887] 05007.05027> "error": "invalid_client" [1785.887] 05007.05027> } [1785.887] 05007.05027> [ OK ] GoogleAuthProviderImplTest.GetAppIdTokenInvalidAudienceError (2 ms) [1785.887] 05007.05027> [ RUN ] GoogleAuthProviderImplTest.GetAppIdTokenInvalidUserError [1785.887] 05007.05027> [1785.887] 05007.05027> TEST_LOOP_RANDOM_SEED="1468794139" [1785.889] 05007.05027> [WARNING:topaz/auth_providers/google/google_auth_provider_impl.cc(234)] Got error: OAuth backend returned error: 401 [1785.889] 05007.05027> [WARNING:topaz/auth_providers/google/google_auth_provider_impl.cc(235)] Got response: { [1785.889] 05007.05027> "error": "invalid_credential" [1785.889] 05007.05027> } [1785.889] 05007.05027> [ OK ] GoogleAuthProviderImplTest.GetAppIdTokenInvalidUserError (2 ms) [1785.889] 05007.05027> [ RUN ] GoogleAuthProviderImplTest.GetAppFirebaseTokenSuccess [1785.895] 05007.05027> [1785.895] 05007.05027> TEST_LOOP_RANDOM_SEED="2705843946" [1785.895] 05007.05027> [ OK ] GoogleAuthProviderImplTest.GetAppFirebaseTokenSuccess (2 ms) [1785.895] 05007.05027> [ RUN ] GoogleAuthProviderImplTest.GetAppFirebaseTokenBadRequestError [1785.895] 05007.05027> [1785.895] 05007.05027> TEST_LOOP_RANDOM_SEED="3064911572" [1785.895] 05007.05027> [ OK ] GoogleAuthProviderImplTest.GetAppFirebaseTokenBadRequestError (0 ms) [1785.895] 05007.05027> [ RUN ] GoogleAuthProviderImplTest.RevokeAppOrPersistentCredentialUnsupported [1785.895] 05007.05027> [1785.895] 05007.05027> TEST_LOOP_RANDOM_SEED="2615576276" [1785.895] 05007.05027> [ OK ] GoogleAuthProviderImplTest.RevokeAppOrPersistentCredentialUnsupported (0 ms) [1785.895] 05007.05027> [----------] 12 tests from GoogleAuthProviderImplTest (15 ms total) [1785.895] 05007.05027> [1785.895] 05007.05027> [----------] 6 tests from OAuthRequestBuilderTest [1785.895] 05007.05027> [ RUN ] OAuthRequestBuilderTest.JsonEncodedPostRequest [1785.895] 05007.05027> [ OK ] OAuthRequestBuilderTest.JsonEncodedPostRequest (0 ms) [1785.895] 05007.05027> [ RUN ] OAuthRequestBuilderTest.UrlEncodedPostRequest [1785.895] 05007.05027> [ OK ] OAuthRequestBuilderTest.UrlEncodedPostRequest (0 ms) [1785.895] 05007.05027> [ RUN ] OAuthRequestBuilderTest.EmptyBodyPostRequest [1785.895] 05007.05027> [ OK ] OAuthRequestBuilderTest.EmptyBodyPostRequest (0 ms) [1785.895] 05007.05027> [ RUN ] OAuthRequestBuilderTest.CheckAuthHeader [1785.895] 05007.05027> [ OK ] OAuthRequestBuilderTest.CheckAuthHeader (0 ms) [1785.895] 05007.05027> [ RUN ] OAuthRequestBuilderTest.GetRequest [1785.895] 05007.05027> [ OK ] OAuthRequestBuilderTest.GetRequest (0 ms) [1785.895] 05007.05027> [ RUN ] OAuthRequestBuilderTest.GetRequestWithQueryParams [1785.895] 05007.05027> [ OK ] OAuthRequestBuilderTest.GetRequestWithQueryParams (0 ms) [1785.895] 05007.05027> [----------] 6 tests from OAuthRequestBuilderTest (0 ms total) [1785.895] 05007.05027> [1785.895] 05007.05027> [----------] 1 test from OAuthResponseTest [1785.895] 05007.05027> [ RUN ] OAuthResponseTest.CheckParseOAuthResponse [1785.895] 05007.05027> [ERROR:topaz/auth_providers/oauth/oauth_response.cc(22)] Encountered error: -2 ,with description: Bad request [1785.902] 05007.05027> [ OK ] OAuthResponseTest.CheckParseOAuthResponse (10 ms) [1785.902] 05007.05027> [----------] 1 test from OAuthResponseTest (10 ms total) [1785.902] 05007.05027> [1785.902] 05007.05027> [----------] 1 test from SpotifyFactoryImplTest [1785.902] 05007.05027> [ RUN ] SpotifyFactoryImplTest.GetAuthProvider [1785.902] 05007.05027> [1785.902] 05007.05027> TEST_LOOP_RANDOM_SEED="2648270377" [1785.902] 05007.05027> [ OK ] SpotifyFactoryImplTest.GetAuthProvider (0 ms) [1785.902] 05007.05027> [----------] 1 test from SpotifyFactoryImplTest (0 ms total) [1785.902] 05007.05027> [1785.902] 05007.05027> [----------] 6 tests from SpotifyAuthProviderImplTest [1785.902] 05007.05027> [ RUN ] SpotifyAuthProviderImplTest.EmptyWhenClientDisconnected [1785.902] 05007.05027> [1785.902] 05007.05027> TEST_LOOP_RANDOM_SEED="1042704897" [1785.902] 05007.05027> [ OK ] SpotifyAuthProviderImplTest.EmptyWhenClientDisconnected (0 ms) [1785.902] 05007.05027> [ RUN ] SpotifyAuthProviderImplTest.GetAppAccessTokenSuccess [1785.902] 05007.05027> [1785.902] 05007.05027> TEST_LOOP_RANDOM_SEED="2000623636" [1785.904] 05007.05027> [ OK ] SpotifyAuthProviderImplTest.GetAppAccessTokenSuccess (2 ms) [1785.904] 05007.05027> [ RUN ] SpotifyAuthProviderImplTest.GetAppAccessTokenError [1785.904] 05007.05027> [1785.904] 05007.05027> TEST_LOOP_RANDOM_SEED="209998801" [1785.906] 05007.05027> [ OK ] SpotifyAuthProviderImplTest.GetAppAccessTokenError (2 ms) [1785.906] 05007.05027> [ RUN ] SpotifyAuthProviderImplTest.GetAppIdTokenUnsupported [1785.906] 05007.05027> [1785.906] 05007.05027> TEST_LOOP_RANDOM_SEED="1432574399" [1785.906] 05007.05027> [ OK ] SpotifyAuthProviderImplTest.GetAppIdTokenUnsupported (0 ms) [1785.906] 05007.05027> [ RUN ] SpotifyAuthProviderImplTest.GetAppFirebaseTokenUnsupported [1785.906] 05007.05027> [1785.906] 05007.05027> TEST_LOOP_RANDOM_SEED="3601359124" [1785.906] 05007.05027> [ OK ] SpotifyAuthProviderImplTest.GetAppFirebaseTokenUnsupported (0 ms) [1785.906] 05007.05027> [ RUN ] SpotifyAuthProviderImplTest.RevokeAppOrPersistentCredentialUnsupported [1785.906] 05007.05027> [1785.906] 05007.05027> TEST_LOOP_RANDOM_SEED="73875806" [1785.906] 05007.05027> [ OK ] SpotifyAuthProviderImplTest.RevokeAppOrPersistentCredentialUnsupported (0 ms) [1785.906] 05007.05027> [----------] 6 tests from SpotifyAuthProviderImplTest (5 ms total) [1785.906] 05007.05027> [1785.906] 05007.05027> [----------] Global test environment tear-down [1785.906] 05007.05027> [==========] 27 tests from 6 test cases ran. (30 ms total) [1785.906] 05007.05027> [ PASSED ] 27 tests. [1785.907] 05007.05027> PASSED: /system/test/auth_provider_unittests passed [1785.907] 05007.05027> [1785.907] 05007.05027> ------------------------------------------------ [1785.907] 05007.05027> RUNNING TEST: /system/test/web_view_link_test [1785.907] 05007.05027> [1786.135] 05007.05027> PASSED: /system/test/web_view_link_test passed [1786.135] 05007.05027> [1786.135] 05007.05027> SUMMARY: Ran 27 tests: 0 failed (74.902 sec) 2018/12/13 18:06:03 reading "summary.json" 2018/12/13 18:06:03 copying test output 2018/12/13 18:06:03 tarring test output... 2018/12/13 18:06:03 rebooting the node "upper-swoop-clock-grope" [1834.582] 01537.01621> netsvc: tftp read of file /tmp/infra-test-output/summary.json completed [1834.596] 01537.01621> netsvc: tftp read of file /tmp/infra-test-output/syslog.txt completed [1834.597] 01537.01621> netsvc: tftp read of file /tmp/infra-test-output/pkgfs/packages/catapult_converter/0/test/catapult_converter_test/stdout-and-stderr.txt completed [1834.597] 01537.01621> netsvc: tftp read of file /tmp/infra-test-output/pkgfs/packages/dart_zircon_test/0/test/dart_zircon_test/stdout-and-stderr.txt completed [1834.598] 01537.01621> netsvc: tftp read of file /tmp/infra-test-output/pkgfs/packages/deprecated_loop_tests/0/test/deprecated_loop_unittests/stdout-and-stderr.txt completed [1834.598] 01537.01621> netsvc: tftp read of file /tmp/infra-test-output/pkgfs/packages/fidl_bindings_test/0/test/fidl_bindings_test/stdout-and-stderr.txt completed [1834.599] 01537.01621> netsvc: tftp read of file /tmp/infra-test-output/pkgfs/packages/fidl_compatibility_test/0/test/run_fidl_compatibility_test_topaz.sh/stdout-and-stderr.txt completed [1834.600] 01537.01621> netsvc: tftp read of file /tmp/infra-test-output/pkgfs/packages/flutter_runner_tests/0/test/flutter_runner_unittests/stdout-and-stderr.txt completed [1834.600] 01537.01621> netsvc: tftp read of file /tmp/infra-test-output/pkgfs/packages/flutter_screencap_test/0/test/run_flutter_screencap_test.sh/stdout-and-stderr.txt completed [1834.601] 01537.01621> netsvc: tftp read of file /tmp/infra-test-output/pkgfs/packages/flutter_screencap_test/0/test/run_flutter_screencap_test.sh/stdout-and-stderr.txt completed [1834.602] 01537.01621> netsvc: tftp read of file /tmp/infra-test-output/pkgfs/packages/fuchsia_modular_package_integration_tests/0/test/fuchsia_modular_package_integration_tests/stdout-and-stderr.txt completed [1834.602] 01537.01621> netsvc: tftp read of file /tmp/infra-test-output/pkgfs/packages/fuchsia_services_package_integration_tests/0/test/fuchsia_services_package_integration_tests/stdout-and-stderr.txt completed [1834.603] 01537.01621> netsvc: tftp read of file /tmp/infra-test-output/pkgfs/packages/goodbye_dart_test/0/test/goodbye_dart_test/stdout-and-stderr.txt completed [1834.603] 01537.01621> netsvc: tftp read of file /tmp/infra-test-output/pkgfs/packages/run_test_component_test/0/test/run_test_component_test/stdout-and-stderr.txt completed [1834.604] 01537.01621> netsvc: tftp read of file /tmp/infra-test-output/pkgfs/packages/run_test_component_unittests/0/test/run_test_component_unittests/stdout-and-stderr.txt completed [1834.605] 01537.01621> netsvc: tftp read of file /tmp/infra-test-output/pkgfs/packages/scenic_tests/0/test/geometry_util_unittests/stdout-and-stderr.txt completed [1834.605] 01537.01621> netsvc: tftp read of file /tmp/infra-test-output/pkgfs/packages/scenic_tests/0/test/gfx_apptests/stdout-and-stderr.txt completed [1834.606] 01537.01621> netsvc: tftp read of file /tmp/infra-test-output/pkgfs/packages/scenic_tests/0/test/gfx_unittests/stdout-and-stderr.txt completed [1834.607] 01537.01621> netsvc: tftp read of file /tmp/infra-test-output/pkgfs/packages/scenic_tests/0/test/input_apptests/stdout-and-stderr.txt completed [1834.608] 01537.01621> netsvc: tftp read of file /tmp/infra-test-output/pkgfs/packages/scenic_tests/0/test/input_tool_unittests/stdout-and-stderr.txt completed [1834.608] 01537.01621> netsvc: tftp read of file /tmp/infra-test-output/pkgfs/packages/scenic_tests/0/test/input_unittests/stdout-and-stderr.txt completed [1834.609] 01537.01621> netsvc: tftp read of file /tmp/infra-test-output/pkgfs/packages/scenic_tests/0/test/presentation_mode_unittests/stdout-and-stderr.txt completed [1834.610] 01537.01621> netsvc: tftp read of file /tmp/infra-test-output/pkgfs/packages/scenic_tests/0/test/scenic_unittests/stdout-and-stderr.txt completed [1834.610] 01537.01621> netsvc: tftp read of file /tmp/infra-test-output/pkgfs/packages/scenic_tests/0/test/view_manager_apptests/stdout-and-stderr.txt completed [1834.611] 01537.01621> netsvc: tftp read of file /tmp/infra-test-output/pkgfs/packages/topaz_modular_integration_tests/0/test/run_topaz_modular_integration_tests.sh/stdout-and-stderr.txt completed [1834.611] 01537.01621> netsvc: tftp read of file /tmp/infra-test-output/pkgfs/packages/topaz_modular_integration_tests/0/test/run_topaz_modular_integration_tests.sh/stdout-and-stderr.txt completed [1834.612] 01537.01621> netsvc: tftp read of file /tmp/infra-test-output/pkgfs/packages/web_runner_tests/0/test/web_runner_unittests/stdout-and-stderr.txt completed [1834.613] 01537.01621> netsvc: tftp read of file /tmp/infra-test-output/system/test/auth_provider_unittests/stdout-and-stderr.txt completed [1834.613] 01537.01621> netsvc: tftp read of file /tmp/infra-test-output/system/test/web_view_link_test/stdout-and-stderr.txt completed