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

[00000.000000][0][0][klog] INFO: zbi: @ 0xffffff800074f000 (5347728 bytes) [00000.000000][0][0][klog] INFO: UART: FIFO depth 16 [00000.000000][0][0][klog] INFO: PMM: boot reserve add [0x100000, 0x2b4fff] [00000.000000][0][0][klog] INFO: PMM: boot reserve add [0x74f000, 0xc68fff] [00000.000000][0][0][klog] INFO: PMM: boot reserve marking WIRED [0x100000, 0x2b4fff] [00000.000000][0][0][klog] INFO: PMM: boot reserve marking WIRED [0x74f000, 0xc68fff] [00000.000000][0][0][klog] INFO: [00000.000000][0][0][klog] INFO: welcome to Zircon [00000.000000][0][0][klog] INFO: [00000.000000][0][0][klog] INFO: KASLR: .text section at 0xffffffff00100000 [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff001a029c (global_prng_seed) at level 0x30000, flags 0x1 [00000.000000][0][0][klog] INFO: initializing vm pre-heap [00000.000000][0][0][klog] INFO: VM: marking boot alloc used range [0xc69000, 0xc69080) [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00142ad0 (elf_build_id) at level 0x4fffe, flags 0x1 [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00142c5c (version) at level 0x4ffff, flags 0x1 [00000.000000][0][0][klog] INFO: version: [00000.000000][0][0][klog] INFO: arch: x86 [00000.000000][0][0][klog] INFO: platform: pc [00000.000000][0][0][klog] INFO: target: pc [00000.000000][0][0][klog] INFO: project: x64 [00000.000000][0][0][klog] INFO: buildid: git-7245967679cadf83c3ec6e027b7ad3f21292ccb2 [00000.000000][0][0][klog] INFO: ELF build ID: d84f2a4922fca02267e3779bcc2aaa55eda523b2 [00000.000000][0][0][klog] INFO: initializing heap [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff001033d8 (x86_resource_init) at level 0x50000, flags 0x1 [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff0019e774 (console) at level 0x50000, flags 0x1 [00000.000000][0][0][klog] INFO: initializing vm [00000.000000][0][0][klog] INFO: VM: reserving kernel region [0xffffffff00100000, 0xffffffff001df000) flags 0x28 name 'kernel_code' [00000.000000][0][0][klog] INFO: VM: reserving kernel region [0xffffffff001df000, 0xffffffff00221000) flags 0x8 name 'kernel_rodata' [00000.000000][0][0][klog] INFO: VM: reserving kernel region [0xffffffff00221000, 0xffffffff00231000) flags 0x18 name 'kernel_data' [00000.000000][0][0][klog] INFO: VM: reserving kernel region [0xffffffff00231000, 0xffffffff002b5000) flags 0x18 name 'kernel_bss' [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00101168 (acpi_tables) at level 0x60001, flags 0x1 [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff001040a8 (display_memtype) at level 0x60001, flags 0x1 [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00102130 (hpet) at level 0x60002, flags 0x1 [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff001024b8 (apic) at level 0x60002, flags 0x1 [00000.000000][0][0][klog] INFO: x2APIC enabled [00000.000000][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff001067d4 (timer) at level 0x60003, flags 0x1 [00000.000000][0][0][klog] INFO: pvclock: Clocksource is stable [00000.000000][0][0][klog] INFO: HPET frequency: 100000 ticks/ms [00000.000000][0][0][klog] INFO: pvclock: Fetching TSC frequency [00000.000000][0][0][klog] INFO: TSC frequency: 2299798 ticks/ms [00000.483270][0][0][klog] INFO: timer features: constant_tsc 1 invariant_tsc 1 tsc_deadline 1 [00000.483271][0][0][klog] INFO: Using TSC as wallclock [00000.483271][0][0][klog] INFO: initializing kernel [00000.483272][0][0][klog] INFO: initializing mp [00000.483273][0][0][klog] INFO: initializing timers [00000.483281][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00137744 (debuglog) at level 0x7ffff, flags 0x1 [00000.483333][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff0018c888 (thread_set_priority_experiment) at level 0x7ffff, flags 0x1 [00000.483343][0][0][klog] INFO: thread set priority experiment is : ENABLED [00000.483344][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff001a04f0 (global_prng_thread_safe) at level 0x7ffff, flags 0x1 [00000.483348][0][0][klog] INFO: creating bootstrap completion thread [00000.659922][0][0][klog] INFO: top of bootstrap2() [00000.659925][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff0012dc20 (dpc) at level 0x80000, flags 0x1 [00000.666770][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff0014d928 (libobject) at level 0x80000, flags 0x1 [00000.666841][0][0][klog] INFO: OOM: started thread [00000.674048][0][0][klog] INFO: Processor Model Info: type 0 family 0x6 model 0xf stepping 0 [00000.674049][0][0][klog] INFO: display_family 0x6 display_model 0x3f [00000.674050][0][0][klog] INFO: Vendor: Intel [00000.674051][0][0][klog] INFO: Microarch: Haswell [00000.674051][0][0][klog] INFO: F/M/S: 6/3f/0 [00000.674052][0][0][klog] INFO: Brand: Intel(R) Xeon(R) CPU @ 2.30GHz [00000.674054][0][0][klog] INFO: Features: fpu sse sse2 sse3 ssse3 sse4.1 sse4.2 mmx avx avx2 fxsr pcid xsave aesni clflush [00000.674055][0][0][klog] INFO: fsgsbase tsc_adj smep erms rdrand syscall nx rdtscp tsc_deadline x2apic hypervisor [00000.674077][0][0][klog] INFO: initializing platform [00000.674079][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00110814 (x86_perfmon) at level 0x90000, flags 0x1 [00000.674117][0][0][klog] INFO: x86_perfmon_init_once:495: perfmon: unexpected programmable counter width 0 in cpuid.0AH [00000.674714][0][0][klog] INFO: UART: started IRQ driven RX [00000.674714][0][0][klog] INFO: UART: started IRQ driven TX [00000.674737][0][0][klog] INFO: cpu topology: [00000.674738][0][0][klog] INFO: 0: apic id 0x0 package 0 node 0 core 0 smt 0 BSP [00000.674739][0][0][klog] INFO: 1: apic id 0x1 package 1 node 0 core 0 smt 0 [00000.674740][0][0][klog] INFO: 2: apic id 0x2 package 2 node 0 core 0 smt 0 [00000.674740][0][0][klog] INFO: 3: apic id 0x3 package 3 node 0 core 0 smt 0 [00000.674741][0][0][klog] INFO: Found 4 cpus [00000.675263][0][0][klog] INFO: booting apic ids: 0x1 0x2 0x3 [00000.750735][0][0][klog] INFO: entering scheduler on cpu 1 [00000.750795][0][0][klog] INFO: entering scheduler on cpu 2 [00000.750932][0][0][klog] INFO: entering scheduler on cpu 3 [00000.765871][0][0][klog] INFO: smbios: manufacturer="QEMU" product="Standard PC (Q35 + ICH9, 2009)" [00000.765907][0][0][klog] INFO: initializing target [00000.765909][0][0][klog] INFO: INIT: cpu 2, calling hook 0xffffffff001059b8 (x86_pcie_init) at level 0xa0000, flags 0x1 [00000.766046][0][0][klog] INFO: moving to last init level [00000.766068][0][0][klog] INFO: INIT: cpu 2, calling hook 0xffffffff0013f308 (ktrace) at level 0xc0000, flags 0x1 [00000.783351][0][0][klog] INFO: ktrace: buffer at 0xffffff99c58aa000 (33554432 bytes) [00000.783409][0][0][klog] INFO: INIT: cpu 2, calling hook 0xffffffff00142ac8 (userboot) at level 0xc0000, flags 0x1 [00000.783415][0][0][klog] INFO: userboot: ramdisk 0x51a000 @ 0xffffff800074f000 [00000.805710][0][0][klog] INFO: userboot: userboot rodata 0 @ [0x2348fb1bf000,0x2348fb1c2000) [00000.805713][0][0][klog] INFO: userboot: userboot code 0x3000 @ [0x2348fb1c2000,0x2348fb1cd000) [00000.805715][0][0][klog] INFO: userboot: vdso/full rodata 0 @ [0x2348fb1cd000,0x2348fb1d4000) [00000.805717][0][0][klog] INFO: userboot: vdso/full code 0x7000 @ [0x2348fb1d4000,0x2348fb1d5000) [00000.805765][0][0][klog] INFO: userboot: entry point @ 0x2348fb1c2c10 [00000.805798][0][0][klog] INFO: INIT: cpu 2, calling hook 0xffffffff0019f174 (kernel_shell) at level 0xc0000, flags 0x1 [00000.806116][1032][1039][klog] INFO: userboot: option "/b/s/w/ir/multiboot.bin=" [00000.806171][1032][1039][klog] INFO: userboot: option "zircon.autorun.system=/boot/bin/sh+/boot/infra/runcmds" [00000.806172][1032][1039][klog] INFO: userboot: option "kernel.halt-on-panic=true" [00000.806173][1032][1039][klog] INFO: userboot: option "devmgr.suspend-timeout-debug=true" [00000.806173][1032][1039][klog] INFO: userboot: option "TERM=dumb" [00000.806174][1032][1039][klog] INFO: userboot: option "kernel.serial=legacy" [00000.806175][1032][1039][klog] INFO: userboot: option "multiboot.boot_loader_name=qemu" [00000.827604][1032][1039][klog] INFO: userboot: searching bootfs for 'bin/bootsvc' [00000.827654][1032][1039][klog] INFO: userboot: 'bin/bootsvc' has PT_INTERP "lib/ld.so.1" [00000.827655][1032][1039][klog] INFO: userboot: searching bootfs for 'lib/ld.so.1' [00000.827701][1032][1039][klog] INFO: userboot: userboot: loaded lib/ld.so.1 at 0x6945b1c1d000, entry point 0x6945b1ca3623 [00000.827720][1032][1039][klog] INFO: userboot: userboot: loaded vDSO at 0x6b9151c8d000, entry point 0x0 [00000.827795][1032][1039][klog] INFO: userboot: process bin/bootsvc started. [00000.827796][1032][1039][klog] INFO: userboot: waiting for loader-service requests... [00000.828424][1032][1039][klog] INFO: userboot: searching bootfs for 'lib/libasync-default.so' [00000.828878][1032][1039][klog] INFO: userboot: searching bootfs for 'lib/libfdio.so' [00000.829165][1032][1039][klog] INFO: userboot: searching bootfs for 'lib/liblaunchpad.so' [00000.829454][1032][1039][klog] INFO: userboot: searching bootfs for 'lib/libtrace-engine.so' [00000.831079][1046][1049][klog] INFO: {{{reset}}} [00000.831081][1046][1049][klog] INFO: {{{module:0:<application>:elf:ca926a5288509b6336f62dda5a14644b7e91b38d}}} [00000.831082][1046][1049][klog] INFO: {{{mmap:0x4c7bc8a69000:0x2d000:load:0:rx:0}}} [00000.831083][1046][1049][klog] INFO: {{{mmap:0x4c7bc8a96000:0x4000:load:0:rw:0x2d000}}} [00000.831083][1046][1049][klog] INFO: dso: id=ca926a5288509b6336f62dda5a14644b7e91b38d base=0x00004c7bc8a69000 name=<application> [00000.831084][1046][1049][klog] INFO: {{{module:0x3:libasync-default.so:elf:5e3c55aea09e8943ae76d3603cc8c0b1ba27bfb7}}} [00000.831085][1046][1049][klog] INFO: {{{mmap:0x52e793e40000:0x1000:load:0x3:rx:0}}} [00000.831086][1046][1049][klog] INFO: {{{mmap:0x52e793e41000:0x1000:load:0x3:rw:0x1000}}} [00000.831087][1046][1049][klog] INFO: dso: id=5e3c55aea09e8943ae76d3603cc8c0b1ba27bfb7 base=0x000052e793e40000 name=libasync-default.so [00000.831087][1046][1049][klog] INFO: {{{module:0x2:<vDSO>:elf:1a34fba7caf24078ee471c50d1de84f810448877}}} [00000.831088][1046][1049][klog] INFO: {{{mmap:0x6b9151c8d000:0x7000:load:0x2:r:0}}} [00000.831088][1046][1049][klog] INFO: {{{mmap:0x6b9151c94000:0x1000:load:0x2:rx:0x7000}}} [00000.831089][1046][1049][klog] INFO: dso: id=1a34fba7caf24078ee471c50d1de84f810448877 base=0x00006b9151c8d000 name=<vDSO> [00000.831090][1046][1049][klog] INFO: {{{module:0x1:libc.so:elf:a3c5a3bb3ab016d0bc21ad3b934d4347d27a4ac2}}} [00000.831090][1046][1049][klog] INFO: {{{mmap:0x6945b1c1d000:0xcb000:load:0x1:rx:0}}} [00000.831091][1046][1049][klog] INFO: {{{mmap:0x6945b1ce9000:0x6000:load:0x1:rw:0xcc000}}} [00000.831091][1046][1049][klog] INFO: dso: id=a3c5a3bb3ab016d0bc21ad3b934d4347d27a4ac2 base=0x00006945b1c1d000 name=libc.so [00000.831092][1046][1049][klog] INFO: {{{module:0x4:libfdio.so:elf:fb6715ba574ce88bcfca5045ee90fef94163808f}}} [00000.831093][1046][1049][klog] INFO: {{{mmap:0x6114308b6000:0x22000:load:0x4:rx:0}}} [00000.831093][1046][1049][klog] INFO: {{{mmap:0x6114308d8000:0x5000:load:0x4:rw:0x22000}}} [00000.831094][1046][1049][klog] INFO: dso: id=fb6715ba574ce88bcfca5045ee90fef94163808f base=0x00006114308b6000 name=libfdio.so [00000.831094][1046][1049][klog] INFO: {{{module:0x5:liblaunchpad.so:elf:3275d148fdc55d5dfbd06f5a7edb9dc4c294763f}}} [00000.831095][1046][1049][klog] INFO: {{{mmap:0x58fd875e5000:0x7000:load:0x5:rx:0}}} [00000.831095][1046][1049][klog] INFO: {{{mmap:0x58fd875ec000:0x2000:load:0x5:rw:0x7000}}} [00000.831096][1046][1049][klog] INFO: dso: id=3275d148fdc55d5dfbd06f5a7edb9dc4c294763f base=0x000058fd875e5000 name=liblaunchpad.so [00000.831097][1046][1049][klog] INFO: {{{module:0x6:libtrace-engine.so:elf:dbdab49d734285639cadc0b2eb151b302ecb2edf}}} [00000.831097][1046][1049][klog] INFO: {{{mmap:0x37cefa490000:0x9000:load:0x6:rx:0}}} [00000.831098][1046][1049][klog] INFO: {{{mmap:0x37cefa499000:0x2000:load:0x6:rw:0x9000}}} [00000.831098][1046][1049][klog] INFO: dso: id=dbdab49d734285639cadc0b2eb151b302ecb2edf base=0x000037cefa490000 name=libtrace-engine.so [00000.831370][1046][1049][klog] INFO: bootsvc: Starting... [00000.831460][1046][1049][klog] INFO: bootsvc: Creating bootfs service... [00000.831494][1032][1039][klog] INFO: userboot: loader-service channel peer closed [00000.831699][1032][1039][klog] INFO: userboot: finished! [00000.832099][1046][1049][klog] INFO: bootsvc: Processing bootdata... [00000.832112][1046][1049][klog] INFO: bootsvc: Loading boot cmdline overrides... [00000.832135][1046][1049][klog] INFO: bootsvc: Loading kernel VMOs... [00000.832158][1046][1049][klog] INFO: bootsvc: Creating loader service... [00000.832164][1046][1049][klog] INFO: bootsvc: Launching next process... [00000.834616][1046][1093][klog] INFO: bootsvc: launched bin/devmgr [00000.839073][1101][1114][klog] INFO: devmgr: main() [00000.839084][1101][1114][klog] INFO: devmgr: init [00000.839122][1101][1114][klog] INFO: coordinator_init() [00000.839169][1101][1114][klog] INFO: cmdline: /b/s/w/ir/multiboot.bin= [00000.839170][1101][1114][klog] INFO: cmdline: zircon.autorun.system=/boot/bin/sh+/boot/infra/runcmds [00000.839171][1101][1114][klog] INFO: cmdline: kernel.halt-on-panic=true [00000.839172][1101][1114][klog] INFO: cmdline: devmgr.suspend-timeout-debug=true [00000.839173][1101][1114][klog] INFO: cmdline: TERM=dumb [00000.839174][1101][1114][klog] INFO: cmdline: kernel.serial=legacy [00000.839174][1101][1114][klog] INFO: cmdline: multiboot.boot_loader_name=qemu [00000.839175][1101][1114][klog] INFO: cmdline: devhost.asan.strict=false [00000.839176][1101][1114][klog] INFO: cmdline: devmgr.require-system=true [00000.839177][1101][1114][klog] INFO: cmdline: zircon.system.pkgfs.cmd=bin/pkgsvr+09837f9d348e49ed20780c9606eaf21da324efac837791532da80100ba2be410 [00000.839178][1101][1114][klog] INFO: cmdline: zircon.system.pkgfs.file.bin/pkgsvr=2ecb4b100adb8b2da45e4c6dba5439aa3489d7eaa3007200f1f6f2a72f62f364 [00000.839179][1101][1114][klog] INFO: cmdline: zircon.system.pkgfs.file.lib/ld.so.1=9f9954f7cb3c3490edc45e24b2181196753cf990f69387b5c564214a9d66f681 [00000.839180][1101][1114][klog] INFO: cmdline: zircon.system.pkgfs.file.lib/libfdio.so=0713dccae7391ad9793c92522d6e634047629ba296c8177bc60f1692fad644eb [00000.839185][1101][1114][klog] INFO: devmgr: svc init [00000.839960][1101][1114][klog] INFO: devmgr: launch /boot/bin/svchost (svchost) OK [00000.839963][1101][1114][klog] INFO: devmgr: vfs init [00000.845979][1101][1114][klog] INFO: devmgr: launch /boot/bin/fshost (fshost) OK [00000.846226][1101][1114][klog] INFO: devmgr: coordinator() [00000.850071][1101][1242][klog] INFO: devmgr: shell startup [00001.203878][1177][1229][klog] INFO: fshost: started. [00001.217085][1101][1247][klog] INFO: devmgr: launch /boot/bin/crashsvc (crashsvc) OK [00001.226191][1101][1237][klog] INFO: devmgr: launch /boot/bin/pwrbtn-monitor (pwrbtn-monitor) OK [00001.233084][1101][1247][klog] INFO: devmgr: launch /boot/bin/netsvc (netsvc) OK [00001.244149][1101][1247][klog] INFO: devmgr: launch /boot/bin/virtual-console (virtual-console) OK [00001.314750][1101][1114][klog] INFO: devcoord: launch devhost 'devhost:sys': pid=2001 [00001.317238][1101][1114][klog] INFO: devcoord: launch devhost 'devhost:test': pid=2038 [00001.318216][1101][1114][klog] INFO: devcoord: full system required, ignoring fallback drivers until /system is loaded [00001.326090][1101][1114][klog] INFO: devcoord: launch devhost 'devhost:root': pid=2080 [00001.356360][1101][1114][klog] INFO: devcoord: launch devhost 'devhost:misc': pid=2141 [00001.387606][2001][2025][klog] INFO: acpi-bus: not using IOMMU [00001.417096][2001][2025][klog] INFO: acpi-pwrbtn: initialized [00001.438103][1101][1242][klog] INFO: devmgr: launch /boot/bin/sh (sh:console) OK [00001.511391][2001][2025][klog] INFO: acpi: published device pci(0x5abf4f2875c0), parent=proxy(0x5abf4f26e0c0), handle=0x5abf4f259390 [00001.511453][2001][2025][klog] INFO: acpi: failed to get PCI base bus number for device 'PCI0' (status 4294967295) [00001.526182][2001][2025][klog] INFO: acpi: published device rtc(0x5abf4f287540), parent=acpi(0x5abf4f26e240), handle=0x5abf4f259840 [00001.533401][2001][2025][klog] INFO: acpi: published device i8042(0x5abf4f2874c0), parent=acpi(0x5abf4f26e240), handle=0x5abf4f2598d0 [00001.538641][2001][2025][klog] INFO: cpuperf_init_once: No PM support [00001.538730][2001][2025][klog] INFO: IntelPT: No PT support [00001.538742][2001][2025][klog] INFO: devhost[proxy/cpu-trace] bind driver '/boot/driver/cpu-trace.so' failed: -2 [00001.539092][1101][1114][klog] INFO: devcoord: rpc: bind-driver 'cpu-trace' status -2 [00001.539650][1504][1560][klog] INFO: vc: new input device /dev/class/input/000 [00001.565493][1101][1114][klog] INFO: devcoord: launch devhost 'devhost:pci#1:1234:1111': pid=2547 [00001.579636][1101][1114][klog] INFO: devcoord: launch devhost 'devhost:pci#2:1af4:1001': pid=2599 [00001.592708][1101][1114][klog] INFO: devcoord: launch devhost 'devhost:pci#3:1af4:1001': pid=2670 [00001.600451][1101][1114][klog] INFO: devcoord: launch devhost 'devhost:pci#5:8086:2922': pid=2750 [00001.609106][2001][2025][klog] INFO: devhost: WARNING: driver '/boot/driver/pc-ps2.so' did not add device in bind() [00001.625296][2001][2850][klog] INFO: i8042 device 0xab 0x41 [00001.635213][1504][1560][klog] INFO: vc: new input device /dev/class/input/001 [00001.643002][2750][2796][klog] INFO: ahci: using MSI interrupt [00001.643129][2547][2572][klog] INFO: bochs_vbe: initialized display, 1024 x 768 (stride=1024 format=00020001) [00001.645830][1504][1560][klog] INFO: vc: new display device /dev/class/display-controller/000/virtcon [00001.663538][1504][1560][klog] INFO: vc: Successfully attached to display 1 [00001.708340][1177][1229][klog] INFO: devmgr: /dev/class/block/000: FVM? [00001.709099][1101][1114][klog] INFO: devcoord: dc_bind_device() '/boot/driver/fvm.so' [00001.723252][1177][1229][klog] INFO: devmgr: mounting minfs [00001.727115][1177][1229][klog] INFO: fshost: cannot read GUID from minfs-formatted device [00001.739206][1177][1229][klog] INFO: devmgr: launch /boot/bin/blobfs (blobfs:/blob) OK [00001.820658][2001][2850][klog] INFO: i8042 device 0x00 [00001.824865][2001][2025][klog] INFO: hid: boot mouse hack for "hid-device-002": report count (1->1), inp sz (24->24), out sz (0->0), feat sz (0->0) [00001.825711][1177][1229][klog] INFO: fshost: starting 'bin/pkgsvr' '09837f9d348e49ed20780c9606eaf21da324efac837791532da80100ba2be410'... [00001.856279][1504][1560][klog] INFO: vc: new input device /dev/class/input/002 [00001.886524][1177][1229][klog] INFO: devmgr: launch bin/pkgsvr (pkgfs) OK [00001.924432][3257][3308][klog] INFO: pkgsvr: system: will be served from 09837f9d348e49ed20780c9606eaf21da324efac837791532da80100ba2be410 [00001.924521][3257][3308][klog] INFO: pkgsvr: pkgfs serving index /data/pkgfs_index from blobfs /blob [00001.944791][1177][1229][klog] INFO: devmgr: mounting minfs [00001.953660][1177][1229][klog] INFO: devmgr: launch /boot/bin/minfs (minfs:/data) OK [00001.974274][1101][3569][klog] INFO: devmgr: adding system driver 'brcmfmac' '/system/driver/brcmfmac.so' [00002.001619][1101][1566][klog] INFO: devmgr: launch /system/bin/appmgr (appmgr) OK [00002.001732][1101][1566][klog] INFO: zircon.autorun.system: starting '/boot/bin/sh' '/boot/infra/runcmds'... [00002.026349][1101][1566][klog] INFO: devmgr: launch /boot/bin/sh (autorun:system) OK [00002.062616][1101][3569][klog] INFO: devmgr: adding system driver 'bthost' '/system/driver/bthost.so' [00002.092917][1101][3569][klog] INFO: devmgr: adding system driver 'wlantapctl' '/system/driver/wlantap.so' [00002.120581][1101][3569][klog] INFO: devmgr: adding system driver 'gpu' '/system/driver/libmsd_intel.so' [00002.156865][1101][3569][klog] INFO: devmgr: adding system driver 'bthci_fake' '/system/driver/bthci-fake.so' [00002.188949][1101][3569][klog] INFO: devmgr: adding system driver 'usb_video' '/system/driver/usb_video.so' [00002.215411][1101][3569][klog] INFO: devmgr: adding system driver 'bt_passthrough_hci' '/system/driver/bthci-passthrough.so' [00002.259945][1101][3569][klog] INFO: devmgr: adding system driver 'wlan' '/system/driver/wlan.so' [00002.303109][1101][3569][klog] INFO: devmgr: adding system driver 'btintel' '/system/driver/btintel.so' [00002.340513][1101][3569][klog] INFO: devmgr: adding system driver 'wlan' '/system/driver/wlanphy.so' [00002.386315][4558][4575][klog] INFO: [00002.386342][4558][4575][klog] INFO: ------------------------------------------------ [00002.386345][4558][4575][klog] INFO: RUNNING TEST: /pkgfs/packages/catapult_converter/0/test/catapult_converter_test [00002.386345][4558][4575][klog] INFO: [00002.387672][1101][3569][klog] INFO: devmgr: adding system driver 'ralink' '/system/driver/ralink.so' [00002.431516][1101][3569][klog] INFO: devmgr: adding system driver 'qmi_usb' '/system/driver/qmi-transport.so' [00002.457983][1101][3569][klog] INFO: devmgr: adding system driver 'wlan' '/system/driver/wlanif.so' [00002.495407][1101][3569][klog] INFO: devmgr: adding system driver 'rtl88xx' '/system/driver/rtl88xx.so' [00002.528859][1101][3569][klog] INFO: devmgr: adding system driver 'ath10k_pci' '/system/driver/ath10k.so' [00002.543912][1101][3569][klog] INFO: devmgr: adding system driver 'bthog' '/system/driver/bthog.so' [00002.547244][1101][1114][klog] INFO: devcoord: fallback driver 'usb_composite' is available [00002.547285][1101][1114][klog] INFO: devcoord: fallback driver 'intel_disp' is available [00002.547288][1101][1114][klog] INFO: devcoord: driver 'bthog' added [00002.547294][1101][1114][klog] INFO: devcoord: driver 'ath10k_pci' added [00002.547297][1101][1114][klog] INFO: devcoord: driver 'rtl88xx' added [00002.547299][1101][1114][klog] INFO: devcoord: driver 'wlan' added [00002.547303][1101][1114][klog] INFO: devcoord: driver 'qmi_usb' added [00002.547304][1101][1114][klog] INFO: devcoord: driver 'ralink' added [00002.547306][1101][1114][klog] INFO: devcoord: driver 'wlan' added [00002.547308][1101][1114][klog] INFO: devcoord: driver 'btintel' added [00002.547309][1101][1114][klog] INFO: devcoord: driver 'wlan' added [00002.547311][1101][1114][klog] INFO: devcoord: driver 'usb_video' added [00002.547313][1101][1114][klog] INFO: devcoord: driver 'bthci_fake' added [00002.547315][1101][1114][klog] INFO: devcoord: driver 'gpu' added [00002.547317][1101][1114][klog] INFO: devcoord: driver 'wlantapctl' added [00002.547423][1101][1114][klog] INFO: devcoord: driver 'bthost' added [00002.547425][1101][1114][klog] INFO: devcoord: driver 'brcmfmac' added [00002.547428][1101][1114][klog] INFO: devcoord: driver 'bt_passthrough_hci' added [00002.547430][1101][1114][klog] INFO: devcoord: driver 'usb_composite' added [00002.547432][1101][1114][klog] INFO: devcoord: driver 'intel_disp' added [00002.622021][4558][4575][klog] INFO: Running main() from gtest_main.cc [00002.623686][4558][4575][klog] INFO: [==========] Running 8 tests from 2 test cases. [00002.623712][4558][4575][klog] INFO: [----------] Global test environment set-up. [00002.623713][4558][4575][klog] INFO: [----------] 1 test from TestTools [00002.623713][4558][4575][klog] INFO: [ RUN ] TestTools.SplitLines [00002.626602][4558][4575][klog] INFO: [ OK ] TestTools.SplitLines (3 ms) [00002.626705][4558][4575][klog] INFO: [----------] 1 test from TestTools (3 ms total) [00002.626726][4558][4575][klog] INFO: [00002.626727][4558][4575][klog] INFO: [----------] 7 tests from CatapultConverter [00002.626728][4558][4575][klog] INFO: [ RUN ] CatapultConverter.Convert [00002.644813][4558][4575][klog] INFO: [ OK ] CatapultConverter.Convert (18 ms) [00002.644841][4558][4575][klog] INFO: [ RUN ] CatapultConverter.ConvertWithSplitFirst [00002.645604][4558][4575][klog] INFO: [ OK ] CatapultConverter.ConvertWithSplitFirst (1 ms) [00002.645631][4558][4575][klog] INFO: [ RUN ] CatapultConverter.ConvertThroughputUnits [00002.646365][4558][4575][klog] INFO: [ OK ] CatapultConverter.ConvertThroughputUnits (1 ms) [00002.646412][4558][4575][klog] INFO: [ RUN ] CatapultConverter.ConvertBytesUnit [00002.646413][4558][4575][klog] INFO: [ OK ] CatapultConverter.ConvertBytesUnit (0 ms) [00002.646413][4558][4575][klog] INFO: [ RUN ] CatapultConverter.ZeroValues [00002.646414][4558][4575][klog] INFO: [ OK ] CatapultConverter.ZeroValues (0 ms) [00002.646414][4558][4575][klog] INFO: [ RUN ] CatapultConverter.NegativeValues [00002.646767][4558][4575][klog] INFO: [ OK ] CatapultConverter.NegativeValues (0 ms) [00002.646795][4558][4575][klog] INFO: [ RUN ] CatapultConverter.ConverterMain [00002.666239][4558][4575][klog] INFO: [ OK ] CatapultConverter.ConverterMain (9 ms) [00002.666278][4558][4575][klog] INFO: [----------] 7 tests from CatapultConverter (29 ms total) [00002.666279][4558][4575][klog] INFO: [00002.666279][4558][4575][klog] INFO: [----------] Global test environment tear-down [00002.666280][4558][4575][klog] INFO: [==========] 8 tests from 2 test cases ran. (32 ms total) [00002.666281][4558][4575][klog] INFO: [ PASSED ] 8 tests. [00002.671817][4558][4575][klog] INFO: PASSED: /pkgfs/packages/catapult_converter/0/test/catapult_converter_test passed [00002.790097][4558][4575][klog] INFO: [00002.790129][4558][4575][klog] INFO: ------------------------------------------------ [00002.790133][4558][4575][klog] INFO: RUNNING TEST: /pkgfs/packages/dart_zircon_test/0/test/dart_zircon_test [00002.790133][4558][4575][klog] INFO: [00003.317283][6645][6657][klog] INFO: netstack: started [00003.318563][6645][6657][klog] INFO: netstack: socket server started [00003.318998][6645][6657][klog] INFO: netstack: OnInterfacesChanged failed: ErrPeerClosed: zx.Channel.Write [00003.525174][7164][7176][klog] INFO: [INFO:cobalt_main.cc(102)] Cobalt client schedule params: schedule_interval=3600 seconds, min_interval=10 seconds, initial_interval=60 seconds. [00003.600901][1568893177][0][amber] INFO: monitoring for updates [00003.601609][1568893177][0][amber] INFO: system update monitor exited [00003.609841][1568893177][0][amber] INFO: control_server: could not get update for device_settings_manager/0: daemon: no update found for device_settings_manager/0/ [00003.614615][1568893177][0][amber] INFO: control_server: could not get update for sshd-host/0: daemon: no update found for sshd-host/0/ [00003.614749][1568893177][0][amber] INFO: control_server: could not get update for thermd/0: daemon: no update found for thermd/0/ [00003.614790][1568893177][0][amber] INFO: control_server: could not get update for netcfg/0: daemon: no update found for netcfg/0/ [00003.614849][1568893177][0][amber] INFO: control_server: could not get update for dart_zircon_test/0: daemon: no update found for dart_zircon_test/0/ [00003.614887][1568893177][0][amber] INFO: control_server: could not get update for trace_manager/0: daemon: no update found for trace_manager/0/ [00003.614939][1568893177][0][amber] INFO: control_server: could not get update for cobalt_system_metrics/0: daemon: no update found for cobalt_system_metrics/0/ [00003.615347][1568893177][0][amber] INFO: control_server: could not get update for wlancfg/0: daemon: no update found for wlancfg/0/ [00003.615449][1568893177][0][amber] INFO: control_server: could not get update for network_time/0: daemon: no update found for network_time/0/ [00003.892000][1568893177][0][amber] INFO: control_server: could not get update for dart_jit_runner/0: daemon: no update found for dart_jit_runner/0/ [00003.974689][8374][8386][klog] INFO: netcfg: started [00004.067647][8463][8475][klog] INFO: [INFO:main.cc(35)] Trace Manager starting with config: /pkg/data/tracing.config [00004.375074][1568893177][0][amber] INFO: control_server: could not get update for cpuperf_provider/0: daemon: no update found for cpuperf_provider/0/ [00004.393651][1568893177][0][amber] INFO: control_server: could not get update for ktrace_provider/0: daemon: no update found for ktrace_provider/0/ [00004.413924][1568893177][0][amber] INFO: control_server: could not get update for wlanstack2/0: daemon: no update found for wlanstack2/0/ [00004.498292][8641][9347][klog] INFO: Observatory server failed to start after 1 tries [00004.593819][9334][9358][network_time] INFO: main.cc(29): Opening client config from /pkg/data/roughtime-servers.json [00004.593834][9334][9358][network_time] INFO: timezone.cc(24): started [00004.736173][8641][8998][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/dart_zircon_test#meta/dart_zircon_test.cmx is running from kernel [00004.755156][9334][9358][network_time] ERROR: garnet/bin/network_time/roughtime_server.cc(61): resolving roughtime.sandbox.google.com:2002: Name does not resolve [00004.831633][8641][8998][klog] INFO: 00:00 +0: fromFile [00004.836351][10277][10289][klog] INFO: wlanstack2 [I]: Starting [00004.885183][8641][8998][klog] INFO: 00:00 +1: create socket [00004.891268][8641][8998][klog] INFO: 00:00 +2: close socket [00004.895709][8641][8998][klog] INFO: 00:00 +3: read write socket [00004.899646][8641][8998][klog] INFO: 00:00 +4: partial read socket [00004.902228][8641][8998][klog] INFO: 00:00 +5: partial write socket [00004.904636][8641][8998][klog] INFO: 00:00 +6: async wait socket read [00004.907586][8641][8998][klog] INFO: 00:00 +7: async wait socket closed [00004.910199][8641][8998][klog] INFO: 00:00 +8: create channel [00004.912127][8641][8998][klog] INFO: 00:00 +9: close channel [00004.914785][8641][8998][klog] INFO: 00:00 +10: channel bytes [00004.917303][8641][8998][klog] INFO: 00:00 +11: channel handles [00004.919716][8641][8998][klog] INFO: 00:00 +12: async wait channel read [00004.922710][8641][8998][klog] INFO: 00:00 +13: async wait channel closed [00004.933612][8641][8998][klog] INFO: 00:00 +14: All tests passed! [00004.948492][4558][4575][klog] INFO: PASSED: /pkgfs/packages/dart_zircon_test/0/test/dart_zircon_test passed [00005.014490][4558][4575][klog] INFO: [00005.014620][4558][4575][klog] INFO: ------------------------------------------------ [00005.014627][4558][4575][klog] INFO: RUNNING TEST: /pkgfs/packages/deprecated_loop_tests/0/test/deprecated_loop_unittests [00005.014684][4558][4575][klog] INFO: [00005.069958][4558][4575][klog] INFO: [==========] Running 15 tests from 2 test cases. [00005.070005][4558][4575][klog] INFO: [----------] Global test environment set-up. [00005.070006][4558][4575][klog] INFO: [----------] 14 tests from MessageLoop [00005.070007][4558][4575][klog] INFO: [ RUN ] MessageLoop.Current [00005.070010][4558][4575][klog] INFO: [ OK ] MessageLoop.Current (0 ms) [00005.070010][4558][4575][klog] INFO: [ RUN ] MessageLoop.RunsTasksOnCurrentThread [00005.071457][4558][4575][klog] INFO: [ OK ] MessageLoop.RunsTasksOnCurrentThread (1 ms) [00005.071486][4558][4575][klog] INFO: [ RUN ] MessageLoop.CanRunTasks [00005.071487][4558][4575][klog] INFO: [ OK ] MessageLoop.CanRunTasks (0 ms) [00005.071488][4558][4575][klog] INFO: [ RUN ] MessageLoop.CanPostTasksFromTasks [00005.071560][4558][4575][klog] INFO: [ OK ] MessageLoop.CanPostTasksFromTasks (0 ms) [00005.071561][4558][4575][klog] INFO: [ RUN ] MessageLoop.TriplyNestedTasks [00005.071647][4558][4575][klog] INFO: [ OK ] MessageLoop.TriplyNestedTasks (0 ms) [00005.071647][4558][4575][klog] INFO: [ RUN ] MessageLoop.CanRunTasksInOrder [00005.071735][4558][4575][klog] INFO: [ OK ] MessageLoop.CanRunTasksInOrder (0 ms) [00005.071736][4558][4575][klog] INFO: [ RUN ] MessageLoop.CanPreloadTasks [00005.071739][4558][4575][klog] INFO: [ OK ] MessageLoop.CanPreloadTasks (0 ms) [00005.071741][4558][4575][klog] INFO: [ RUN ] MessageLoop.AfterTaskCallbacks [00005.071901][4558][4575][klog] INFO: [ OK ] MessageLoop.AfterTaskCallbacks (0 ms) [00005.071902][4558][4575][klog] INFO: [ RUN ] MessageLoop.RemoveAfterTaskCallbacksDuringCallback [00005.166020][4558][4575][klog] INFO: [ OK ] MessageLoop.RemoveAfterTaskCallbacksDuringCallback (0 ms) [00005.166092][4558][4575][klog] INFO: [ RUN ] MessageLoop.TaskDestructionTime [00005.166100][4558][4575][klog] INFO: [ OK ] MessageLoop.TaskDestructionTime (0 ms) [00005.166100][4558][4575][klog] INFO: [ RUN ] MessageLoop.CanQuitCurrent [00005.166101][4558][4575][klog] INFO: [ OK ] MessageLoop.CanQuitCurrent (0 ms) [00005.166102][4558][4575][klog] INFO: [ RUN ] MessageLoop.CanQuitManyTimes [00005.166103][4558][4575][klog] INFO: [ OK ] MessageLoop.CanQuitManyTimes (0 ms) [00005.166290][4558][4575][klog] INFO: [ RUN ] MessageLoop.FDWaiter [00005.166293][4558][4575][klog] INFO: [ OK ] MessageLoop.FDWaiter (0 ms) [00005.166294][4558][4575][klog] INFO: [ RUN ] MessageLoop.TaskRunnerAvailableDuringLoopDestruction [00005.166294][4558][4575][klog] INFO: [ OK ] MessageLoop.TaskRunnerAvailableDuringLoopDestruction (0 ms) [00005.166295][4558][4575][klog] INFO: [----------] 14 tests from MessageLoop (2 ms total) [00005.166296][4558][4575][klog] INFO: [00005.166298][4558][4575][klog] INFO: [----------] 1 test from Thread [00005.166298][4558][4575][klog] INFO: [ RUN ] Thread.Control [00005.166299][4558][4575][klog] INFO: [ OK ] Thread.Control (3 ms) [00005.166299][4558][4575][klog] INFO: [----------] 1 test from Thread (3 ms total) [00005.166300][4558][4575][klog] INFO: [00005.166301][4558][4575][klog] INFO: [----------] Global test environment tear-down [00005.166302][4558][4575][klog] INFO: [==========] 15 tests from 2 test cases ran. (6 ms total) [00005.166302][4558][4575][klog] INFO: [ PASSED ] 15 tests. [00005.166583][4558][4575][klog] INFO: PASSED: /pkgfs/packages/deprecated_loop_tests/0/test/deprecated_loop_unittests passed [00005.328442][4558][4575][klog] INFO: [00005.328475][4558][4575][klog] INFO: ------------------------------------------------ [00005.328478][4558][4575][klog] INFO: RUNNING TEST: /pkgfs/packages/fidl_bindings_test/0/test/fidl_bindings_test [00005.328478][4558][4575][klog] INFO: [00005.427854][1568893177][0][amber] INFO: control_server: could not get update for fidl_bindings_test/0: daemon: no update found for fidl_bindings_test/0/ [00005.469848][1568893177][0][amber] INFO: control_server: could not get update for dart_jit_runner/0: daemon: no update found for dart_jit_runner/0/ [00005.874796][11243][11597][klog] INFO: Observatory server failed to start after 1 tries [00006.012818][11243][11459][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_bindings_test#meta/fidl_bindings_test.cmx is running from kernel [00006.053060][11243][11459][klog] INFO: clone-test [00006.096673][11243][11459][klog] INFO: 00:00 +0: two way (setUpAll) [00006.142866][1568893177][0][amber] INFO: control_server: could not get update for fidl_bindings_test_server/0: daemon: no update found for fidl_bindings_test_server/0/ [00006.143308][4943][5013][klog] INFO: [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 [00006.143365][4943][5013][klog] INFO: .cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00006.153787][11243][11459][klog] INFO: 00:00 +0: two way no args [00006.247816][11243][11882][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_bindings_test_server is running from kernel [00006.301963][11243][11459][klog] INFO: 00:00 +1: two way string arg [00006.311387][11243][11459][klog] INFO: 00:00 +2: two way three args [00006.330073][11243][11459][klog] INFO: 00:00 +3: two way (tearDownAll) [00006.352332][11243][11459][klog] INFO: Proxy error: Peer unexpectedly closed [00006.354062][11243][11459][klog] INFO: 00:00 +3: proxy state initial [00006.354954][11243][11459][klog] INFO: Proxy error: Peer unexpectedly closed [00006.358320][11243][11459][klog] INFO: 00:00 +4: proxy state request [00006.362909][11243][11459][klog] INFO: 00:00 +5: proxy state bind [00006.368032][11243][11459][klog] INFO: Proxy error: Peer unexpectedly closed [00006.368815][11243][11459][klog] INFO: 00:00 +6: proxy state after bind [00006.374332][11243][11459][klog] INFO: 00:00 +7: proxy state unbind [00006.379283][11243][11459][klog] INFO: 00:00 +8: proxy state close [00006.383306][11243][11459][klog] INFO: 00:00 +9: proxy state after close [00006.391064][11243][11459][klog] INFO: 00:00 +10: hash and equality enums simple [00006.394072][11243][11459][klog] INFO: 00:00 +11: hash and equality enums constructed [00006.396232][11243][11459][klog] INFO: 00:00 +12: hash and equality enums different types [00006.398512][11243][11459][klog] INFO: 00:00 +13: hash and equality enums map [00006.400497][11243][11459][klog] INFO: 00:00 +14: hash and equality structs no handles [00006.403932][11243][11459][klog] INFO: 00:00 +15: hash and equality structs handles [00006.407401][11243][11459][klog] INFO: 00:00 +16: hash and equality unions equality [00006.412457][11243][11459][klog] INFO: 00:00 +17: hash and equality unions hash [00006.414866][11243][11459][klog] INFO: 00:00 +18: one way (setUpAll) [00006.418485][1568893177][0][amber] INFO: control_server: could not get update for fidl_bindings_test_server/0: daemon: no update found for fidl_bindings_test_server/0/ [00006.418722][4943][5013][klog] INFO: [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 [00006.418765][4943][5013][klog] INFO: .cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00006.420191][11243][11459][klog] INFO: 00:00 +18: one way no args [00006.510250][11243][12092][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_bindings_test_server is running from kernel [00006.553002][11243][11459][klog] INFO: 00:00 +19: one way string arg [00006.561413][11243][11459][klog] INFO: 00:00 +20: one way three args [00006.578328][11243][11459][klog] INFO: Proxy error: Peer unexpectedly closed [00006.578786][11243][11459][klog] INFO: Proxy error: Peer unexpectedly closed [00006.579647][11243][11459][klog] INFO: 00:00 +21: one way table [00006.596160][11243][11459][klog] INFO: 00:00 +22: one way partial table [00006.601300][11243][11459][klog] INFO: 00:00 +23: one way empty table [00006.606791][11243][11459][klog] INFO: 00:00 +24: one way (tearDownAll) [00006.610622][11243][11459][klog] INFO: 00:00 +24: clone exact [00006.613918][11243][11459][klog] INFO: 00:00 +25: clone modify field [00006.617099][11243][11459][klog] INFO: 00:00 +26: clone set field [00006.619552][11243][11459][klog] INFO: 00:00 +27: clone unset field [00006.622953][11243][11459][klog] INFO: 00:00 +28: failure remote close during call [00006.635545][1568893177][0][amber] INFO: control_server: could not get update for fidl_bindings_test_server/0: daemon: no update found for fidl_bindings_test_server/0/ [00006.636739][4943][5013][klog] INFO: [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 [00006.637213][4943][5013][klog] INFO: .cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00006.655473][11243][11459][klog] INFO: Proxy error: Peer unexpectedly closed [00006.657820][11243][11459][klog] INFO: Proxy error: Peer unexpectedly closed [00006.745644][11243][12279][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_bindings_test_server is running from kernel [00006.879034][11243][12194][klog] INFO: Observatory server failed to start after 2 tries [00006.906562][11243][11459][klog] INFO: Proxy error: Peer unexpectedly closed [00006.911673][11243][11459][klog] INFO: 00:00 +29: failure local close during call [00006.919113][4943][5013][klog] INFO: [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 [00006.919153][4943][5013][klog] INFO: .cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00006.917743][1568893177][0][amber] INFO: control_server: could not get update for fidl_bindings_test_server/0: daemon: no update found for fidl_bindings_test_server/0/ [00006.948760][11243][11459][klog] INFO: Proxy error: Peer unexpectedly closed [00006.949861][11243][11459][klog] INFO: 00:00 +30: failure server killed during call [00006.950413][11243][11459][klog] INFO: Proxy error: Peer unexpectedly closed [00006.979490][4943][5013][klog] INFO: [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 [00006.979555][4943][5013][klog] INFO: .cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00006.979132][1568893177][0][amber] INFO: control_server: could not get update for fidl_bindings_test_server/0: daemon: no update found for fidl_bindings_test_server/0/ [00007.087663][11243][12448][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_bindings_test_server is running from kernel [00007.091058][11243][11459][klog] INFO: Proxy error: Peer unexpectedly closed [00007.102803][11243][11459][klog] INFO: Proxy error: Peer unexpectedly closed [00007.124944][11243][12552][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_bindings_test_server is running from kernel [00007.137983][11243][11459][klog] INFO: Proxy error: Peer unexpectedly closed [00007.138990][11243][11459][klog] INFO: Proxy error: Peer unexpectedly closed [00007.139284][11243][11459][klog] INFO: Proxy error: Peer unexpectedly closed [00007.142069][11243][11459][klog] INFO: 00:01 +31: failure one-way call on closed proxy [00007.150262][11243][11459][klog] INFO: 00:01 +32: failure two-way call on closed proxy [00007.157672][11243][11459][klog] INFO: 00:01 +33: failure listen for events on a closed proxy [00007.158175][1568893177][0][amber] INFO: control_server: could not get update for fidl_bindings_test_server/0: daemon: no update found for fidl_bindings_test_server/0/ [00007.158335][1568893177][0][amber] INFO: control_server: could not get update for fidl_bindings_test_server/0: daemon: no update found for fidl_bindings_test_server/0/ [00007.158995][4943][5013][klog] INFO: [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 [00007.159059][4943][5013][klog] INFO: .cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00007.170284][11243][11459][klog] INFO: 00:01 +34: failure proxy closes while listening for events [00007.177557][11243][11459][klog] INFO: 00:01 +35: failure binding closes immediately after sending response [00007.186676][11243][11459][klog] INFO: Proxy error: Peer unexpectedly closed [00007.189207][11243][11459][klog] INFO: 00:01 +36: unbound one-way call on unbound proxy [00007.191583][11243][11459][klog] INFO: 00:01 +37: unbound two-way call on unbound proxy [00007.194187][11243][11459][klog] INFO: 00:01 +38: unbound event listen on unbound proxy [00007.198558][11243][11459][klog] INFO: 00:01 +39: events (setUpAll) [00007.205639][11243][11459][klog] INFO: 00:01 +39: events empty [00007.225136][4943][5013][klog] INFO: [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 [00007.225198][4943][5013][klog] INFO: .cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00007.255785][1568893177][0][amber] INFO: control_server: could not get update for fidl_bindings_test_server/0: daemon: no update found for fidl_bindings_test_server/0/ [00007.256290][4943][5013][klog] INFO: [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 [00007.256349][4943][5013][klog] INFO: .cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00007.256915][1568893177][0][amber] INFO: control_server: could not get update for fidl_bindings_test_server/0: daemon: no update found for fidl_bindings_test_server/0/ [00007.256941][1568893177][0][amber] INFO: control_server: could not get update for fidl_bindings_test_server/0: daemon: no update found for fidl_bindings_test_server/0/ [00007.256957][1568893177][0][amber] INFO: control_server: could not get update for fidl_bindings_test_server/0: daemon: no update found for fidl_bindings_test_server/0/ [00007.261059][4943][5013][klog] INFO: [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 [00007.261100][4943][5013][klog] INFO: .cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00007.303741][4943][5013][klog] INFO: [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 [00007.303784][4943][5013][klog] INFO: .cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00007.306514][4943][5013][klog] INFO: [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 [00007.306543][4943][5013][klog] INFO: .cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00007.572370][11243][12825][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_bindings_test_server is running from kernel [00007.587447][11243][11459][klog] INFO: Proxy error: Peer unexpectedly closed [00007.882941][11243][12683][klog] INFO: Observatory server failed to start after 3 tries [00007.934585][11243][12909][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_bindings_test_server is running from kernel [00008.154498][11243][11459][klog] INFO: Proxy error: Peer unexpectedly closed [00008.182120][11243][12976][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_bindings_test_server is running from kernel [00008.190376][11243][11459][klog] INFO: Proxy error: Peer unexpectedly closed [00008.222104][11243][11459][klog] INFO: Proxy error: Peer unexpectedly closed [00008.284748][11243][11459][klog] INFO: Proxy error: Peer unexpectedly closed [00008.294201][11243][11459][klog] INFO: Proxy error: Peer unexpectedly closed [00008.363598][11243][13051][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_bindings_test_server is running from kernel [00008.368595][11243][11459][klog] INFO: Proxy error: Peer unexpectedly closed [00008.369131][11243][11459][klog] INFO: Proxy error: Peer unexpectedly closed [00008.380947][11243][13097][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_bindings_test_server is running from kernel [00008.382877][11243][11459][klog] INFO: Proxy error: Peer unexpectedly closed [00008.383285][11243][11459][klog] INFO: Proxy error: Peer unexpectedly closed [00008.410131][11243][13130][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_bindings_test_server is running from kernel [00008.454749][11243][11459][klog] INFO: 00:02 +40: events string arg [00008.462834][11243][11459][klog] INFO: 00:02 +41: events three args [00008.474999][11243][11459][klog] INFO: 00:02 +42: events multiple events [00008.603102][11243][11459][klog] INFO: 00:02 +43: events (tearDownAll) [00008.624423][11243][11459][klog] INFO: 00:02 +43: All tests passed! [00008.707307][4558][4575][klog] INFO: PASSED: /pkgfs/packages/fidl_bindings_test/0/test/fidl_bindings_test passed [00008.788912][4558][4575][klog] INFO: [00008.788985][4558][4575][klog] INFO: ------------------------------------------------ [00008.789047][4558][4575][klog] INFO: RUNNING TEST: /pkgfs/packages/fidl_compatibility_test/0/test/run_fidl_compatibility_test_topaz.sh [00008.789300][4558][4575][klog] INFO: [00008.867603][4558][4575][klog] INFO: [==========] Running 32 tests from 1 test case. [00008.867636][4558][4575][klog] INFO: [----------] Global test environment set-up. [00008.867637][4558][4575][klog] INFO: [----------] 32 tests from CompatibilityTest/CompatibilityTest [00008.867638][4558][4575][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/0 [00008.887484][4943][5013][klog] INFO: [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 [00008.870235][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_cpp/0: daemon: no update found for fidl_compatibility_test_server_cpp/0/ [00008.887527][4943][5013][klog] INFO: mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00008.954777][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_cpp/0: daemon: no update found for fidl_compatibility_test_server_cpp/0/ [00008.955263][4943][5013][klog] INFO: [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 [00008.955320][4943][5013][klog] INFO: mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00009.061035][4558][4575][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/0 (193 ms) [00009.061073][4558][4575][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/1 [00009.073444][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_cpp/0: daemon: no update found for fidl_compatibility_test_server_cpp/0/ [00009.074230][4943][5013][klog] INFO: [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 [00009.074314][4943][5013][klog] INFO: mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00009.145592][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_dart/0: daemon: no update found for fidl_compatibility_test_server_dart/0/ [00009.146518][4943][5013][klog] INFO: [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_ [00009.146605][4943][5013][klog] INFO: compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00009.198382][1568893177][0][amber] INFO: control_server: could not get update for dart_jit_runner/0: daemon: no update found for dart_jit_runner/0/ [00009.372154][1568893177][0][amber] INFO: control_server: could not get update for timezone/0: daemon: no update found for timezone/0/ [00009.611103][14719][15291][klog] INFO: Observatory listening on http://127.0.0.1:26913/ [00009.618911][14719][15291][klog] INFO: Creating /tmp/dart.services/26913 [00009.642598][14719][14945][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [00009.754184][4558][4575][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/1 (693 ms) [00009.754232][4558][4575][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/2 [00009.755869][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_cpp/0: daemon: no update found for fidl_compatibility_test_server_cpp/0/ [00009.757582][4943][5013][klog] INFO: [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 [00009.757614][4943][5013][klog] INFO: mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00009.842664][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_go/0: daemon: no update found for fidl_compatibility_test_server_go/0/ [00009.843030][4943][5013][klog] INFO: [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 [00009.843116][4943][5013][klog] INFO: atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00009.935944][4558][4575][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/2 (181 ms) [00009.935979][4558][4575][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/3 [00009.963823][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_cpp/0: daemon: no update found for fidl_compatibility_test_server_cpp/0/ [00009.964382][4943][5013][klog] INFO: [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 [00009.964421][4943][5013][klog] INFO: mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00010.038345][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_rust/0: daemon: no update found for fidl_compatibility_test_server_rust/0/ [00010.038801][4943][5013][klog] INFO: [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_ [00010.038843][4943][5013][klog] INFO: compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00010.130057][4558][4575][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/3 (193 ms) [00010.130080][4558][4575][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/4 [00010.131977][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_dart/0: daemon: no update found for fidl_compatibility_test_server_dart/0/ [00010.141755][4943][5013][klog] INFO: [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_ [00010.141798][4943][5013][klog] INFO: compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00010.256401][14719][16436][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [00010.334196][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_cpp/0: daemon: no update found for fidl_compatibility_test_server_cpp/0/ [00010.335791][4943][5013][klog] INFO: [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 [00010.335832][4943][5013][klog] INFO: mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00010.419161][4558][4575][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/4 (290 ms) [00010.419196][4558][4575][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/5 [00010.425950][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_dart/0: daemon: no update found for fidl_compatibility_test_server_dart/0/ [00010.426458][4943][5013][klog] INFO: [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_ [00010.426484][4943][5013][klog] INFO: compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00010.574936][14719][16870][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [00010.641660][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_dart/0: daemon: no update found for fidl_compatibility_test_server_dart/0/ [00010.642085][4943][5013][klog] INFO: [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_ [00010.642174][4943][5013][klog] INFO: compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00010.717014][14719][17052][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [00010.808972][4558][4575][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/5 (389 ms) [00010.808996][4558][4575][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/6 [00010.821204][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_dart/0: daemon: no update found for fidl_compatibility_test_server_dart/0/ [00010.821596][4943][5013][klog] INFO: [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_ [00010.821640][4943][5013][klog] INFO: compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00010.939771][14719][17272][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [00011.020557][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_go/0: daemon: no update found for fidl_compatibility_test_server_go/0/ [00011.020842][4943][5013][klog] INFO: [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 [00011.020872][4943][5013][klog] INFO: atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00011.123597][4558][4575][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/6 (313 ms) [00011.123621][4558][4575][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/7 [00011.128216][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_dart/0: daemon: no update found for fidl_compatibility_test_server_dart/0/ [00011.145626][4943][5013][klog] INFO: [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_ [00011.145653][4943][5013][klog] INFO: compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00011.283764][14719][17793][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [00011.353661][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_rust/0: daemon: no update found for fidl_compatibility_test_server_rust/0/ [00011.354278][4943][5013][klog] INFO: [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_ [00011.354322][4943][5013][klog] INFO: compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00011.446993][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_go/0: daemon: no update found for fidl_compatibility_test_server_go/0/ [00011.447523][4558][4575][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/7 (324 ms) [00011.447524][4943][5013][klog] INFO: [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 [00011.447586][4558][4575][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/8 [00011.447628][4943][5013][klog] INFO: atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00011.556348][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_cpp/0: daemon: no update found for fidl_compatibility_test_server_cpp/0/ [00011.557337][4943][5013][klog] INFO: [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 [00011.557368][4943][5013][klog] INFO: mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00011.627817][4558][4575][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/8 (182 ms) [00011.627841][4558][4575][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/9 [00011.642428][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_go/0: daemon: no update found for fidl_compatibility_test_server_go/0/ [00011.643065][4943][5013][klog] INFO: [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 [00011.643092][4943][5013][klog] INFO: atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00011.739861][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_dart/0: daemon: no update found for fidl_compatibility_test_server_dart/0/ [00011.740714][4943][5013][klog] INFO: [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_ [00011.740797][4943][5013][klog] INFO: compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00011.866528][14719][18911][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [00011.967087][4558][4575][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/9 (339 ms) [00011.967143][4558][4575][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/10 [00011.996575][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_go/0: daemon: no update found for fidl_compatibility_test_server_go/0/ [00012.000431][4943][5013][klog] INFO: [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 [00012.000615][4943][5013][klog] INFO: atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00012.123072][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_go/0: daemon: no update found for fidl_compatibility_test_server_go/0/ [00012.123773][4943][5013][klog] INFO: [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 [00012.123841][4943][5013][klog] INFO: atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00012.200928][4558][4575][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/10 (234 ms) [00012.200970][4558][4575][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/11 [00012.214323][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_go/0: daemon: no update found for fidl_compatibility_test_server_go/0/ [00012.215199][4943][5013][klog] INFO: [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 [00012.215248][4943][5013][klog] INFO: atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00012.309635][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_rust/0: daemon: no update found for fidl_compatibility_test_server_rust/0/ [00012.310175][4943][5013][klog] INFO: [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_ [00012.310449][4943][5013][klog] INFO: compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00012.405288][4558][4575][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/11 (205 ms) [00012.405324][4558][4575][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/12 [00012.408390][4943][5013][klog] INFO: [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_ [00012.408429][4943][5013][klog] INFO: compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00012.407718][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_rust/0: daemon: no update found for fidl_compatibility_test_server_rust/0/ [00012.497720][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_cpp/0: daemon: no update found for fidl_compatibility_test_server_cpp/0/ [00012.498207][4943][5013][klog] INFO: [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 [00012.498237][4943][5013][klog] INFO: mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00012.586561][4558][4575][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/12 (181 ms) [00012.586601][4558][4575][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/13 [00012.634585][4943][5013][klog] INFO: [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_ [00012.634633][4943][5013][klog] INFO: compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00012.634127][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_rust/0: daemon: no update found for fidl_compatibility_test_server_rust/0/ [00012.725509][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_dart/0: daemon: no update found for fidl_compatibility_test_server_dart/0/ [00012.726198][4943][5013][klog] INFO: [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_ [00012.726243][4943][5013][klog] INFO: compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00012.854682][14719][20629][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [00012.951505][4558][4575][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/13 (365 ms) [00012.951544][4558][4575][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/14 [00012.988599][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_rust/0: daemon: no update found for fidl_compatibility_test_server_rust/0/ [00012.999954][4943][5013][klog] INFO: [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_ [00013.000007][4943][5013][klog] INFO: compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00013.080454][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_go/0: daemon: no update found for fidl_compatibility_test_server_go/0/ [00013.081401][4943][5013][klog] INFO: [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 [00013.081518][4943][5013][klog] INFO: atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00013.183251][4558][4575][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/14 (231 ms) [00013.183298][4558][4575][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/15 [00013.202867][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_rust/0: daemon: no update found for fidl_compatibility_test_server_rust/0/ [00013.203387][4943][5013][klog] INFO: [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_ [00013.203422][4943][5013][klog] INFO: compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00013.286657][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_rust/0: daemon: no update found for fidl_compatibility_test_server_rust/0/ [00013.286909][4943][5013][klog] INFO: [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_ [00013.286948][4943][5013][klog] INFO: compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00013.357988][4558][4575][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/15 (175 ms) [00013.358034][4558][4575][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/0 [00013.382046][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_cpp/0: daemon: no update found for fidl_compatibility_test_server_cpp/0/ [00013.382633][4943][5013][klog] INFO: [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 [00013.382713][4943][5013][klog] INFO: mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00013.459909][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_cpp/0: daemon: no update found for fidl_compatibility_test_server_cpp/0/ [00013.460296][4943][5013][klog] INFO: [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 [00013.460339][4943][5013][klog] INFO: mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00013.532877][4558][4575][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/0 (175 ms) [00013.532909][4558][4575][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/1 [00013.567283][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_cpp/0: daemon: no update found for fidl_compatibility_test_server_cpp/0/ [00013.570879][4943][5013][klog] INFO: [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 [00013.570912][4943][5013][klog] INFO: mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00013.649444][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_dart/0: daemon: no update found for fidl_compatibility_test_server_dart/0/ [00013.650277][4943][5013][klog] INFO: [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_ [00013.650321][4943][5013][klog] INFO: compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00013.774147][14719][22135][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [00013.872147][4558][4575][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/1 (339 ms) [00013.872238][4558][4575][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/2 [00013.897299][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_cpp/0: daemon: no update found for fidl_compatibility_test_server_cpp/0/ [00013.897914][4943][5013][klog] INFO: [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 [00013.897949][4943][5013][klog] INFO: mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00013.972915][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_go/0: daemon: no update found for fidl_compatibility_test_server_go/0/ [00013.973622][4943][5013][klog] INFO: [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 [00013.973650][4943][5013][klog] INFO: atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00014.068550][4558][4575][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/2 (196 ms) [00014.068578][4558][4575][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/3 [00014.078140][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_cpp/0: daemon: no update found for fidl_compatibility_test_server_cpp/0/ [00014.082207][4943][5013][klog] INFO: [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 [00014.082280][4943][5013][klog] INFO: mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00014.157673][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_rust/0: daemon: no update found for fidl_compatibility_test_server_rust/0/ [00014.158172][4943][5013][klog] INFO: [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_ [00014.158209][4943][5013][klog] INFO: compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00014.238112][4558][4575][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/3 (170 ms) [00014.238177][4558][4575][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/4 [00014.275506][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_dart/0: daemon: no update found for fidl_compatibility_test_server_dart/0/ [00014.276045][4943][5013][klog] INFO: [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_ [00014.276088][4943][5013][klog] INFO: compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00014.389512][14719][23209][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [00014.462661][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_cpp/0: daemon: no update found for fidl_compatibility_test_server_cpp/0/ [00014.463047][4943][5013][klog] INFO: [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 [00014.463082][4943][5013][klog] INFO: mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00014.552343][4558][4575][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/4 (314 ms) [00014.552382][4558][4575][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/5 [00014.563601][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_dart/0: daemon: no update found for fidl_compatibility_test_server_dart/0/ [00014.570143][4943][5013][klog] INFO: [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_ [00014.570188][4943][5013][klog] INFO: compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00014.691908][14719][23617][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [00014.771927][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_dart/0: daemon: no update found for fidl_compatibility_test_server_dart/0/ [00014.772553][4943][5013][klog] INFO: [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_ [00014.772644][4943][5013][klog] INFO: compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00014.856780][14719][23796][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [00014.962353][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_dart/0: daemon: no update found for fidl_compatibility_test_server_dart/0/ [00014.963206][4943][5013][klog] INFO: [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_ [00014.963411][4943][5013][klog] INFO: compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00014.964918][4558][4575][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/5 (408 ms) [00014.964950][4558][4575][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/6 [00015.095323][14719][24010][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [00015.170388][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_go/0: daemon: no update found for fidl_compatibility_test_server_go/0/ [00015.171047][4943][5013][klog] INFO: [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 [00015.171078][4943][5013][klog] INFO: atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00015.277996][4558][4575][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/6 (317 ms) [00015.290907][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_dart/0: daemon: no update found for fidl_compatibility_test_server_dart/0/ [00015.278019][4558][4575][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/7 [00015.291778][4943][5013][klog] INFO: [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_ [00015.291872][4943][5013][klog] INFO: compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00015.409952][14719][24512][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [00015.486826][4943][5013][klog] INFO: [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_ [00015.486172][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_rust/0: daemon: no update found for fidl_compatibility_test_server_rust/0/ [00015.486875][4943][5013][klog] INFO: compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00015.579978][4558][4575][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/7 (301 ms) [00015.580027][4558][4575][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/8 [00015.581827][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_go/0: daemon: no update found for fidl_compatibility_test_server_go/0/ [00015.588724][4943][5013][klog] INFO: [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 [00015.588758][4943][5013][klog] INFO: atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00015.690765][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_cpp/0: daemon: no update found for fidl_compatibility_test_server_cpp/0/ [00015.691285][4943][5013][klog] INFO: [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 [00015.691341][4943][5013][klog] INFO: mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00015.790206][4558][4575][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/8 (211 ms) [00015.790291][4558][4575][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/9 [00015.817369][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_go/0: daemon: no update found for fidl_compatibility_test_server_go/0/ [00015.817967][4943][5013][klog] INFO: [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 [00015.818002][4943][5013][klog] INFO: atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00015.915495][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_dart/0: daemon: no update found for fidl_compatibility_test_server_dart/0/ [00015.915886][4943][5013][klog] INFO: [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_ [00015.915934][4943][5013][klog] INFO: compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00016.030286][14719][25627][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [00016.111716][4558][4575][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/9 (321 ms) [00016.111756][4558][4575][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/10 [00016.124273][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_go/0: daemon: no update found for fidl_compatibility_test_server_go/0/ [00016.124669][4943][5013][klog] INFO: [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 [00016.124697][4943][5013][klog] INFO: atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00016.235807][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_go/0: daemon: no update found for fidl_compatibility_test_server_go/0/ [00016.236341][4943][5013][klog] INFO: [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 [00016.236384][4943][5013][klog] INFO: atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00016.306699][4558][4575][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/10 (195 ms) [00016.306725][4558][4575][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/11 [00016.331103][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_go/0: daemon: no update found for fidl_compatibility_test_server_go/0/ [00016.338049][4943][5013][klog] INFO: [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 [00016.338084][4943][5013][klog] INFO: atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00016.442164][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_rust/0: daemon: no update found for fidl_compatibility_test_server_rust/0/ [00016.443037][4943][5013][klog] INFO: [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_ [00016.443094][4943][5013][klog] INFO: compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00016.534925][4558][4575][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/11 (228 ms) [00016.534986][4558][4575][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/12 [00016.555756][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_rust/0: daemon: no update found for fidl_compatibility_test_server_rust/0/ [00016.567842][4943][5013][klog] INFO: [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_ [00016.568272][4943][5013][klog] INFO: compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00016.662600][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_cpp/0: daemon: no update found for fidl_compatibility_test_server_cpp/0/ [00016.663097][4943][5013][klog] INFO: [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 [00016.663126][4943][5013][klog] INFO: mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00016.738542][4558][4575][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/12 (202 ms) [00016.738567][4558][4575][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/13 [00016.755015][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_rust/0: daemon: no update found for fidl_compatibility_test_server_rust/0/ [00016.755449][4943][5013][klog] INFO: [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_ [00016.755512][4943][5013][klog] INFO: compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00016.827345][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_dart/0: daemon: no update found for fidl_compatibility_test_server_dart/0/ [00016.828336][4943][5013][klog] INFO: [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_ [00016.828405][4943][5013][klog] INFO: compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00016.949833][14719][27314][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [00017.050309][4558][4575][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/13 (314 ms) [00017.050377][4558][4575][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/14 [00017.093952][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_rust/0: daemon: no update found for fidl_compatibility_test_server_rust/0/ [00017.094768][4943][5013][klog] INFO: [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_ [00017.094809][4943][5013][klog] INFO: compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00017.173034][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_go/0: daemon: no update found for fidl_compatibility_test_server_go/0/ [00017.173958][4943][5013][klog] INFO: [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 [00017.173989][4943][5013][klog] INFO: atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00017.275986][4558][4575][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/14 (225 ms) [00017.276030][4558][4575][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/15 [00017.301959][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_rust/0: daemon: no update found for fidl_compatibility_test_server_rust/0/ [00017.302710][4943][5013][klog] INFO: [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_ [00017.302747][4943][5013][klog] INFO: compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00017.379945][1568893177][0][amber] INFO: control_server: could not get update for fidl_compatibility_test_server_rust/0: daemon: no update found for fidl_compatibility_test_server_rust/0/ [00017.380409][4943][5013][klog] INFO: [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_ [00017.380445][4943][5013][klog] INFO: compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00017.444439][4558][4575][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/15 (168 ms) [00017.444497][4558][4575][klog] INFO: [----------] 32 tests from CompatibilityTest/CompatibilityTest (8577 ms total) [00017.444526][4558][4575][klog] INFO: [00017.444526][4558][4575][klog] INFO: [----------] Global test environment tear-down [00017.444527][4558][4575][klog] INFO: [==========] 32 tests from 1 test case ran. (8577 ms total) [00017.444528][4558][4575][klog] INFO: [ PASSED ] 32 tests. [00017.480443][4558][4575][klog] INFO: PASSED: /pkgfs/packages/fidl_compatibility_test/0/test/run_fidl_compatibility_test_topaz.sh passed [00018.892479][4558][4575][klog] INFO: [00018.892507][4558][4575][klog] INFO: ------------------------------------------------ [00018.892510][4558][4575][klog] INFO: RUNNING TEST: /pkgfs/packages/flutter_runner_tests/0/test/flutter_runner_unittests [00018.892511][4558][4575][klog] INFO: [00019.051131][4558][4575][klog] INFO: Running main() from gtest_main.cc [00019.051183][4558][4575][klog] INFO: [==========] Running 10 tests from 1 test case. [00019.051184][4558][4575][klog] INFO: [----------] Global test environment set-up. [00019.051184][4558][4575][klog] INFO: [----------] 10 tests from FuchsiaFontManagerTest [00019.051186][4558][4575][klog] INFO: [ RUN ] FuchsiaFontManagerTest.ValidResponseWhenCharacterFound [00019.085508][1568893177][0][amber] INFO: control_server: could not get update for fonts/0: daemon: no update found for fonts/0/ [00019.311983][4558][4575][klog] INFO: [ OK ] FuchsiaFontManagerTest.ValidResponseWhenCharacterFound (260 ms) [00019.312023][4558][4575][klog] INFO: [ RUN ] FuchsiaFontManagerTest.EmptyResponseWhenCharacterNotFound [00019.334739][1568893177][0][amber] INFO: control_server: could not get update for fonts/0: daemon: no update found for fonts/0/ [00019.555441][4558][4575][klog] INFO: [ OK ] FuchsiaFontManagerTest.EmptyResponseWhenCharacterNotFound (244 ms) [00019.555471][4558][4575][klog] INFO: [ RUN ] FuchsiaFontManagerTest.Caching [00019.578079][1568893177][0][amber] INFO: control_server: could not get update for fonts/0: daemon: no update found for fonts/0/ [00019.805578][4558][4575][klog] INFO: [ OK ] FuchsiaFontManagerTest.Caching (250 ms) [00019.805618][4558][4575][klog] INFO: [ RUN ] FuchsiaFontManagerTest.TypefaceOutlivesManager [00019.827985][1568893177][0][amber] INFO: control_server: could not get update for fonts/0: daemon: no update found for fonts/0/ [00020.048525][4558][4575][klog] INFO: [ OK ] FuchsiaFontManagerTest.TypefaceOutlivesManager (243 ms) [00020.048553][4558][4575][klog] INFO: [ RUN ] FuchsiaFontManagerTest.ReleaseThenCreateAgain [00020.071979][1568893177][0][amber] INFO: control_server: could not get update for fonts/0: daemon: no update found for fonts/0/ [00020.308507][4558][4575][klog] INFO: [ OK ] FuchsiaFontManagerTest.ReleaseThenCreateAgain (260 ms) [00020.308557][4558][4575][klog] INFO: [ RUN ] FuchsiaFontManagerTest.ReleasedTypefaceIsPurged [00020.339192][1568893177][0][amber] INFO: control_server: could not get update for fonts/0: daemon: no update found for fonts/0/ [00020.567984][4558][4575][klog] INFO: [ OK ] FuchsiaFontManagerTest.ReleasedTypefaceIsPurged (259 ms) [00020.568012][4558][4575][klog] INFO: [ RUN ] FuchsiaFontManagerTest.MatchUnknownFamily [00020.580027][1568893177][0][amber] INFO: control_server: could not get update for fonts/0: daemon: no update found for fonts/0/ [00020.796288][4558][4575][klog] INFO: [ OK ] FuchsiaFontManagerTest.MatchUnknownFamily (227 ms) [00020.796317][4558][4575][klog] INFO: [ RUN ] FuchsiaFontManagerTest.MatchKnownFamily [00020.819710][1568893177][0][amber] INFO: control_server: could not get update for fonts/0: daemon: no update found for fonts/0/ [00021.049634][4558][4575][klog] INFO: [ OK ] FuchsiaFontManagerTest.MatchKnownFamily (255 ms) [00021.049673][4558][4575][klog] INFO: [ RUN ] FuchsiaFontManagerTest.FontFamilyGetStyle [00021.084821][1568893177][0][amber] INFO: control_server: could not get update for fonts/0: daemon: no update found for fonts/0/ [00021.295356][4558][4575][klog] INFO: [ OK ] FuchsiaFontManagerTest.FontFamilyGetStyle (246 ms) [00021.295402][4558][4575][klog] INFO: [ RUN ] FuchsiaFontManagerTest.FontFamilyCreateTypeface [00021.318664][1568893177][0][amber] INFO: control_server: could not get update for fonts/0: daemon: no update found for fonts/0/ [00021.534942][4558][4575][klog] INFO: [ OK ] FuchsiaFontManagerTest.FontFamilyCreateTypeface (239 ms) [00021.534990][4558][4575][klog] INFO: [----------] 10 tests from FuchsiaFontManagerTest (2483 ms total) [00021.535034][4558][4575][klog] INFO: [00021.535055][4558][4575][klog] INFO: [----------] Global test environment tear-down [00021.535056][4558][4575][klog] INFO: [==========] 10 tests from 1 test case ran. (2484 ms total) [00021.535059][4558][4575][klog] INFO: [ PASSED ] 10 tests. [00021.564795][4558][4575][klog] INFO: PASSED: /pkgfs/packages/flutter_runner_tests/0/test/flutter_runner_unittests passed [00021.624320][4558][4575][klog] INFO: [00021.624413][4558][4575][klog] INFO: ------------------------------------------------ [00021.624416][4558][4575][klog] INFO: RUNNING TEST: /pkgfs/packages/fuchsia_modular_package_integration_tests/0/test/fuchsia_modular_package_integration_tests [00021.624416][4558][4575][klog] INFO: [00021.717553][1568893177][0][amber] INFO: control_server: could not get update for fuchsia_modular_package_integration_tests/0: daemon: no update found for fuchsia_modular_package_integration_tests/0/ [00021.759380][1568893177][0][amber] INFO: control_server: could not get update for dart_jit_runner/0: daemon: no update found for dart_jit_runner/0/ [00022.020108][32624][32944][klog] INFO: Observatory server failed to start after 1 tries [00022.269575][32624][32817][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fuchsia_modular_package_integration_tests#meta/fuchsia_modular_package_integration_tests.cmx is running from kernel [00022.377412][32624][32817][klog] INFO: 00:00 +0: calls the set onAccept function [00022.414670][32624][32817][klog] INFO: 00:00 +1: sets values on display [00022.424139][32624][32817][klog] INFO: 00:00 +2: addModuleAffinity [00022.431939][32624][32817][klog] INFO: 00:00 +3: addStoryAffinity [00022.438084][32624][32817][klog] INFO: 00:00 +4: calls the callback when proposal accepted [00022.444660][32624][32817][klog] INFO: 00:00 +5: factory should return same instance [00022.452473][32624][32817][klog] INFO: 00:00 +6: startupContext [00022.471947][32624][32817][klog] INFO: 00:00 +7: verify Lifecycle init during the construction of ModuleImpl [00022.480208][32624][32817][klog] INFO: 00:00 +8: verify exposeService arguments [00022.490480][32624][32817][klog] INFO: 00:00 +9: verify exposeServiceProvider arguments [00022.499550][32624][32817][klog] INFO: 00:00 +10: service bindings tests verify exposeService binds the correct service on connect request [00022.514308][32624][32817][klog] INFO: 00:00 +11: service bindings tests verify exposeService waits for all futures and binds the correct service on connect request [00022.528432][32624][32817][klog] INFO: 00:00 +12: service bindings tests verify exposeServiceProvider waits for all futures and binds the correct service on connect request [00022.542981][32624][32817][klog] INFO: 00:00 +13: service bindings tests verify exposeServiceProvider waits binds the correct service on connect request [00022.554942][32624][32817][klog] INFO: 00:00 +14: verify getTokenManager should call context.getTokenManager [00022.565187][32624][32817][klog] INFO: 00:00 +15: module tests factory returns same instance [00022.573848][32624][32817][klog] INFO: 00:00 +16: no-op intent handler calling handle intent does nothing [00022.582355][32624][32817][klog] INFO: 00:00 +17: intent constructors intent sets the action [00022.591402][32624][32817][klog] INFO: 00:00 +18: intent constructors intent with handler sets the handler [00022.600244][32624][32817][klog] INFO: 00:00 +19: intent parameters addParameterFromEntityReference adds it to the list [00022.608794][32624][32817][klog] INFO: 00:00 +20: intent parameters getEntity throws for missing name [00022.617105][32624][32817][klog] INFO: 00:00 +21: intent parameters getEntity returns valid entity for link entity [00022.625793][32624][32817][klog] INFO: 00:00 +22: addModuleToStory should return ModuleController upon success [00022.634575][32624][32817][klog] INFO: 00:00 +23: addModuleToStory should throw ModuleResolutionException if no module found [00022.644095][32624][32817][klog] INFO: 00:00 +24: addModuleToStory should throw ModuleStateException for unknown errors [00022.648253][32624][32817][klog] INFO: 00:00 +25: embedModule should throw ModuleResolutionException if no module found [00022.653849][32624][32817][klog] INFO: 00:00 +26: embedModule should throw ModuleStateException for unknown errors [00022.657181][32624][32817][klog] INFO: 00:00 +27: embedModule should throw ArgumentError for invalid name [00022.661143][32624][32817][klog] INFO: 00:00 +28: intent handling throws when registerIntentHandler called twice [00022.665894][32624][32817][klog] INFO: 00:00 +29: intent handling throws when no intent handler registered [00022.669654][32624][32817][klog] INFO: 00:00 +30: intent handling module proxies intents to handler [00022.672582][32624][32817][klog] INFO: 00:00 +31: verify Lifecycle init during the construction of ModuleImpl [00022.677941][32624][32817][klog] INFO: 00:00 +32: embedModule throws for empty name [00022.680919][32624][32817][klog] INFO: 00:00 +33: embedModule throws for null intent [00022.683767][32624][32817][klog] INFO: 00:00 +34: addModuleToStory throws for empty name [00022.686217][32624][32817][klog] INFO: 00:00 +35: addModuleToStory throws for null intent [00022.688861][32624][32817][klog] INFO: 00:00 +36: verify requestFocus should call context.requestFocus [00022.691775][32624][32817][klog] INFO: 00:00 +37: verify removeSelfFromStory should call context.removeSelfFromStory [00022.694354][32624][32817][klog] INFO: 00:00 +38: intent handler impl handleIntent calls registered handler [00022.697240][32624][32817][klog] INFO: 00:00 +39: intent handler impl handleIntent passes along the correct action [00022.700186][32624][32817][klog] INFO: 00:00 +40: intent transformers convertFidlIntentToIntent clones correct fields [00022.703606][32624][32817][klog] INFO: 00:00 +41: intent transformers convertFidlIntentToIntent handles null fidl intent parametsrs [00022.706621][32624][32817][klog] INFO: 00:00 +42: intent transformers Intents with const parameter lists can still be modified [00022.710469][32624][32817][klog] INFO: 00:00 +43: ComponentContext: getComponentContext does not return null instance [00022.715834][3534][3717][klog] INFO: [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 [00022.715874][3534][3717][klog] INFO: ia.modular.ComponentContext because this service is not present in the component's sandbox. [00022.715875][3534][3717][klog] INFO: Refer to https://fuchsia.googlesource.com/docs/+/master/the-book/sandboxing.md#services for more information. [00022.717971][32624][32817][klog] INFO: Proxy error: Peer unexpectedly closed [00022.719145][32624][32817][klog] INFO: 00:00 +44: ComponentContext: getComponentContext returns the same instance [00022.721155][32624][32817][klog] INFO: 00:00 +45: connectToAgentService:= throws for null or empty agent url [00022.724636][32624][32817][klog] INFO: 00:00 +46: connectToAgentService:= throws if serviceProxy is null [00022.727302][32624][32817][klog] INFO: 00:00 +47: connectToEnvironmentService throws if serviceProxy is null [00022.729819][32624][32817][klog] INFO: 00:00 +48: factory should return same instance [00022.731763][32624][32817][klog] INFO: 00:00 +49: addTerminateListener throws for null listener [00022.734516][32624][32817][klog] INFO: 00:00 +50: addTerminateListener should return false when adding same handler [00022.736708][32624][32817][klog] INFO: 00:00 +51: addTerminateListener successful add [00022.738652][32624][32817][klog] INFO: 00:00 +52: failing terminate handler should error [00022.740971][32624][32817][klog] INFO: testing 1 [00022.746080][32624][32817][klog] INFO: terminateListener1 [00022.747483][32624][32817][klog] INFO: throwingTerminateListener [00022.750825][32624][32817][klog] INFO: 00:00 +53: terminate should trigger all added listeners to execute [00022.753676][32624][32817][klog] INFO: Skip: this test will cause other tests to not run after it is invoked since it calls exit() [00022.762927][32624][32817][klog] INFO: 00:00 +53 ~1: All tests passed! [00022.934178][4558][4575][klog] INFO: PASSED: /pkgfs/packages/fuchsia_modular_package_integration_tests/0/test/fuchsia_modular_package_integration_tests passed [00022.992947][4558][4575][klog] INFO: [00022.992985][4558][4575][klog] INFO: ------------------------------------------------ [00022.993021][4558][4575][klog] INFO: RUNNING TEST: /pkgfs/packages/fuchsia_services_package_integration_tests/0/test/fuchsia_services_package_integration_tests [00022.993025][4558][4575][klog] INFO: [00023.085808][1568893177][0][amber] INFO: control_server: could not get update for fuchsia_services_package_integration_tests/0: daemon: no update found for fuchsia_services_package_integration_tests/0/ [00023.124962][1568893177][0][amber] INFO: control_server: could not get update for dart_jit_runner/0: daemon: no update found for dart_jit_runner/0/ [00023.418854][33895][34216][klog] INFO: Observatory server failed to start after 1 tries [00023.525252][33895][34088][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fuchsia_services_package_integration_tests#meta/fuchsia_services_package_integration_tests.cmx is running from kernel [00023.589381][33895][34088][klog] INFO: 00:00 +0: StartupContext fromStartupInfo does not return null instance [00023.631278][33895][34088][klog] INFO: 00:00 +1: StartupContext fromStartupInfo returns the same instance [00023.634482][33895][34088][klog] INFO: 00:00 +2: service provider impl connect to service calls correct thunk [00023.650200][33895][34088][klog] INFO: 00:00 +3: All tests passed! [00023.698009][4558][4575][klog] INFO: PASSED: /pkgfs/packages/fuchsia_services_package_integration_tests/0/test/fuchsia_services_package_integration_tests passed [00023.762125][4558][4575][klog] INFO: [00023.762153][4558][4575][klog] INFO: ------------------------------------------------ [00023.762156][4558][4575][klog] INFO: RUNNING TEST: /pkgfs/packages/goodbye_dart_test/0/test/goodbye_dart_test [00023.762157][4558][4575][klog] INFO: [00023.860020][1568893177][0][amber] INFO: control_server: could not get update for goodbye_dart_test/0: daemon: no update found for goodbye_dart_test/0/ [00023.938498][1568893177][0][amber] INFO: control_server: could not get update for run/0: daemon: no update found for run/0/ [00023.974153][1568893177][0][amber] INFO: control_server: could not get update for goodbye_dart_aot/0: daemon: no update found for goodbye_dart_aot/0/ [00023.974503][4943][5013][klog] INFO: [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 [00023.974571][4943][5013][klog] INFO: uchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00024.022449][1568893177][0][amber] INFO: control_server: could not get update for dart_aot_runner/0: daemon: no update found for dart_aot_runner/0/ [00024.342331][35252][35571][klog] INFO: Observatory server failed to start after 1 tries [00024.344337][35252][35444][klog] INFO: [INFO:dart_component_controller.cc(105)] fuchsia-pkg://fuchsia.com/goodbye_dart_aot is running from an app snapshot [00024.345035][35252][35444][klog] INFO: Hello, Dart! [00024.345077][35252][35444][klog] INFO: Goodbye now! [00024.373651][1568893177][0][amber] INFO: control_server: could not get update for run/0: daemon: no update found for run/0/ [00024.406422][1568893177][0][amber] INFO: control_server: could not get update for goodbye_dart_aot/0: daemon: no update found for goodbye_dart_aot/0/ [00024.406748][4943][5013][klog] INFO: [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 [00024.406797][4943][5013][klog] INFO: uchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00024.495797][35252][35793][klog] INFO: [INFO:dart_component_controller.cc(105)] fuchsia-pkg://fuchsia.com/goodbye_dart_aot is running from an app snapshot [00024.496792][35252][35793][klog] INFO: Hello, Dart! [00025.344503][35252][35571][klog] INFO: Observatory server failed to start after 2 tries [00025.498577][35252][35793][klog] INFO: Goodbye, Dart! [00025.537812][4558][4575][klog] INFO: PASSED: /pkgfs/packages/goodbye_dart_test/0/test/goodbye_dart_test passed [00025.590800][4558][4575][klog] INFO: [00025.590842][4558][4575][klog] INFO: ------------------------------------------------ [00025.590845][4558][4575][klog] INFO: RUNNING TEST: /pkgfs/packages/run_test_component_test/0/test/run_test_component_test [00025.590846][4558][4575][klog] INFO: [00025.689683][1568893177][0][amber] INFO: control_server: could not get update for run_test_component_test/0: daemon: no update found for run_test_component_test/0/ [00025.769490][4558][4575][klog] INFO: [==========] Running 1 test from 1 test case. [00025.769529][4558][4575][klog] INFO: [----------] Global test environment set-up. [00025.769572][4558][4575][klog] INFO: [----------] 1 test from Run [00025.769573][4558][4575][klog] INFO: [ RUN ] Run.TestHermeticEnv [00025.785604][4558][4575][klog] INFO: [ OK ] Run.TestHermeticEnv (16 ms) [00025.785678][4558][4575][klog] INFO: [----------] 1 test from Run (16 ms total) [00025.785679][4558][4575][klog] INFO: [00025.785679][4558][4575][klog] INFO: [----------] Global test environment tear-down [00025.785680][4558][4575][klog] INFO: [==========] 1 test from 1 test case ran. (16 ms total) [00025.785786][4558][4575][klog] INFO: [ PASSED ] 1 test. [00025.823673][4558][4575][klog] INFO: PASSED: /pkgfs/packages/run_test_component_test/0/test/run_test_component_test passed [00025.882459][4558][4575][klog] INFO: [00025.882497][4558][4575][klog] INFO: ------------------------------------------------ [00025.882499][4558][4575][klog] INFO: RUNNING TEST: /pkgfs/packages/run_test_component_unittests/0/test/run_test_component_unittests [00025.882500][4558][4575][klog] INFO: [00025.980861][1568893177][0][amber] INFO: control_server: could not get update for run_test_component_unittests/0: daemon: no update found for run_test_component_unittests/0/ [00026.047292][4558][4575][klog] INFO: [==========] Running 19 tests from 4 test cases. [00026.047440][4558][4575][klog] INFO: [----------] Global test environment set-up. [00026.047463][4558][4575][klog] INFO: [----------] 5 tests from EnvironmentConfigTest [00026.047464][4558][4575][klog] INFO: [ RUN ] EnvironmentConfigTest.InvalidJson [00026.068322][4558][4575][klog] INFO: [ OK ] EnvironmentConfigTest.InvalidJson (21 ms) [00026.068384][4558][4575][klog] INFO: [ RUN ] EnvironmentConfigTest.NoRootElement [00026.073056][4558][4575][klog] INFO: [ OK ] EnvironmentConfigTest.NoRootElement (4 ms) [00026.073095][4558][4575][klog] INFO: [ RUN ] EnvironmentConfigTest.NoSysElement [00026.078024][4558][4575][klog] INFO: [ OK ] EnvironmentConfigTest.NoSysElement (4 ms) [00026.078070][4558][4575][klog] INFO: [ RUN ] EnvironmentConfigTest.InvalidSection [00026.084737][4558][4575][klog] INFO: [ OK ] EnvironmentConfigTest.InvalidSection (6 ms) [00026.084792][4558][4575][klog] INFO: [ RUN ] EnvironmentConfigTest.ValidConfig [00026.088578][4558][4575][klog] INFO: [ OK ] EnvironmentConfigTest.ValidConfig (4 ms) [00026.088607][4558][4575][klog] INFO: [----------] 5 tests from EnvironmentConfigTest (41 ms total) [00026.088608][4558][4575][klog] INFO: [00026.088608][4558][4575][klog] INFO: [----------] 2 tests from Url [00026.088609][4558][4575][klog] INFO: [ RUN ] Url.ParseURL [00026.088979][4558][4575][klog] INFO: [ OK ] Url.ParseURL (1 ms) [00026.089008][4558][4575][klog] INFO: [ RUN ] Url.GenerateComponentUrl [00026.089548][4558][4575][klog] INFO: [ OK ] Url.GenerateComponentUrl (0 ms) [00026.089591][4558][4575][klog] INFO: [----------] 2 tests from Url (1 ms total) [00026.089592][4558][4575][klog] INFO: [00026.089592][4558][4575][klog] INFO: [----------] 1 test from RunTest [00026.089593][4558][4575][klog] INFO: [ RUN ] RunTest.ParseArgs [00026.109614][4558][4575][klog] INFO: [ OK ] RunTest.ParseArgs (19 ms) [00026.109642][4558][4575][klog] INFO: [----------] 1 test from RunTest (19 ms total) [00026.109643][4558][4575][klog] INFO: [00026.109643][4558][4575][klog] INFO: [----------] 11 tests from TestMetadataTest [00026.109644][4558][4575][klog] INFO: [ RUN ] TestMetadataTest.InvalidJson [00026.113187][4558][4575][klog] INFO: [ OK ] TestMetadataTest.InvalidJson (5 ms) [00026.113241][4558][4575][klog] INFO: [ RUN ] TestMetadataTest.NoFacet [00026.118618][4558][4575][klog] INFO: [ OK ] TestMetadataTest.NoFacet (5 ms) [00026.118649][4558][4575][klog] INFO: [ RUN ] TestMetadataTest.NoFuchsiaTestFacet [00026.124005][4558][4575][klog] INFO: [ OK ] TestMetadataTest.NoFuchsiaTestFacet (5 ms) [00026.124038][4558][4575][klog] INFO: [ RUN ] TestMetadataTest.NoServices [00026.131374][4558][4575][klog] INFO: [ OK ] TestMetadataTest.NoServices (7 ms) [00026.131437][4558][4575][klog] INFO: [ RUN ] TestMetadataTest.InvalidTestFacet [00026.139250][4558][4575][klog] INFO: [ OK ] TestMetadataTest.InvalidTestFacet (8 ms) [00026.139289][4558][4575][klog] INFO: [ RUN ] TestMetadataTest.InvalidServicesType [00026.145101][4558][4575][klog] INFO: [ OK ] TestMetadataTest.InvalidServicesType (6 ms) [00026.145157][4558][4575][klog] INFO: [ RUN ] TestMetadataTest.InvalidSystemServicesType [00026.158860][4558][4575][klog] INFO: [ OK ] TestMetadataTest.InvalidSystemServicesType (13 ms) [00026.158916][4558][4575][klog] INFO: [ RUN ] TestMetadataTest.InvalidServices [00026.171361][4558][4575][klog] INFO: [ OK ] TestMetadataTest.InvalidServices (13 ms) [00026.171422][4558][4575][klog] INFO: [ RUN ] TestMetadataTest.EmptyServices [00026.176613][4558][4575][klog] INFO: [ OK ] TestMetadataTest.EmptyServices (4 ms) [00026.176638][4558][4575][klog] INFO: [ RUN ] TestMetadataTest.ValidServices [00026.181077][4558][4575][klog] INFO: [ OK ] TestMetadataTest.ValidServices (6 ms) [00026.181104][4558][4575][klog] INFO: [ RUN ] TestMetadataTest.ValidSystemServices [00026.187916][4558][4575][klog] INFO: [ OK ] TestMetadataTest.ValidSystemServices (6 ms) [00026.187963][4558][4575][klog] INFO: [----------] 11 tests from TestMetadataTest (79 ms total) [00026.187964][4558][4575][klog] INFO: [00026.187965][4558][4575][klog] INFO: [----------] Global test environment tear-down [00026.187966][4558][4575][klog] INFO: [==========] 19 tests from 4 test cases ran. (141 ms total) [00026.187967][4558][4575][klog] INFO: [ PASSED ] 19 tests. [00026.206598][4558][4575][klog] INFO: PASSED: /pkgfs/packages/run_test_component_unittests/0/test/run_test_component_unittests passed [00026.325177][4558][4575][klog] INFO: [00026.325220][4558][4575][klog] INFO: ------------------------------------------------ [00026.325223][4558][4575][klog] INFO: RUNNING TEST: /pkgfs/packages/scenic_tests/0/test/geometry_util_unittests [00026.325223][4558][4575][klog] INFO: [00026.373454][4558][4575][klog] INFO: Running main() from gtest_main.cc [00026.373484][4558][4575][klog] INFO: [==========] Running 10 tests from 4 test cases. [00026.373486][4558][4575][klog] INFO: [----------] Global test environment set-up. [00026.373486][4558][4575][klog] INFO: [----------] 1 test from RectTest [00026.373487][4558][4575][klog] INFO: [ RUN ] RectTest.Comparisons [00026.373487][4558][4575][klog] INFO: [ OK ] RectTest.Comparisons (0 ms) [00026.373488][4558][4575][klog] INFO: [----------] 1 test from RectTest (0 ms total) [00026.373488][4558][4575][klog] INFO: [00026.373489][4558][4575][klog] INFO: [----------] 1 test from SizeTest [00026.373525][4558][4575][klog] INFO: [ RUN ] SizeTest.Comparisons [00026.373526][4558][4575][klog] INFO: [ OK ] SizeTest.Comparisons (0 ms) [00026.373530][4558][4575][klog] INFO: [----------] 1 test from SizeTest (0 ms total) [00026.373531][4558][4575][klog] INFO: [00026.373531][4558][4575][klog] INFO: [----------] 1 test from PointTest [00026.373531][4558][4575][klog] INFO: [ RUN ] PointTest.Comparisons [00026.373532][4558][4575][klog] INFO: [ OK ] PointTest.Comparisons (0 ms) [00026.373532][4558][4575][klog] INFO: [----------] 1 test from PointTest (0 ms total) [00026.373535][4558][4575][klog] INFO: [00026.373536][4558][4575][klog] INFO: [----------] 7 tests from TransformFunctionsTest [00026.373583][4558][4575][klog] INFO: [ RUN ] TransformFunctionsTest.SetIdentityTransform [00026.373584][4558][4575][klog] INFO: [ OK ] TransformFunctionsTest.SetIdentityTransform (0 ms) [00026.378947][4558][4575][klog] INFO: [ RUN ] TransformFunctionsTest.SetTranslationTransform [00026.378973][4558][4575][klog] INFO: [ OK ] TransformFunctionsTest.SetTranslationTransform (0 ms) [00026.378976][4558][4575][klog] INFO: [ RUN ] TransformFunctionsTest.Translate [00026.379133][4558][4575][klog] INFO: [ OK ] TransformFunctionsTest.Translate (0 ms) [00026.379157][4558][4575][klog] INFO: [ RUN ] TransformFunctionsTest.Scale [00026.379158][4558][4575][klog] INFO: [ OK ] TransformFunctionsTest.Scale (0 ms) [00026.379158][4558][4575][klog] INFO: [ RUN ] TransformFunctionsTest.CreateIdentityTransform [00026.379159][4558][4575][klog] INFO: [ OK ] TransformFunctionsTest.CreateIdentityTransform (0 ms) [00026.379159][4558][4575][klog] INFO: [ RUN ] TransformFunctionsTest.CreateTranslationTransform [00026.379160][4558][4575][klog] INFO: [ OK ] TransformFunctionsTest.CreateTranslationTransform (0 ms) [00026.379160][4558][4575][klog] INFO: [ RUN ] TransformFunctionsTest.CreateScaleTransform [00026.379160][4558][4575][klog] INFO: [ OK ] TransformFunctionsTest.CreateScaleTransform (0 ms) [00026.379161][4558][4575][klog] INFO: [----------] 7 tests from TransformFunctionsTest (0 ms total) [00026.379161][4558][4575][klog] INFO: [00026.379161][4558][4575][klog] INFO: [----------] Global test environment tear-down [00026.379174][4558][4575][klog] INFO: [==========] 10 tests from 4 test cases ran. (0 ms total) [00026.379174][4558][4575][klog] INFO: [ PASSED ] 10 tests. [00026.389671][4558][4575][klog] INFO: PASSED: /pkgfs/packages/scenic_tests/0/test/geometry_util_unittests passed [00026.558112][4558][4575][klog] INFO: [00026.558154][4558][4575][klog] INFO: ------------------------------------------------ [00026.558157][4558][4575][klog] INFO: RUNNING TEST: /pkgfs/packages/scenic_tests/0/test/gfx_apptests [00026.558157][4558][4575][klog] INFO: [00026.703264][4558][4575][klog] INFO: Running main() from gtest_main.cc [00026.703304][4558][4575][klog] INFO: [==========] Running 0 tests from 0 test cases. [00026.703304][4558][4575][klog] INFO: [==========] 0 tests from 0 test cases ran. (0 ms total) [00026.703309][4558][4575][klog] INFO: [ PASSED ] 0 tests. [00026.703312][4558][4575][klog] INFO: [00026.703378][4558][4575][klog] INFO: YOU HAVE 4 DISABLED TESTS [00026.703378][4558][4575][klog] INFO: [00026.727414][4558][4575][klog] INFO: PASSED: /pkgfs/packages/scenic_tests/0/test/gfx_apptests passed [00026.786431][4558][4575][klog] INFO: [00026.786469][4558][4575][klog] INFO: ------------------------------------------------ [00026.786472][4558][4575][klog] INFO: RUNNING TEST: /pkgfs/packages/scenic_tests/0/test/gfx_unittests [00026.786473][4558][4575][klog] INFO: [00026.927723][4558][4575][klog] INFO: [==========] Running 113 tests from 13 test cases. [00026.927769][4558][4575][klog] INFO: [----------] Global test environment set-up. [00026.927811][4558][4575][klog] INFO: [----------] 5 tests from HLATest [00026.927853][4558][4575][klog] INFO: [ RUN ] HLATest.HasHardwareLayerAssignment [00026.927856][4558][4575][klog] INFO: [00026.927857][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="1190390503" [00026.927908][4558][4575][klog] INFO: [ OK ] HLATest.HasHardwareLayerAssignment (0 ms) [00026.927909][4558][4575][klog] INFO: [ RUN ] HLATest.HardwareLayerAssignmentMissingSwapchain [00026.927910][4558][4575][klog] INFO: [00026.927910][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="2645952895" [00026.969506][4558][4575][klog] INFO: [WARNING:garnet/lib/ui/gfx/engine/hardware_layer_assignment.cc(16)] Invalid HardwareLayerAssignment: no swapchain. [00026.969531][4558][4575][klog] INFO: [ OK ] HLATest.HardwareLayerAssignmentMissingSwapchain (0 ms) [00026.969538][4558][4575][klog] INFO: [ RUN ] HLATest.HardwareLayerAssignmentMissingLayers [00026.969538][4558][4575][klog] INFO: [00026.969539][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3919630963" [00026.969539][4558][4575][klog] INFO: [WARNING:garnet/lib/ui/gfx/engine/hardware_layer_assignment.cc(26)] Invalid HardwareLayerAssignment: item with no layers. [00026.969540][4558][4575][klog] INFO: [ OK ] HLATest.HardwareLayerAssignmentMissingLayers (0 ms) [00026.969540][4558][4575][klog] INFO: [ RUN ] HLATest.HardwareLayerAssignmentMissingItems [00026.969541][4558][4575][klog] INFO: [00026.969587][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="1381213072" [00026.969951][4558][4575][klog] INFO: [WARNING:garnet/lib/ui/gfx/engine/hardware_layer_assignment.cc(19)] Invalid HardwareLayerAssignment: no items. [00026.969952][4558][4575][klog] INFO: [ OK ] HLATest.HardwareLayerAssignmentMissingItems (1 ms) [00026.969953][4558][4575][klog] INFO: [ RUN ] HLATest.HardwareLayerAssignmentDuplicateLayerIDs [00026.969953][4558][4575][klog] INFO: [00026.969954][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="2311252627" [00026.969959][4558][4575][klog] INFO: [WARNING:garnet/lib/ui/gfx/engine/hardware_layer_assignment.cc(32)] Invalid HardwareLayerAssignment: duplicate layer ID [00026.969960][4558][4575][klog] INFO: [ OK ] HLATest.HardwareLayerAssignmentDuplicateLayerIDs (0 ms) [00026.969960][4558][4575][klog] INFO: [----------] 5 tests from HLATest (1 ms total) [00026.969960][4558][4575][klog] INFO: [00026.969961][4558][4575][klog] INFO: [----------] 1 test from MultiSessionHitTestTest [00026.969961][4558][4575][klog] INFO: [ RUN ] MultiSessionHitTestTest.GlobalHits [00026.969962][4558][4575][klog] INFO: [00026.969962][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="182600064" [00026.969963][4558][4575][klog] INFO: [WARNING:garnet/lib/ui/scenic/event_reporter.cc(23)] EventReporter not set up, dropped event: ViewConnectedEvent(view_id=1008) [00026.969964][4558][4575][klog] INFO: [WARNING:garnet/lib/ui/scenic/event_reporter.cc(23)] EventReporter not set up, dropped event: ViewAttachedToSceneEvent(view_id=2001, properties=<TBD> [00026.969964][4558][4575][klog] INFO: [WARNING:garnet/lib/ui/scenic/event_reporter.cc(23)] EventReporter not set up, dropped event: ViewPropertiesChangedEvent(view_id=2001, properties=<TBD>) [00026.969965][4558][4575][klog] INFO: [WARNING:garnet/lib/ui/scenic/event_reporter.cc(23)] EventReporter not set up, dropped event: ViewConnectedEvent(view_id=1009) [00026.989910][4558][4575][klog] INFO: [WARNING:garnet/lib/ui/scenic/event_reporter.cc(23)] EventReporter not set up, dropped event: ViewAttachedToSceneEvent(view_id=3001, properties=<TBD> [00026.989939][4558][4575][klog] INFO: [WARNING:garnet/lib/ui/scenic/event_reporter.cc(23)] EventReporter not set up, dropped event: ViewPropertiesChangedEvent(view_id=3001, properties=<TBD>) [00026.989940][4558][4575][klog] INFO: [WARNING:garnet/lib/ui/scenic/event_reporter.cc(23)] EventReporter not set up, dropped event: ViewDisconnectedEvent(view_id=1009) [00026.989941][4558][4575][klog] INFO: [WARNING:garnet/lib/ui/scenic/event_reporter.cc(23)] EventReporter not set up, dropped event: ViewDisconnectedEvent(view_id=1008) [00026.989941][4558][4575][klog] INFO: [ OK ] MultiSessionHitTestTest.GlobalHits (40 ms) [00026.989942][4558][4575][klog] INFO: [----------] 1 test from MultiSessionHitTestTest (40 ms total) [00026.989942][4558][4575][klog] INFO: [00026.989944][4558][4575][klog] INFO: [----------] 17 tests from HitTestTest [00026.989944][4558][4575][klog] INFO: [ RUN ] HitTestTest.InvalidNodeId [00026.989945][4558][4575][klog] INFO: [00026.989945][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3501126316" [00026.989960][4558][4575][klog] INFO: [ OK ] HitTestTest.InvalidNodeId (2 ms) [00026.989960][4558][4575][klog] INFO: [ RUN ] HitTestTest.RayBelowScenePointingDown [00026.989961][4558][4575][klog] INFO: [00026.989961][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3584764363" [00026.989962][4558][4575][klog] INFO: [ OK ] HitTestTest.RayBelowScenePointingDown (1 ms) [00026.989962][4558][4575][klog] INFO: [ RUN ] HitTestTest.RayBelowScenePointingUp [00026.989963][4558][4575][klog] INFO: [00026.989963][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="119637792" [00026.989963][4558][4575][klog] INFO: [ OK ] HitTestTest.RayBelowScenePointingUp (7 ms) [00026.989964][4558][4575][klog] INFO: [ RUN ] HitTestTest.RayAboveScenePointingUp [00026.989964][4558][4575][klog] INFO: [00026.989965][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="1449419498" [00026.989965][4558][4575][klog] INFO: [ OK ] HitTestTest.RayAboveScenePointingUp (7 ms) [00026.989965][4558][4575][klog] INFO: [ RUN ] HitTestTest.Hit10InTopLeftCornerFromNode1 [00026.989966][4558][4575][klog] INFO: [00026.989966][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="1945297255" [00026.989971][4558][4575][klog] INFO: [ OK ] HitTestTest.Hit10InTopLeftCornerFromNode1 (4 ms) [00026.989971][4558][4575][klog] INFO: [ RUN ] HitTestTest.Hit10InTopLeftCornerFromNode2 [00026.989971][4558][4575][klog] INFO: [00026.989972][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="435493905" [00026.990556][4558][4575][klog] INFO: [ OK ] HitTestTest.Hit10InTopLeftCornerFromNode2 (1 ms) [00026.990942][4558][4575][klog] INFO: [ RUN ] HitTestTest.Hit10InTopLeftCornerFromNode3 [00026.991142][4558][4575][klog] INFO: [00026.991356][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3099226474" [00027.016885][4558][4575][klog] INFO: [ OK ] HitTestTest.Hit10InTopLeftCornerFromNode3 (24 ms) [00027.017742][4558][4575][klog] INFO: [ RUN ] HitTestTest.Hit10InTopLeftCornerFromNode4 [00027.017989][4558][4575][klog] INFO: [00027.018403][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="233053739" [00027.042688][4558][4575][klog] INFO: [ OK ] HitTestTest.Hit10InTopLeftCornerFromNode4 (5 ms) [00027.042730][4558][4575][klog] INFO: [ RUN ] HitTestTest.Hit20InMiddleFromNode1 [00027.042731][4558][4575][klog] INFO: [00027.042732][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="198393451" [00027.042736][4558][4575][klog] INFO: [ OK ] HitTestTest.Hit20InMiddleFromNode1 (6 ms) [00027.042737][4558][4575][klog] INFO: [ RUN ] HitTestTest.Hit20InMiddleFromNode2 [00027.042737][4558][4575][klog] INFO: [00027.042738][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="98194337" [00027.042738][4558][4575][klog] INFO: [ OK ] HitTestTest.Hit20InMiddleFromNode2 (5 ms) [00027.042738][4558][4575][klog] INFO: [ RUN ] HitTestTest.Hit20InMiddleFromNode3 [00027.042739][4558][4575][klog] INFO: [00027.042739][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="2062877635" [00027.042739][4558][4575][klog] INFO: [ OK ] HitTestTest.Hit20InMiddleFromNode3 (5 ms) [00027.042740][4558][4575][klog] INFO: [ RUN ] HitTestTest.Hit20InMiddleFromNode5 [00027.042740][4558][4575][klog] INFO: [00027.042740][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3486105057" [00027.042741][4558][4575][klog] INFO: [ OK ] HitTestTest.Hit20InMiddleFromNode5 (2 ms) [00027.042741][4558][4575][klog] INFO: [ RUN ] HitTestTest.Hit20InMiddleFromNode6 [00027.042741][4558][4575][klog] INFO: [00027.042742][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="2752947367" [00027.042742][4558][4575][klog] INFO: [ OK ] HitTestTest.Hit20InMiddleFromNode6 (1 ms) [00027.042742][4558][4575][klog] INFO: [ RUN ] HitTestTest.HitBoth10And20FromNode1 [00027.042743][4558][4575][klog] INFO: [00027.042743][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="2462574888" [00027.043871][4558][4575][klog] INFO: [ OK ] HitTestTest.HitBoth10And20FromNode1 (3 ms) [00027.043909][4558][4575][klog] INFO: [ RUN ] HitTestTest.HitBoth20And30FromNode1 [00027.043909][4558][4575][klog] INFO: [00027.043910][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3529871337" [00027.355042][4558][4575][klog] INFO: [ OK ] HitTestTest.HitBoth20And30FromNode1 (311 ms) [00027.355106][4558][4575][klog] INFO: [ RUN ] HitTestTest.SuppressNode25FromNode1 [00027.355106][4558][4575][klog] INFO: [00027.355107][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="755081683" [00027.368310][4558][4575][klog] INFO: [ OK ] HitTestTest.SuppressNode25FromNode1 (0 ms) [00027.368346][4558][4575][klog] INFO: [ RUN ] HitTestTest.Clipping [00027.368347][4558][4575][klog] INFO: [00027.368347][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="2512774685" [00027.368352][4558][4575][klog] INFO: [ OK ] HitTestTest.Clipping (2 ms) [00027.368353][4558][4575][klog] INFO: [----------] 17 tests from HitTestTest (389 ms total) [00027.368353][4558][4575][klog] INFO: [00027.368354][4558][4575][klog] INFO: [----------] 7 tests from ImagePipeTest [00027.368354][4558][4575][klog] INFO: [ RUN ] ImagePipeTest.ImagePipeImageIdMustNotBeZero [00027.368355][4558][4575][klog] INFO: [00027.368355][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="141512232" [00027.368356][4558][4575][klog] INFO: [WARNING:garnet/lib/ui/gfx/engine/engine.cc(138)] No FrameScheduler available; applying update immediately [00027.368356][4558][4575][klog] INFO: [ OK ] ImagePipeTest.ImagePipeImageIdMustNotBeZero (6 ms) [00027.368356][4558][4575][klog] INFO: [ RUN ] ImagePipeTest.PresentImagesOutOfOrder [00027.368357][4558][4575][klog] INFO: [00027.368357][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="2667084907" [00027.432460][4558][4575][klog] INFO: [WARNING:garnet/lib/ui/gfx/engine/engine.cc(138)] No FrameScheduler available; applying update immediately [00027.432536][4558][4575][klog] INFO: [INFO:image.cc(62)] Destroying Image with unowned VkImage (perhaps a swapchain image?) [00027.433132][4558][4575][klog] INFO: [ OK ] ImagePipeTest.PresentImagesOutOfOrder (70 ms) [00027.433156][4558][4575][klog] INFO: [ RUN ] ImagePipeTest.PresentImagesInOrder [00027.433157][4558][4575][klog] INFO: [00027.433157][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="1456467946" [00027.433976][4558][4575][klog] INFO: [INFO:image.cc(62)] Destroying Image with unowned VkImage (perhaps a swapchain image?) [00027.434902][4558][4575][klog] INFO: [ OK ] ImagePipeTest.PresentImagesInOrder (1 ms) [00027.434926][4558][4575][klog] INFO: [ RUN ] ImagePipeTest.PresentImagesWithOffset [00027.434926][4558][4575][klog] INFO: [00027.434927][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="755296340" [00027.441977][4558][4575][klog] INFO: [INFO:image.cc(62)] Destroying Image with unowned VkImage (perhaps a swapchain image?) [00027.442621][4558][4575][klog] INFO: [ OK ] ImagePipeTest.PresentImagesWithOffset (8 ms) [00027.442656][4558][4575][klog] INFO: [ RUN ] ImagePipeTest.ImagePipePresentTwoFrames [00027.442657][4558][4575][klog] INFO: [00027.442734][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="2707022431" [00027.448234][4558][4575][klog] INFO: [WARNING:garnet/lib/ui/gfx/engine/engine.cc(138)] No FrameScheduler available; applying update immediately [00027.448355][4558][4575][klog] INFO: [WARNING:garnet/lib/ui/gfx/engine/engine.cc(138)] No FrameScheduler available; applying update immediately [00027.448430][4558][4575][klog] INFO: [INFO:image.cc(62)] Destroying Image with unowned VkImage (perhaps a swapchain image?) [00027.448431][4558][4575][klog] INFO: [INFO:image.cc(62)] Destroying Image with unowned VkImage (perhaps a swapchain image?) [00027.453216][4558][4575][klog] INFO: [ OK ] ImagePipeTest.ImagePipePresentTwoFrames (6 ms) [00027.453252][4558][4575][klog] INFO: [ RUN ] ImagePipeTest.ImagePipeUpdateTwoFrames [00027.453253][4558][4575][klog] INFO: [00027.453253][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="2652152997" [00027.455692][4558][4575][klog] INFO: [WARNING:garnet/lib/ui/gfx/engine/engine.cc(138)] No FrameScheduler available; applying update immediately [00027.455735][4558][4575][klog] INFO: [WARNING:garnet/lib/ui/gfx/engine/engine.cc(138)] No FrameScheduler available; applying update immediately [00027.455736][4558][4575][klog] INFO: [WARNING:garnet/lib/ui/gfx/engine/engine.cc(138)] No FrameScheduler available; applying update immediately [00027.455737][4558][4575][klog] INFO: [WARNING:garnet/lib/ui/gfx/engine/engine.cc(138)] No FrameScheduler available; applying update immediately [00027.455737][4558][4575][klog] INFO: [INFO:image.cc(62)] Destroying Image with unowned VkImage (perhaps a swapchain image?) [00027.455739][4558][4575][klog] INFO: [INFO:image.cc(62)] Destroying Image with unowned VkImage (perhaps a swapchain image?) [00027.455743][4558][4575][klog] INFO: [ OK ] ImagePipeTest.ImagePipeUpdateTwoFrames (5 ms) [00027.455743][4558][4575][klog] INFO: [ RUN ] ImagePipeTest.ImagePipeRemoveImageThatIsPendingPresent [00027.455744][4558][4575][klog] INFO: [00027.455744][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="335828007" [00027.455745][4558][4575][klog] INFO: [WARNING:garnet/lib/ui/gfx/engine/engine.cc(138)] No FrameScheduler available; applying update immediately [00027.455745][4558][4575][klog] INFO: [WARNING:garnet/lib/ui/gfx/engine/engine.cc(138)] No FrameScheduler available; applying update immediately [00027.455746][4558][4575][klog] INFO: [INFO:image.cc(62)] Destroying Image with unowned VkImage (perhaps a swapchain image?) [00027.455746][4558][4575][klog] INFO: [INFO:image.cc(62)] Destroying Image with unowned VkImage (perhaps a swapchain image?) [00027.455747][4558][4575][klog] INFO: [ OK ] ImagePipeTest.ImagePipeRemoveImageThatIsPendingPresent (1 ms) [00027.455747][4558][4575][klog] INFO: [----------] 7 tests from ImagePipeTest (98 ms total) [00027.455747][4558][4575][klog] INFO: [00027.455748][4558][4575][klog] INFO: [----------] 16 tests from ImportTest [00027.455748][4558][4575][klog] INFO: [ RUN ] ImportTest.ExportsResourceViaCmd [00027.455748][4558][4575][klog] INFO: [00027.455749][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="4122710383" [00027.459794][4558][4575][klog] INFO: [ OK ] ImportTest.ExportsResourceViaCmd (4 ms) [00027.459821][4558][4575][klog] INFO: [ RUN ] ImportTest.ImportsUnlinkedImportViaCmd [00027.459821][4558][4575][klog] INFO: [00027.459821][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="1306666256" [00027.665647][4558][4575][klog] INFO: [ OK ] ImportTest.ImportsUnlinkedImportViaCmd (205 ms) [00027.665795][4558][4575][klog] INFO: [ RUN ] ImportTest.PerformsFullLinking [00027.665795][4558][4575][klog] INFO: [00027.665796][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="519249188" [00027.665800][4558][4575][klog] INFO: [ OK ] ImportTest.PerformsFullLinking (1 ms) [00027.665800][4558][4575][klog] INFO: [ RUN ] ImportTest.HandlesDeadSourceHandle [00027.665801][4558][4575][klog] INFO: [00027.665801][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3156009719" [00027.666850][4558][4575][klog] INFO: [ OK ] ImportTest.HandlesDeadSourceHandle (1 ms) [00027.666879][4558][4575][klog] INFO: [ RUN ] ImportTest.HandlesDeadDestinationHandle [00027.666879][4558][4575][klog] INFO: [00027.666880][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="606644765" [00027.667984][4558][4575][klog] INFO: [ OK ] ImportTest.HandlesDeadDestinationHandle (1 ms) [00027.668008][4558][4575][klog] INFO: [ RUN ] ImportTest.DestroyingExportedResourceSendsEvent [00027.668009][4558][4575][klog] INFO: [00027.668009][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="4187898996" [00027.675956][4558][4575][klog] INFO: [ OK ] ImportTest.DestroyingExportedResourceSendsEvent (4 ms) [00027.675988][4558][4575][klog] INFO: [ RUN ] ImportTest.ImportingNodeAfterDestroyingExportedResourceSendsEvent [00027.676045][4558][4575][klog] INFO: [00027.676048][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="2624546057" [00027.677686][4558][4575][klog] INFO: [ OK ] ImportTest.ImportingNodeAfterDestroyingExportedResourceSendsEvent (5 ms) [00027.677745][4558][4575][klog] INFO: [ RUN ] ImportTest.KillingImportedResourceEvictsFromResourceLinker [00027.677745][4558][4575][klog] INFO: [00027.677746][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="1882836343" [00027.684722][4558][4575][klog] INFO: [ OK ] ImportTest.KillingImportedResourceEvictsFromResourceLinker (7 ms) [00027.684882][4558][4575][klog] INFO: [ RUN ] ImportTest.ResourceUnexportedAfterImportsAndImportHandlesDie1 [00027.684883][4558][4575][klog] INFO: [00027.684883][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="1007816284" [00027.770063][4558][4575][klog] INFO: [ OK ] ImportTest.ResourceUnexportedAfterImportsAndImportHandlesDie1 (85 ms) [00027.770089][4558][4575][klog] INFO: [ RUN ] ImportTest.ResourceUnexportedAfterImportsAndImportHandlesDie2 [00027.770090][4558][4575][klog] INFO: [00027.770090][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="1598177487" [00027.770095][4558][4575][klog] INFO: [ OK ] ImportTest.ResourceUnexportedAfterImportsAndImportHandlesDie2 (0 ms) [00027.770096][4558][4575][klog] INFO: [ RUN ] ImportTest.ResourceUnexportedAfterImportsAndImportHandlesDie3 [00027.770096][4558][4575][klog] INFO: [00027.770097][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="4047718614" [00027.770825][4558][4575][klog] INFO: [ OK ] ImportTest.ResourceUnexportedAfterImportsAndImportHandlesDie3 (1 ms) [00027.770900][4558][4575][klog] INFO: [ RUN ] ImportTest.ResourceUnexportedAfterImportsAndImportHandlesDie4 [00027.770924][4558][4575][klog] INFO: [00027.770948][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="25514399" [00027.772624][4558][4575][klog] INFO: [ OK ] ImportTest.ResourceUnexportedAfterImportsAndImportHandlesDie4 (2 ms) [00027.772646][4558][4575][klog] INFO: [ RUN ] ImportTest.ProxiesCanBeFoundByTheirContainerOrTheirUnderlyingEntityType [00027.772647][4558][4575][klog] INFO: [00027.772647][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="121866471" [00027.840456][4558][4575][klog] INFO: [ OK ] ImportTest.ProxiesCanBeFoundByTheirContainerOrTheirUnderlyingEntityType (68 ms) [00027.840514][4558][4575][klog] INFO: [ RUN ] ImportTest.UnlinkedImportedResourceCanAcceptCommands [00027.840515][4558][4575][klog] INFO: [00027.840516][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="250308998" [00027.841127][4558][4575][klog] INFO: [ OK ] ImportTest.UnlinkedImportedResourceCanAcceptCommands (0 ms) [00027.841154][4558][4575][klog] INFO: [ RUN ] ImportTest.LinkedResourceShouldBeAbleToAcceptCommands [00027.841155][4558][4575][klog] INFO: [00027.841155][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="795245582" [00027.876882][4558][4575][klog] INFO: [ OK ] ImportTest.LinkedResourceShouldBeAbleToAcceptCommands (34 ms) [00027.876926][4558][4575][klog] INFO: [ RUN ] ImportTest.EmbedderCanEmbedNodesFromElsewhere [00027.876926][4558][4575][klog] INFO: [00027.876927][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="1136818011" [00027.877371][4558][4575][klog] INFO: [ OK ] ImportTest.EmbedderCanEmbedNodesFromElsewhere (1 ms) [00027.889491][4558][4575][klog] INFO: [----------] 16 tests from ImportTest (434 ms total) [00027.889610][4558][4575][klog] INFO: [00027.889881][4558][4575][klog] INFO: [----------] 1 test from MemoryTest [00027.889911][4558][4575][klog] INFO: [ RUN ] MemoryTest.MemoryAllocationSizeValidation [00027.889912][4558][4575][klog] INFO: [00027.889912][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="2934309926" [00027.892284][4558][4575][klog] INFO: [ OK ] MemoryTest.MemoryAllocationSizeValidation (2 ms) [00027.892325][4558][4575][klog] INFO: [----------] 1 test from MemoryTest (3 ms total) [00027.892326][4558][4575][klog] INFO: [00027.892327][4558][4575][klog] INFO: [----------] 4 tests from NodeTest [00027.892327][4558][4575][klog] INFO: [ RUN ] NodeTest.Tagging [00027.892328][4558][4575][klog] INFO: [00027.892328][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="1480942744" [00027.896287][4558][4575][klog] INFO: [ OK ] NodeTest.Tagging (4 ms) [00027.896288][4558][4575][klog] INFO: [ RUN ] NodeTest.ShapeNodeMaterialAndShape [00027.896288][4558][4575][klog] INFO: [00027.896289][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3946508489" [00027.974531][4558][4575][klog] INFO: [ OK ] NodeTest.ShapeNodeMaterialAndShape (78 ms) [00027.974579][4558][4575][klog] INFO: [ RUN ] NodeTest.NodesWithChildren [00027.974579][4558][4575][klog] INFO: [00027.974580][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="2263641574" [00027.975376][4558][4575][klog] INFO: [ OK ] NodeTest.NodesWithChildren (1 ms) [00027.975482][4558][4575][klog] INFO: [ RUN ] NodeTest.SettingHitTestBehavior [00027.975483][4558][4575][klog] INFO: [00027.975483][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="154026762" [00027.980558][4558][4575][klog] INFO: [ OK ] NodeTest.SettingHitTestBehavior (5 ms) [00027.980583][4558][4575][klog] INFO: [----------] 4 tests from NodeTest (88 ms total) [00027.980584][4558][4575][klog] INFO: [00027.982017][4558][4575][klog] INFO: [----------] 21 tests from ObjectLinkerTest [00027.982038][4558][4575][klog] INFO: [ RUN ] ObjectLinkerTest.InitialState [00027.982039][4558][4575][klog] INFO: [00027.982039][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3242735579" [00027.982040][4558][4575][klog] INFO: [ OK ] ObjectLinkerTest.InitialState (0 ms) [00027.982041][4558][4575][klog] INFO: [ RUN ] ObjectLinkerTest.AllowsExport [00027.982042][4558][4575][klog] INFO: [00027.982042][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="1844653083" [00027.984532][4558][4575][klog] INFO: [ OK ] ObjectLinkerTest.AllowsExport (3 ms) [00027.984558][4558][4575][klog] INFO: [ RUN ] ObjectLinkerTest.CannotExportInvalidToken [00027.984559][4558][4575][klog] INFO: [00027.984559][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3392391774" [00027.984891][4558][4575][klog] INFO: [ OK ] ObjectLinkerTest.CannotExportInvalidToken (0 ms) [00027.984918][4558][4575][klog] INFO: [ RUN ] ObjectLinkerTest.CannotExportWithDeadExportToken [00027.984918][4558][4575][klog] INFO: [00027.984919][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3484512725" [00027.985205][4558][4575][klog] INFO: [ OK ] ObjectLinkerTest.CannotExportWithDeadExportToken (1 ms) [00027.985232][4558][4575][klog] INFO: [ RUN ] ObjectLinkerTest.CanExportWithDeadImportToken [00027.985256][4558][4575][klog] INFO: [00027.985256][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="1711571176" [00027.992668][4558][4575][klog] INFO: [ OK ] ObjectLinkerTest.CanExportWithDeadImportToken (1 ms) [00027.992700][4558][4575][klog] INFO: [ RUN ] ObjectLinkerTest.CannotExportSameTokenTwice [00027.992700][4558][4575][klog] INFO: [00027.992701][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="2126646034" [00027.992701][4558][4575][klog] INFO: [ OK ] ObjectLinkerTest.CannotExportSameTokenTwice (0 ms) [00027.992702][4558][4575][klog] INFO: [ RUN ] ObjectLinkerTest.LinkDeathRemovesExport [00027.992702][4558][4575][klog] INFO: [00027.992702][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="2440608777" [00027.992703][4558][4575][klog] INFO: [ OK ] ObjectLinkerTest.LinkDeathRemovesExport (0 ms) [00027.992704][4558][4575][klog] INFO: [ RUN ] ObjectLinkerTest.AllowsImport [00027.992704][4558][4575][klog] INFO: [00027.992705][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="567593610" [00027.992705][4558][4575][klog] INFO: [ OK ] ObjectLinkerTest.AllowsImport (0 ms) [00027.992705][4558][4575][klog] INFO: [ RUN ] ObjectLinkerTest.CannotImportInvalidToken [00027.992706][4558][4575][klog] INFO: [00027.992706][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="1411383392" [00027.992711][4558][4575][klog] INFO: [ OK ] ObjectLinkerTest.CannotImportInvalidToken (0 ms) [00027.992712][4558][4575][klog] INFO: [ RUN ] ObjectLinkerTest.CannotImportWithDeadImportToken [00027.992712][4558][4575][klog] INFO: [00027.992712][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3615628268" [00027.992713][4558][4575][klog] INFO: [ OK ] ObjectLinkerTest.CannotImportWithDeadImportToken (1 ms) [00027.992713][4558][4575][klog] INFO: [ RUN ] ObjectLinkerTest.CanImportWithDeadExportToken [00027.992713][4558][4575][klog] INFO: [00027.992714][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3670568437" [00027.992714][4558][4575][klog] INFO: [ OK ] ObjectLinkerTest.CanImportWithDeadExportToken (0 ms) [00027.992714][4558][4575][klog] INFO: [ RUN ] ObjectLinkerTest.CannotImportSameTokenTwice [00027.992715][4558][4575][klog] INFO: [00027.992715][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="932333196" [00027.992715][4558][4575][klog] INFO: [ OK ] ObjectLinkerTest.CannotImportSameTokenTwice (0 ms) [00027.992716][4558][4575][klog] INFO: [ RUN ] ObjectLinkerTest.LinkDeathRemovesImport [00027.992716][4558][4575][klog] INFO: [00027.992716][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="2033145980" [00027.992717][4558][4575][klog] INFO: [ OK ] ObjectLinkerTest.LinkDeathRemovesImport (0 ms) [00027.992717][4558][4575][klog] INFO: [ RUN ] ObjectLinkerTest.InitializingLinkTwiceCausesDeath [00027.992717][4558][4575][klog] INFO: [00027.992718][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="2827172054" [00027.992718][4558][4575][klog] INFO: [00027.992719][4558][4575][klog] INFO: [WARNING] ../../garnet/lib/ui/gfx/tests/object_linker_unittest.cc:264:: Death tests are not supported on this platform. [00027.994151][4558][4575][klog] INFO: 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 [00027.994194][4558][4575][klog] INFO: 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( [00027.994196][4558][4575][klog] INFO: ) << "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 [00027.994197][4558][4575][klog] INFO: 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()) [00027.994198][4558][4575][klog] INFO: = ::testing::Message() << "Delegate called unexpectedly: " << "export.link_disconnected"; }))' cannot be verified. [00027.994198][4558][4575][klog] INFO: [ OK ] ObjectLinkerTest.InitializingLinkTwiceCausesDeath (0 ms) [00027.994202][4558][4575][klog] INFO: [ RUN ] ObjectLinkerTest.InitializeLinksMatchingPeers [00027.994202][4558][4575][klog] INFO: [00027.994203][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="938321870" [00027.994203][4558][4575][klog] INFO: [ OK ] ObjectLinkerTest.InitializeLinksMatchingPeers (0 ms) [00027.994203][4558][4575][klog] INFO: [ RUN ] ObjectLinkerTest.InitializeLinksMatchingPeersWithImportBeforeExport [00027.994204][4558][4575][klog] INFO: [00027.994204][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="1006945856" [00027.994205][4558][4575][klog] INFO: [ OK ] ObjectLinkerTest.InitializeLinksMatchingPeersWithImportBeforeExport (0 ms) [00027.994205][4558][4575][klog] INFO: [ RUN ] ObjectLinkerTest.InitializeDoesNotLinkNonMatchingPeers [00027.994205][4558][4575][klog] INFO: [00027.994206][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="1345277186" [00027.994206][4558][4575][klog] INFO: [ OK ] ObjectLinkerTest.InitializeDoesNotLinkNonMatchingPeers (0 ms) [00027.994206][4558][4575][klog] INFO: [ RUN ] ObjectLinkerTest.EarlyImportTokenDeathCausesExportDisconnection [00027.994207][4558][4575][klog] INFO: [00027.994207][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3435132441" [00027.994207][4558][4575][klog] INFO: [ OK ] ObjectLinkerTest.EarlyImportTokenDeathCausesExportDisconnection (0 ms) [00027.994208][4558][4575][klog] INFO: [ RUN ] ObjectLinkerTest.ImportTokenDeathCausesExportDisconnection [00027.994208][4558][4575][klog] INFO: [00027.994208][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3015053004" [00027.994209][4558][4575][klog] INFO: [ OK ] ObjectLinkerTest.ImportTokenDeathCausesExportDisconnection (0 ms) [00027.994209][4558][4575][klog] INFO: [ RUN ] ObjectLinkerTest.EarlyExportTokenDeathCausesImportDisconnection [00027.994209][4558][4575][klog] INFO: [00027.994210][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="1782196616" [00027.994889][4558][4575][klog] INFO: [ OK ] ObjectLinkerTest.EarlyExportTokenDeathCausesImportDisconnection (0 ms) [00027.994964][4558][4575][klog] INFO: [ RUN ] ObjectLinkerTest.ExportTokenDeathCausesImportDisconnection [00027.994965][4558][4575][klog] INFO: [00027.994965][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="386624705" [00027.994966][4558][4575][klog] INFO: [ OK ] ObjectLinkerTest.ExportTokenDeathCausesImportDisconnection (0 ms) [00027.994966][4558][4575][klog] INFO: [----------] 21 tests from ObjectLinkerTest (6 ms total) [00027.994966][4558][4575][klog] INFO: [00027.994967][4558][4575][klog] INFO: [----------] 15 tests from ResourceLinkerTest [00027.994967][4558][4575][klog] INFO: [ RUN ] ResourceLinkerTest.AllowsExport [00027.994968][4558][4575][klog] INFO: [00027.994968][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="4086169071" [00027.997493][4558][4575][klog] INFO: [ OK ] ResourceLinkerTest.AllowsExport (10 ms) [00027.997529][4558][4575][klog] INFO: [ RUN ] ResourceLinkerTest.AllowsImport [00027.997555][4558][4575][klog] INFO: [00027.997556][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="4140553772" [00027.998592][4558][4575][klog] INFO: [ OK ] ResourceLinkerTest.AllowsImport (1 ms) [00027.998620][4558][4575][klog] INFO: [ RUN ] ResourceLinkerTest.CannotImportWithDeadSourceAndDestinationHandles [00027.998620][4558][4575][klog] INFO: [00027.998621][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3827120834" [00028.475962][4558][4575][klog] INFO: [ OK ] ResourceLinkerTest.CannotImportWithDeadSourceAndDestinationHandles (477 ms) [00028.475993][4558][4575][klog] INFO: [ RUN ] ResourceLinkerTest.CannotImportWithDeadDestinationHandles [00028.475994][4558][4575][klog] INFO: [00028.475994][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="2337831274" [00028.483152][4558][4575][klog] INFO: [ OK ] ResourceLinkerTest.CannotImportWithDeadDestinationHandles (3 ms) [00028.483186][4558][4575][klog] INFO: [ RUN ] ResourceLinkerTest.CanImportWithDeadSourceHandle [00028.483187][4558][4575][klog] INFO: [00028.483187][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="2675747059" [00028.489594][4558][4575][klog] INFO: [ OK ] ResourceLinkerTest.CanImportWithDeadSourceHandle (6 ms) [00028.489619][4558][4575][klog] INFO: [ RUN ] ResourceLinkerTest.CannotExportWithDeadSourceAndDestinationHandles [00028.489619][4558][4575][klog] INFO: [00028.489619][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="2285712701" [00028.490328][4558][4575][klog] INFO: [ OK ] ResourceLinkerTest.CannotExportWithDeadSourceAndDestinationHandles (6 ms) [00028.490360][4558][4575][klog] INFO: [ RUN ] ResourceLinkerTest.CannotExportWithDeadSourceHandle [00028.490360][4558][4575][klog] INFO: [00028.490361][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="1140751074" [00028.545657][4558][4575][klog] INFO: [ OK ] ResourceLinkerTest.CannotExportWithDeadSourceHandle (55 ms) [00028.545744][4558][4575][klog] INFO: [ RUN ] ResourceLinkerTest.CanExportWithDeadDestinationHandle [00028.545745][4558][4575][klog] INFO: [00028.545745][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3095120366" [00028.546962][4558][4575][klog] INFO: [ OK ] ResourceLinkerTest.CanExportWithDeadDestinationHandle (1 ms) [00028.546992][4558][4575][klog] INFO: [ RUN ] ResourceLinkerTest.DestinationHandleDeathAutomaticallyCleansUpResourceExport [00028.546992][4558][4575][klog] INFO: [00028.546995][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="1408571122" [00028.557858][4558][4575][klog] INFO: [ OK ] ResourceLinkerTest.DestinationHandleDeathAutomaticallyCleansUpResourceExport (6 ms) [00028.557883][4558][4575][klog] INFO: [ RUN ] ResourceLinkerTest.SourceHandleDeathAutomaticallyCleansUpUnresolvedImports [00028.557911][4558][4575][klog] INFO: [00028.557911][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3757476931" [00028.603998][4558][4575][klog] INFO: [ OK ] ResourceLinkerTest.SourceHandleDeathAutomaticallyCleansUpUnresolvedImports (6 ms) [00028.604060][4558][4575][klog] INFO: [ RUN ] ResourceLinkerTest.ResourceDeathAutomaticallyCleansUpResourceExport [00028.604203][4558][4575][klog] INFO: [00028.604278][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="1270166898" [00028.604903][4558][4575][klog] INFO: [ OK ] ResourceLinkerTest.ResourceDeathAutomaticallyCleansUpResourceExport (0 ms) [00028.604955][4558][4575][klog] INFO: [ RUN ] ResourceLinkerTest.ImportsBeforeExportsAreServiced [00028.605001][4558][4575][klog] INFO: [00028.605002][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="1058180110" [00028.641743][4558][4575][klog] INFO: [ OK ] ResourceLinkerTest.ImportsBeforeExportsAreServiced (3 ms) [00028.641784][4558][4575][klog] INFO: [ RUN ] ResourceLinkerTest.ImportAfterReleasedExportedResourceFails [00028.641785][4558][4575][klog] INFO: [00028.641785][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="862641685" [00028.641790][4558][4575][klog] INFO: [ OK ] ResourceLinkerTest.ImportAfterReleasedExportedResourceFails (7 ms) [00028.641791][4558][4575][klog] INFO: [ RUN ] ResourceLinkerTest.DuplicatedDestinationHandlesAllowMultipleImports [00028.641791][4558][4575][klog] INFO: [00028.641791][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="4156332330" [00028.641792][4558][4575][klog] INFO: [ OK ] ResourceLinkerTest.DuplicatedDestinationHandlesAllowMultipleImports (6 ms) [00028.641793][4558][4575][klog] INFO: [ RUN ] ResourceLinkerTest.UnresolvedImportIsRemovedIfDestroyed [00028.641793][4558][4575][klog] INFO: [00028.641793][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="524788683" [00028.641794][4558][4575][klog] INFO: [ OK ] ResourceLinkerTest.UnresolvedImportIsRemovedIfDestroyed (4 ms) [00028.641794][4558][4575][klog] INFO: [----------] 15 tests from ResourceLinkerTest (637 ms total) [00028.641795][4558][4575][klog] INFO: [00028.641796][4558][4575][klog] INFO: [----------] 6 tests from SessionTest [00028.641797][4558][4575][klog] INFO: [ RUN ] SessionTest.ScheduleUpdateOutOfOrder [00028.641798][4558][4575][klog] INFO: [00028.641798][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="2774871028" [00028.641798][4558][4575][klog] INFO: [ OK ] SessionTest.ScheduleUpdateOutOfOrder (7 ms) [00028.641799][4558][4575][klog] INFO: [ RUN ] SessionTest.ScheduleUpdateInOrder [00028.641799][4558][4575][klog] INFO: [00028.641800][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3745883960" [00028.641800][4558][4575][klog] INFO: [ OK ] SessionTest.ScheduleUpdateInOrder (5 ms) [00028.641801][4558][4575][klog] INFO: [ RUN ] SessionTest.ResourceIdAlreadyUsed [00028.641801][4558][4575][klog] INFO: [00028.641801][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3388477809" [00028.641802][4558][4575][klog] INFO: [ OK ] SessionTest.ResourceIdAlreadyUsed (1 ms) [00028.649228][4558][4575][klog] INFO: [ RUN ] SessionTest.AddAndRemoveResource [00028.649260][4558][4575][klog] INFO: [00028.649260][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3215117506" [00028.649261][4558][4575][klog] INFO: [ OK ] SessionTest.AddAndRemoveResource (4 ms) [00028.649261][4558][4575][klog] INFO: [ RUN ] SessionTest.Labeling [00028.649262][4558][4575][klog] INFO: [00028.649262][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="446682501" [00028.649262][4558][4575][klog] INFO: [ OK ] SessionTest.Labeling (0 ms) [00028.649263][4558][4575][klog] INFO: [ RUN ] SessionTest.SendingSizeChangeEventWorks [00028.649268][4558][4575][klog] INFO: [00028.649268][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3010747734" [00028.649269][4558][4575][klog] INFO: [ OK ] SessionTest.SendingSizeChangeEventWorks (2 ms) [00028.649269][4558][4575][klog] INFO: [----------] 6 tests from SessionTest (19 ms total) [00028.649269][4558][4575][klog] INFO: [00028.649270][4558][4575][klog] INFO: [----------] 2 tests from ShapeTest [00028.649270][4558][4575][klog] INFO: [ RUN ] ShapeTest.Circle [00028.649270][4558][4575][klog] INFO: [00028.649271][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="652834454" [00028.654873][4558][4575][klog] INFO: [ OK ] ShapeTest.Circle (11 ms) [00028.654943][4558][4575][klog] INFO: [ RUN ] ShapeTest.Rectangle [00028.654944][4558][4575][klog] INFO: [00028.654944][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="702339512" [00028.830335][4558][4575][klog] INFO: [ OK ] ShapeTest.Rectangle (57 ms) [00028.830373][4558][4575][klog] INFO: [----------] 2 tests from ShapeTest (69 ms total) [00028.830373][4558][4575][klog] INFO: [00028.830374][4558][4575][klog] INFO: [----------] 1 test from StereoCameraTest [00028.830374][4558][4575][klog] INFO: [ RUN ] StereoCameraTest.Basic [00028.830375][4558][4575][klog] INFO: [00028.830375][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="2814873701" [00028.831695][4558][4575][klog] INFO: [ OK ] StereoCameraTest.Basic (119 ms) [00028.831727][4558][4575][klog] INFO: [----------] 1 test from StereoCameraTest (119 ms total) [00028.831727][4558][4575][klog] INFO: [00028.831728][4558][4575][klog] INFO: [----------] 17 tests from ViewTest [00028.831729][4558][4575][klog] INFO: [ RUN ] ViewTest.CreateViewWithBadTokenDies [00028.831729][4558][4575][klog] INFO: [00028.831730][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="2789063273" [00028.840743][4558][4575][klog] INFO: [00028.840813][4558][4575][klog] INFO: [WARNING] ../../garnet/lib/ui/gfx/tests/view_unittest.cc:25:: Death tests are not supported on this platform. [00028.840857][4558][4575][klog] INFO: Statement 'Apply(scenic::NewCreateViewCmd(1, zx::eventpair(), ""))' cannot be verified. [00028.840858][4558][4575][klog] INFO: [00028.840858][4558][4575][klog] INFO: [WARNING] ../../garnet/lib/ui/gfx/tests/view_unittest.cc:27:: Death tests are not supported on this platform. [00028.840859][4558][4575][klog] INFO: Statement 'Apply(scenic::NewCreateViewHolderCmd(2, zx::eventpair(), ""))' cannot be verified. [00028.840864][4558][4575][klog] INFO: [ OK ] ViewTest.CreateViewWithBadTokenDies (4 ms) [00028.840864][4558][4575][klog] INFO: [ RUN ] ViewTest.Children [00028.840865][4558][4575][klog] INFO: [00028.840865][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="2854966201" [00028.840865][4558][4575][klog] INFO: [ OK ] ViewTest.Children (2 ms) [00028.840866][4558][4575][klog] INFO: [ RUN ] ViewTest.ExportsViewHolderViaCmd [00028.840866][4558][4575][klog] INFO: [00028.840867][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="774733555" [00028.842870][4558][4575][klog] INFO: [ OK ] ViewTest.ExportsViewHolderViaCmd (4 ms) [00028.842914][4558][4575][klog] INFO: [ RUN ] ViewTest.ImportsViewViaCmd [00028.842915][4558][4575][klog] INFO: [00028.842916][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="4185050213" [00028.846858][4558][4575][klog] INFO: [ OK ] ViewTest.ImportsViewViaCmd (5 ms) [00028.846896][4558][4575][klog] INFO: [ RUN ] ViewTest.PairedViewAndHolderAreLinked [00028.846897][4558][4575][klog] INFO: [00028.846897][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="2578074432" [00028.849092][4558][4575][klog] INFO: [ OK ] ViewTest.PairedViewAndHolderAreLinked (3 ms) [00028.849117][4558][4575][klog] INFO: [ RUN ] ViewTest.ExportViewHolderWithDeadHandleFails [00028.849117][4558][4575][klog] INFO: [00028.849118][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="2963243070" [00028.975469][4558][4575][klog] INFO: [ OK ] ViewTest.ExportViewHolderWithDeadHandleFails (126 ms) [00028.975495][4558][4575][klog] INFO: [ RUN ] ViewTest.ViewHolderDestroyedBeforeView [00028.975496][4558][4575][klog] INFO: [00028.975496][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3678388059" [00028.990201][4558][4575][klog] INFO: [ OK ] ViewTest.ViewHolderDestroyedBeforeView (1 ms) [00028.990228][4558][4575][klog] INFO: [ RUN ] ViewTest.ViewDestroyedBeforeViewHolder [00028.990230][4558][4575][klog] INFO: [00028.990230][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="1887638734" [00028.990231][4558][4575][klog] INFO: [ OK ] ViewTest.ViewDestroyedBeforeViewHolder (1 ms) [00028.990232][4558][4575][klog] INFO: [ RUN ] ViewTest.ViewHolderConnectsToScene [00028.990232][4558][4575][klog] INFO: [00028.990233][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="2348315552" [00028.990238][4558][4575][klog] INFO: [ OK ] ViewTest.ViewHolderConnectsToScene (6 ms) [00028.990239][4558][4575][klog] INFO: [ RUN ] ViewTest.ViewHolderGrandchildGetsSceneRefreshed [00028.990239][4558][4575][klog] INFO: [00028.990240][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="828023248" [00028.990240][4558][4575][klog] INFO: [ OK ] ViewTest.ViewHolderGrandchildGetsSceneRefreshed (6 ms) [00028.990241][4558][4575][klog] INFO: [ RUN ] ViewTest.ViewLinksAfterViewHolderConnectsToScene [00028.990242][4558][4575][klog] INFO: [00028.990242][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="1959816119" [00028.994062][4558][4575][klog] INFO: [ OK ] ViewTest.ViewLinksAfterViewHolderConnectsToScene (2 ms) [00028.994063][4558][4575][klog] INFO: [ RUN ] ViewTest.ViewStateChangeNotifiesViewHolder [00028.994063][4558][4575][klog] INFO: [00028.994064][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3065406830" [00028.999780][4558][4575][klog] INFO: [ OK ] ViewTest.ViewStateChangeNotifiesViewHolder (4 ms) [00028.999816][4558][4575][klog] INFO: [ RUN ] ViewTest.RenderStateAcrossManyFrames [00028.999817][4558][4575][klog] INFO: [00028.999817][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="2245000152" [00029.087697][4558][4575][klog] INFO: [ OK ] ViewTest.RenderStateAcrossManyFrames (6 ms) [00029.087722][4558][4575][klog] INFO: [ RUN ] ViewTest.RenderStateFalseWhenViewDisconnects [00029.087724][4558][4575][klog] INFO: [00029.087725][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="2227990873" [00029.087725][4558][4575][klog] INFO: [ OK ] ViewTest.RenderStateFalseWhenViewDisconnects (5 ms) [00029.087726][4558][4575][klog] INFO: [ RUN ] ViewTest.ViewHolderRenderWaitClearedWhenViewDestroyed [00029.087726][4558][4575][klog] INFO: [00029.087727][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3548358025" [00029.093634][4558][4575][klog] INFO: [ OK ] ViewTest.ViewHolderRenderWaitClearedWhenViewDestroyed (81 ms) [00029.093680][4558][4575][klog] INFO: [ RUN ] ViewTest.RenderSignalDoesntCrashWhenViewHolderDestroyed [00029.095626][4558][4575][klog] INFO: [00029.095665][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3736088632" [00029.095665][4558][4575][klog] INFO: [ OK ] ViewTest.RenderSignalDoesntCrashWhenViewHolderDestroyed (1 ms) [00029.095666][4558][4575][klog] INFO: [ RUN ] ViewTest.RenderStateFalseWhenViewHolderDisconnectsFromScene [00029.095667][4558][4575][klog] INFO: [00029.095667][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="594092302" [00029.095668][4558][4575][klog] INFO: [ OK ] ViewTest.RenderStateFalseWhenViewHolderDisconnectsFromScene (6 ms) [00029.095676][4558][4575][klog] INFO: [----------] 17 tests from ViewTest (263 ms total) [00029.095677][4558][4575][klog] INFO: [00029.095678][4558][4575][klog] INFO: [----------] Global test environment tear-down [00029.095678][4558][4575][klog] INFO: [==========] 113 tests from 13 test cases ran. (2167 ms total) [00029.095679][4558][4575][klog] INFO: [ PASSED ] 113 tests. [00029.095679][4558][4575][klog] INFO: [00029.095680][4558][4575][klog] INFO: YOU HAVE 2 DISABLED TESTS [00029.095680][4558][4575][klog] INFO: [00029.105310][4558][4575][klog] INFO: PASSED: /pkgfs/packages/scenic_tests/0/test/gfx_unittests passed [00029.257844][4558][4575][klog] INFO: [00029.257874][4558][4575][klog] INFO: ------------------------------------------------ [00029.257877][4558][4575][klog] INFO: RUNNING TEST: /pkgfs/packages/scenic_tests/0/test/input_apptests [00029.257878][4558][4575][klog] INFO: [00029.325654][4558][4575][klog] INFO: Running main() from gtest_main.cc [00029.325720][4558][4575][klog] INFO: [==========] Running 6 tests from 3 test cases. [00029.325721][4558][4575][klog] INFO: [----------] Global test environment set-up. [00029.325722][4558][4575][klog] INFO: [----------] 2 tests from InputInterfaceTest [00029.325722][4558][4575][klog] INFO: [ RUN ] InputInterfaceTest.RegisterKeyboardTest [00029.325723][4558][4575][klog] INFO: [00029.325723][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="1576945728" [00029.325984][4558][4575][klog] INFO: [ OK ] InputInterfaceTest.RegisterKeyboardTest (1 ms) [00029.326019][4558][4575][klog] INFO: [ RUN ] InputInterfaceTest.InputKeyboardTest [00029.326046][4558][4575][klog] INFO: [00029.326047][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3266994358" [00029.326497][4558][4575][klog] INFO: [ OK ] InputInterfaceTest.InputKeyboardTest (0 ms) [00029.326498][4558][4575][klog] INFO: [----------] 2 tests from InputInterfaceTest (1 ms total) [00029.326498][4558][4575][klog] INFO: [00029.326499][4558][4575][klog] INFO: [----------] 2 tests from ReaderInterpreterTest [00029.326500][4558][4575][klog] INFO: [ RUN ] ReaderInterpreterTest.RegisterKeyboardTest [00029.326500][4558][4575][klog] INFO: [00029.326501][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="1467633088" [00029.326712][4558][4575][klog] INFO: [ OK ] ReaderInterpreterTest.RegisterKeyboardTest (0 ms) [00029.326713][4558][4575][klog] INFO: [ RUN ] ReaderInterpreterTest.RemoveKeyboardTest [00029.326713][4558][4575][klog] INFO: [00029.326714][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="1884506669" [00029.335331][4558][4575][klog] INFO: [ERROR:garnet/bin/ui/input_reader/input_interpreter.cc(490)] Failed to read from input: -1 for MockHidDecoder [00029.335361][4558][4575][klog] INFO: [ OK ] ReaderInterpreterTest.RemoveKeyboardTest (1 ms) [00029.335362][4558][4575][klog] INFO: [----------] 2 tests from ReaderInterpreterTest (1 ms total) [00029.335362][4558][4575][klog] INFO: [00029.335363][4558][4575][klog] INFO: [----------] 2 tests from ReaderInterpreterInputTest [00029.335363][4558][4575][klog] INFO: [ RUN ] ReaderInterpreterInputTest.KeyboardTest [00029.335367][4558][4575][klog] INFO: [00029.335368][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3147873903" [00029.446643][4558][4575][klog] INFO: [ OK ] ReaderInterpreterInputTest.KeyboardTest (119 ms) [00029.446676][4558][4575][klog] INFO: [ RUN ] ReaderInterpreterInputTest.LightSensorTest [00029.446677][4558][4575][klog] INFO: [00029.446678][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="698340963" [00029.446679][4558][4575][klog] INFO: [ OK ] ReaderInterpreterInputTest.LightSensorTest (0 ms) [00029.446680][4558][4575][klog] INFO: [----------] 2 tests from ReaderInterpreterInputTest (119 ms total) [00029.446680][4558][4575][klog] INFO: [00029.446681][4558][4575][klog] INFO: [----------] Global test environment tear-down [00029.446682][4558][4575][klog] INFO: [==========] 6 tests from 3 test cases ran. (121 ms total) [00029.446683][4558][4575][klog] INFO: [ PASSED ] 6 tests. [00029.495670][4558][4575][klog] INFO: PASSED: /pkgfs/packages/scenic_tests/0/test/input_apptests passed [00029.563680][4558][4575][klog] INFO: [00029.563719][4558][4575][klog] INFO: ------------------------------------------------ [00029.563722][4558][4575][klog] INFO: RUNNING TEST: /pkgfs/packages/scenic_tests/0/test/input_tool_unittests [00029.563723][4558][4575][klog] INFO: [00029.615177][4558][4575][klog] INFO: Running main() from gtest_main.cc [00029.615223][4558][4575][klog] INFO: [==========] Running 5 tests from 1 test case. [00029.615223][4558][4575][klog] INFO: [----------] Global test environment set-up. [00029.615224][4558][4575][klog] INFO: [----------] 5 tests from InverseKeymapTest [00029.615224][4558][4575][klog] INFO: [ RUN ] InverseKeymapTest.PlainKey [00029.615536][4558][4575][klog] INFO: [ OK ] InverseKeymapTest.PlainKey (0 ms) [00029.615560][4558][4575][klog] INFO: [ RUN ] InverseKeymapTest.ShiftKey [00029.615561][4558][4575][klog] INFO: [ OK ] InverseKeymapTest.ShiftKey (0 ms) [00029.615561][4558][4575][klog] INFO: [ RUN ] InverseKeymapTest.Lowercase [00029.615712][4558][4575][klog] INFO: [ OK ] InverseKeymapTest.Lowercase (0 ms) [00029.615751][4558][4575][klog] INFO: [ RUN ] InverseKeymapTest.Sentence [00029.615899][4558][4575][klog] INFO: [ OK ] InverseKeymapTest.Sentence (0 ms) [00029.615943][4558][4575][klog] INFO: [ RUN ] InverseKeymapTest.HoldShift [00029.616549][4558][4575][klog] INFO: [ OK ] InverseKeymapTest.HoldShift (0 ms) [00029.616594][4558][4575][klog] INFO: [----------] 5 tests from InverseKeymapTest (1 ms total) [00029.616595][4558][4575][klog] INFO: [00029.616595][4558][4575][klog] INFO: [----------] Global test environment tear-down [00029.616596][4558][4575][klog] INFO: [==========] 5 tests from 1 test case ran. (1 ms total) [00029.616596][4558][4575][klog] INFO: [ PASSED ] 5 tests. [00029.691545][4558][4575][klog] INFO: PASSED: /pkgfs/packages/scenic_tests/0/test/input_tool_unittests passed [00029.757082][4558][4575][klog] INFO: [00029.757118][4558][4575][klog] INFO: ------------------------------------------------ [00029.757121][4558][4575][klog] INFO: RUNNING TEST: /pkgfs/packages/scenic_tests/0/test/input_unittests [00029.757122][4558][4575][klog] INFO: [00029.903660][4558][4575][klog] INFO: [==========] Running 7 tests from 5 test cases. [00029.903735][4558][4575][klog] INFO: [----------] Global test environment set-up. [00029.903736][4558][4575][klog] INFO: [----------] 1 test from CoordinateTransformTest [00029.903737][4558][4575][klog] INFO: [ RUN ] CoordinateTransformTest.CoordinateTransform [00029.903737][4558][4575][klog] INFO: [00029.903738][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="1725741563" [00029.904434][4558][4575][klog] INFO: [INFO:gfx_system.cc(176)] No Vulkan found, but using a test-only "display". [00029.905245][4558][4575][klog] INFO: [INFO:input_system.cc(230)] Scenic input system initialized. [00029.913267][4558][4575][klog] INFO: [ OK ] CoordinateTransformTest.CoordinateTransform (6 ms) [00029.913268][4558][4575][klog] INFO: [----------] 1 test from CoordinateTransformTest (6 ms total) [00029.913268][4558][4575][klog] INFO: [00029.913269][4558][4575][klog] INFO: [----------] 2 tests from FocusAvoidanceTest [00029.913269][4558][4575][klog] INFO: [ RUN ] FocusAvoidanceTest.ViewHierarchyByViewManager [00029.913269][4558][4575][klog] INFO: [00029.913270][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="1308041561" [00029.913270][4558][4575][klog] INFO: [INFO:gfx_system.cc(176)] No Vulkan found, but using a test-only "display". [00029.913583][4558][4575][klog] INFO: [INFO:input_system.cc(230)] Scenic input system initialized. [00029.917314][4558][4575][klog] INFO: [ OK ] FocusAvoidanceTest.ViewHierarchyByViewManager (8 ms) [00029.917545][4558][4575][klog] INFO: [ RUN ] FocusAvoidanceTest.ViewHierarchyByScenic [00029.917545][4558][4575][klog] INFO: [00029.917546][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="599596658" [00029.919102][4558][4575][klog] INFO: [INFO:gfx_system.cc(176)] No Vulkan found, but using a test-only "display". [00029.999035][4558][4575][klog] INFO: [INFO:input_system.cc(230)] Scenic input system initialized. [00029.999075][4558][4575][klog] INFO: [ OK ] FocusAvoidanceTest.ViewHierarchyByScenic (78 ms) [00029.999076][4558][4575][klog] INFO: [----------] 2 tests from FocusAvoidanceTest (86 ms total) [00029.999077][4558][4575][klog] INFO: [00029.999077][4558][4575][klog] INFO: [----------] 1 test from HardKeyboardDeliveryTest [00029.999078][4558][4575][klog] INFO: [ RUN ] HardKeyboardDeliveryTest.Test [00029.999078][4558][4575][klog] INFO: [00029.999079][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="392610119" [00029.999080][4558][4575][klog] INFO: [INFO:gfx_system.cc(176)] No Vulkan found, but using a test-only "display". [00029.999080][4558][4575][klog] INFO: [INFO:input_system.cc(230)] Scenic input system initialized. [00029.999081][4558][4575][klog] INFO: [ERROR:garnet/lib/ui/input/input_system.cc(227)] Scenic lost connection to TextSync [00030.001456][4558][4575][klog] INFO: [ OK ] HardKeyboardDeliveryTest.Test (6 ms) [00030.001514][4558][4575][klog] INFO: [----------] 1 test from HardKeyboardDeliveryTest (6 ms total) [00030.001515][4558][4575][klog] INFO: [00030.001516][4558][4575][klog] INFO: [----------] 1 test from ImportNodeTest [00030.001516][4558][4575][klog] INFO: [ RUN ] ImportNodeTest.ImportNodeEventDelivery [00030.001517][4558][4575][klog] INFO: [00030.001518][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3929619519" [00030.001518][4558][4575][klog] INFO: [INFO:gfx_system.cc(176)] No Vulkan found, but using a test-only "display". [00030.001874][4558][4575][klog] INFO: [INFO:input_system.cc(230)] Scenic input system initialized. [00030.087556][4558][4575][klog] INFO: [ OK ] ImportNodeTest.ImportNodeEventDelivery (83 ms) [00030.087601][4558][4575][klog] INFO: [----------] 1 test from ImportNodeTest (84 ms total) [00030.087602][4558][4575][klog] INFO: [00030.087602][4558][4575][klog] INFO: [----------] 2 tests from MouseDeliveryTest [00030.087603][4558][4575][klog] INFO: [ RUN ] MouseDeliveryTest.StandardTest [00030.087603][4558][4575][klog] INFO: [00030.087604][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3642418082" [00030.087604][4558][4575][klog] INFO: [INFO:gfx_system.cc(176)] No Vulkan found, but using a test-only "display". [00030.087609][4558][4575][klog] INFO: [INFO:input_system.cc(230)] Scenic input system initialized. [00030.089081][4558][4575][klog] INFO: [ERROR:garnet/lib/ui/input/input_system.cc(227)] Scenic lost connection to TextSync [00030.093047][4558][4575][klog] INFO: [ OK ] MouseDeliveryTest.StandardTest (7 ms) [00030.093082][4558][4575][klog] INFO: [ RUN ] MouseDeliveryTest.NoFocusTest [00030.093083][4558][4575][klog] INFO: [00030.093083][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="2029053519" [00030.093084][4558][4575][klog] INFO: [INFO:gfx_system.cc(176)] No Vulkan found, but using a test-only "display". [00030.093469][4558][4575][klog] INFO: [INFO:input_system.cc(230)] Scenic input system initialized. [00030.099355][4558][4575][klog] INFO: [ OK ] MouseDeliveryTest.NoFocusTest (5 ms) [00030.099370][4558][4575][klog] INFO: [----------] 2 tests from MouseDeliveryTest (12 ms total) [00030.099396][4558][4575][klog] INFO: [00030.099396][4558][4575][klog] INFO: [----------] Global test environment tear-down [00030.099487][4558][4575][klog] INFO: [==========] 7 tests from 5 test cases ran. (194 ms total) [00030.099489][4558][4575][klog] INFO: [ PASSED ] 7 tests. [00030.178527][4558][4575][klog] INFO: PASSED: /pkgfs/packages/scenic_tests/0/test/input_unittests passed [00030.245676][4558][4575][klog] INFO: [00030.245829][4558][4575][klog] INFO: ------------------------------------------------ [00030.245833][4558][4575][klog] INFO: RUNNING TEST: /pkgfs/packages/scenic_tests/0/test/presentation_mode_unittests [00030.245961][4558][4575][klog] INFO: [00030.296467][4558][4575][klog] INFO: Running main() from gtest_main.cc [00030.296821][4558][4575][klog] INFO: [==========] Running 7 tests from 3 test cases. [00030.296890][4558][4575][klog] INFO: [----------] Global test environment set-up. [00030.296891][4558][4575][klog] INFO: [----------] 1 test from PositiveData [00030.296891][4558][4575][klog] INFO: [ RUN ] PositiveData.MovingAverage [00030.296892][4558][4575][klog] INFO: [ OK ] PositiveData.MovingAverage (0 ms) [00030.296892][4558][4575][klog] INFO: [----------] 1 test from PositiveData (0 ms total) [00030.296893][4558][4575][klog] INFO: [00030.296893][4558][4575][klog] INFO: [----------] 1 test from NegativeData [00030.296894][4558][4575][klog] INFO: [ RUN ] NegativeData.MovingAverage [00030.296894][4558][4575][klog] INFO: [ OK ] NegativeData.MovingAverage (0 ms) [00030.296894][4558][4575][klog] INFO: [----------] 1 test from NegativeData (0 ms total) [00030.296895][4558][4575][klog] INFO: [00030.296895][4558][4575][klog] INFO: [----------] 5 tests from Detector [00030.296895][4558][4575][klog] INFO: [ RUN ] Detector.Closed [00030.296899][4558][4575][klog] INFO: [ OK ] Detector.Closed (1 ms) [00030.296899][4558][4575][klog] INFO: [ RUN ] Detector.Laptop [00030.296900][4558][4575][klog] INFO: [ OK ] Detector.Laptop (0 ms) [00030.296900][4558][4575][klog] INFO: [ RUN ] Detector.Tablet [00030.296901][4558][4575][klog] INFO: [ OK ] Detector.Tablet (0 ms) [00030.296901][4558][4575][klog] INFO: [ RUN ] Detector.Tent [00030.296902][4558][4575][klog] INFO: [ OK ] Detector.Tent (0 ms) [00030.296971][4558][4575][klog] INFO: [ RUN ] Detector.NonAccelerometer [00030.296995][4558][4575][klog] INFO: [ OK ] Detector.NonAccelerometer (0 ms) [00030.390329][4558][4575][klog] INFO: [----------] 5 tests from Detector (1 ms total) [00030.390578][4558][4575][klog] INFO: [00030.390579][4558][4575][klog] INFO: [----------] Global test environment tear-down [00030.390580][4558][4575][klog] INFO: [==========] 7 tests from 3 test cases ran. (1 ms total) [00030.390580][4558][4575][klog] INFO: [ PASSED ] 7 tests. [00030.412618][4558][4575][klog] INFO: PASSED: /pkgfs/packages/scenic_tests/0/test/presentation_mode_unittests passed [00030.477377][4558][4575][klog] INFO: [00030.477407][4558][4575][klog] INFO: ------------------------------------------------ [00030.477410][4558][4575][klog] INFO: RUNNING TEST: /pkgfs/packages/scenic_tests/0/test/scenic_unittests [00030.477411][4558][4575][klog] INFO: [00030.544712][4558][4575][klog] INFO: Running main() from gtest_main.cc [00030.544752][4558][4575][klog] INFO: [==========] Running 1 test from 1 test case. [00030.544798][4558][4575][klog] INFO: [----------] Global test environment set-up. [00030.544799][4558][4575][klog] INFO: [----------] 1 test from ScenicTest [00030.544799][4558][4575][klog] INFO: [ RUN ] ScenicTest.SessionCreatedAfterAllSystemsInitialized [00030.544800][4558][4575][klog] INFO: [00030.544800][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="409230633" [00030.545580][4558][4575][klog] INFO: [ OK ] ScenicTest.SessionCreatedAfterAllSystemsInitialized (1 ms) [00030.545915][4558][4575][klog] INFO: [----------] 1 test from ScenicTest (1 ms total) [00030.545973][4558][4575][klog] INFO: [00030.545974][4558][4575][klog] INFO: [----------] Global test environment tear-down [00030.545974][4558][4575][klog] INFO: [==========] 1 test from 1 test case ran. (1 ms total) [00030.545975][4558][4575][klog] INFO: [ PASSED ] 1 test. [00030.558308][4558][4575][klog] INFO: PASSED: /pkgfs/packages/scenic_tests/0/test/scenic_unittests passed [00030.650843][4558][4575][klog] INFO: [00030.650879][4558][4575][klog] INFO: ------------------------------------------------ [00030.650882][4558][4575][klog] INFO: RUNNING TEST: /pkgfs/packages/scenic_tests/0/test/view_manager_apptests [00030.650883][4558][4575][klog] INFO: [00030.799029][4558][4575][klog] INFO: Running main() from gtest_main.cc [00030.799147][4558][4575][klog] INFO: [==========] Running 0 tests from 0 test cases. [00030.799147][4558][4575][klog] INFO: [==========] 0 tests from 0 test cases ran. (0 ms total) [00030.799148][4558][4575][klog] INFO: [ PASSED ] 0 tests. [00030.840804][4558][4575][klog] INFO: PASSED: /pkgfs/packages/scenic_tests/0/test/view_manager_apptests passed [00030.898559][4558][4575][klog] INFO: [00030.898597][4558][4575][klog] INFO: ------------------------------------------------ [00030.898600][4558][4575][klog] INFO: RUNNING TEST: /pkgfs/packages/web_runner_tests/0/test/web_runner_unittests [00030.898600][4558][4575][klog] INFO: [00030.960584][4558][4575][klog] INFO: Running main() from gtest_main.cc [00030.960625][4558][4575][klog] INFO: [==========] Running 2 tests from 1 test case. [00030.960713][4558][4575][klog] INFO: [----------] Global test environment set-up. [00030.960714][4558][4575][klog] INFO: [----------] 2 tests from RunnerTest [00030.960714][4558][4575][klog] INFO: [ RUN ] RunnerTest.Trivial [00030.960715][4558][4575][klog] INFO: [00030.960715][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="211963595" [00030.961137][4558][4575][klog] INFO: [ OK ] RunnerTest.Trivial (1 ms) [00030.961802][4558][4575][klog] INFO: [ RUN ] RunnerTest.CreatesWebView [00030.991805][4558][4575][klog] INFO: [00030.992241][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="2840577830" [00031.002512][4558][4575][klog] INFO: [ OK ] RunnerTest.CreatesWebView (41 ms) [00031.002572][4558][4575][klog] INFO: [----------] 2 tests from RunnerTest (42 ms total) [00031.002575][4558][4575][klog] INFO: [00031.002576][4558][4575][klog] INFO: [----------] Global test environment tear-down [00031.002577][4558][4575][klog] INFO: [==========] 2 tests from 1 test case ran. (42 ms total) [00031.002577][4558][4575][klog] INFO: [ PASSED ] 2 tests. [00031.012820][4558][4575][klog] INFO: PASSED: /pkgfs/packages/web_runner_tests/0/test/web_runner_unittests passed [00031.043303][4558][4575][klog] INFO: [00031.043324][4558][4575][klog] INFO: ------------------------------------------------ [00031.043327][4558][4575][klog] INFO: RUNNING TEST: /system/test/auth_provider_unittests [00031.043328][4558][4575][klog] INFO: [00031.099871][4558][4575][klog] INFO: Running main() from gtest_main.cc [00031.100217][4558][4575][klog] INFO: [==========] Running 27 tests from 6 test cases. [00031.100253][4558][4575][klog] INFO: [----------] Global test environment set-up. [00031.100254][4558][4575][klog] INFO: [----------] 1 test from GoogleFactoryImplTest [00031.100255][4558][4575][klog] INFO: [ RUN ] GoogleFactoryImplTest.GetAuthProvider [00031.100256][4558][4575][klog] INFO: [00031.100258][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3157794280" [00031.102409][4558][4575][klog] INFO: [ OK ] GoogleFactoryImplTest.GetAuthProvider (2 ms) [00031.102455][4558][4575][klog] INFO: [----------] 1 test from GoogleFactoryImplTest (2 ms total) [00031.102456][4558][4575][klog] INFO: [00031.102457][4558][4575][klog] INFO: [----------] 12 tests from GoogleAuthProviderImplTest [00031.102458][4558][4575][klog] INFO: [ RUN ] GoogleAuthProviderImplTest.EmptyWhenClientDisconnected [00031.102458][4558][4575][klog] INFO: [00031.102459][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="445659842" [00031.102893][4558][4575][klog] INFO: [ OK ] GoogleAuthProviderImplTest.EmptyWhenClientDisconnected (0 ms) [00031.102933][4558][4575][klog] INFO: [ RUN ] GoogleAuthProviderImplTest.GetAppAccessTokenSuccess [00031.102934][4558][4575][klog] INFO: [00031.102934][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3324270254" [00031.105065][4558][4575][klog] INFO: [ OK ] GoogleAuthProviderImplTest.GetAppAccessTokenSuccess (3 ms) [00031.105089][4558][4575][klog] INFO: [ RUN ] GoogleAuthProviderImplTest.GetAppAccessTokenBadRequestError [00031.105090][4558][4575][klog] INFO: [00031.105090][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="2432617074" [00031.107191][4558][4575][klog] INFO: [ OK ] GoogleAuthProviderImplTest.GetAppAccessTokenBadRequestError (0 ms) [00031.107219][4558][4575][klog] INFO: [ RUN ] GoogleAuthProviderImplTest.GetAppAccessTokenInvalidClientError [00031.107220][4558][4575][klog] INFO: [00031.107220][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3901906531" [00031.107831][4558][4575][klog] INFO: [WARNING:topaz/auth_providers/google/google_auth_provider_impl.cc(196)] Got error: OAuth backend returned error: 401 [00031.107970][4558][4575][klog] INFO: [WARNING:topaz/auth_providers/google/google_auth_provider_impl.cc(197)] Got response: { [00031.107971][4558][4575][klog] INFO: "error": "invalid_client" [00031.107971][4558][4575][klog] INFO: } [00031.107974][4558][4575][klog] INFO: [ OK ] GoogleAuthProviderImplTest.GetAppAccessTokenInvalidClientError (3 ms) [00031.107974][4558][4575][klog] INFO: [ RUN ] GoogleAuthProviderImplTest.GetAppAccessTokenInvalidUserError [00031.107975][4558][4575][klog] INFO: [00031.107975][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="218413953" [00031.110115][4558][4575][klog] INFO: [WARNING:topaz/auth_providers/google/google_auth_provider_impl.cc(196)] Got error: OAuth backend returned error: 401 [00031.110164][4558][4575][klog] INFO: [WARNING:topaz/auth_providers/google/google_auth_provider_impl.cc(197)] Got response: { [00031.110196][4558][4575][klog] INFO: "error": "invalid_credential" [00031.110196][4558][4575][klog] INFO: } [00031.110197][4558][4575][klog] INFO: [ OK ] GoogleAuthProviderImplTest.GetAppAccessTokenInvalidUserError (2 ms) [00031.110197][4558][4575][klog] INFO: [ RUN ] GoogleAuthProviderImplTest.GetAppIdTokenSuccess [00031.110198][4558][4575][klog] INFO: [00031.110198][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="671922688" [00031.113069][4558][4575][klog] INFO: [ OK ] GoogleAuthProviderImplTest.GetAppIdTokenSuccess (2 ms) [00031.113102][4558][4575][klog] INFO: [ RUN ] GoogleAuthProviderImplTest.GetAppIdTokenBadRequestError [00031.113103][4558][4575][klog] INFO: [00031.113103][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="514363479" [00031.113104][4558][4575][klog] INFO: [ OK ] GoogleAuthProviderImplTest.GetAppIdTokenBadRequestError (0 ms) [00031.113105][4558][4575][klog] INFO: [ RUN ] GoogleAuthProviderImplTest.GetAppIdTokenInvalidAudienceError [00031.113106][4558][4575][klog] INFO: [00031.113106][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3292776627" [00031.114756][4558][4575][klog] INFO: [WARNING:topaz/auth_providers/google/google_auth_provider_impl.cc(234)] Got error: OAuth backend returned error: 401 [00031.114781][4558][4575][klog] INFO: [WARNING:topaz/auth_providers/google/google_auth_provider_impl.cc(235)] Got response: { [00031.114782][4558][4575][klog] INFO: "error": "invalid_client" [00031.114782][4558][4575][klog] INFO: } [00031.114785][4558][4575][klog] INFO: [ OK ] GoogleAuthProviderImplTest.GetAppIdTokenInvalidAudienceError (2 ms) [00031.114786][4558][4575][klog] INFO: [ RUN ] GoogleAuthProviderImplTest.GetAppIdTokenInvalidUserError [00031.115046][4558][4575][klog] INFO: [00031.115384][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="1334578395" [00031.132186][4558][4575][klog] INFO: [WARNING:topaz/auth_providers/google/google_auth_provider_impl.cc(234)] Got error: OAuth backend returned error: 401 [00031.132241][4558][4575][klog] INFO: [WARNING:topaz/auth_providers/google/google_auth_provider_impl.cc(235)] Got response: { [00031.132242][4558][4575][klog] INFO: "error": "invalid_credential" [00031.132243][4558][4575][klog] INFO: } [00031.132243][4558][4575][klog] INFO: [ OK ] GoogleAuthProviderImplTest.GetAppIdTokenInvalidUserError (18 ms) [00031.132244][4558][4575][klog] INFO: [ RUN ] GoogleAuthProviderImplTest.GetAppFirebaseTokenSuccess [00031.132244][4558][4575][klog] INFO: [00031.132245][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="208017531" [00031.137129][4558][4575][klog] INFO: [ OK ] GoogleAuthProviderImplTest.GetAppFirebaseTokenSuccess (3 ms) [00031.137272][4558][4575][klog] INFO: [ RUN ] GoogleAuthProviderImplTest.GetAppFirebaseTokenBadRequestError [00031.137518][4558][4575][klog] INFO: [00031.137739][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3267728885" [00031.138103][4558][4575][klog] INFO: [ OK ] GoogleAuthProviderImplTest.GetAppFirebaseTokenBadRequestError (0 ms) [00031.138220][4558][4575][klog] INFO: [ RUN ] GoogleAuthProviderImplTest.RevokeAppOrPersistentCredentialUnsupported [00031.138317][4558][4575][klog] INFO: [00031.138428][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="1836438211" [00031.138785][4558][4575][klog] INFO: [ OK ] GoogleAuthProviderImplTest.RevokeAppOrPersistentCredentialUnsupported (0 ms) [00031.139034][4558][4575][klog] INFO: [----------] 12 tests from GoogleAuthProviderImplTest (33 ms total) [00031.139268][4558][4575][klog] INFO: [00031.139567][4558][4575][klog] INFO: [----------] 6 tests from OAuthRequestBuilderTest [00031.139930][4558][4575][klog] INFO: [ RUN ] OAuthRequestBuilderTest.JsonEncodedPostRequest [00031.140633][4558][4575][klog] INFO: [ OK ] OAuthRequestBuilderTest.JsonEncodedPostRequest (0 ms) [00031.141164][4558][4575][klog] INFO: [ RUN ] OAuthRequestBuilderTest.UrlEncodedPostRequest [00031.142056][4558][4575][klog] INFO: [ OK ] OAuthRequestBuilderTest.UrlEncodedPostRequest (0 ms) [00031.142744][4558][4575][klog] INFO: [ RUN ] OAuthRequestBuilderTest.EmptyBodyPostRequest [00031.151185][4558][4575][klog] INFO: [ OK ] OAuthRequestBuilderTest.EmptyBodyPostRequest (9 ms) [00031.151221][4558][4575][klog] INFO: [ RUN ] OAuthRequestBuilderTest.CheckAuthHeader [00031.151222][4558][4575][klog] INFO: [ OK ] OAuthRequestBuilderTest.CheckAuthHeader (0 ms) [00031.151222][4558][4575][klog] INFO: [ RUN ] OAuthRequestBuilderTest.GetRequest [00031.151223][4558][4575][klog] INFO: [ OK ] OAuthRequestBuilderTest.GetRequest (0 ms) [00031.151223][4558][4575][klog] INFO: [ RUN ] OAuthRequestBuilderTest.GetRequestWithQueryParams [00031.151224][4558][4575][klog] INFO: [ OK ] OAuthRequestBuilderTest.GetRequestWithQueryParams (0 ms) [00031.151224][4558][4575][klog] INFO: [----------] 6 tests from OAuthRequestBuilderTest (11 ms total) [00031.151225][4558][4575][klog] INFO: [00031.151225][4558][4575][klog] INFO: [----------] 1 test from OAuthResponseTest [00031.151225][4558][4575][klog] INFO: [ RUN ] OAuthResponseTest.CheckParseOAuthResponse [00031.151226][4558][4575][klog] INFO: [ERROR:topaz/auth_providers/oauth/oauth_response.cc(22)] Encountered error: -2 ,with description: Bad request [00031.156476][4558][4575][klog] INFO: [ OK ] OAuthResponseTest.CheckParseOAuthResponse (10 ms) [00031.156519][4558][4575][klog] INFO: [----------] 1 test from OAuthResponseTest (10 ms total) [00031.156520][4558][4575][klog] INFO: [00031.156520][4558][4575][klog] INFO: [----------] 1 test from SpotifyFactoryImplTest [00031.156521][4558][4575][klog] INFO: [ RUN ] SpotifyFactoryImplTest.GetAuthProvider [00031.156521][4558][4575][klog] INFO: [00031.156522][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="684523409" [00031.156603][4558][4575][klog] INFO: [ OK ] SpotifyFactoryImplTest.GetAuthProvider (0 ms) [00031.156682][4558][4575][klog] INFO: [----------] 1 test from SpotifyFactoryImplTest (0 ms total) [00031.156682][4558][4575][klog] INFO: [00031.156683][4558][4575][klog] INFO: [----------] 6 tests from SpotifyAuthProviderImplTest [00031.156684][4558][4575][klog] INFO: [ RUN ] SpotifyAuthProviderImplTest.EmptyWhenClientDisconnected [00031.156684][4558][4575][klog] INFO: [00031.156684][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3157664668" [00031.156687][4558][4575][klog] INFO: [ OK ] SpotifyAuthProviderImplTest.EmptyWhenClientDisconnected (0 ms) [00031.156687][4558][4575][klog] INFO: [ RUN ] SpotifyAuthProviderImplTest.GetAppAccessTokenSuccess [00031.156688][4558][4575][klog] INFO: [00031.156688][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3109260274" [00031.159764][4558][4575][klog] INFO: [ OK ] SpotifyAuthProviderImplTest.GetAppAccessTokenSuccess (3 ms) [00031.159792][4558][4575][klog] INFO: [ RUN ] SpotifyAuthProviderImplTest.GetAppAccessTokenError [00031.159793][4558][4575][klog] INFO: [00031.159793][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3802311820" [00031.162954][4558][4575][klog] INFO: [ OK ] SpotifyAuthProviderImplTest.GetAppAccessTokenError (2 ms) [00031.162991][4558][4575][klog] INFO: [ RUN ] SpotifyAuthProviderImplTest.GetAppIdTokenUnsupported [00031.162993][4558][4575][klog] INFO: [00031.162994][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="1447096657" [00031.162998][4558][4575][klog] INFO: [ OK ] SpotifyAuthProviderImplTest.GetAppIdTokenUnsupported (1 ms) [00031.162998][4558][4575][klog] INFO: [ RUN ] SpotifyAuthProviderImplTest.GetAppFirebaseTokenUnsupported [00031.162999][4558][4575][klog] INFO: [00031.162999][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="3433791583" [00031.162999][4558][4575][klog] INFO: [ OK ] SpotifyAuthProviderImplTest.GetAppFirebaseTokenUnsupported (0 ms) [00031.163000][4558][4575][klog] INFO: [ RUN ] SpotifyAuthProviderImplTest.RevokeAppOrPersistentCredentialUnsupported [00031.163000][4558][4575][klog] INFO: [00031.163001][4558][4575][klog] INFO: TEST_LOOP_RANDOM_SEED="1272641040" [00031.163001][4558][4575][klog] INFO: [ OK ] SpotifyAuthProviderImplTest.RevokeAppOrPersistentCredentialUnsupported (0 ms) [00031.163002][4558][4575][klog] INFO: [----------] 6 tests from SpotifyAuthProviderImplTest (6 ms total) [00031.163002][4558][4575][klog] INFO: [00031.163002][4558][4575][klog] INFO: [----------] Global test environment tear-down [00031.163003][4558][4575][klog] INFO: [==========] 27 tests from 6 test cases ran. (62 ms total) [00031.163003][4558][4575][klog] INFO: [ PASSED ] 27 tests. [00031.175416][4558][4575][klog] INFO: PASSED: /system/test/auth_provider_unittests passed [00031.292725][4558][4575][klog] INFO: [00031.292770][4558][4575][klog] INFO: ------------------------------------------------ [00031.292773][4558][4575][klog] INFO: RUNNING TEST: /system/test/web_view_link_test [00031.292774][4558][4575][klog] INFO: [00032.001229][4558][4575][klog] INFO: PASSED: /system/test/web_view_link_test passed