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

[00000.000000][0][0][klog] INFO: zbi: @ 0xffffff8000762000 (5425536 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 [0x762000, 0xc8efff] [00000.000000][0][0][klog] INFO: PMM: boot reserve marking WIRED [0x100000, 0x2b4fff] [00000.000000][0][0][klog] INFO: PMM: boot reserve marking WIRED [0x762000, 0xc8efff] [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 0xffffffff001a046c (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 [0xc8f000, 0xc8f080) [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-0735b0830a94f0eba329f96065c971682dab9892 [00000.000000][0][0][klog] INFO: ELF build ID: d61a219b3f8a6e6f7533bf88945c108afe675335 [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 0xffffffff0019e944 (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: 2299784 ticks/ms [00000.434132][0][0][klog] INFO: timer features: constant_tsc 1 invariant_tsc 1 tsc_deadline 1 [00000.434133][0][0][klog] INFO: Using TSC as wallclock [00000.434133][0][0][klog] INFO: initializing kernel [00000.434134][0][0][klog] INFO: initializing mp [00000.434134][0][0][klog] INFO: initializing timers [00000.434141][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00137744 (debuglog) at level 0x7ffff, flags 0x1 [00000.434180][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff0018c888 (thread_set_priority_experiment) at level 0x7ffff, flags 0x1 [00000.434188][0][0][klog] INFO: thread set priority experiment is : ENABLED [00000.434189][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff001a06c0 (global_prng_thread_safe) at level 0x7ffff, flags 0x1 [00000.434192][0][0][klog] INFO: creating bootstrap completion thread [00000.594681][0][0][klog] INFO: top of bootstrap2() [00000.594683][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff0012dc20 (dpc) at level 0x80000, flags 0x1 [00000.601441][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff0014d928 (libobject) at level 0x80000, flags 0x1 [00000.601506][0][0][klog] INFO: OOM: started thread [00000.607968][0][0][klog] INFO: Processor Model Info: type 0 family 0x6 model 0xf stepping 0 [00000.607969][0][0][klog] INFO: display_family 0x6 display_model 0x3f [00000.607969][0][0][klog] INFO: Vendor: Intel [00000.607970][0][0][klog] INFO: Microarch: Haswell [00000.607970][0][0][klog] INFO: F/M/S: 6/3f/0 [00000.607970][0][0][klog] INFO: Brand: Intel(R) Xeon(R) CPU @ 2.30GHz [00000.607972][0][0][klog] INFO: Features: fpu sse sse2 sse3 ssse3 sse4.1 sse4.2 mmx avx avx2 fxsr pcid xsave aesni clflush [00000.607973][0][0][klog] INFO: fsgsbase tsc_adj smep erms rdrand syscall nx rdtscp tsc_deadline x2apic hypervisor [00000.607989][0][0][klog] INFO: initializing platform [00000.607990][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00110814 (x86_perfmon) at level 0x90000, flags 0x1 [00000.608012][0][0][klog] INFO: x86_perfmon_init_once:495: perfmon: unexpected programmable counter width 0 in cpuid.0AH [00000.608566][0][0][klog] INFO: UART: started IRQ driven RX [00000.608566][0][0][klog] INFO: UART: started IRQ driven TX [00000.608587][0][0][klog] INFO: cpu topology: [00000.608589][0][0][klog] INFO: 0: apic id 0x0 package 0 node 0 core 0 smt 0 BSP [00000.608589][0][0][klog] INFO: 1: apic id 0x1 package 1 node 0 core 0 smt 0 [00000.608590][0][0][klog] INFO: 2: apic id 0x2 package 2 node 0 core 0 smt 0 [00000.608590][0][0][klog] INFO: 3: apic id 0x3 package 3 node 0 core 0 smt 0 [00000.608591][0][0][klog] INFO: Found 4 cpus [00000.609057][0][0][klog] INFO: booting apic ids: 0x1 0x2 0x3 [00000.674953][0][0][klog] INFO: entering scheduler on cpu 2 [00000.675008][0][0][klog] INFO: entering scheduler on cpu 1 [00000.675038][0][0][klog] INFO: entering scheduler on cpu 3 [00000.685780][0][0][klog] INFO: smbios: manufacturer="QEMU" product="Standard PC (Q35 + ICH9, 2009)" [00000.685814][0][0][klog] INFO: initializing target [00000.685815][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff001059b8 (x86_pcie_init) at level 0xa0000, flags 0x1 [00000.685874][0][0][klog] INFO: moving to last init level [00000.685883][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff0013f308 (ktrace) at level 0xc0000, flags 0x1 [00000.727581][0][0][klog] INFO: ktrace: buffer at 0xffffff9af0153000 (33554432 bytes) [00000.727638][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff00142ac8 (userboot) at level 0xc0000, flags 0x1 [00000.727643][0][0][klog] INFO: userboot: ramdisk 0x52d000 @ 0xffffff8000762000 [00000.742925][0][0][klog] INFO: userboot: userboot rodata 0 @ [0x549982492000,0x549982495000) [00000.742951][0][0][klog] INFO: userboot: userboot code 0x3000 @ [0x549982495000,0x5499824a0000) [00000.742952][0][0][klog] INFO: userboot: vdso/full rodata 0 @ [0x5499824a0000,0x5499824a7000) [00000.742953][0][0][klog] INFO: userboot: vdso/full code 0x7000 @ [0x5499824a7000,0x5499824a8000) [00000.743046][0][0][klog] INFO: userboot: entry point @ 0x549982495c10 [00000.743100][0][0][klog] INFO: INIT: cpu 0, calling hook 0xffffffff0019f344 (kernel_shell) at level 0xc0000, flags 0x1 [00000.772857][1032][1039][klog] INFO: userboot: option "/b/s/w/ir/multiboot.bin=" [00000.772897][1032][1039][klog] INFO: userboot: option "zircon.autorun.system=/boot/bin/sh+/boot/infra/runcmds" [00000.772897][1032][1039][klog] INFO: userboot: option "kernel.halt-on-panic=true" [00000.772898][1032][1039][klog] INFO: userboot: option "devmgr.suspend-timeout-debug=true" [00000.772898][1032][1039][klog] INFO: userboot: option "TERM=dumb" [00000.772899][1032][1039][klog] INFO: userboot: option "kernel.serial=legacy" [00000.772899][1032][1039][klog] INFO: userboot: option "multiboot.boot_loader_name=qemu" [00000.790287][1032][1039][klog] INFO: userboot: searching bootfs for 'bin/bootsvc' [00000.790356][1032][1039][klog] INFO: userboot: 'bin/bootsvc' has PT_INTERP "lib/ld.so.1" [00000.790356][1032][1039][klog] INFO: userboot: searching bootfs for 'lib/ld.so.1' [00000.790401][1032][1039][klog] INFO: userboot: userboot: loaded lib/ld.so.1 at 0x4c8c75faf000, entry point 0x4c8c76035623 [00000.790416][1032][1039][klog] INFO: userboot: userboot: loaded vDSO at 0x79fd80a34000, entry point 0x0 [00000.790496][1032][1039][klog] INFO: userboot: process bin/bootsvc started. [00000.790526][1032][1039][klog] INFO: userboot: waiting for loader-service requests... [00000.832866][1032][1039][klog] INFO: userboot: searching bootfs for 'lib/libasync-default.so' [00000.833181][1032][1039][klog] INFO: userboot: searching bootfs for 'lib/libfdio.so' [00000.833407][1032][1039][klog] INFO: userboot: searching bootfs for 'lib/liblaunchpad.so' [00000.833844][1032][1039][klog] INFO: userboot: searching bootfs for 'lib/libtrace-engine.so' [00000.850543][1046][1049][klog] INFO: {{{reset}}} [00000.850575][1046][1049][klog] INFO: {{{module:0:<application>:elf:ca926a5288509b6336f62dda5a14644b7e91b38d}}} [00000.850576][1046][1049][klog] INFO: {{{mmap:0x4aac5240e000:0x2d000:load:0:rx:0}}} [00000.850577][1046][1049][klog] INFO: {{{mmap:0x4aac5243b000:0x4000:load:0:rw:0x2d000}}} [00000.850577][1046][1049][klog] INFO: dso: id=ca926a5288509b6336f62dda5a14644b7e91b38d base=0x00004aac5240e000 name=<application> [00000.850578][1046][1049][klog] INFO: {{{module:0x3:libasync-default.so:elf:5e3c55aea09e8943ae76d3603cc8c0b1ba27bfb7}}} [00000.850579][1046][1049][klog] INFO: {{{mmap:0x2ddcecb0c000:0x1000:load:0x3:rx:0}}} [00000.850579][1046][1049][klog] INFO: {{{mmap:0x2ddcecb0d000:0x1000:load:0x3:rw:0x1000}}} [00000.850580][1046][1049][klog] INFO: dso: id=5e3c55aea09e8943ae76d3603cc8c0b1ba27bfb7 base=0x00002ddcecb0c000 name=libasync-default.so [00000.850580][1046][1049][klog] INFO: {{{module:0x2:<vDSO>:elf:15398eb3d5622ee39ebf0738728ef1da4cdb710c}}} [00000.850581][1046][1049][klog] INFO: {{{mmap:0x79fd80a34000:0x7000:load:0x2:r:0}}} [00000.850581][1046][1049][klog] INFO: {{{mmap:0x79fd80a3b000:0x1000:load:0x2:rx:0x7000}}} [00000.850582][1046][1049][klog] INFO: dso: id=15398eb3d5622ee39ebf0738728ef1da4cdb710c base=0x000079fd80a34000 name=<vDSO> [00000.850582][1046][1049][klog] INFO: {{{module:0x1:libc.so:elf:a3c5a3bb3ab016d0bc21ad3b934d4347d27a4ac2}}} [00000.850583][1046][1049][klog] INFO: {{{mmap:0x4c8c75faf000:0xcb000:load:0x1:rx:0}}} [00000.850584][1046][1049][klog] INFO: {{{mmap:0x4c8c7607b000:0x6000:load:0x1:rw:0xcc000}}} [00000.850584][1046][1049][klog] INFO: dso: id=a3c5a3bb3ab016d0bc21ad3b934d4347d27a4ac2 base=0x00004c8c75faf000 name=libc.so [00000.850585][1046][1049][klog] INFO: {{{module:0x4:libfdio.so:elf:fb6715ba574ce88bcfca5045ee90fef94163808f}}} [00000.850585][1046][1049][klog] INFO: {{{mmap:0x629eb4e31000:0x22000:load:0x4:rx:0}}} [00000.850586][1046][1049][klog] INFO: {{{mmap:0x629eb4e53000:0x5000:load:0x4:rw:0x22000}}} [00000.850586][1046][1049][klog] INFO: dso: id=fb6715ba574ce88bcfca5045ee90fef94163808f base=0x0000629eb4e31000 name=libfdio.so [00000.850587][1046][1049][klog] INFO: {{{module:0x5:liblaunchpad.so:elf:3275d148fdc55d5dfbd06f5a7edb9dc4c294763f}}} [00000.850587][1046][1049][klog] INFO: {{{mmap:0x3c0881fe6000:0x7000:load:0x5:rx:0}}} [00000.850588][1046][1049][klog] INFO: {{{mmap:0x3c0881fed000:0x2000:load:0x5:rw:0x7000}}} [00000.850588][1046][1049][klog] INFO: dso: id=3275d148fdc55d5dfbd06f5a7edb9dc4c294763f base=0x00003c0881fe6000 name=liblaunchpad.so [00000.850589][1046][1049][klog] INFO: {{{module:0x6:libtrace-engine.so:elf:22a757d9bba453b05c7f8ec9c8b4fb972e0a8c38}}} [00000.850589][1046][1049][klog] INFO: {{{mmap:0x3535afdc1000:0x9000:load:0x6:rx:0}}} [00000.850590][1046][1049][klog] INFO: {{{mmap:0x3535afdca000:0x2000:load:0x6:rw:0x9000}}} [00000.850590][1046][1049][klog] INFO: dso: id=22a757d9bba453b05c7f8ec9c8b4fb972e0a8c38 base=0x00003535afdc1000 name=libtrace-engine.so [00000.850860][1046][1049][klog] INFO: bootsvc: Starting... [00000.851077][1032][1039][klog] INFO: userboot: loader-service channel peer closed [00000.851130][1046][1049][klog] INFO: bootsvc: Creating bootfs service... [00000.851154][1032][1039][klog] INFO: userboot: finished! [00001.001405][1046][1049][klog] INFO: bootsvc: Processing bootdata... [00001.001463][1046][1049][klog] INFO: bootsvc: Loading boot cmdline overrides... [00001.001492][1046][1049][klog] INFO: bootsvc: Loading kernel VMOs... [00001.001508][1046][1049][klog] INFO: bootsvc: Creating loader service... [00001.001516][1046][1049][klog] INFO: bootsvc: Launching next process... [00001.002339][1046][1093][klog] INFO: bootsvc: launched bin/devmgr [00001.022506][1101][1114][klog] INFO: devmgr: main() [00001.022554][1101][1114][klog] INFO: devmgr: init [00001.022586][1101][1114][klog] INFO: coordinator_init() [00001.022616][1101][1114][klog] INFO: cmdline: /b/s/w/ir/multiboot.bin= [00001.022638][1101][1114][klog] INFO: cmdline: zircon.autorun.system=/boot/bin/sh+/boot/infra/runcmds [00001.022673][1101][1114][klog] INFO: cmdline: kernel.halt-on-panic=true [00001.022703][1101][1114][klog] INFO: cmdline: devmgr.suspend-timeout-debug=true [00001.022704][1101][1114][klog] INFO: cmdline: TERM=dumb [00001.022704][1101][1114][klog] INFO: cmdline: kernel.serial=legacy [00001.022705][1101][1114][klog] INFO: cmdline: multiboot.boot_loader_name=qemu [00001.022706][1101][1114][klog] INFO: cmdline: devhost.asan.strict=false [00001.022707][1101][1114][klog] INFO: cmdline: devmgr.require-system=true [00001.022708][1101][1114][klog] INFO: cmdline: zircon.system.pkgfs.cmd=bin/pkgsvr+8f43ac8a8ac89f3cf7341d224849c607020da9374af14b35def444071ad7ef7a [00001.022709][1101][1114][klog] INFO: cmdline: zircon.system.pkgfs.file.bin/pkgsvr=2ae3deccffb2b80388cce780ec1d9e2047addcdde8f0d3c3fb5bccb484835b5d [00001.022710][1101][1114][klog] INFO: cmdline: zircon.system.pkgfs.file.lib/ld.so.1=9f9954f7cb3c3490edc45e24b2181196753cf990f69387b5c564214a9d66f681 [00001.022711][1101][1114][klog] INFO: cmdline: zircon.system.pkgfs.file.lib/libfdio.so=0713dccae7391ad9793c92522d6e634047629ba296c8177bc60f1692fad644eb [00001.022715][1101][1114][klog] INFO: devmgr: svc init [00001.023536][1101][1114][klog] INFO: devmgr: launch /boot/bin/svchost (svchost) OK [00001.023573][1101][1114][klog] INFO: devmgr: vfs init [00001.097234][1101][1114][klog] INFO: devmgr: launch /boot/bin/fshost (fshost) OK [00001.098574][1101][1242][klog] INFO: devmgr: shell startup [00001.106760][1177][1229][klog] INFO: fshost: started. [00001.107100][1101][1114][klog] INFO: devmgr: coordinator() [00001.119827][1101][1237][klog] INFO: devmgr: launch /boot/bin/pwrbtn-monitor (pwrbtn-monitor) OK [00001.126629][1101][1275][klog] INFO: devmgr: launch /boot/bin/crashsvc (crashsvc) OK [00001.131703][1101][1275][klog] INFO: devmgr: launch /boot/bin/netsvc (netsvc) OK [00001.148550][1101][1275][klog] INFO: devmgr: launch /boot/bin/virtual-console (virtual-console) OK [00001.180655][1101][1114][klog] INFO: devcoord: launch devhost 'devhost:sys': pid=2001 [00001.190200][1101][1114][klog] INFO: devcoord: launch devhost 'devhost:test': pid=2038 [00001.190250][1101][1114][klog] INFO: devcoord: full system required, ignoring fallback drivers until /system is loaded [00001.205606][1101][1114][klog] INFO: devcoord: launch devhost 'devhost:root': pid=2091 [00001.220950][2001][2025][klog] INFO: acpi-bus: not using IOMMU [00001.228220][1101][1114][klog] INFO: devcoord: launch devhost 'devhost:misc': pid=2127 [00001.249374][2001][2025][klog] INFO: acpi-pwrbtn: initialized [00001.264373][1101][1242][klog] INFO: devmgr: launch /boot/bin/sh (sh:console) OK [00001.340966][2001][2025][klog] INFO: acpi: published device pci(0x37c9f69725c0), parent=proxy(0x37c9f69590c0), handle=0x37c9f6944390 [00001.341530][2001][2025][klog] INFO: acpi: failed to get PCI base bus number for device 'PCI0' (status 4294967295) [00001.343597][2001][2025][klog] INFO: acpi: published device rtc(0x37c9f6972540), parent=acpi(0x37c9f6959240), handle=0x37c9f6944840 [00001.344415][2001][2025][klog] INFO: acpi: published device i8042(0x37c9f69724c0), parent=acpi(0x37c9f6959240), handle=0x37c9f69448d0 [00001.348436][2001][2025][klog] INFO: cpuperf_init_once: No PM support [00001.348540][2001][2025][klog] INFO: IntelPT: No PT support [00001.348550][2001][2025][klog] INFO: devhost[proxy/cpu-trace] bind driver '/boot/driver/cpu-trace.so' failed: -2 [00001.348801][1101][1114][klog] INFO: devcoord: rpc: bind-driver 'cpu-trace' status -2 [00001.349546][1480][1559][klog] INFO: vc: new input device /dev/class/input/000 [00001.355313][1101][1114][klog] INFO: devcoord: launch devhost 'devhost:pci#1:1234:1111': pid=2549 [00001.358831][1101][1114][klog] INFO: devcoord: launch devhost 'devhost:pci#2:1af4:1001': pid=2594 [00001.399284][1101][1114][klog] INFO: devcoord: launch devhost 'devhost:pci#3:1af4:1001': pid=2651 [00001.422326][1101][1114][klog] INFO: devcoord: launch devhost 'devhost:pci#5:8086:2922': pid=2742 [00001.430095][2001][2025][klog] INFO: devhost: WARNING: driver '/boot/driver/pc-ps2.so' did not add device in bind() [00001.442402][2001][2834][klog] INFO: i8042 device 0xab 0x41 [00001.446605][2549][2572][klog] INFO: bochs_vbe: initialized display, 1024 x 768 (stride=1024 format=00020001) [00001.459076][1480][1559][klog] INFO: vc: new input device /dev/class/input/001 [00001.464398][1480][1559][klog] INFO: vc: new display device /dev/class/display-controller/000/virtcon [00001.466563][2742][2780][klog] INFO: ahci: using MSI interrupt [00001.468500][1480][1559][klog] INFO: vc: Successfully attached to display 1 [00001.500198][1177][1229][klog] INFO: devmgr: /dev/class/block/000: FVM? [00001.505061][1101][1114][klog] INFO: devcoord: dc_bind_device() '/boot/driver/fvm.so' [00001.525116][1177][1229][klog] INFO: devmgr: launch /boot/bin/blobfs (blobfs:/blob) OK [00001.599992][2001][2834][klog] INFO: i8042 device 0x00 [00001.604316][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.611247][1177][1229][klog] INFO: fshost: starting 'bin/pkgsvr' '8f43ac8a8ac89f3cf7341d224849c607020da9374af14b35def444071ad7ef7a'... [00001.619928][1480][1559][klog] INFO: vc: new input device /dev/class/input/002 [00001.658554][1177][1229][klog] INFO: devmgr: launch bin/pkgsvr (pkgfs) OK [00001.689717][3253][3489][klog] INFO: pkgsvr: system: will be served from 8f43ac8a8ac89f3cf7341d224849c607020da9374af14b35def444071ad7ef7a [00001.689810][3253][3489][klog] INFO: pkgsvr: pkgfs serving index /data/pkgfs_index from blobfs /blob [00001.707347][1177][1229][klog] INFO: devmgr: mounting minfs [00001.717952][1177][1229][klog] INFO: devmgr: launch /boot/bin/minfs (minfs:/data) OK [00001.755545][1101][3533][klog] INFO: devmgr: adding system driver 'bt_passthrough_hci' '/system/driver/bthci-passthrough.so' [00001.767172][1101][1576][klog] INFO: devmgr: launch /system/bin/appmgr (appmgr) OK [00001.772687][1101][1576][klog] INFO: zircon.autorun.system: starting '/boot/bin/sh' '/boot/infra/runcmds'... [00001.778771][1177][1229][klog] INFO: devmgr: mounting minfs [00001.779203][1177][1229][klog] INFO: fshost: cannot read GUID from minfs-formatted device [00001.799734][1101][3533][klog] INFO: devmgr: adding system driver 'ath10k_pci' '/system/driver/ath10k.so' [00001.814544][1101][1576][klog] INFO: devmgr: launch /boot/bin/sh (autorun:system) OK [00001.823978][1101][3533][klog] INFO: devmgr: adding system driver 'bthog' '/system/driver/bthog.so' [00001.842653][1101][3533][klog] INFO: devmgr: adding system driver 'rtl88xx' '/system/driver/rtl88xx.so' [00001.874884][1101][3533][klog] INFO: devmgr: adding system driver 'wlantapctl' '/system/driver/wlantap.so' [00001.904746][1101][3533][klog] INFO: devmgr: adding system driver 'usb_video' '/system/driver/usb_video.so' [00001.926857][1101][3533][klog] INFO: devmgr: adding system driver 'qmi_usb' '/system/driver/qmi-transport.so' [00001.957833][1101][3533][klog] INFO: devmgr: adding system driver 'gpu' '/system/driver/libmsd_intel.so' [00002.000613][1101][3533][klog] INFO: devmgr: adding system driver 'ralink' '/system/driver/ralink.so' [00002.020937][1101][3533][klog] INFO: devmgr: adding system driver 'wlan' '/system/driver/wlanif.so' [00002.051674][1101][3533][klog] INFO: devmgr: adding system driver 'wlan' '/system/driver/wlanphy.so' [00002.094558][1101][3533][klog] INFO: devmgr: adding system driver 'bthost' '/system/driver/bthost.so' [00002.116522][1101][3533][klog] INFO: devmgr: adding system driver 'bthci_fake' '/system/driver/bthci-fake.so' [00002.148283][4599][4618][klog] INFO: [00002.148308][4599][4618][klog] INFO: ------------------------------------------------ [00002.148311][4599][4618][klog] INFO: RUNNING TEST: /pkgfs/packages/catapult_converter/0/test/catapult_converter_test [00002.148311][4599][4618][klog] INFO: [00002.153617][1101][3533][klog] INFO: devmgr: adding system driver 'wlan' '/system/driver/wlan.so' [00002.187443][1101][3533][klog] INFO: devmgr: adding system driver 'brcmfmac' '/system/driver/brcmfmac.so' [00002.233099][1101][3533][klog] INFO: devmgr: adding system driver 'btintel' '/system/driver/btintel.so' [00002.244486][1101][1114][klog] INFO: devcoord: fallback driver 'usb_composite' is available [00002.244515][1101][1114][klog] INFO: devcoord: fallback driver 'intel_disp' is available [00002.244583][1101][1114][klog] INFO: devcoord: driver 'btintel' added [00002.244589][1101][1114][klog] INFO: devcoord: driver 'brcmfmac' added [00002.244591][1101][1114][klog] INFO: devcoord: driver 'wlan' added [00002.244593][1101][1114][klog] INFO: devcoord: driver 'bthci_fake' added [00002.244596][1101][1114][klog] INFO: devcoord: driver 'bthost' added [00002.244597][1101][1114][klog] INFO: devcoord: driver 'wlan' added [00002.244599][1101][1114][klog] INFO: devcoord: driver 'wlan' added [00002.244601][1101][1114][klog] INFO: devcoord: driver 'ralink' added [00002.244603][1101][1114][klog] INFO: devcoord: driver 'gpu' added [00002.244605][1101][1114][klog] INFO: devcoord: driver 'qmi_usb' added [00002.244607][1101][1114][klog] INFO: devcoord: driver 'usb_video' added [00002.244608][1101][1114][klog] INFO: devcoord: driver 'wlantapctl' added [00002.244834][1101][1114][klog] INFO: devcoord: driver 'rtl88xx' added [00002.244903][1101][1114][klog] INFO: devcoord: driver 'bthog' added [00002.244929][1101][1114][klog] INFO: devcoord: driver 'ath10k_pci' added [00002.244931][1101][1114][klog] INFO: devcoord: driver 'bt_passthrough_hci' added [00002.244932][1101][1114][klog] INFO: devcoord: driver 'usb_composite' added [00002.244933][1101][1114][klog] INFO: devcoord: driver 'intel_disp' added [00002.416485][4599][4618][klog] INFO: Running main() from gtest_main.cc [00002.420954][4599][4618][klog] INFO: [==========] Running 8 tests from 2 test cases. [00002.421004][4599][4618][klog] INFO: [----------] Global test environment set-up. [00002.421005][4599][4618][klog] INFO: [----------] 1 test from TestTools [00002.421006][4599][4618][klog] INFO: [ RUN ] TestTools.SplitLines [00002.436345][4599][4618][klog] INFO: [ OK ] TestTools.SplitLines (0 ms) [00002.436375][4599][4618][klog] INFO: [----------] 1 test from TestTools (0 ms total) [00002.436376][4599][4618][klog] INFO: [00002.436376][4599][4618][klog] INFO: [----------] 7 tests from CatapultConverter [00002.436377][4599][4618][klog] INFO: [ RUN ] CatapultConverter.Convert [00002.436902][4599][4618][klog] INFO: [ OK ] CatapultConverter.Convert (16 ms) [00002.436928][4599][4618][klog] INFO: [ RUN ] CatapultConverter.ConvertWithSplitFirst [00002.462156][4599][4618][klog] INFO: [ OK ] CatapultConverter.ConvertWithSplitFirst (25 ms) [00002.462204][4599][4618][klog] INFO: [ RUN ] CatapultConverter.ConvertThroughputUnits [00002.462383][4599][4618][klog] INFO: [ OK ] CatapultConverter.ConvertThroughputUnits (0 ms) [00002.462417][4599][4618][klog] INFO: [ RUN ] CatapultConverter.ConvertBytesUnit [00002.479215][4599][4618][klog] INFO: [ OK ] CatapultConverter.ConvertBytesUnit (16 ms) [00002.479369][4599][4618][klog] INFO: [ RUN ] CatapultConverter.ZeroValues [00002.479405][4599][4618][klog] INFO: [ OK ] CatapultConverter.ZeroValues (0 ms) [00002.479405][4599][4618][klog] INFO: [ RUN ] CatapultConverter.NegativeValues [00002.479428][4599][4618][klog] INFO: [ OK ] CatapultConverter.NegativeValues (0 ms) [00002.479429][4599][4618][klog] INFO: [ RUN ] CatapultConverter.ConverterMain [00002.485498][4599][4618][klog] INFO: [ OK ] CatapultConverter.ConverterMain (5 ms) [00002.485520][4599][4618][klog] INFO: [----------] 7 tests from CatapultConverter (63 ms total) [00002.485520][4599][4618][klog] INFO: [00002.485521][4599][4618][klog] INFO: [----------] Global test environment tear-down [00002.485523][4599][4618][klog] INFO: [==========] 8 tests from 2 test cases ran. (66 ms total) [00002.485524][4599][4618][klog] INFO: [ PASSED ] 8 tests. [00002.486012][4599][4618][klog] INFO: PASSED: /pkgfs/packages/catapult_converter/0/test/catapult_converter_test passed [00002.623080][4599][4618][klog] INFO: [00002.623103][4599][4618][klog] INFO: ------------------------------------------------ [00002.623106][4599][4618][klog] INFO: RUNNING TEST: /pkgfs/packages/dart_zircon_test/0/test/dart_zircon_test [00002.623107][4599][4618][klog] INFO: [00002.946781][6245][6260][klog] INFO: netstack: main.go:40: started [00002.947031][6245][6260][klog] INFO: netstack: main.go:61: socket server started [00002.947551][6245][6260][klog] INFO: netstack: main.go:117: OnInterfacesChanged failed: ErrPeerClosed: zx.Channel.Write [00003.282112][7182][7196][klog] INFO: [INFO:cobalt_main.cc(102)] Cobalt client schedule params: schedule_interval=3600 seconds, min_interval=10 seconds, initial_interval=60 seconds. [00003.346232][1253939897][0][amber] INFO: monitoring for updates [00003.346358][1253939897][0][amber] INFO: system update monitor exited [00003.366310][1253939897][0][amber] INFO: control_server: could not get update for network_time/0: daemon: no update found for network_time/0/ [00003.369834][1253939897][0][amber] INFO: control_server: could not get update for thermd/0: daemon: no update found for thermd/0/ [00003.369875][1253939897][0][amber] INFO: control_server: could not get update for netcfg/0: daemon: no update found for netcfg/0/ [00003.369949][1253939897][0][amber] INFO: control_server: could not get update for wlancfg/0: daemon: no update found for wlancfg/0/ [00003.369968][1253939897][0][amber] INFO: control_server: could not get update for device_settings_manager/0: daemon: no update found for device_settings_manager/0/ [00003.369975][1253939897][0][amber] INFO: control_server: could not get update for sshd-host/0: daemon: no update found for sshd-host/0/ [00003.370262][1253939897][0][amber] INFO: control_server: could not get update for trace_manager/0: daemon: no update found for trace_manager/0/ [00003.370296][1253939897][0][amber] INFO: control_server: could not get update for cobalt_system_metrics/0: daemon: no update found for cobalt_system_metrics/0/ [00003.370305][1253939897][0][amber] INFO: control_server: could not get update for dart_zircon_test/0: daemon: no update found for dart_zircon_test/0/ [00003.558030][8066][8079][klog] INFO: netcfg: started [00003.470192][7672][7691][network_time] INFO: main.cc(29): Opening client config from /pkg/data/roughtime-servers.json [00003.470204][7672][7691][network_time] INFO: timezone.cc(24): started [00003.688051][7672][7691][network_time] ERROR: garnet/bin/network_time/roughtime_server.cc(61): resolving roughtime.sandbox.google.com:2002: Name does not resolve [00003.805693][8693][8719][klog] INFO: [INFO:main.cc(35)] Trace Manager starting with config: /pkg/data/tracing.config [00003.818012][1253939897][0][amber] INFO: control_server: could not get update for dart_jit_runner/0: daemon: no update found for dart_jit_runner/0/ [00003.864389][1253939897][0][amber] INFO: control_server: could not get update for wlanstack2/0: daemon: no update found for wlanstack2/0/ [00004.066797][1253939897][0][amber] INFO: control_server: could not get update for ktrace_provider/0: daemon: no update found for ktrace_provider/0/ [00004.066914][1253939897][0][amber] INFO: control_server: could not get update for cpuperf_provider/0: daemon: no update found for cpuperf_provider/0/ [00004.142212][9455][9471][klog] INFO: wlanstack2 [I]: Starting [00004.348387][9301][10169][klog] INFO: Observatory server failed to start after 1 tries [00004.432245][9301][9713][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.495159][9301][9713][klog] INFO: 00:00 +0: fromFile [00004.534946][9301][9713][klog] INFO: 00:00 +1: create socket [00004.540294][9301][9713][klog] INFO: 00:00 +2: close socket [00004.543290][9301][9713][klog] INFO: 00:00 +3: read write socket [00004.546234][9301][9713][klog] INFO: 00:00 +4: partial read socket [00004.548368][9301][9713][klog] INFO: 00:00 +5: partial write socket [00004.550459][9301][9713][klog] INFO: 00:00 +6: async wait socket read [00004.552946][9301][9713][klog] INFO: 00:00 +7: async wait socket closed [00004.555418][9301][9713][klog] INFO: 00:00 +8: create channel [00004.557255][9301][9713][klog] INFO: 00:00 +9: close channel [00004.559846][9301][9713][klog] INFO: 00:00 +10: channel bytes [00004.562042][9301][9713][klog] INFO: 00:00 +11: channel handles [00004.564076][9301][9713][klog] INFO: 00:00 +12: async wait channel read [00004.566523][9301][9713][klog] INFO: 00:00 +13: async wait channel closed [00004.576312][9301][9713][klog] INFO: 00:00 +14: All tests passed! [00004.651274][4599][4618][klog] INFO: PASSED: /pkgfs/packages/dart_zircon_test/0/test/dart_zircon_test passed [00004.719506][4599][4618][klog] INFO: [00004.719538][4599][4618][klog] INFO: ------------------------------------------------ [00004.719541][4599][4618][klog] INFO: RUNNING TEST: /pkgfs/packages/deprecated_loop_tests/0/test/deprecated_loop_unittests [00004.719541][4599][4618][klog] INFO: [00004.772387][4599][4618][klog] INFO: [==========] Running 15 tests from 2 test cases. [00004.772431][4599][4618][klog] INFO: [----------] Global test environment set-up. [00004.772475][4599][4618][klog] INFO: [----------] 14 tests from MessageLoop [00004.772528][4599][4618][klog] INFO: [ RUN ] MessageLoop.Current [00004.772529][4599][4618][klog] INFO: [ OK ] MessageLoop.Current (0 ms) [00004.772553][4599][4618][klog] INFO: [ RUN ] MessageLoop.RunsTasksOnCurrentThread [00004.795474][4599][4618][klog] INFO: [ OK ] MessageLoop.RunsTasksOnCurrentThread (23 ms) [00004.795508][4599][4618][klog] INFO: [ RUN ] MessageLoop.CanRunTasks [00004.795509][4599][4618][klog] INFO: [ OK ] MessageLoop.CanRunTasks (0 ms) [00004.795510][4599][4618][klog] INFO: [ RUN ] MessageLoop.CanPostTasksFromTasks [00004.795511][4599][4618][klog] INFO: [ OK ] MessageLoop.CanPostTasksFromTasks (0 ms) [00004.795513][4599][4618][klog] INFO: [ RUN ] MessageLoop.TriplyNestedTasks [00004.795514][4599][4618][klog] INFO: [ OK ] MessageLoop.TriplyNestedTasks (0 ms) [00004.795515][4599][4618][klog] INFO: [ RUN ] MessageLoop.CanRunTasksInOrder [00004.829076][4599][4618][klog] INFO: [ OK ] MessageLoop.CanRunTasksInOrder (0 ms) [00004.829100][4599][4618][klog] INFO: [ RUN ] MessageLoop.CanPreloadTasks [00004.829101][4599][4618][klog] INFO: [ OK ] MessageLoop.CanPreloadTasks (0 ms) [00004.829102][4599][4618][klog] INFO: [ RUN ] MessageLoop.AfterTaskCallbacks [00004.829102][4599][4618][klog] INFO: [ OK ] MessageLoop.AfterTaskCallbacks (0 ms) [00004.829103][4599][4618][klog] INFO: [ RUN ] MessageLoop.RemoveAfterTaskCallbacksDuringCallback [00004.829104][4599][4618][klog] INFO: [ OK ] MessageLoop.RemoveAfterTaskCallbacksDuringCallback (0 ms) [00004.829105][4599][4618][klog] INFO: [ RUN ] MessageLoop.TaskDestructionTime [00004.829107][4599][4618][klog] INFO: [ OK ] MessageLoop.TaskDestructionTime (0 ms) [00004.829108][4599][4618][klog] INFO: [ RUN ] MessageLoop.CanQuitCurrent [00004.829108][4599][4618][klog] INFO: [ OK ] MessageLoop.CanQuitCurrent (0 ms) [00004.829109][4599][4618][klog] INFO: [ RUN ] MessageLoop.CanQuitManyTimes [00004.829110][4599][4618][klog] INFO: [ OK ] MessageLoop.CanQuitManyTimes (0 ms) [00004.829110][4599][4618][klog] INFO: [ RUN ] MessageLoop.FDWaiter [00004.829334][4599][4618][klog] INFO: [ OK ] MessageLoop.FDWaiter (33 ms) [00004.829509][4599][4618][klog] INFO: [ RUN ] MessageLoop.TaskRunnerAvailableDuringLoopDestruction [00004.829926][4599][4618][klog] INFO: [ OK ] MessageLoop.TaskRunnerAvailableDuringLoopDestruction (0 ms) [00004.830185][4599][4618][klog] INFO: [----------] 14 tests from MessageLoop (57 ms total) [00004.830492][4599][4618][klog] INFO: [00004.830740][4599][4618][klog] INFO: [----------] 1 test from Thread [00004.830783][4599][4618][klog] INFO: [ RUN ] Thread.Control [00004.830790][4599][4618][klog] INFO: [ OK ] Thread.Control (2 ms) [00004.830791][4599][4618][klog] INFO: [----------] 1 test from Thread (2 ms total) [00004.830792][4599][4618][klog] INFO: [00004.830792][4599][4618][klog] INFO: [----------] Global test environment tear-down [00004.831458][4599][4618][klog] INFO: [==========] 15 tests from 2 test cases ran. (59 ms total) [00004.831487][4599][4618][klog] INFO: [ PASSED ] 15 tests. [00004.838108][4599][4618][klog] INFO: PASSED: /pkgfs/packages/deprecated_loop_tests/0/test/deprecated_loop_unittests passed [00004.958692][4599][4618][klog] INFO: [00004.958727][4599][4618][klog] INFO: ------------------------------------------------ [00004.958730][4599][4618][klog] INFO: RUNNING TEST: /pkgfs/packages/fidl_bindings_test/0/test/fidl_bindings_test [00004.958731][4599][4618][klog] INFO: [00005.039344][1253939897][0][amber] INFO: control_server: could not get update for fidl_bindings_test/0: daemon: no update found for fidl_bindings_test/0/ [00005.071218][1253939897][0][amber] INFO: control_server: could not get update for dart_jit_runner/0: daemon: no update found for dart_jit_runner/0/ [00005.470887][11257][11608][klog] INFO: Observatory server failed to start after 1 tries [00005.580877][11257][11473][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_bindings_test#meta/fidl_bindings_test.cmx is running from kernel [00005.617853][11257][11473][klog] INFO: clone-test [00005.651408][11257][11473][klog] INFO: 00:00 +0: two way (setUpAll) [00005.689170][1253939897][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/ [00005.689598][4852][4905][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 [00005.689657][4852][4905][klog] INFO: .cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00005.696822][11257][11473][klog] INFO: 00:00 +0: two way no args [00005.783591][11257][11896][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_bindings_test_server is running from kernel [00005.832057][11257][11473][klog] INFO: 00:00 +1: two way string arg [00005.838921][11257][11473][klog] INFO: 00:00 +2: two way three args [00005.852660][11257][11473][klog] INFO: 00:00 +3: two way (tearDownAll) [00005.872347][11257][11473][klog] INFO: Proxy error: Peer unexpectedly closed [00005.873524][11257][11473][klog] INFO: 00:00 +3: proxy state initial [00005.874050][11257][11473][klog] INFO: Proxy error: Peer unexpectedly closed [00005.876144][11257][11473][klog] INFO: 00:00 +4: proxy state request [00005.879235][11257][11473][klog] INFO: 00:00 +5: proxy state bind [00005.882661][11257][11473][klog] INFO: Proxy error: Peer unexpectedly closed [00005.883240][11257][11473][klog] INFO: 00:00 +6: proxy state after bind [00005.886569][11257][11473][klog] INFO: 00:00 +7: proxy state unbind [00005.889933][11257][11473][klog] INFO: 00:00 +8: proxy state close [00005.892397][11257][11473][klog] INFO: 00:00 +9: proxy state after close [00005.899363][11257][11473][klog] INFO: 00:00 +10: hash and equality enums simple [00005.901491][11257][11473][klog] INFO: 00:00 +11: hash and equality enums constructed [00005.903271][11257][11473][klog] INFO: 00:00 +12: hash and equality enums different types [00005.905493][11257][11473][klog] INFO: 00:00 +13: hash and equality enums map [00005.907266][11257][11473][klog] INFO: 00:00 +14: hash and equality structs no handles [00005.910492][11257][11473][klog] INFO: 00:00 +15: hash and equality structs handles [00005.913272][11257][11473][klog] INFO: 00:00 +16: hash and equality unions equality [00005.918572][11257][11473][klog] INFO: 00:00 +17: hash and equality unions hash [00005.920796][11257][11473][klog] INFO: 00:00 +18: one way (setUpAll) [00005.924591][11257][11473][klog] INFO: 00:00 +18: one way no args [00005.944910][1253939897][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/ [00005.945568][4852][4905][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 [00005.945602][4852][4905][klog] INFO: .cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00006.025980][11257][12107][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_bindings_test_server is running from kernel [00006.067307][11257][11473][klog] INFO: 00:00 +19: one way string arg [00006.074892][11257][11473][klog] INFO: 00:00 +20: one way three args [00006.090332][11257][11473][klog] INFO: Proxy error: Peer unexpectedly closed [00006.090721][11257][11473][klog] INFO: Proxy error: Peer unexpectedly closed [00006.091506][11257][11473][klog] INFO: 00:00 +21: one way table [00006.107900][11257][11473][klog] INFO: 00:00 +22: one way partial table [00006.112013][11257][11473][klog] INFO: 00:00 +23: one way empty table [00006.116037][11257][11473][klog] INFO: 00:00 +24: one way (tearDownAll) [00006.119629][11257][11473][klog] INFO: 00:00 +24: clone exact [00006.122711][11257][11473][klog] INFO: 00:00 +25: clone modify field [00006.128278][11257][11473][klog] INFO: Proxy error: Peer unexpectedly closed [00006.129844][11257][11473][klog] INFO: 00:00 +26: clone set field [00006.130583][11257][11473][klog] INFO: Proxy error: Peer unexpectedly closed [00006.133498][11257][11473][klog] INFO: 00:00 +27: clone unset field [00006.137351][11257][11473][klog] INFO: 00:00 +28: failure remote close during call [00006.141403][1253939897][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.141690][4852][4905][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.141734][4852][4905][klog] INFO: .cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00006.224999][11257][12293][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_bindings_test_server is running from kernel [00006.376009][11257][11473][klog] INFO: Proxy error: Peer unexpectedly closed [00006.385417][11257][11473][klog] INFO: 00:00 +29: failure local close during call [00006.386119][11257][11473][klog] INFO: Proxy error: Peer unexpectedly closed [00006.387870][11257][11473][klog] INFO: Proxy error: Peer unexpectedly closed [00006.397199][1253939897][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.398831][4852][4905][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.398883][4852][4905][klog] INFO: .cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00006.399040][11257][11473][klog] INFO: 00:00 +30: failure server killed during call [00006.431694][1253939897][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.431970][4852][4905][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.431997][4852][4905][klog] INFO: .cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00006.476656][11257][12209][klog] INFO: Observatory server failed to start after 2 tries [00006.561994][11257][12471][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_bindings_test_server is running from kernel [00006.571231][11257][11473][klog] INFO: Proxy error: Peer unexpectedly closed [00006.571927][11257][11473][klog] INFO: Proxy error: Peer unexpectedly closed [00006.592010][11257][12556][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_bindings_test_server is running from kernel [00006.604068][11257][11473][klog] INFO: Proxy error: Peer unexpectedly closed [00006.604665][11257][11473][klog] INFO: Proxy error: Peer unexpectedly closed [00006.608723][11257][11473][klog] INFO: Proxy error: AsyncProxyController<ComponentController> failed to write to channel: Channel(Handle(1903428991)) (status: -24) [00006.610527][11257][11473][klog] INFO: 00:00 +31: failure one-way call on closed proxy [00006.613531][1253939897][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.614204][4852][4905][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.614245][4852][4905][klog] INFO: .cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00006.617443][11257][11473][klog] INFO: 00:00 +32: failure two-way call on closed proxy [00006.625170][11257][11473][klog] INFO: 00:00 +33: failure listen for events on a closed proxy [00006.630111][11257][11473][klog] INFO: 00:00 +34: failure proxy closes while listening for events [00006.634257][11257][11473][klog] INFO: 00:00 +35: failure binding closes immediately after sending response [00006.641685][11257][11473][klog] INFO: Proxy error: Peer unexpectedly closed [00006.643962][11257][11473][klog] INFO: 00:00 +36: unbound one-way call on unbound proxy [00006.646519][11257][11473][klog] INFO: 00:00 +37: unbound two-way call on unbound proxy [00006.649347][11257][11473][klog] INFO: 00:01 +38: unbound event listen on unbound proxy [00006.654099][11257][11473][klog] INFO: 00:01 +39: events (setUpAll) [00006.663182][11257][11473][klog] INFO: 00:01 +39: events empty [00006.669781][1253939897][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.669915][1253939897][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.669961][1253939897][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.670028][1253939897][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.670043][1253939897][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.677633][4852][4905][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.677669][4852][4905][klog] INFO: .cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00006.691419][4852][4905][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.691452][4852][4905][klog] INFO: .cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00006.742171][4852][4905][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.742213][4852][4905][klog] INFO: .cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00006.768469][4852][4905][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.768496][4852][4905][klog] INFO: .cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00006.793349][4852][4905][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.793378][4852][4905][klog] INFO: .cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00006.846919][11257][12779][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_bindings_test_server is running from kernel [00006.874124][11257][11473][klog] INFO: Proxy error: Peer unexpectedly closed [00006.928761][11257][11473][klog] INFO: Proxy error: Peer unexpectedly closed [00007.066821][11257][12946][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_bindings_test_server is running from kernel [00007.135231][11257][13044][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_bindings_test_server is running from kernel [00007.149966][11257][11473][klog] INFO: Proxy error: Peer unexpectedly closed [00007.157892][11257][11473][klog] INFO: Proxy error: Peer unexpectedly closed [00007.419943][11257][11473][klog] INFO: 00:01 +40: events string arg [00007.426113][11257][13100][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_bindings_test_server is running from kernel [00007.440299][11257][11473][klog] INFO: Proxy error: Peer unexpectedly closed [00007.447405][11257][11473][klog] INFO: Proxy error: Peer unexpectedly closed [00007.471145][11257][11473][klog] INFO: 00:01 +41: events three args [00007.478932][11257][11608][klog] INFO: Observatory server failed to start after 3 tries [00007.546047][11257][11473][klog] INFO: 00:01 +42: events multiple events [00007.586235][11257][13240][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_bindings_test_server is running from kernel [00007.598370][11257][11473][klog] INFO: Proxy error: Peer unexpectedly closed [00007.602775][11257][11473][klog] INFO: Proxy error: Peer unexpectedly closed [00007.615543][11257][13201][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_bindings_test_server is running from kernel [00007.624835][11257][11473][klog] INFO: Proxy error: Peer unexpectedly closed [00007.625199][11257][11473][klog] INFO: Proxy error: Peer unexpectedly closed [00007.699795][11257][11473][klog] INFO: 00:02 +43: events (tearDownAll) [00007.716907][11257][11473][klog] INFO: 00:02 +43: All tests passed! [00007.779310][4599][4618][klog] INFO: PASSED: /pkgfs/packages/fidl_bindings_test/0/test/fidl_bindings_test passed [00007.852357][4599][4618][klog] INFO: [00007.852407][4599][4618][klog] INFO: ------------------------------------------------ [00007.852410][4599][4618][klog] INFO: RUNNING TEST: /pkgfs/packages/fidl_compatibility_test/0/test/run_fidl_compatibility_test_topaz.sh [00007.852411][4599][4618][klog] INFO: [00007.925961][4599][4618][klog] INFO: [==========] Running 32 tests from 1 test case. [00007.925996][4599][4618][klog] INFO: [----------] Global test environment set-up. [00007.925997][4599][4618][klog] INFO: [----------] 32 tests from CompatibilityTest/CompatibilityTest [00007.925998][4599][4618][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/0 [00007.945184][1253939897][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/ [00007.945778][4852][4905][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 [00007.945878][4852][4905][klog] INFO: mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00008.014084][1253939897][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.014547][4852][4905][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.014633][4852][4905][klog] INFO: mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00008.104955][4599][4618][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/0 (179 ms) [00008.104982][4599][4618][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/1 [00008.116621][1253939897][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.116953][4852][4905][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.116983][4852][4905][klog] INFO: mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00008.176851][1253939897][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/ [00008.177543][4852][4905][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_ [00008.177581][4852][4905][klog] INFO: compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00008.218688][1253939897][0][amber] INFO: control_server: could not get update for dart_jit_runner/0: daemon: no update found for dart_jit_runner/0/ [00008.363243][1253939897][0][amber] INFO: control_server: could not get update for timezone/0: daemon: no update found for timezone/0/ [00008.642184][14709][14945][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [00008.660441][14709][15313][klog] INFO: Observatory listening on http://127.0.0.1:26913/ [00008.663725][14709][15313][klog] INFO: Creating /tmp/dart.services/26913 [00008.760259][4599][4618][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/1 (655 ms) [00008.760301][4599][4618][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/2 [00008.787531][1253939897][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.790607][4852][4905][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.790692][4852][4905][klog] INFO: mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00008.854538][1253939897][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/ [00008.855258][4852][4905][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 [00008.855355][4852][4905][klog] INFO: atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00008.945866][4599][4618][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/2 (186 ms) [00008.945953][4599][4618][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/3 [00008.969455][1253939897][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.969690][4852][4905][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.969746][4852][4905][klog] INFO: mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00009.032247][1253939897][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/ [00009.032801][4852][4905][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_ [00009.032939][4852][4905][klog] INFO: compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00009.122212][4599][4618][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/3 (176 ms) [00009.122234][4599][4618][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/4 [00009.125954][1253939897][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.135221][4852][4905][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.135294][4852][4905][klog] INFO: compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00009.227253][14709][16434][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [00009.294178][1253939897][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.294653][4852][4905][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.294689][4852][4905][klog] INFO: mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00009.368395][4599][4618][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/4 (246 ms) [00009.368434][4599][4618][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/5 [00009.384193][1253939897][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.388174][4852][4905][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.388204][4852][4905][klog] INFO: compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00009.505920][14709][16868][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [00009.571295][1253939897][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.571631][4852][4905][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.571668][4852][4905][klog] INFO: compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00009.634862][14709][17054][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [00009.715078][4599][4618][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/5 (347 ms) [00009.715109][4599][4618][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/6 [00009.725832][1253939897][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.726347][4852][4905][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.726484][4852][4905][klog] INFO: compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00009.822467][14709][17267][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [00009.889637][1253939897][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.890487][4852][4905][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.890543][4852][4905][klog] INFO: atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00009.984396][4599][4618][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/6 (266 ms) [00009.984908][4599][4618][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/7 [00009.999789][1253939897][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.008227][4852][4905][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.008543][4852][4905][klog] INFO: compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00010.128959][14709][17792][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [00010.202366][1253939897][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.202609][4852][4905][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.202663][4852][4905][klog] INFO: compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00010.280827][4599][4618][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/7 (300 ms) [00010.280869][4599][4618][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/8 [00010.283886][1253939897][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/ [00010.291388][4852][4905][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 [00010.291452][4852][4905][klog] INFO: atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00010.377175][1253939897][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.377797][4852][4905][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.377938][4852][4905][klog] INFO: mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00010.450154][4599][4618][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/8 (169 ms) [00010.450189][4599][4618][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/9 [00010.463011][4852][4905][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 [00010.463165][4852][4905][klog] INFO: atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00010.462322][1253939897][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/ [00010.544888][1253939897][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.545894][4852][4905][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.545937][4852][4905][klog] INFO: compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00010.645422][14709][18911][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [00010.723824][4599][4618][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/9 (274 ms) [00010.724003][4599][4618][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/10 [00010.745332][1253939897][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/ [00010.747596][4852][4905][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 [00010.747632][4852][4905][klog] INFO: atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00010.846761][1253939897][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/ [00010.847973][4852][4905][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 [00010.848004][4852][4905][klog] INFO: atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00010.917732][4599][4618][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/10 (193 ms) [00010.917763][4599][4618][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/11 [00010.934615][1253939897][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/ [00010.935216][4852][4905][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 [00010.935245][4852][4905][klog] INFO: atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00011.017715][1253939897][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.018565][4852][4905][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.018639][4852][4905][klog] INFO: compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00011.089630][4599][4618][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/11 (173 ms) [00011.089657][4599][4618][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/12 [00011.090677][1253939897][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.091342][4852][4905][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.091373][4852][4905][klog] INFO: compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00011.163392][1253939897][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.163938][4852][4905][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.163988][4852][4905][klog] INFO: mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00011.228764][4599][4618][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/12 (139 ms) [00011.228789][4599][4618][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/13 [00011.253541][1253939897][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.254045][4852][4905][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.254107][4852][4905][klog] INFO: compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00011.323243][1253939897][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.323925][4852][4905][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.324029][4852][4905][klog] INFO: compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00011.419049][14709][20627][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [00011.492962][4599][4618][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/13 (264 ms) [00011.493074][4599][4618][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/14 [00011.529263][1253939897][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.541722][4852][4905][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.541758][4852][4905][klog] INFO: compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00011.614462][1253939897][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.614891][4852][4905][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.614994][4852][4905][klog] INFO: atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00011.706982][4599][4618][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/14 (214 ms) [00011.715766][4599][4618][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStruct/15 [00011.747297][1253939897][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.747699][4852][4905][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.747730][4852][4905][klog] INFO: compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00011.817337][1253939897][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.819273][4852][4905][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.819348][4852][4905][klog] INFO: compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00011.869506][4599][4618][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStruct/15 (163 ms) [00011.869525][4599][4618][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/0 [00011.884636][1253939897][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.885319][4852][4905][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.885364][4852][4905][klog] INFO: mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00011.949298][1253939897][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.949681][4852][4905][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.949719][4852][4905][klog] INFO: mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00012.007316][4599][4618][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/0 (137 ms) [00012.007358][4599][4618][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/1 [00012.056098][1253939897][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.056486][4852][4905][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.056536][4852][4905][klog] INFO: mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00012.118103][1253939897][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.118680][4852][4905][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.118823][4852][4905][klog] INFO: compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00012.207219][14709][22154][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [00012.282706][4599][4618][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/1 (276 ms) [00012.282745][4599][4618][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/2 [00012.303762][1253939897][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.307769][4852][4905][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.307989][4852][4905][klog] INFO: mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00012.368939][1253939897][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.369568][4852][4905][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.369624][4852][4905][klog] INFO: atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00012.451209][4599][4618][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/2 (168 ms) [00012.451250][4599][4618][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/3 [00012.480429][1253939897][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.482761][4852][4905][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.482803][4852][4905][klog] INFO: mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00012.568821][1253939897][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.569419][4852][4905][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.569453][4852][4905][klog] INFO: compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00012.638031][4599][4618][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/3 (187 ms) [00012.638070][4599][4618][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/4 [00012.656513][1253939897][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.659777][4852][4905][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.659863][4852][4905][klog] INFO: compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00012.753604][14709][23240][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [00012.812819][1253939897][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.813703][4852][4905][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.813734][4852][4905][klog] INFO: mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00012.885264][4599][4618][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/4 (247 ms) [00012.890997][4599][4618][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/5 [00012.903097][1253939897][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.903686][4852][4905][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.903720][4852][4905][klog] INFO: compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00013.008225][14709][23661][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [00013.073700][1253939897][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.074146][4852][4905][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.074182][4852][4905][klog] INFO: compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00013.143835][14709][23838][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [00013.239792][4599][4618][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/5 (354 ms) [00013.239816][4599][4618][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/6 [00013.254040][1253939897][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.254747][4852][4905][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.254786][4852][4905][klog] INFO: compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00013.353449][14709][24056][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [00013.423950][1253939897][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.424446][4852][4905][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.424477][4852][4905][klog] INFO: atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00013.508249][4599][4618][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/6 (269 ms) [00013.508275][4599][4618][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/7 [00013.524217][1253939897][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.525644][4852][4905][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.525719][4852][4905][klog] INFO: compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00013.619166][14709][24568][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [00013.692025][1253939897][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.692665][4852][4905][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.692695][4852][4905][klog] INFO: compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00013.766424][4599][4618][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/7 (258 ms) [00013.766462][4599][4618][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/8 [00013.786698][1253939897][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.813300][4852][4905][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.813980][4852][4905][klog] INFO: atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00013.903050][1253939897][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.903946][4852][4905][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.904013][4852][4905][klog] INFO: mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00013.968163][4599][4618][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/8 (201 ms) [00013.968199][4599][4618][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/9 [00013.974538][1253939897][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.975029][4852][4905][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.975066][4852][4905][klog] INFO: atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00014.066958][1253939897][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.068161][4852][4905][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.068376][4852][4905][klog] INFO: compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00014.162535][14709][25685][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [00014.244615][4599][4618][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/9 (277 ms) [00014.244681][4599][4618][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/10 [00014.259472][1253939897][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/ [00014.263954][4852][4905][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 [00014.264019][4852][4905][klog] INFO: atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00014.366863][1253939897][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/ [00014.367677][4852][4905][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 [00014.367746][4852][4905][klog] INFO: atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00014.432918][4599][4618][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/10 (188 ms) [00014.433014][4599][4618][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/11 [00014.466473][1253939897][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/ [00014.470295][4852][4905][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 [00014.470368][4852][4905][klog] INFO: atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00014.567362][1253939897][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.569595][4852][4905][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.569637][4852][4905][klog] INFO: compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00014.644843][4599][4618][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/11 (212 ms) [00014.644915][4599][4618][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/12 [00014.669516][1253939897][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.674489][4852][4905][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.674702][4852][4905][klog] INFO: compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00014.751039][1253939897][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.751865][4852][4905][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.751962][4852][4905][klog] INFO: mpatibility_test_server_cpp.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00014.827983][4599][4618][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/12 (182 ms) [00014.828008][4599][4618][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/13 [00014.862292][1253939897][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.862759][4852][4905][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.862832][4852][4905][klog] INFO: compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00014.930167][1253939897][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.930655][4852][4905][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.930817][4852][4905][klog] INFO: compatibility_test_server_dart.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00015.028243][14709][27375][klog] INFO: [INFO:dart_component_controller.cc(107)] fuchsia-pkg://fuchsia.com/fidl_compatibility_test_server_dart is running from kernel [00015.122671][4599][4618][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/13 (295 ms) [00015.122711][4599][4618][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/14 [00015.149646][1253939897][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.150041][4852][4905][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.150067][4852][4905][klog] INFO: compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00015.221812][1253939897][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.222446][4852][4905][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.222486][4852][4905][klog] INFO: atibility_test_server_go.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00015.304100][4599][4618][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/14 (181 ms) [00015.304148][4599][4618][klog] INFO: [ RUN ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/15 [00015.345618][1253939897][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.349444][4852][4905][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.349506][4852][4905][klog] INFO: compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00015.417787][1253939897][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.418226][4852][4905][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.418307][4852][4905][klog] INFO: compatibility_test_server_rust.cmx instead. See https://fuchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00015.478643][4599][4618][klog] INFO: [ OK ] CompatibilityTest/CompatibilityTest.EchoStructNoRetVal/15 (175 ms) [00015.478731][4599][4618][klog] INFO: [----------] 32 tests from CompatibilityTest/CompatibilityTest (7553 ms total) [00015.478732][4599][4618][klog] INFO: [00015.478732][4599][4618][klog] INFO: [----------] Global test environment tear-down [00015.478733][4599][4618][klog] INFO: [==========] 32 tests from 1 test case ran. (7553 ms total) [00015.478733][4599][4618][klog] INFO: [ PASSED ] 32 tests. [00015.504409][4599][4618][klog] INFO: PASSED: /pkgfs/packages/fidl_compatibility_test/0/test/run_fidl_compatibility_test_topaz.sh passed [00020.227913][4599][4618][klog] INFO: [00020.227934][4599][4618][klog] INFO: ------------------------------------------------ [00020.227937][4599][4618][klog] INFO: RUNNING TEST: /pkgfs/packages/flutter_runner_tests/0/test/flutter_runner_unittests [00020.227938][4599][4618][klog] INFO: [00020.369483][4599][4618][klog] INFO: Running main() from gtest_main.cc [00020.369541][4599][4618][klog] INFO: [==========] Running 10 tests from 1 test case. [00020.369583][4599][4618][klog] INFO: [----------] Global test environment set-up. [00020.369584][4599][4618][klog] INFO: [----------] 10 tests from FuchsiaFontManagerTest [00020.369584][4599][4618][klog] INFO: [ RUN ] FuchsiaFontManagerTest.ValidResponseWhenCharacterFound [00020.402970][1253939897][0][amber] INFO: control_server: could not get update for fonts/0: daemon: no update found for fonts/0/ [00020.579029][4599][4618][klog] INFO: [ OK ] FuchsiaFontManagerTest.ValidResponseWhenCharacterFound (209 ms) [00020.579054][4599][4618][klog] INFO: [ RUN ] FuchsiaFontManagerTest.EmptyResponseWhenCharacterNotFound [00020.609697][1253939897][0][amber] INFO: control_server: could not get update for fonts/0: daemon: no update found for fonts/0/ [00020.778448][4599][4618][klog] INFO: [ OK ] FuchsiaFontManagerTest.EmptyResponseWhenCharacterNotFound (200 ms) [00020.778470][4599][4618][klog] INFO: [ RUN ] FuchsiaFontManagerTest.Caching [00020.803710][1253939897][0][amber] INFO: control_server: could not get update for fonts/0: daemon: no update found for fonts/0/ [00020.985670][4599][4618][klog] INFO: [ OK ] FuchsiaFontManagerTest.Caching (207 ms) [00020.985699][4599][4618][klog] INFO: [ RUN ] FuchsiaFontManagerTest.TypefaceOutlivesManager [00021.007996][1253939897][0][amber] INFO: control_server: could not get update for fonts/0: daemon: no update found for fonts/0/ [00021.172771][4599][4618][klog] INFO: [ OK ] FuchsiaFontManagerTest.TypefaceOutlivesManager (187 ms) [00021.172800][4599][4618][klog] INFO: [ RUN ] FuchsiaFontManagerTest.ReleaseThenCreateAgain [00021.194756][1253939897][0][amber] INFO: control_server: could not get update for fonts/0: daemon: no update found for fonts/0/ [00021.371812][4599][4618][klog] INFO: [ OK ] FuchsiaFontManagerTest.ReleaseThenCreateAgain (199 ms) [00021.371835][4599][4618][klog] INFO: [ RUN ] FuchsiaFontManagerTest.ReleasedTypefaceIsPurged [00021.402740][1253939897][0][amber] INFO: control_server: could not get update for fonts/0: daemon: no update found for fonts/0/ [00021.569483][4599][4618][klog] INFO: [ OK ] FuchsiaFontManagerTest.ReleasedTypefaceIsPurged (198 ms) [00021.569523][4599][4618][klog] INFO: [ RUN ] FuchsiaFontManagerTest.MatchUnknownFamily [00021.603369][1253939897][0][amber] INFO: control_server: could not get update for fonts/0: daemon: no update found for fonts/0/ [00021.777581][4599][4618][klog] INFO: [ OK ] FuchsiaFontManagerTest.MatchUnknownFamily (204 ms) [00021.777607][4599][4618][klog] INFO: [ RUN ] FuchsiaFontManagerTest.MatchKnownFamily [00021.815932][1253939897][0][amber] INFO: control_server: could not get update for fonts/0: daemon: no update found for fonts/0/ [00022.005911][4599][4618][klog] INFO: [ OK ] FuchsiaFontManagerTest.MatchKnownFamily (232 ms) [00022.005945][4599][4618][klog] INFO: [ RUN ] FuchsiaFontManagerTest.FontFamilyGetStyle [00022.027292][1253939897][0][amber] INFO: control_server: could not get update for fonts/0: daemon: no update found for fonts/0/ [00022.215558][4599][4618][klog] INFO: [ OK ] FuchsiaFontManagerTest.FontFamilyGetStyle (210 ms) [00022.215616][4599][4618][klog] INFO: [ RUN ] FuchsiaFontManagerTest.FontFamilyCreateTypeface [00022.240424][1253939897][0][amber] INFO: control_server: could not get update for fonts/0: daemon: no update found for fonts/0/ [00022.424790][4599][4618][klog] INFO: [ OK ] FuchsiaFontManagerTest.FontFamilyCreateTypeface (209 ms) [00022.424825][4599][4618][klog] INFO: [----------] 10 tests from FuchsiaFontManagerTest (2055 ms total) [00022.424827][4599][4618][klog] INFO: [00022.424827][4599][4618][klog] INFO: [----------] Global test environment tear-down [00022.424851][4599][4618][klog] INFO: [==========] 10 tests from 1 test case ran. (2055 ms total) [00022.424851][4599][4618][klog] INFO: [ PASSED ] 10 tests. [00022.462043][4599][4618][klog] INFO: PASSED: /pkgfs/packages/flutter_runner_tests/0/test/flutter_runner_unittests passed [00022.515407][4599][4618][klog] INFO: [00022.515450][4599][4618][klog] INFO: ------------------------------------------------ [00022.515453][4599][4618][klog] INFO: RUNNING TEST: /pkgfs/packages/fuchsia_modular_package_integration_tests/0/test/fuchsia_modular_package_integration_tests [00022.515454][4599][4618][klog] INFO: [00022.591496][1253939897][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/ [00022.625174][1253939897][0][amber] INFO: control_server: could not get update for dart_jit_runner/0: daemon: no update found for dart_jit_runner/0/ [00022.925480][32635][32972][klog] INFO: Observatory server failed to start after 1 tries [00023.138582][32635][32830][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 [00023.242832][32635][32830][klog] INFO: 00:00 +0: calls the set onAccept function [00023.277883][32635][32830][klog] INFO: 00:00 +1: sets values on display [00023.286457][32635][32830][klog] INFO: 00:00 +2: addModuleAffinity [00023.293185][32635][32830][klog] INFO: 00:00 +3: addStoryAffinity [00023.298751][32635][32830][klog] INFO: 00:00 +4: calls the callback when proposal accepted [00023.305164][32635][32830][klog] INFO: 00:00 +5: factory should return same instance [00023.311728][32635][32830][klog] INFO: 00:00 +6: startupContext [00023.328893][32635][32830][klog] INFO: 00:00 +7: verify Lifecycle init during the construction of ModuleImpl [00023.336525][32635][32830][klog] INFO: 00:00 +8: verify exposeService arguments [00023.346031][32635][32830][klog] INFO: 00:00 +9: verify exposeServiceProvider arguments [00023.352618][32635][32830][klog] INFO: 00:00 +10: service bindings tests verify exposeService binds the correct service on connect request [00023.366833][32635][32830][klog] INFO: 00:00 +11: service bindings tests verify exposeService waits for all futures and binds the correct service on connect request [00023.379487][32635][32830][klog] INFO: 00:00 +12: service bindings tests verify exposeServiceProvider waits for all futures and binds the correct service on connect request [00023.392565][32635][32830][klog] INFO: 00:00 +13: service bindings tests verify exposeServiceProvider waits binds the correct service on connect request [00023.403099][32635][32830][klog] INFO: 00:00 +14: verify getTokenManager should call context.getTokenManager [00023.415869][32635][32830][klog] INFO: 00:00 +15: module tests factory returns same instance [00023.423892][32635][32830][klog] INFO: 00:00 +16: no-op intent handler calling handle intent does nothing [00023.432857][32635][32830][klog] INFO: 00:00 +17: intent constructors intent sets the action [00023.442553][32635][32830][klog] INFO: 00:00 +18: intent constructors intent with handler sets the handler [00023.450096][32635][32830][klog] INFO: 00:00 +19: intent parameters addParameterFromEntityReference adds it to the list [00023.458406][32635][32830][klog] INFO: 00:00 +20: intent parameters getEntity throws for missing name [00023.466108][32635][32830][klog] INFO: 00:00 +21: intent parameters getEntity returns valid entity for link entity [00023.474376][32635][32830][klog] INFO: 00:00 +22: addModuleToStory should return ModuleController upon success [00023.484581][32635][32830][klog] INFO: 00:00 +23: addModuleToStory should throw ModuleResolutionException if no module found [00023.495366][32635][32830][klog] INFO: 00:00 +24: addModuleToStory should throw ModuleStateException for unknown errors [00023.499990][32635][32830][klog] INFO: 00:00 +25: embedModule should throw ModuleResolutionException if no module found [00023.506755][32635][32830][klog] INFO: 00:00 +26: embedModule should throw ModuleStateException for unknown errors [00023.510937][32635][32830][klog] INFO: 00:00 +27: embedModule should throw ArgumentError for invalid name [00023.518103][32635][32830][klog] INFO: 00:00 +28: intent handling throws when registerIntentHandler called twice [00023.522707][32635][32830][klog] INFO: 00:00 +29: intent handling throws when no intent handler registered [00023.526971][32635][32830][klog] INFO: 00:00 +30: intent handling module proxies intents to handler [00023.529966][32635][32830][klog] INFO: 00:00 +31: verify Lifecycle init during the construction of ModuleImpl [00023.534619][32635][32830][klog] INFO: 00:00 +32: embedModule throws for empty name [00023.536715][32635][32830][klog] INFO: 00:00 +33: embedModule throws for null intent [00023.538864][32635][32830][klog] INFO: 00:00 +34: addModuleToStory throws for empty name [00023.540936][32635][32830][klog] INFO: 00:00 +35: addModuleToStory throws for null intent [00023.543131][32635][32830][klog] INFO: 00:00 +36: verify requestFocus should call context.requestFocus [00023.545908][32635][32830][klog] INFO: 00:00 +37: verify removeSelfFromStory should call context.removeSelfFromStory [00023.548141][32635][32830][klog] INFO: 00:00 +38: intent handler impl handleIntent calls registered handler [00023.550485][32635][32830][klog] INFO: 00:00 +39: intent handler impl handleIntent passes along the correct action [00023.553020][32635][32830][klog] INFO: 00:00 +40: intent transformers convertFidlIntentToIntent clones correct fields [00023.556458][32635][32830][klog] INFO: 00:00 +41: intent transformers convertFidlIntentToIntent handles null fidl intent parametsrs [00023.559039][32635][32830][klog] INFO: 00:00 +42: intent transformers Intents with const parameter lists can still be modified [00023.561699][32635][32830][klog] INFO: 00:00 +43: ComponentContext: getComponentContext does not return null instance [00023.565911][3542][3729][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 [00023.565943][3542][3729][klog] INFO: ia.modular.ComponentContext because this service is not present in the component's sandbox. [00023.565944][3542][3729][klog] INFO: Refer to https://fuchsia.googlesource.com/docs/+/master/the-book/sandboxing.md#services for more information. [00023.567596][32635][32830][klog] INFO: Proxy error: Peer unexpectedly closed [00023.568502][32635][32830][klog] INFO: 00:00 +44: ComponentContext: getComponentContext returns the same instance [00023.570732][32635][32830][klog] INFO: 00:00 +45: connectToAgentService:= throws for null or empty agent url [00023.573854][32635][32830][klog] INFO: 00:00 +46: connectToAgentService:= throws if serviceProxy is null [00023.576090][32635][32830][klog] INFO: 00:00 +47: connectToEnvironmentService throws if serviceProxy is null [00023.578115][32635][32830][klog] INFO: 00:00 +48: factory should return same instance [00023.579821][32635][32830][klog] INFO: 00:00 +49: addTerminateListener throws for null listener [00023.581928][32635][32830][klog] INFO: 00:00 +50: addTerminateListener should return false when adding same handler [00023.583693][32635][32830][klog] INFO: 00:00 +51: addTerminateListener successful add [00023.585552][32635][32830][klog] INFO: 00:00 +52: failing terminate handler should error [00023.588167][32635][32830][klog] INFO: testing 1 [00023.595961][32635][32830][klog] INFO: terminateListener1 [00023.598136][32635][32830][klog] INFO: throwingTerminateListener [00023.604760][32635][32830][klog] INFO: 00:00 +53: terminate should trigger all added listeners to execute [00023.605551][32635][32830][klog] INFO: Skip: this test will cause other tests to not run after it is invoked since it calls exit() [00023.613745][32635][32830][klog] INFO: 00:00 +53 ~1: All tests passed! [00023.760796][4599][4618][klog] INFO: PASSED: /pkgfs/packages/fuchsia_modular_package_integration_tests/0/test/fuchsia_modular_package_integration_tests passed [00023.816664][4599][4618][klog] INFO: [00023.816709][4599][4618][klog] INFO: ------------------------------------------------ [00023.816712][4599][4618][klog] INFO: RUNNING TEST: /pkgfs/packages/fuchsia_services_package_integration_tests/0/test/fuchsia_services_package_integration_tests [00023.816713][4599][4618][klog] INFO: [00023.905197][1253939897][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.936955][1253939897][0][amber] INFO: control_server: could not get update for dart_jit_runner/0: daemon: no update found for dart_jit_runner/0/ [00024.253581][33902][34224][klog] INFO: Observatory server failed to start after 1 tries [00024.342973][33902][34097][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 [00024.405223][33902][34097][klog] INFO: 00:00 +0: StartupContext fromStartupInfo does not return null instance [00024.444044][33902][34097][klog] INFO: 00:00 +1: StartupContext fromStartupInfo returns the same instance [00024.447794][33902][34097][klog] INFO: 00:00 +2: service provider impl connect to service calls correct thunk [00024.460119][33902][34097][klog] INFO: 00:00 +3: All tests passed! [00024.492359][4599][4618][klog] INFO: PASSED: /pkgfs/packages/fuchsia_services_package_integration_tests/0/test/fuchsia_services_package_integration_tests passed [00024.558125][4599][4618][klog] INFO: [00024.558172][4599][4618][klog] INFO: ------------------------------------------------ [00024.558175][4599][4618][klog] INFO: RUNNING TEST: /pkgfs/packages/goodbye_dart_test/0/test/goodbye_dart_test [00024.558176][4599][4618][klog] INFO: [00024.643697][1253939897][0][amber] INFO: control_server: could not get update for goodbye_dart_test/0: daemon: no update found for goodbye_dart_test/0/ [00024.704432][1253939897][0][amber] INFO: control_server: could not get update for run/0: daemon: no update found for run/0/ [00024.741150][1253939897][0][amber] INFO: control_server: could not get update for goodbye_dart_aot/0: daemon: no update found for goodbye_dart_aot/0/ [00024.741551][4852][4905][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.741609][4852][4905][klog] INFO: uchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00024.784926][1253939897][0][amber] INFO: control_server: could not get update for dart_aot_runner/0: daemon: no update found for dart_aot_runner/0/ [00025.071531][35257][35578][klog] INFO: Observatory server failed to start after 1 tries [00025.078093][35257][35451][klog] INFO: [INFO:dart_component_controller.cc(105)] fuchsia-pkg://fuchsia.com/goodbye_dart_aot is running from an app snapshot [00025.078868][35257][35451][klog] INFO: Hello, Dart! [00025.078952][35257][35451][klog] INFO: Goodbye now! [00025.116032][1253939897][0][amber] INFO: control_server: could not get update for run/0: daemon: no update found for run/0/ [00025.152011][1253939897][0][amber] INFO: control_server: could not get update for goodbye_dart_aot/0: daemon: no update found for goodbye_dart_aot/0/ [00025.152402][4852][4905][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 [00025.152466][4852][4905][klog] INFO: uchsia.googlesource.com/docs/+/master/glossary.md#fuchsia_pkg-url for more information. [00025.237848][35257][35802][klog] INFO: [INFO:dart_component_controller.cc(105)] fuchsia-pkg://fuchsia.com/goodbye_dart_aot is running from an app snapshot [00025.245943][35257][35802][klog] INFO: Hello, Dart! [00026.073699][35257][35862][klog] INFO: Observatory server failed to start after 2 tries [00026.248686][35257][35802][klog] INFO: Goodbye, Dart! [00026.272883][4599][4618][klog] INFO: PASSED: /pkgfs/packages/goodbye_dart_test/0/test/goodbye_dart_test passed [00026.330723][4599][4618][klog] INFO: [00026.330750][4599][4618][klog] INFO: ------------------------------------------------ [00026.330754][4599][4618][klog] INFO: RUNNING TEST: /pkgfs/packages/run_test_component_test/0/test/run_test_component_test [00026.330755][4599][4618][klog] INFO: [00026.416374][1253939897][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/ [00026.500704][4599][4618][klog] INFO: [==========] Running 1 test from 1 test case. [00026.500783][4599][4618][klog] INFO: [----------] Global test environment set-up. [00026.500808][4599][4618][klog] INFO: [----------] 1 test from Run [00026.500809][4599][4618][klog] INFO: [ RUN ] Run.TestHermeticEnv [00026.516641][4599][4618][klog] INFO: [ OK ] Run.TestHermeticEnv (16 ms) [00026.516710][4599][4618][klog] INFO: [----------] 1 test from Run (16 ms total) [00026.516732][4599][4618][klog] INFO: [00026.516735][4599][4618][klog] INFO: [----------] Global test environment tear-down [00026.516736][4599][4618][klog] INFO: [==========] 1 test from 1 test case ran. (16 ms total) [00026.516759][4599][4618][klog] INFO: [ PASSED ] 1 test. [00026.554056][4599][4618][klog] INFO: PASSED: /pkgfs/packages/run_test_component_test/0/test/run_test_component_test passed [00026.607773][4599][4618][klog] INFO: [00026.607801][4599][4618][klog] INFO: ------------------------------------------------ [00026.607804][4599][4618][klog] INFO: RUNNING TEST: /pkgfs/packages/run_test_component_unittests/0/test/run_test_component_unittests [00026.607805][4599][4618][klog] INFO: [00026.691537][1253939897][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.750099][4599][4618][klog] INFO: [==========] Running 19 tests from 4 test cases. [00026.750156][4599][4618][klog] INFO: [----------] Global test environment set-up. [00026.750157][4599][4618][klog] INFO: [----------] 5 tests from EnvironmentConfigTest [00026.750157][4599][4618][klog] INFO: [ RUN ] EnvironmentConfigTest.InvalidJson [00026.754838][4599][4618][klog] INFO: [ OK ] EnvironmentConfigTest.InvalidJson (5 ms) [00026.754863][4599][4618][klog] INFO: [ RUN ] EnvironmentConfigTest.NoRootElement [00026.759737][4599][4618][klog] INFO: [ OK ] EnvironmentConfigTest.NoRootElement (5 ms) [00026.759761][4599][4618][klog] INFO: [ RUN ] EnvironmentConfigTest.NoSysElement [00026.763960][4599][4618][klog] INFO: [ OK ] EnvironmentConfigTest.NoSysElement (4 ms) [00026.764128][4599][4618][klog] INFO: [ RUN ] EnvironmentConfigTest.InvalidSection [00026.770872][4599][4618][klog] INFO: [ OK ] EnvironmentConfigTest.InvalidSection (7 ms) [00026.770897][4599][4618][klog] INFO: [ RUN ] EnvironmentConfigTest.ValidConfig [00026.809409][4599][4618][klog] INFO: [ OK ] EnvironmentConfigTest.ValidConfig (38 ms) [00026.809446][4599][4618][klog] INFO: [----------] 5 tests from EnvironmentConfigTest (60 ms total) [00026.809446][4599][4618][klog] INFO: [00026.809447][4599][4618][klog] INFO: [----------] 2 tests from Url [00026.809447][4599][4618][klog] INFO: [ RUN ] Url.ParseURL [00026.809891][4599][4618][klog] INFO: [ OK ] Url.ParseURL (0 ms) [00026.809914][4599][4618][klog] INFO: [ RUN ] Url.GenerateComponentUrl [00026.810260][4599][4618][klog] INFO: [ OK ] Url.GenerateComponentUrl (0 ms) [00026.810281][4599][4618][klog] INFO: [----------] 2 tests from Url (0 ms total) [00026.810282][4599][4618][klog] INFO: [00026.810282][4599][4618][klog] INFO: [----------] 1 test from RunTest [00026.810282][4599][4618][klog] INFO: [ RUN ] RunTest.ParseArgs [00026.852012][4599][4618][klog] INFO: [ OK ] RunTest.ParseArgs (22 ms) [00026.852035][4599][4618][klog] INFO: [----------] 1 test from RunTest (22 ms total) [00026.852036][4599][4618][klog] INFO: [00026.852039][4599][4618][klog] INFO: [----------] 11 tests from TestMetadataTest [00026.852040][4599][4618][klog] INFO: [ RUN ] TestMetadataTest.InvalidJson [00026.852040][4599][4618][klog] INFO: [ OK ] TestMetadataTest.InvalidJson (4 ms) [00026.852041][4599][4618][klog] INFO: [ RUN ] TestMetadataTest.NoFacet [00026.852041][4599][4618][klog] INFO: [ OK ] TestMetadataTest.NoFacet (4 ms) [00026.852042][4599][4618][klog] INFO: [ RUN ] TestMetadataTest.NoFuchsiaTestFacet [00026.852042][4599][4618][klog] INFO: [ OK ] TestMetadataTest.NoFuchsiaTestFacet (5 ms) [00026.852043][4599][4618][klog] INFO: [ RUN ] TestMetadataTest.NoServices [00026.852043][4599][4618][klog] INFO: [ OK ] TestMetadataTest.NoServices (4 ms) [00026.852044][4599][4618][klog] INFO: [ RUN ] TestMetadataTest.InvalidTestFacet [00026.893446][4599][4618][klog] INFO: [ OK ] TestMetadataTest.InvalidTestFacet (44 ms) [00026.893662][4599][4618][klog] INFO: [ RUN ] TestMetadataTest.InvalidServicesType [00026.899027][4599][4618][klog] INFO: [ OK ] TestMetadataTest.InvalidServicesType (6 ms) [00026.899242][4599][4618][klog] INFO: [ RUN ] TestMetadataTest.InvalidSystemServicesType [00026.934828][4599][4618][klog] INFO: [ OK ] TestMetadataTest.InvalidSystemServicesType (36 ms) [00026.934882][4599][4618][klog] INFO: [ RUN ] TestMetadataTest.InvalidServices [00026.948423][4599][4618][klog] INFO: [ OK ] TestMetadataTest.InvalidServices (13 ms) [00026.948453][4599][4618][klog] INFO: [ RUN ] TestMetadataTest.EmptyServices [00026.961419][4599][4618][klog] INFO: [ OK ] TestMetadataTest.EmptyServices (12 ms) [00026.961481][4599][4618][klog] INFO: [ RUN ] TestMetadataTest.ValidServices [00026.974864][4599][4618][klog] INFO: [ OK ] TestMetadataTest.ValidServices (13 ms) [00026.974921][4599][4618][klog] INFO: [ RUN ] TestMetadataTest.ValidSystemServices [00026.989626][4599][4618][klog] INFO: [ OK ] TestMetadataTest.ValidSystemServices (16 ms) [00026.989661][4599][4618][klog] INFO: [----------] 11 tests from TestMetadataTest (158 ms total) [00026.989662][4599][4618][klog] INFO: [00026.989662][4599][4618][klog] INFO: [----------] Global test environment tear-down [00026.989662][4599][4618][klog] INFO: [==========] 19 tests from 4 test cases ran. (240 ms total) [00026.989663][4599][4618][klog] INFO: [ PASSED ] 19 tests. [00027.014789][4599][4618][klog] INFO: PASSED: /pkgfs/packages/run_test_component_unittests/0/test/run_test_component_unittests passed [00027.066261][4599][4618][klog] INFO: [00027.066293][4599][4618][klog] INFO: ------------------------------------------------ [00027.066296][4599][4618][klog] INFO: RUNNING TEST: /pkgfs/packages/scenic_tests/0/test/geometry_util_unittests [00027.066298][4599][4618][klog] INFO: [00027.114064][4599][4618][klog] INFO: Running main() from gtest_main.cc [00027.114106][4599][4618][klog] INFO: [==========] Running 10 tests from 4 test cases. [00027.114107][4599][4618][klog] INFO: [----------] Global test environment set-up. [00027.114108][4599][4618][klog] INFO: [----------] 1 test from RectTest [00027.114108][4599][4618][klog] INFO: [ RUN ] RectTest.Comparisons [00027.132536][4599][4618][klog] INFO: [ OK ] RectTest.Comparisons (0 ms) [00027.132566][4599][4618][klog] INFO: [----------] 1 test from RectTest (0 ms total) [00027.132567][4599][4618][klog] INFO: [00027.132567][4599][4618][klog] INFO: [----------] 1 test from SizeTest [00027.132568][4599][4618][klog] INFO: [ RUN ] SizeTest.Comparisons [00027.132568][4599][4618][klog] INFO: [ OK ] SizeTest.Comparisons (0 ms) [00027.132568][4599][4618][klog] INFO: [----------] 1 test from SizeTest (0 ms total) [00027.132569][4599][4618][klog] INFO: [00027.132569][4599][4618][klog] INFO: [----------] 1 test from PointTest [00027.132570][4599][4618][klog] INFO: [ RUN ] PointTest.Comparisons [00027.132570][4599][4618][klog] INFO: [ OK ] PointTest.Comparisons (0 ms) [00027.132570][4599][4618][klog] INFO: [----------] 1 test from PointTest (0 ms total) [00027.132571][4599][4618][klog] INFO: [00027.132571][4599][4618][klog] INFO: [----------] 7 tests from TransformFunctionsTest [00027.132571][4599][4618][klog] INFO: [ RUN ] TransformFunctionsTest.SetIdentityTransform [00027.132572][4599][4618][klog] INFO: [ OK ] TransformFunctionsTest.SetIdentityTransform (0 ms) [00027.132572][4599][4618][klog] INFO: [ RUN ] TransformFunctionsTest.SetTranslationTransform [00027.132573][4599][4618][klog] INFO: [ OK ] TransformFunctionsTest.SetTranslationTransform (0 ms) [00027.132573][4599][4618][klog] INFO: [ RUN ] TransformFunctionsTest.Translate [00027.132573][4599][4618][klog] INFO: [ OK ] TransformFunctionsTest.Translate (0 ms) [00027.132574][4599][4618][klog] INFO: [ RUN ] TransformFunctionsTest.Scale [00027.132577][4599][4618][klog] INFO: [ OK ] TransformFunctionsTest.Scale (1 ms) [00027.132577][4599][4618][klog] INFO: [ RUN ] TransformFunctionsTest.CreateIdentityTransform [00027.132578][4599][4618][klog] INFO: [ OK ] TransformFunctionsTest.CreateIdentityTransform (0 ms) [00027.132578][4599][4618][klog] INFO: [ RUN ] TransformFunctionsTest.CreateTranslationTransform [00027.132578][4599][4618][klog] INFO: [ OK ] TransformFunctionsTest.CreateTranslationTransform (0 ms) [00027.132579][4599][4618][klog] INFO: [ RUN ] TransformFunctionsTest.CreateScaleTransform [00027.132579][4599][4618][klog] INFO: [ OK ] TransformFunctionsTest.CreateScaleTransform (0 ms) [00027.132579][4599][4618][klog] INFO: [----------] 7 tests from TransformFunctionsTest (1 ms total) [00027.132580][4599][4618][klog] INFO: [00027.132580][4599][4618][klog] INFO: [----------] Global test environment tear-down [00027.132580][4599][4618][klog] INFO: [==========] 10 tests from 4 test cases ran. (1 ms total) [00027.132581][4599][4618][klog] INFO: [ PASSED ] 10 tests. [00027.268621][4599][4618][klog] INFO: PASSED: /pkgfs/packages/scenic_tests/0/test/geometry_util_unittests passed [00027.329540][4599][4618][klog] INFO: [00027.329574][4599][4618][klog] INFO: ------------------------------------------------ [00027.329577][4599][4618][klog] INFO: RUNNING TEST: /pkgfs/packages/scenic_tests/0/test/gfx_apptests [00027.329577][4599][4618][klog] INFO: [00027.456306][4599][4618][klog] INFO: Running main() from gtest_main.cc [00027.456416][4599][4618][klog] INFO: [==========] Running 0 tests from 0 test cases. [00027.456417][4599][4618][klog] INFO: [==========] 0 tests from 0 test cases ran. (0 ms total) [00027.456417][4599][4618][klog] INFO: [ PASSED ] 0 tests. [00027.456418][4599][4618][klog] INFO: [00027.456419][4599][4618][klog] INFO: YOU HAVE 4 DISABLED TESTS [00027.456419][4599][4618][klog] INFO: [00027.461329][4599][4618][klog] INFO: PASSED: /pkgfs/packages/scenic_tests/0/test/gfx_apptests passed [00027.521688][4599][4618][klog] INFO: [00027.521728][4599][4618][klog] INFO: ------------------------------------------------ [00027.521731][4599][4618][klog] INFO: RUNNING TEST: /pkgfs/packages/scenic_tests/0/test/gfx_unittests [00027.521732][4599][4618][klog] INFO: [00027.667150][4599][4618][klog] INFO: [==========] Running 113 tests from 13 test cases. [00027.667185][4599][4618][klog] INFO: [----------] Global test environment set-up. [00027.667186][4599][4618][klog] INFO: [----------] 5 tests from HLATest [00027.667187][4599][4618][klog] INFO: [ RUN ] HLATest.HasHardwareLayerAssignment [00027.667187][4599][4618][klog] INFO: [00027.667187][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="1793195341" [00027.667188][4599][4618][klog] INFO: [ OK ] HLATest.HasHardwareLayerAssignment (0 ms) [00027.667188][4599][4618][klog] INFO: [ RUN ] HLATest.HardwareLayerAssignmentMissingSwapchain [00027.667189][4599][4618][klog] INFO: [00027.667189][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="3082729115" [00027.667192][4599][4618][klog] INFO: [WARNING:garnet/lib/ui/gfx/engine/hardware_layer_assignment.cc(16)] Invalid HardwareLayerAssignment: no swapchain. [00027.667193][4599][4618][klog] INFO: [ OK ] HLATest.HardwareLayerAssignmentMissingSwapchain (0 ms) [00027.667193][4599][4618][klog] INFO: [ RUN ] HLATest.HardwareLayerAssignmentMissingLayers [00027.667193][4599][4618][klog] INFO: [00027.667194][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="1087157546" [00027.667196][4599][4618][klog] INFO: [WARNING:garnet/lib/ui/gfx/engine/hardware_layer_assignment.cc(26)] Invalid HardwareLayerAssignment: item with no layers. [00027.668442][4599][4618][klog] INFO: [ OK ] HLATest.HardwareLayerAssignmentMissingLayers (0 ms) [00027.668513][4599][4618][klog] INFO: [ RUN ] HLATest.HardwareLayerAssignmentMissingItems [00027.668513][4599][4618][klog] INFO: [00027.668514][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="3222135443" [00027.668514][4599][4618][klog] INFO: [WARNING:garnet/lib/ui/gfx/engine/hardware_layer_assignment.cc(19)] Invalid HardwareLayerAssignment: no items. [00027.668514][4599][4618][klog] INFO: [ OK ] HLATest.HardwareLayerAssignmentMissingItems (1 ms) [00027.668515][4599][4618][klog] INFO: [ RUN ] HLATest.HardwareLayerAssignmentDuplicateLayerIDs [00027.668515][4599][4618][klog] INFO: [00027.668516][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="3234218236" [00027.668516][4599][4618][klog] INFO: [WARNING:garnet/lib/ui/gfx/engine/hardware_layer_assignment.cc(32)] Invalid HardwareLayerAssignment: duplicate layer ID [00027.668519][4599][4618][klog] INFO: [ OK ] HLATest.HardwareLayerAssignmentDuplicateLayerIDs (0 ms) [00027.668520][4599][4618][klog] INFO: [----------] 5 tests from HLATest (1 ms total) [00027.668520][4599][4618][klog] INFO: [00027.668520][4599][4618][klog] INFO: [----------] 1 test from MultiSessionHitTestTest [00027.668521][4599][4618][klog] INFO: [ RUN ] MultiSessionHitTestTest.GlobalHits [00027.668521][4599][4618][klog] INFO: [00027.668521][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="390397200" [00027.816063][4599][4618][klog] INFO: [WARNING:garnet/lib/ui/scenic/event_reporter.cc(23)] EventReporter not set up, dropped event: ViewConnectedEvent(view_id=1008) [00027.816157][4599][4618][klog] INFO: [WARNING:garnet/lib/ui/scenic/event_reporter.cc(23)] EventReporter not set up, dropped event: ViewAttachedToSceneEvent(view_id=2001, properties=<TBD> [00027.816159][4599][4618][klog] INFO: [WARNING:garnet/lib/ui/scenic/event_reporter.cc(23)] EventReporter not set up, dropped event: ViewPropertiesChangedEvent(view_id=2001, properties=<TBD>) [00027.816160][4599][4618][klog] INFO: [WARNING:garnet/lib/ui/scenic/event_reporter.cc(23)] EventReporter not set up, dropped event: ViewConnectedEvent(view_id=1009) [00027.816161][4599][4618][klog] INFO: [WARNING:garnet/lib/ui/scenic/event_reporter.cc(23)] EventReporter not set up, dropped event: ViewAttachedToSceneEvent(view_id=3001, properties=<TBD> [00027.816162][4599][4618][klog] INFO: [WARNING:garnet/lib/ui/scenic/event_reporter.cc(23)] EventReporter not set up, dropped event: ViewPropertiesChangedEvent(view_id=3001, properties=<TBD>) [00027.816162][4599][4618][klog] INFO: [WARNING:garnet/lib/ui/scenic/event_reporter.cc(23)] EventReporter not set up, dropped event: ViewDisconnectedEvent(view_id=1009) [00027.816167][4599][4618][klog] INFO: [WARNING:garnet/lib/ui/scenic/event_reporter.cc(23)] EventReporter not set up, dropped event: ViewDisconnectedEvent(view_id=1008) [00027.816167][4599][4618][klog] INFO: [ OK ] MultiSessionHitTestTest.GlobalHits (148 ms) [00027.816169][4599][4618][klog] INFO: [----------] 1 test from MultiSessionHitTestTest (148 ms total) [00027.816169][4599][4618][klog] INFO: [00027.816170][4599][4618][klog] INFO: [----------] 17 tests from HitTestTest [00027.816170][4599][4618][klog] INFO: [ RUN ] HitTestTest.InvalidNodeId [00027.816171][4599][4618][klog] INFO: [00027.816171][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="748273904" [00027.817368][4599][4618][klog] INFO: [ OK ] HitTestTest.InvalidNodeId (1 ms) [00027.817401][4599][4618][klog] INFO: [ RUN ] HitTestTest.RayBelowScenePointingDown [00027.817401][4599][4618][klog] INFO: [00027.817402][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="1573764988" [00027.840705][4599][4618][klog] INFO: [ OK ] HitTestTest.RayBelowScenePointingDown (12 ms) [00027.840784][4599][4618][klog] INFO: [ RUN ] HitTestTest.RayBelowScenePointingUp [00027.840806][4599][4618][klog] INFO: [00027.840806][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="107776354" [00027.851483][4599][4618][klog] INFO: [ OK ] HitTestTest.RayBelowScenePointingUp (13 ms) [00027.851646][4599][4618][klog] INFO: [ RUN ] HitTestTest.RayAboveScenePointingUp [00027.851647][4599][4618][klog] INFO: [00027.851647][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="551940795" [00027.962660][4599][4618][klog] INFO: [ OK ] HitTestTest.RayAboveScenePointingUp (120 ms) [00027.962692][4599][4618][klog] INFO: [ RUN ] HitTestTest.Hit10InTopLeftCornerFromNode1 [00027.962747][4599][4618][klog] INFO: [00027.962750][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="1879659545" [00027.979713][4599][4618][klog] INFO: [ OK ] HitTestTest.Hit10InTopLeftCornerFromNode1 (0 ms) [00027.979741][4599][4618][klog] INFO: [ RUN ] HitTestTest.Hit10InTopLeftCornerFromNode2 [00027.979766][4599][4618][klog] INFO: [00027.979768][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="120552663" [00027.979772][4599][4618][klog] INFO: [ OK ] HitTestTest.Hit10InTopLeftCornerFromNode2 (16 ms) [00027.979793][4599][4618][klog] INFO: [ RUN ] HitTestTest.Hit10InTopLeftCornerFromNode3 [00027.979794][4599][4618][klog] INFO: [00027.979794][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="1818002127" [00027.980013][4599][4618][klog] INFO: [ OK ] HitTestTest.Hit10InTopLeftCornerFromNode3 (0 ms) [00027.980040][4599][4618][klog] INFO: [ RUN ] HitTestTest.Hit10InTopLeftCornerFromNode4 [00027.980041][4599][4618][klog] INFO: [00027.980066][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2855135219" [00027.980069][4599][4618][klog] INFO: [ OK ] HitTestTest.Hit10InTopLeftCornerFromNode4 (1 ms) [00027.980097][4599][4618][klog] INFO: [ RUN ] HitTestTest.Hit20InMiddleFromNode1 [00027.980098][4599][4618][klog] INFO: [00027.980098][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2290819424" [00027.980930][4599][4618][klog] INFO: [ OK ] HitTestTest.Hit20InMiddleFromNode1 (1 ms) [00027.980966][4599][4618][klog] INFO: [ RUN ] HitTestTest.Hit20InMiddleFromNode2 [00027.980967][4599][4618][klog] INFO: [00027.980967][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2958158266" [00028.050557][4599][4618][klog] INFO: [ OK ] HitTestTest.Hit20InMiddleFromNode2 (69 ms) [00028.050596][4599][4618][klog] INFO: [ RUN ] HitTestTest.Hit20InMiddleFromNode3 [00028.050597][4599][4618][klog] INFO: [00028.050597][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2955359068" [00028.051058][4599][4618][klog] INFO: [ OK ] HitTestTest.Hit20InMiddleFromNode3 (0 ms) [00028.051102][4599][4618][klog] INFO: [ RUN ] HitTestTest.Hit20InMiddleFromNode5 [00028.051103][4599][4618][klog] INFO: [00028.051103][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="1752238117" [00028.078201][4599][4618][klog] INFO: [ OK ] HitTestTest.Hit20InMiddleFromNode5 (27 ms) [00028.078254][4599][4618][klog] INFO: [ RUN ] HitTestTest.Hit20InMiddleFromNode6 [00028.078283][4599][4618][klog] INFO: [00028.078283][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="1529491143" [00028.095264][4599][4618][klog] INFO: [ OK ] HitTestTest.Hit20InMiddleFromNode6 (1 ms) [00028.095305][4599][4618][klog] INFO: [ RUN ] HitTestTest.HitBoth10And20FromNode1 [00028.095306][4599][4618][klog] INFO: [00028.095308][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="3172737438" [00028.095313][4599][4618][klog] INFO: [ OK ] HitTestTest.HitBoth10And20FromNode1 (16 ms) [00028.095313][4599][4618][klog] INFO: [ RUN ] HitTestTest.HitBoth20And30FromNode1 [00028.095315][4599][4618][klog] INFO: [00028.095316][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="1339227181" [00028.095316][4599][4618][klog] INFO: [ OK ] HitTestTest.HitBoth20And30FromNode1 (0 ms) [00028.095317][4599][4618][klog] INFO: [ RUN ] HitTestTest.SuppressNode25FromNode1 [00028.095317][4599][4618][klog] INFO: [00028.095318][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2373777594" [00028.096636][4599][4618][klog] INFO: [ OK ] HitTestTest.SuppressNode25FromNode1 (2 ms) [00028.096666][4599][4618][klog] INFO: [ RUN ] HitTestTest.Clipping [00028.096666][4599][4618][klog] INFO: [00028.096666][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="1135949933" [00028.100669][4599][4618][klog] INFO: [ OK ] HitTestTest.Clipping (1 ms) [00028.100697][4599][4618][klog] INFO: [----------] 17 tests from HitTestTest (282 ms total) [00028.100792][4599][4618][klog] INFO: [00028.100793][4599][4618][klog] INFO: [----------] 7 tests from ImagePipeTest [00028.100793][4599][4618][klog] INFO: [ RUN ] ImagePipeTest.ImagePipeImageIdMustNotBeZero [00028.100794][4599][4618][klog] INFO: [00028.100794][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="4194740902" [00028.101673][4599][4618][klog] INFO: [WARNING:garnet/lib/ui/gfx/engine/engine.cc(138)] No FrameScheduler available; applying update immediately [00028.181554][4599][4618][klog] INFO: [ OK ] ImagePipeTest.ImagePipeImageIdMustNotBeZero (84 ms) [00028.181599][4599][4618][klog] INFO: [ RUN ] ImagePipeTest.PresentImagesOutOfOrder [00028.181645][4599][4618][klog] INFO: [00028.181646][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2727545706" [00028.183607][4599][4618][klog] INFO: [WARNING:garnet/lib/ui/gfx/engine/engine.cc(138)] No FrameScheduler available; applying update immediately [00028.183636][4599][4618][klog] INFO: [INFO:image.cc(62)] Destroying Image with unowned VkImage (perhaps a swapchain image?) [00028.209528][4599][4618][klog] INFO: [ OK ] ImagePipeTest.PresentImagesOutOfOrder (27 ms) [00028.209554][4599][4618][klog] INFO: [ RUN ] ImagePipeTest.PresentImagesInOrder [00028.209557][4599][4618][klog] INFO: [00028.209558][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="615958621" [00028.210092][4599][4618][klog] INFO: [INFO:image.cc(62)] Destroying Image with unowned VkImage (perhaps a swapchain image?) [00028.210519][4599][4618][klog] INFO: [ OK ] ImagePipeTest.PresentImagesInOrder (1 ms) [00028.210520][4599][4618][klog] INFO: [ RUN ] ImagePipeTest.PresentImagesWithOffset [00028.210520][4599][4618][klog] INFO: [00028.210521][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="3127932562" [00028.243823][4599][4618][klog] INFO: [INFO:image.cc(62)] Destroying Image with unowned VkImage (perhaps a swapchain image?) [00028.244477][4599][4618][klog] INFO: [ OK ] ImagePipeTest.PresentImagesWithOffset (34 ms) [00028.244502][4599][4618][klog] INFO: [ RUN ] ImagePipeTest.ImagePipePresentTwoFrames [00028.244502][4599][4618][klog] INFO: [00028.244503][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="1426695491" [00028.251308][4599][4618][klog] INFO: [WARNING:garnet/lib/ui/gfx/engine/engine.cc(138)] No FrameScheduler available; applying update immediately [00028.251471][4599][4618][klog] INFO: [WARNING:garnet/lib/ui/gfx/engine/engine.cc(138)] No FrameScheduler available; applying update immediately [00028.251506][4599][4618][klog] INFO: [INFO:image.cc(62)] Destroying Image with unowned VkImage (perhaps a swapchain image?) [00028.251507][4599][4618][klog] INFO: [INFO:image.cc(62)] Destroying Image with unowned VkImage (perhaps a swapchain image?) [00028.298731][4599][4618][klog] INFO: [ OK ] ImagePipeTest.ImagePipePresentTwoFrames (53 ms) [00028.298773][4599][4618][klog] INFO: [ RUN ] ImagePipeTest.ImagePipeUpdateTwoFrames [00028.298773][4599][4618][klog] INFO: [00028.298774][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="924245810" [00028.298777][4599][4618][klog] INFO: [WARNING:garnet/lib/ui/gfx/engine/engine.cc(138)] No FrameScheduler available; applying update immediately [00028.298778][4599][4618][klog] INFO: [WARNING:garnet/lib/ui/gfx/engine/engine.cc(138)] No FrameScheduler available; applying update immediately [00028.298778][4599][4618][klog] INFO: [WARNING:garnet/lib/ui/gfx/engine/engine.cc(138)] No FrameScheduler available; applying update immediately [00028.298779][4599][4618][klog] INFO: [WARNING:garnet/lib/ui/gfx/engine/engine.cc(138)] No FrameScheduler available; applying update immediately [00028.298780][4599][4618][klog] INFO: [INFO:image.cc(62)] Destroying Image with unowned VkImage (perhaps a swapchain image?) [00028.298817][4599][4618][klog] INFO: [INFO:image.cc(62)] Destroying Image with unowned VkImage (perhaps a swapchain image?) [00028.311297][4599][4618][klog] INFO: [ OK ] ImagePipeTest.ImagePipeUpdateTwoFrames (13 ms) [00028.311360][4599][4618][klog] INFO: [ RUN ] ImagePipeTest.ImagePipeRemoveImageThatIsPendingPresent [00028.311393][4599][4618][klog] INFO: [00028.311393][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="91080452" [00028.316385][4599][4618][klog] INFO: [WARNING:garnet/lib/ui/gfx/engine/engine.cc(138)] No FrameScheduler available; applying update immediately [00028.316683][4599][4618][klog] INFO: [WARNING:garnet/lib/ui/gfx/engine/engine.cc(138)] No FrameScheduler available; applying update immediately [00028.323753][4599][4618][klog] INFO: [INFO:image.cc(62)] Destroying Image with unowned VkImage (perhaps a swapchain image?) [00028.323779][4599][4618][klog] INFO: [INFO:image.cc(62)] Destroying Image with unowned VkImage (perhaps a swapchain image?) [00028.323782][4599][4618][klog] INFO: [ OK ] ImagePipeTest.ImagePipeRemoveImageThatIsPendingPresent (7 ms) [00028.323782][4599][4618][klog] INFO: [----------] 7 tests from ImagePipeTest (220 ms total) [00028.323783][4599][4618][klog] INFO: [00028.323783][4599][4618][klog] INFO: [----------] 16 tests from ImportTest [00028.323784][4599][4618][klog] INFO: [ RUN ] ImportTest.ExportsResourceViaCmd [00028.323784][4599][4618][klog] INFO: [00028.323785][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="545064396" [00028.330583][4599][4618][klog] INFO: [ OK ] ImportTest.ExportsResourceViaCmd (6 ms) [00028.330605][4599][4618][klog] INFO: [ RUN ] ImportTest.ImportsUnlinkedImportViaCmd [00028.330606][4599][4618][klog] INFO: [00028.330606][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="3640239181" [00028.438629][4599][4618][klog] INFO: [ OK ] ImportTest.ImportsUnlinkedImportViaCmd (115 ms) [00028.438654][4599][4618][klog] INFO: [ RUN ] ImportTest.PerformsFullLinking [00028.438654][4599][4618][klog] INFO: [00028.438655][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="781987352" [00028.439624][4599][4618][klog] INFO: [ OK ] ImportTest.PerformsFullLinking (1 ms) [00028.439646][4599][4618][klog] INFO: [ RUN ] ImportTest.HandlesDeadSourceHandle [00028.439646][4599][4618][klog] INFO: [00028.439647][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2990685889" [00028.467787][4599][4618][klog] INFO: [ OK ] ImportTest.HandlesDeadSourceHandle (27 ms) [00028.472286][4599][4618][klog] INFO: [ RUN ] ImportTest.HandlesDeadDestinationHandle [00028.477104][4599][4618][klog] INFO: [00028.477715][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2776978448" [00028.482625][4599][4618][klog] INFO: [ OK ] ImportTest.HandlesDeadDestinationHandle (16 ms) [00028.482730][4599][4618][klog] INFO: [ RUN ] ImportTest.DestroyingExportedResourceSendsEvent [00028.482731][4599][4618][klog] INFO: [00028.482732][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2347431516" [00028.498638][4599][4618][klog] INFO: [ OK ] ImportTest.DestroyingExportedResourceSendsEvent (15 ms) [00028.498662][4599][4618][klog] INFO: [ RUN ] ImportTest.ImportingNodeAfterDestroyingExportedResourceSendsEvent [00028.498664][4599][4618][klog] INFO: [00028.498664][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="955989757" [00028.498667][4599][4618][klog] INFO: [ OK ] ImportTest.ImportingNodeAfterDestroyingExportedResourceSendsEvent (0 ms) [00028.498668][4599][4618][klog] INFO: [ RUN ] ImportTest.KillingImportedResourceEvictsFromResourceLinker [00028.498668][4599][4618][klog] INFO: [00028.498668][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="3686595778" [00028.499360][4599][4618][klog] INFO: [ OK ] ImportTest.KillingImportedResourceEvictsFromResourceLinker (1 ms) [00028.499395][4599][4618][klog] INFO: [ RUN ] ImportTest.ResourceUnexportedAfterImportsAndImportHandlesDie1 [00028.499396][4599][4618][klog] INFO: [00028.499397][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="1018586515" [00028.509549][4599][4618][klog] INFO: [ OK ] ImportTest.ResourceUnexportedAfterImportsAndImportHandlesDie1 (5 ms) [00028.509581][4599][4618][klog] INFO: [ RUN ] ImportTest.ResourceUnexportedAfterImportsAndImportHandlesDie2 [00028.509583][4599][4618][klog] INFO: [00028.509583][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="372433555" [00028.510320][4599][4618][klog] INFO: [ OK ] ImportTest.ResourceUnexportedAfterImportsAndImportHandlesDie2 (5 ms) [00028.510375][4599][4618][klog] INFO: [ RUN ] ImportTest.ResourceUnexportedAfterImportsAndImportHandlesDie3 [00028.510375][4599][4618][klog] INFO: [00028.510376][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2179786939" [00028.585103][4599][4618][klog] INFO: [ OK ] ImportTest.ResourceUnexportedAfterImportsAndImportHandlesDie3 (2 ms) [00028.585127][4599][4618][klog] INFO: [ RUN ] ImportTest.ResourceUnexportedAfterImportsAndImportHandlesDie4 [00028.585128][4599][4618][klog] INFO: [00028.585128][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2629547058" [00028.585129][4599][4618][klog] INFO: [ OK ] ImportTest.ResourceUnexportedAfterImportsAndImportHandlesDie4 (4 ms) [00028.585157][4599][4618][klog] INFO: [ RUN ] ImportTest.ProxiesCanBeFoundByTheirContainerOrTheirUnderlyingEntityType [00028.585185][4599][4618][klog] INFO: [00028.585185][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2121285299" [00028.585186][4599][4618][klog] INFO: [ OK ] ImportTest.ProxiesCanBeFoundByTheirContainerOrTheirUnderlyingEntityType (7 ms) [00028.585186][4599][4618][klog] INFO: [ RUN ] ImportTest.UnlinkedImportedResourceCanAcceptCommands [00028.585187][4599][4618][klog] INFO: [00028.585190][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="3233457727" [00028.585190][4599][4618][klog] INFO: [ OK ] ImportTest.UnlinkedImportedResourceCanAcceptCommands (5 ms) [00028.585191][4599][4618][klog] INFO: [ RUN ] ImportTest.LinkedResourceShouldBeAbleToAcceptCommands [00028.585191][4599][4618][klog] INFO: [00028.585192][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="4099745754" [00028.585192][4599][4618][klog] INFO: [ OK ] ImportTest.LinkedResourceShouldBeAbleToAcceptCommands (3 ms) [00028.585192][4599][4618][klog] INFO: [ RUN ] ImportTest.EmbedderCanEmbedNodesFromElsewhere [00028.585193][4599][4618][klog] INFO: [00028.585193][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2834325342" [00028.585193][4599][4618][klog] INFO: [ OK ] ImportTest.EmbedderCanEmbedNodesFromElsewhere (3 ms) [00028.585199][4599][4618][klog] INFO: [----------] 16 tests from ImportTest (216 ms total) [00028.585200][4599][4618][klog] INFO: [00028.585200][4599][4618][klog] INFO: [----------] 1 test from MemoryTest [00028.585201][4599][4618][klog] INFO: [ RUN ] MemoryTest.MemoryAllocationSizeValidation [00028.585201][4599][4618][klog] INFO: [00028.585201][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="1835432650" [00028.585202][4599][4618][klog] INFO: [ OK ] MemoryTest.MemoryAllocationSizeValidation (6 ms) [00028.585202][4599][4618][klog] INFO: [----------] 1 test from MemoryTest (6 ms total) [00028.585202][4599][4618][klog] INFO: [00028.585203][4599][4618][klog] INFO: [----------] 4 tests from NodeTest [00028.585203][4599][4618][klog] INFO: [ RUN ] NodeTest.Tagging [00028.585203][4599][4618][klog] INFO: [00028.585204][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="1648570497" [00028.585204][4599][4618][klog] INFO: [ OK ] NodeTest.Tagging (6 ms) [00028.585204][4599][4618][klog] INFO: [ RUN ] NodeTest.ShapeNodeMaterialAndShape [00028.585205][4599][4618][klog] INFO: [00028.585205][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2958194149" [00028.585205][4599][4618][klog] INFO: [ OK ] NodeTest.ShapeNodeMaterialAndShape (1 ms) [00028.585206][4599][4618][klog] INFO: [ RUN ] NodeTest.NodesWithChildren [00028.585206][4599][4618][klog] INFO: [00028.585206][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="3621325040" [00028.585207][4599][4618][klog] INFO: [ OK ] NodeTest.NodesWithChildren (4 ms) [00028.585207][4599][4618][klog] INFO: [ RUN ] NodeTest.SettingHitTestBehavior [00028.585208][4599][4618][klog] INFO: [00028.585208][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="1018953300" [00028.585209][4599][4618][klog] INFO: [ OK ] NodeTest.SettingHitTestBehavior (6 ms) [00028.585209][4599][4618][klog] INFO: [----------] 4 tests from NodeTest (17 ms total) [00028.585210][4599][4618][klog] INFO: [00028.585210][4599][4618][klog] INFO: [----------] 21 tests from ObjectLinkerTest [00028.585210][4599][4618][klog] INFO: [ RUN ] ObjectLinkerTest.InitialState [00028.585212][4599][4618][klog] INFO: [00028.585212][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="890074604" [00028.786500][4599][4618][klog] INFO: [ OK ] ObjectLinkerTest.InitialState (0 ms) [00028.786534][4599][4618][klog] INFO: [ RUN ] ObjectLinkerTest.AllowsExport [00028.786535][4599][4618][klog] INFO: [00028.786574][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="4002088391" [00028.786575][4599][4618][klog] INFO: [ OK ] ObjectLinkerTest.AllowsExport (0 ms) [00028.786576][4599][4618][klog] INFO: [ RUN ] ObjectLinkerTest.CannotExportInvalidToken [00028.786576][4599][4618][klog] INFO: [00028.786576][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="863502708" [00028.786577][4599][4618][klog] INFO: [ OK ] ObjectLinkerTest.CannotExportInvalidToken (0 ms) [00028.786578][4599][4618][klog] INFO: [ RUN ] ObjectLinkerTest.CannotExportWithDeadExportToken [00028.786578][4599][4618][klog] INFO: [00028.786579][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="483599952" [00028.786579][4599][4618][klog] INFO: [ OK ] ObjectLinkerTest.CannotExportWithDeadExportToken (0 ms) [00028.786580][4599][4618][klog] INFO: [ RUN ] ObjectLinkerTest.CanExportWithDeadImportToken [00028.786580][4599][4618][klog] INFO: [00028.786580][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="1092132051" [00028.786581][4599][4618][klog] INFO: [ OK ] ObjectLinkerTest.CanExportWithDeadImportToken (0 ms) [00028.786581][4599][4618][klog] INFO: [ RUN ] ObjectLinkerTest.CannotExportSameTokenTwice [00028.786581][4599][4618][klog] INFO: [00028.786582][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="1768638082" [00028.786582][4599][4618][klog] INFO: [ OK ] ObjectLinkerTest.CannotExportSameTokenTwice (0 ms) [00028.786582][4599][4618][klog] INFO: [ RUN ] ObjectLinkerTest.LinkDeathRemovesExport [00028.786583][4599][4618][klog] INFO: [00028.786583][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2730141776" [00028.786583][4599][4618][klog] INFO: [ OK ] ObjectLinkerTest.LinkDeathRemovesExport (0 ms) [00028.786584][4599][4618][klog] INFO: [ RUN ] ObjectLinkerTest.AllowsImport [00028.786584][4599][4618][klog] INFO: [00028.786584][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="493838544" [00028.786590][4599][4618][klog] INFO: [ OK ] ObjectLinkerTest.AllowsImport (0 ms) [00028.786591][4599][4618][klog] INFO: [ RUN ] ObjectLinkerTest.CannotImportInvalidToken [00028.786695][4599][4618][klog] INFO: [00028.786723][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="1814860418" [00028.786724][4599][4618][klog] INFO: [ OK ] ObjectLinkerTest.CannotImportInvalidToken (0 ms) [00028.786724][4599][4618][klog] INFO: [ RUN ] ObjectLinkerTest.CannotImportWithDeadImportToken [00028.786725][4599][4618][klog] INFO: [00028.786725][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2423356678" [00028.786725][4599][4618][klog] INFO: [ OK ] ObjectLinkerTest.CannotImportWithDeadImportToken (0 ms) [00028.786726][4599][4618][klog] INFO: [ RUN ] ObjectLinkerTest.CanImportWithDeadExportToken [00028.786726][4599][4618][klog] INFO: [00028.786726][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="3495711401" [00028.786727][4599][4618][klog] INFO: [ OK ] ObjectLinkerTest.CanImportWithDeadExportToken (0 ms) [00028.786727][4599][4618][klog] INFO: [ RUN ] ObjectLinkerTest.CannotImportSameTokenTwice [00028.786727][4599][4618][klog] INFO: [00028.786728][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2091612534" [00028.786728][4599][4618][klog] INFO: [ OK ] ObjectLinkerTest.CannotImportSameTokenTwice (0 ms) [00028.786728][4599][4618][klog] INFO: [ RUN ] ObjectLinkerTest.LinkDeathRemovesImport [00028.786729][4599][4618][klog] INFO: [00028.786729][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="1624091620" [00028.786729][4599][4618][klog] INFO: [ OK ] ObjectLinkerTest.LinkDeathRemovesImport (0 ms) [00028.786730][4599][4618][klog] INFO: [ RUN ] ObjectLinkerTest.InitializingLinkTwiceCausesDeath [00028.786731][4599][4618][klog] INFO: [00028.786731][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="1175119278" [00028.786731][4599][4618][klog] INFO: [00028.795827][4599][4618][klog] INFO: [WARNING] ../../garnet/lib/ui/gfx/tests/object_linker_unittest.cc:264:: Death tests are not supported on this platform. [00028.795865][4599][4618][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 [00028.795901][4599][4618][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( [00028.795904][4599][4618][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 [00028.795928][4599][4618][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()) [00028.795950][4599][4618][klog] INFO: = ::testing::Message() << "Delegate called unexpectedly: " << "export.link_disconnected"; }))' cannot be verified. [00028.795957][4599][4618][klog] INFO: [ OK ] ObjectLinkerTest.InitializingLinkTwiceCausesDeath (0 ms) [00028.795981][4599][4618][klog] INFO: [ RUN ] ObjectLinkerTest.InitializeLinksMatchingPeers [00028.795982][4599][4618][klog] INFO: [00028.795983][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="3534706820" [00028.795983][4599][4618][klog] INFO: [ OK ] ObjectLinkerTest.InitializeLinksMatchingPeers (0 ms) [00028.795984][4599][4618][klog] INFO: [ RUN ] ObjectLinkerTest.InitializeLinksMatchingPeersWithImportBeforeExport [00028.795984][4599][4618][klog] INFO: [00028.795984][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="29498865" [00028.795985][4599][4618][klog] INFO: [ OK ] ObjectLinkerTest.InitializeLinksMatchingPeersWithImportBeforeExport (1 ms) [00028.795985][4599][4618][klog] INFO: [ RUN ] ObjectLinkerTest.InitializeDoesNotLinkNonMatchingPeers [00028.795986][4599][4618][klog] INFO: [00028.795986][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="3113526846" [00028.795987][4599][4618][klog] INFO: [ OK ] ObjectLinkerTest.InitializeDoesNotLinkNonMatchingPeers (0 ms) [00028.795987][4599][4618][klog] INFO: [ RUN ] ObjectLinkerTest.EarlyImportTokenDeathCausesExportDisconnection [00028.795988][4599][4618][klog] INFO: [00028.795988][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2934413346" [00028.795989][4599][4618][klog] INFO: [ OK ] ObjectLinkerTest.EarlyImportTokenDeathCausesExportDisconnection (0 ms) [00028.795989][4599][4618][klog] INFO: [ RUN ] ObjectLinkerTest.ImportTokenDeathCausesExportDisconnection [00028.795990][4599][4618][klog] INFO: [00028.795990][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2411594994" [00028.795990][4599][4618][klog] INFO: [ OK ] ObjectLinkerTest.ImportTokenDeathCausesExportDisconnection (0 ms) [00028.799633][4599][4618][klog] INFO: [ RUN ] ObjectLinkerTest.EarlyExportTokenDeathCausesImportDisconnection [00028.799662][4599][4618][klog] INFO: [00028.799662][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="3244829147" [00028.799663][4599][4618][klog] INFO: [ OK ] ObjectLinkerTest.EarlyExportTokenDeathCausesImportDisconnection (0 ms) [00028.799664][4599][4618][klog] INFO: [ RUN ] ObjectLinkerTest.ExportTokenDeathCausesImportDisconnection [00028.799664][4599][4618][klog] INFO: [00028.799665][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2261406467" [00028.799665][4599][4618][klog] INFO: [ OK ] ObjectLinkerTest.ExportTokenDeathCausesImportDisconnection (0 ms) [00028.799666][4599][4618][klog] INFO: [----------] 21 tests from ObjectLinkerTest (1 ms total) [00028.799666][4599][4618][klog] INFO: [00028.799667][4599][4618][klog] INFO: [----------] 15 tests from ResourceLinkerTest [00028.799668][4599][4618][klog] INFO: [ RUN ] ResourceLinkerTest.AllowsExport [00028.799668][4599][4618][klog] INFO: [00028.799668][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="3587070835" [00028.799669][4599][4618][klog] INFO: [ OK ] ResourceLinkerTest.AllowsExport (5 ms) [00028.799670][4599][4618][klog] INFO: [ RUN ] ResourceLinkerTest.AllowsImport [00028.799671][4599][4618][klog] INFO: [00028.799671][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="478551896" [00028.799672][4599][4618][klog] INFO: [ OK ] ResourceLinkerTest.AllowsImport (2 ms) [00028.799673][4599][4618][klog] INFO: [ RUN ] ResourceLinkerTest.CannotImportWithDeadSourceAndDestinationHandles [00028.799673][4599][4618][klog] INFO: [00028.799674][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="3060205238" [00028.799675][4599][4618][klog] INFO: [ OK ] ResourceLinkerTest.CannotImportWithDeadSourceAndDestinationHandles (3 ms) [00028.799675][4599][4618][klog] INFO: [ RUN ] ResourceLinkerTest.CannotImportWithDeadDestinationHandles [00028.799676][4599][4618][klog] INFO: [00028.799680][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="1498960833" [00028.799680][4599][4618][klog] INFO: [ OK ] ResourceLinkerTest.CannotImportWithDeadDestinationHandles (6 ms) [00028.799681][4599][4618][klog] INFO: [ RUN ] ResourceLinkerTest.CanImportWithDeadSourceHandle [00028.799682][4599][4618][klog] INFO: [00028.799682][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="3321489667" [00028.799683][4599][4618][klog] INFO: [ OK ] ResourceLinkerTest.CanImportWithDeadSourceHandle (6 ms) [00028.799683][4599][4618][klog] INFO: [ RUN ] ResourceLinkerTest.CannotExportWithDeadSourceAndDestinationHandles [00028.799683][4599][4618][klog] INFO: [00028.799684][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2490153673" [00028.799685][4599][4618][klog] INFO: [ OK ] ResourceLinkerTest.CannotExportWithDeadSourceAndDestinationHandles (1 ms) [00028.799685][4599][4618][klog] INFO: [ RUN ] ResourceLinkerTest.CannotExportWithDeadSourceHandle [00028.799685][4599][4618][klog] INFO: [00028.799686][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2114741797" [00028.799687][4599][4618][klog] INFO: [ OK ] ResourceLinkerTest.CannotExportWithDeadSourceHandle (4 ms) [00028.799687][4599][4618][klog] INFO: [ RUN ] ResourceLinkerTest.CanExportWithDeadDestinationHandle [00028.799687][4599][4618][klog] INFO: [00028.799688][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="1087579500" [00028.799688][4599][4618][klog] INFO: [ OK ] ResourceLinkerTest.CanExportWithDeadDestinationHandle (1 ms) [00028.799689][4599][4618][klog] INFO: [ RUN ] ResourceLinkerTest.DestinationHandleDeathAutomaticallyCleansUpResourceExport [00028.799689][4599][4618][klog] INFO: [00028.799690][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="1024811070" [00028.799690][4599][4618][klog] INFO: [ OK ] ResourceLinkerTest.DestinationHandleDeathAutomaticallyCleansUpResourceExport (201 ms) [00028.800302][4599][4618][klog] INFO: [ RUN ] ResourceLinkerTest.SourceHandleDeathAutomaticallyCleansUpUnresolvedImports [00028.800303][4599][4618][klog] INFO: [00028.800303][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="3019536961" [00028.800304][4599][4618][klog] INFO: [ OK ] ResourceLinkerTest.SourceHandleDeathAutomaticallyCleansUpUnresolvedImports (0 ms) [00028.800304][4599][4618][klog] INFO: [ RUN ] ResourceLinkerTest.ResourceDeathAutomaticallyCleansUpResourceExport [00028.800305][4599][4618][klog] INFO: [00028.800305][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="1467337055" [00028.800306][4599][4618][klog] INFO: [ OK ] ResourceLinkerTest.ResourceDeathAutomaticallyCleansUpResourceExport (4 ms) [00028.800306][4599][4618][klog] INFO: [ RUN ] ResourceLinkerTest.ImportsBeforeExportsAreServiced [00028.800307][4599][4618][klog] INFO: [00028.800307][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="1159733920" [00028.800308][4599][4618][klog] INFO: [ OK ] ResourceLinkerTest.ImportsBeforeExportsAreServiced (0 ms) [00028.800308][4599][4618][klog] INFO: [ RUN ] ResourceLinkerTest.ImportAfterReleasedExportedResourceFails [00028.800309][4599][4618][klog] INFO: [00028.800309][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="484581279" [00028.800310][4599][4618][klog] INFO: [ OK ] ResourceLinkerTest.ImportAfterReleasedExportedResourceFails (4 ms) [00028.800310][4599][4618][klog] INFO: [ RUN ] ResourceLinkerTest.DuplicatedDestinationHandlesAllowMultipleImports [00028.800311][4599][4618][klog] INFO: [00028.800311][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="473576579" [00028.800312][4599][4618][klog] INFO: [ OK ] ResourceLinkerTest.DuplicatedDestinationHandlesAllowMultipleImports (3 ms) [00028.800314][4599][4618][klog] INFO: [ RUN ] ResourceLinkerTest.UnresolvedImportIsRemovedIfDestroyed [00028.800314][4599][4618][klog] INFO: [00028.800315][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2163448670" [00028.800315][4599][4618][klog] INFO: [ OK ] ResourceLinkerTest.UnresolvedImportIsRemovedIfDestroyed (1 ms) [00028.800316][4599][4618][klog] INFO: [----------] 15 tests from ResourceLinkerTest (242 ms total) [00028.800317][4599][4618][klog] INFO: [00028.800317][4599][4618][klog] INFO: [----------] 6 tests from SessionTest [00028.800318][4599][4618][klog] INFO: [ RUN ] SessionTest.ScheduleUpdateOutOfOrder [00028.800318][4599][4618][klog] INFO: [00028.800319][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2682998588" [00028.804199][4599][4618][klog] INFO: [ OK ] SessionTest.ScheduleUpdateOutOfOrder (3 ms) [00028.804250][4599][4618][klog] INFO: [ RUN ] SessionTest.ScheduleUpdateInOrder [00028.804251][4599][4618][klog] INFO: [00028.804252][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2247577456" [00028.804252][4599][4618][klog] INFO: [ OK ] SessionTest.ScheduleUpdateInOrder (1 ms) [00028.804253][4599][4618][klog] INFO: [ RUN ] SessionTest.ResourceIdAlreadyUsed [00028.804253][4599][4618][klog] INFO: [00028.804254][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2751768126" [00028.804254][4599][4618][klog] INFO: [ OK ] SessionTest.ResourceIdAlreadyUsed (0 ms) [00028.804255][4599][4618][klog] INFO: [ RUN ] SessionTest.AddAndRemoveResource [00028.804255][4599][4618][klog] INFO: [00028.804256][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2302874809" [00028.809610][4599][4618][klog] INFO: [ OK ] SessionTest.AddAndRemoveResource (2 ms) [00028.809686][4599][4618][klog] INFO: [ RUN ] SessionTest.Labeling [00028.809717][4599][4618][klog] INFO: [00028.809718][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="1413517376" [00028.811081][4599][4618][klog] INFO: [ OK ] SessionTest.Labeling (5 ms) [00028.811112][4599][4618][klog] INFO: [ RUN ] SessionTest.SendingSizeChangeEventWorks [00028.811113][4599][4618][klog] INFO: [00028.811114][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2939406759" [00029.488069][4599][4618][klog] INFO: [ OK ] SessionTest.SendingSizeChangeEventWorks (675 ms) [00029.488453][4599][4618][klog] INFO: [----------] 6 tests from SessionTest (686 ms total) [00029.488793][4599][4618][klog] INFO: [00029.489191][4599][4618][klog] INFO: [----------] 2 tests from ShapeTest [00029.489431][4599][4618][klog] INFO: [ RUN ] ShapeTest.Circle [00029.493398][4599][4618][klog] INFO: [00029.493660][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2678359456" [00029.494149][4599][4618][klog] INFO: [ OK ] ShapeTest.Circle (0 ms) [00029.494267][4599][4618][klog] INFO: [ RUN ] ShapeTest.Rectangle [00029.494407][4599][4618][klog] INFO: [00029.494529][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="3831865027" [00029.502811][4599][4618][klog] INFO: [ OK ] ShapeTest.Rectangle (15 ms) [00029.502861][4599][4618][klog] INFO: [----------] 2 tests from ShapeTest (15 ms total) [00029.502862][4599][4618][klog] INFO: [00029.502863][4599][4618][klog] INFO: [----------] 1 test from StereoCameraTest [00029.502863][4599][4618][klog] INFO: [ RUN ] StereoCameraTest.Basic [00029.502864][4599][4618][klog] INFO: [00029.502864][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2181140613" [00029.543523][4599][4618][klog] INFO: [ OK ] StereoCameraTest.Basic (43 ms) [00029.543589][4599][4618][klog] INFO: [----------] 1 test from StereoCameraTest (43 ms total) [00029.543590][4599][4618][klog] INFO: [00029.543590][4599][4618][klog] INFO: [----------] 17 tests from ViewTest [00029.543591][4599][4618][klog] INFO: [ RUN ] ViewTest.CreateViewWithBadTokenDies [00029.543591][4599][4618][klog] INFO: [00029.543592][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="3173483755" [00029.543706][4599][4618][klog] INFO: [00029.543730][4599][4618][klog] INFO: [WARNING] ../../garnet/lib/ui/gfx/tests/view_unittest.cc:25:: Death tests are not supported on this platform. [00029.543761][4599][4618][klog] INFO: Statement 'Apply(scenic::NewCreateViewCmd(1, zx::eventpair(), ""))' cannot be verified. [00029.543792][4599][4618][klog] INFO: [00029.543793][4599][4618][klog] INFO: [WARNING] ../../garnet/lib/ui/gfx/tests/view_unittest.cc:27:: Death tests are not supported on this platform. [00029.543794][4599][4618][klog] INFO: Statement 'Apply(scenic::NewCreateViewHolderCmd(2, zx::eventpair(), ""))' cannot be verified. [00029.598050][4599][4618][klog] INFO: [ OK ] ViewTest.CreateViewWithBadTokenDies (53 ms) [00029.598078][4599][4618][klog] INFO: [ RUN ] ViewTest.Children [00029.598080][4599][4618][klog] INFO: [00029.598080][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="4254453534" [00029.598086][4599][4618][klog] INFO: [ OK ] ViewTest.Children (1 ms) [00029.598087][4599][4618][klog] INFO: [ RUN ] ViewTest.ExportsViewHolderViaCmd [00029.598087][4599][4618][klog] INFO: [00029.598088][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="1893480316" [00029.624873][4599][4618][klog] INFO: [ OK ] ViewTest.ExportsViewHolderViaCmd (27 ms) [00029.624929][4599][4618][klog] INFO: [ RUN ] ViewTest.ImportsViewViaCmd [00029.624930][4599][4618][klog] INFO: [00029.624930][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="3837457652" [00029.638582][4599][4618][klog] INFO: [ OK ] ViewTest.ImportsViewViaCmd (14 ms) [00029.638654][4599][4618][klog] INFO: [ RUN ] ViewTest.PairedViewAndHolderAreLinked [00029.638654][4599][4618][klog] INFO: [00029.638654][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2587474514" [00029.639142][4599][4618][klog] INFO: [ OK ] ViewTest.PairedViewAndHolderAreLinked (0 ms) [00029.639189][4599][4618][klog] INFO: [ RUN ] ViewTest.ExportViewHolderWithDeadHandleFails [00029.639189][4599][4618][klog] INFO: [00029.639190][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="84106416" [00029.666053][4599][4618][klog] INFO: [ OK ] ViewTest.ExportViewHolderWithDeadHandleFails (27 ms) [00029.666120][4599][4618][klog] INFO: [ RUN ] ViewTest.ViewHolderDestroyedBeforeView [00029.666120][4599][4618][klog] INFO: [00029.666121][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="964675820" [00029.666583][4599][4618][klog] INFO: [ OK ] ViewTest.ViewHolderDestroyedBeforeView (1 ms) [00029.666636][4599][4618][klog] INFO: [ RUN ] ViewTest.ViewDestroyedBeforeViewHolder [00029.666637][4599][4618][klog] INFO: [00029.666637][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2841281602" [00029.695579][4599][4618][klog] INFO: [ OK ] ViewTest.ViewDestroyedBeforeViewHolder (29 ms) [00029.695615][4599][4618][klog] INFO: [ RUN ] ViewTest.ViewHolderConnectsToScene [00029.695615][4599][4618][klog] INFO: [00029.695685][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="3549164368" [00029.711451][4599][4618][klog] INFO: [ OK ] ViewTest.ViewHolderConnectsToScene (14 ms) [00029.711528][4599][4618][klog] INFO: [ RUN ] ViewTest.ViewHolderGrandchildGetsSceneRefreshed [00029.711529][4599][4618][klog] INFO: [00029.711529][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="989444675" [00029.711532][4599][4618][klog] INFO: [ OK ] ViewTest.ViewHolderGrandchildGetsSceneRefreshed (2 ms) [00029.711533][4599][4618][klog] INFO: [ RUN ] ViewTest.ViewLinksAfterViewHolderConnectsToScene [00029.711534][4599][4618][klog] INFO: [00029.711534][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="1948976727" [00029.716816][4599][4618][klog] INFO: [ OK ] ViewTest.ViewLinksAfterViewHolderConnectsToScene (0 ms) [00029.717457][4599][4618][klog] INFO: [ RUN ] ViewTest.ViewStateChangeNotifiesViewHolder [00029.718111][4599][4618][klog] INFO: [00029.718627][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="116540425" [00029.721263][4599][4618][klog] INFO: [ OK ] ViewTest.ViewStateChangeNotifiesViewHolder (8 ms) [00029.721309][4599][4618][klog] INFO: [ RUN ] ViewTest.RenderStateAcrossManyFrames [00029.721311][4599][4618][klog] INFO: [00029.721312][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="130874365" [00029.721820][4599][4618][klog] INFO: [ OK ] ViewTest.RenderStateAcrossManyFrames (2 ms) [00029.721847][4599][4618][klog] INFO: [ RUN ] ViewTest.RenderStateFalseWhenViewDisconnects [00029.721847][4599][4618][klog] INFO: [00029.721949][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="1230775509" [00029.722211][4599][4618][klog] INFO: [ OK ] ViewTest.RenderStateFalseWhenViewDisconnects (0 ms) [00029.722236][4599][4618][klog] INFO: [ RUN ] ViewTest.ViewHolderRenderWaitClearedWhenViewDestroyed [00029.722236][4599][4618][klog] INFO: [00029.722237][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2034762842" [00029.724172][4599][4618][klog] INFO: [ OK ] ViewTest.ViewHolderRenderWaitClearedWhenViewDestroyed (2 ms) [00029.724200][4599][4618][klog] INFO: [ RUN ] ViewTest.RenderSignalDoesntCrashWhenViewHolderDestroyed [00029.724275][4599][4618][klog] INFO: [00029.724276][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="3608377498" [00029.733319][4599][4618][klog] INFO: [ OK ] ViewTest.RenderSignalDoesntCrashWhenViewHolderDestroyed (5 ms) [00029.733436][4599][4618][klog] INFO: [ RUN ] ViewTest.RenderStateFalseWhenViewHolderDisconnectsFromScene [00029.733437][4599][4618][klog] INFO: [00029.733437][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="3313199280" [00029.738440][4599][4618][klog] INFO: [ OK ] ViewTest.RenderStateFalseWhenViewHolderDisconnectsFromScene (5 ms) [00029.738474][4599][4618][klog] INFO: [----------] 17 tests from ViewTest (190 ms total) [00029.738474][4599][4618][klog] INFO: [00029.738475][4599][4618][klog] INFO: [----------] Global test environment tear-down [00029.738475][4599][4618][klog] INFO: [==========] 113 tests from 13 test cases ran. (2067 ms total) [00029.738476][4599][4618][klog] INFO: [ PASSED ] 113 tests. [00029.738476][4599][4618][klog] INFO: [00029.738476][4599][4618][klog] INFO: YOU HAVE 2 DISABLED TESTS [00029.738477][4599][4618][klog] INFO: [00029.741378][4599][4618][klog] INFO: PASSED: /pkgfs/packages/scenic_tests/0/test/gfx_unittests passed [00035.005266][4599][4618][klog] INFO: [00035.005298][4599][4618][klog] INFO: ------------------------------------------------ [00035.005301][4599][4618][klog] INFO: RUNNING TEST: /pkgfs/packages/scenic_tests/0/test/input_apptests [00035.005301][4599][4618][klog] INFO: [00035.060114][4599][4618][klog] INFO: Running main() from gtest_main.cc [00035.060386][4599][4618][klog] INFO: [==========] Running 6 tests from 3 test cases. [00035.060419][4599][4618][klog] INFO: [----------] Global test environment set-up. [00035.060420][4599][4618][klog] INFO: [----------] 2 tests from InputInterfaceTest [00035.060420][4599][4618][klog] INFO: [ RUN ] InputInterfaceTest.RegisterKeyboardTest [00035.060420][4599][4618][klog] INFO: [00035.060421][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="1655249199" [00035.060677][4599][4618][klog] INFO: [ OK ] InputInterfaceTest.RegisterKeyboardTest (1 ms) [00035.060708][4599][4618][klog] INFO: [ RUN ] InputInterfaceTest.InputKeyboardTest [00035.060708][4599][4618][klog] INFO: [00035.060709][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="128685963" [00035.060865][4599][4618][klog] INFO: [ OK ] InputInterfaceTest.InputKeyboardTest (0 ms) [00035.060888][4599][4618][klog] INFO: [----------] 2 tests from InputInterfaceTest (1 ms total) [00035.060888][4599][4618][klog] INFO: [00035.060888][4599][4618][klog] INFO: [----------] 2 tests from ReaderInterpreterTest [00035.060889][4599][4618][klog] INFO: [ RUN ] ReaderInterpreterTest.RegisterKeyboardTest [00035.060889][4599][4618][klog] INFO: [00035.060889][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="4071635089" [00035.061058][4599][4618][klog] INFO: [ OK ] ReaderInterpreterTest.RegisterKeyboardTest (0 ms) [00035.061086][4599][4618][klog] INFO: [ RUN ] ReaderInterpreterTest.RemoveKeyboardTest [00035.061086][4599][4618][klog] INFO: [00035.061087][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="3730062404" [00035.061196][4599][4618][klog] INFO: [ERROR:garnet/bin/ui/input_reader/input_interpreter.cc(490)] Failed to read from input: -1 for MockHidDecoder [00035.067990][4599][4618][klog] INFO: [ OK ] ReaderInterpreterTest.RemoveKeyboardTest (0 ms) [00035.068026][4599][4618][klog] INFO: [----------] 2 tests from ReaderInterpreterTest (0 ms total) [00035.068026][4599][4618][klog] INFO: [00035.068027][4599][4618][klog] INFO: [----------] 2 tests from ReaderInterpreterInputTest [00035.068028][4599][4618][klog] INFO: [ RUN ] ReaderInterpreterInputTest.KeyboardTest [00035.068030][4599][4618][klog] INFO: [00035.068031][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="242122109" [00035.068032][4599][4618][klog] INFO: [ OK ] ReaderInterpreterInputTest.KeyboardTest (1 ms) [00035.068033][4599][4618][klog] INFO: [ RUN ] ReaderInterpreterInputTest.LightSensorTest [00035.068033][4599][4618][klog] INFO: [00035.068034][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="3549152477" [00035.068035][4599][4618][klog] INFO: [ OK ] ReaderInterpreterInputTest.LightSensorTest (0 ms) [00035.068035][4599][4618][klog] INFO: [----------] 2 tests from ReaderInterpreterInputTest (1 ms total) [00035.068036][4599][4618][klog] INFO: [00035.068036][4599][4618][klog] INFO: [----------] Global test environment tear-down [00035.068037][4599][4618][klog] INFO: [==========] 6 tests from 3 test cases ran. (2 ms total) [00035.068038][4599][4618][klog] INFO: [ PASSED ] 6 tests. [00035.068441][4599][4618][klog] INFO: PASSED: /pkgfs/packages/scenic_tests/0/test/input_apptests passed [00035.250956][4599][4618][klog] INFO: [00035.250991][4599][4618][klog] INFO: ------------------------------------------------ [00035.250995][4599][4618][klog] INFO: RUNNING TEST: /pkgfs/packages/scenic_tests/0/test/input_tool_unittests [00035.250997][4599][4618][klog] INFO: [00035.289213][4599][4618][klog] INFO: Running main() from gtest_main.cc [00035.289285][4599][4618][klog] INFO: [==========] Running 5 tests from 1 test case. [00035.289285][4599][4618][klog] INFO: [----------] Global test environment set-up. [00035.289286][4599][4618][klog] INFO: [----------] 5 tests from InverseKeymapTest [00035.289287][4599][4618][klog] INFO: [ RUN ] InverseKeymapTest.PlainKey [00035.289695][4599][4618][klog] INFO: [ OK ] InverseKeymapTest.PlainKey (1 ms) [00035.289741][4599][4618][klog] INFO: [ RUN ] InverseKeymapTest.ShiftKey [00035.289970][4599][4618][klog] INFO: [ OK ] InverseKeymapTest.ShiftKey (0 ms) [00035.290006][4599][4618][klog] INFO: [ RUN ] InverseKeymapTest.Lowercase [00035.290214][4599][4618][klog] INFO: [ OK ] InverseKeymapTest.Lowercase (0 ms) [00035.290259][4599][4618][klog] INFO: [ RUN ] InverseKeymapTest.Sentence [00035.290652][4599][4618][klog] INFO: [ OK ] InverseKeymapTest.Sentence (1 ms) [00035.290717][4599][4618][klog] INFO: [ RUN ] InverseKeymapTest.HoldShift [00035.290739][4599][4618][klog] INFO: [ OK ] InverseKeymapTest.HoldShift (0 ms) [00035.290740][4599][4618][klog] INFO: [----------] 5 tests from InverseKeymapTest (2 ms total) [00035.290740][4599][4618][klog] INFO: [00035.290741][4599][4618][klog] INFO: [----------] Global test environment tear-down [00035.290741][4599][4618][klog] INFO: [==========] 5 tests from 1 test case ran. (2 ms total) [00035.290742][4599][4618][klog] INFO: [ PASSED ] 5 tests. [00035.311939][4599][4618][klog] INFO: PASSED: /pkgfs/packages/scenic_tests/0/test/input_tool_unittests passed [00035.396612][4599][4618][klog] INFO: [00035.396656][4599][4618][klog] INFO: ------------------------------------------------ [00035.396659][4599][4618][klog] INFO: RUNNING TEST: /pkgfs/packages/scenic_tests/0/test/input_unittests [00035.396659][4599][4618][klog] INFO: [00035.515728][4599][4618][klog] INFO: [==========] Running 7 tests from 5 test cases. [00035.515757][4599][4618][klog] INFO: [----------] Global test environment set-up. [00035.515758][4599][4618][klog] INFO: [----------] 1 test from CoordinateTransformTest [00035.515758][4599][4618][klog] INFO: [ RUN ] CoordinateTransformTest.CoordinateTransform [00035.515760][4599][4618][klog] INFO: [00035.515760][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="3256079034" [00035.516401][4599][4618][klog] INFO: [INFO:gfx_system.cc(176)] No Vulkan found, but using a test-only "display". [00035.517362][4599][4618][klog] INFO: [INFO:input_system.cc(230)] Scenic input system initialized. [00035.517386][4599][4618][klog] INFO: [ERROR:garnet/lib/ui/input/input_system.cc(227)] Scenic lost connection to TextSync [00035.521113][4599][4618][klog] INFO: [ OK ] CoordinateTransformTest.CoordinateTransform (5 ms) [00035.521138][4599][4618][klog] INFO: [----------] 1 test from CoordinateTransformTest (5 ms total) [00035.521138][4599][4618][klog] INFO: [00035.521139][4599][4618][klog] INFO: [----------] 2 tests from FocusAvoidanceTest [00035.521139][4599][4618][klog] INFO: [ RUN ] FocusAvoidanceTest.ViewHierarchyByViewManager [00035.521140][4599][4618][klog] INFO: [00035.521141][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="1588149321" [00035.521201][4599][4618][klog] INFO: [INFO:gfx_system.cc(176)] No Vulkan found, but using a test-only "display". [00035.524357][4599][4618][klog] INFO: [INFO:input_system.cc(230)] Scenic input system initialized. [00035.524862][4599][4618][klog] INFO: [ERROR:garnet/lib/ui/input/input_system.cc(227)] Scenic lost connection to TextSync [00035.554686][4599][4618][klog] INFO: [ OK ] FocusAvoidanceTest.ViewHierarchyByViewManager (6 ms) [00035.554717][4599][4618][klog] INFO: [ RUN ] FocusAvoidanceTest.ViewHierarchyByScenic [00035.554739][4599][4618][klog] INFO: [00035.554740][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="84465554" [00035.554744][4599][4618][klog] INFO: [INFO:gfx_system.cc(176)] No Vulkan found, but using a test-only "display". [00035.554744][4599][4618][klog] INFO: [INFO:input_system.cc(230)] Scenic input system initialized. [00035.554745][4599][4618][klog] INFO: [ERROR:garnet/lib/ui/input/input_system.cc(227)] Scenic lost connection to TextSync [00035.554745][4599][4618][klog] INFO: [ OK ] FocusAvoidanceTest.ViewHierarchyByScenic (3 ms) [00035.554746][4599][4618][klog] INFO: [----------] 2 tests from FocusAvoidanceTest (9 ms total) [00035.554747][4599][4618][klog] INFO: [00035.554747][4599][4618][klog] INFO: [----------] 1 test from HardKeyboardDeliveryTest [00035.554747][4599][4618][klog] INFO: [ RUN ] HardKeyboardDeliveryTest.Test [00035.554748][4599][4618][klog] INFO: [00035.554748][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2327352630" [00035.554749][4599][4618][klog] INFO: [INFO:gfx_system.cc(176)] No Vulkan found, but using a test-only "display". [00035.554749][4599][4618][klog] INFO: [INFO:input_system.cc(230)] Scenic input system initialized. [00035.554750][4599][4618][klog] INFO: [ERROR:garnet/lib/ui/input/input_system.cc(227)] Scenic lost connection to TextSync [00035.554750][4599][4618][klog] INFO: [ OK ] HardKeyboardDeliveryTest.Test (3 ms) [00035.554750][4599][4618][klog] INFO: [----------] 1 test from HardKeyboardDeliveryTest (3 ms total) [00035.554751][4599][4618][klog] INFO: [00035.554751][4599][4618][klog] INFO: [----------] 1 test from ImportNodeTest [00035.554751][4599][4618][klog] INFO: [ RUN ] ImportNodeTest.ImportNodeEventDelivery [00035.554752][4599][4618][klog] INFO: [00035.554752][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="3607936153" [00035.554752][4599][4618][klog] INFO: [INFO:gfx_system.cc(176)] No Vulkan found, but using a test-only "display". [00035.555237][4599][4618][klog] INFO: [INFO:input_system.cc(230)] Scenic input system initialized. [00035.555238][4599][4618][klog] INFO: [ERROR:garnet/lib/ui/input/input_system.cc(227)] Scenic lost connection to TextSync [00035.555238][4599][4618][klog] INFO: [ OK ] ImportNodeTest.ImportNodeEventDelivery (2 ms) [00035.555239][4599][4618][klog] INFO: [----------] 1 test from ImportNodeTest (2 ms total) [00035.555239][4599][4618][klog] INFO: [00035.555239][4599][4618][klog] INFO: [----------] 2 tests from MouseDeliveryTest [00035.555239][4599][4618][klog] INFO: [ RUN ] MouseDeliveryTest.StandardTest [00035.555240][4599][4618][klog] INFO: [00035.555240][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="3979193688" [00035.555241][4599][4618][klog] INFO: [INFO:gfx_system.cc(176)] No Vulkan found, but using a test-only "display". [00035.555241][4599][4618][klog] INFO: [INFO:input_system.cc(230)] Scenic input system initialized. [00035.555241][4599][4618][klog] INFO: [ERROR:garnet/lib/ui/input/input_system.cc(227)] Scenic lost connection to TextSync [00035.555242][4599][4618][klog] INFO: [ OK ] MouseDeliveryTest.StandardTest (5 ms) [00035.555242][4599][4618][klog] INFO: [ RUN ] MouseDeliveryTest.NoFocusTest [00035.555242][4599][4618][klog] INFO: [00035.555243][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="1435982394" [00035.555243][4599][4618][klog] INFO: [INFO:gfx_system.cc(176)] No Vulkan found, but using a test-only "display". [00035.555243][4599][4618][klog] INFO: [INFO:input_system.cc(230)] Scenic input system initialized. [00035.555244][4599][4618][klog] INFO: [ERROR:garnet/lib/ui/input/input_system.cc(227)] Scenic lost connection to TextSync [00035.555244][4599][4618][klog] INFO: [ OK ] MouseDeliveryTest.NoFocusTest (4 ms) [00035.555245][4599][4618][klog] INFO: [----------] 2 tests from MouseDeliveryTest (10 ms total) [00035.555245][4599][4618][klog] INFO: [00035.555246][4599][4618][klog] INFO: [----------] Global test environment tear-down [00035.555246][4599][4618][klog] INFO: [==========] 7 tests from 5 test cases ran. (29 ms total) [00035.555246][4599][4618][klog] INFO: [ PASSED ] 7 tests. [00035.555494][4599][4618][klog] INFO: PASSED: /pkgfs/packages/scenic_tests/0/test/input_unittests passed [00035.740562][4599][4618][klog] INFO: [00035.740603][4599][4618][klog] INFO: ------------------------------------------------ [00035.740606][4599][4618][klog] INFO: RUNNING TEST: /pkgfs/packages/scenic_tests/0/test/presentation_mode_unittests [00035.740607][4599][4618][klog] INFO: [00035.802414][4599][4618][klog] INFO: Running main() from gtest_main.cc [00035.802446][4599][4618][klog] INFO: [==========] Running 7 tests from 3 test cases. [00035.802447][4599][4618][klog] INFO: [----------] Global test environment set-up. [00035.802529][4599][4618][klog] INFO: [----------] 1 test from PositiveData [00035.802530][4599][4618][klog] INFO: [ RUN ] PositiveData.MovingAverage [00035.802531][4599][4618][klog] INFO: [ OK ] PositiveData.MovingAverage (0 ms) [00035.802531][4599][4618][klog] INFO: [----------] 1 test from PositiveData (0 ms total) [00035.802532][4599][4618][klog] INFO: [00035.802532][4599][4618][klog] INFO: [----------] 1 test from NegativeData [00035.802533][4599][4618][klog] INFO: [ RUN ] NegativeData.MovingAverage [00035.802534][4599][4618][klog] INFO: [ OK ] NegativeData.MovingAverage (0 ms) [00035.802534][4599][4618][klog] INFO: [----------] 1 test from NegativeData (0 ms total) [00035.802535][4599][4618][klog] INFO: [00035.802535][4599][4618][klog] INFO: [----------] 5 tests from Detector [00035.802536][4599][4618][klog] INFO: [ RUN ] Detector.Closed [00035.802536][4599][4618][klog] INFO: [ OK ] Detector.Closed (0 ms) [00035.802536][4599][4618][klog] INFO: [ RUN ] Detector.Laptop [00035.802537][4599][4618][klog] INFO: [ OK ] Detector.Laptop (0 ms) [00035.802538][4599][4618][klog] INFO: [ RUN ] Detector.Tablet [00035.802538][4599][4618][klog] INFO: [ OK ] Detector.Tablet (0 ms) [00035.802538][4599][4618][klog] INFO: [ RUN ] Detector.Tent [00035.802539][4599][4618][klog] INFO: [ OK ] Detector.Tent (0 ms) [00035.802730][4599][4618][klog] INFO: [ RUN ] Detector.NonAccelerometer [00035.802757][4599][4618][klog] INFO: [ OK ] Detector.NonAccelerometer (0 ms) [00035.802757][4599][4618][klog] INFO: [----------] 5 tests from Detector (0 ms total) [00035.802757][4599][4618][klog] INFO: [00035.802758][4599][4618][klog] INFO: [----------] Global test environment tear-down [00035.802758][4599][4618][klog] INFO: [==========] 7 tests from 3 test cases ran. (0 ms total) [00035.804674][4599][4618][klog] INFO: [ PASSED ] 7 tests. [00035.805336][4599][4618][klog] INFO: PASSED: /pkgfs/packages/scenic_tests/0/test/presentation_mode_unittests passed [00035.928024][4599][4618][klog] INFO: [00035.928059][4599][4618][klog] INFO: ------------------------------------------------ [00035.928061][4599][4618][klog] INFO: RUNNING TEST: /pkgfs/packages/scenic_tests/0/test/scenic_unittests [00035.928062][4599][4618][klog] INFO: [00035.986523][4599][4618][klog] INFO: Running main() from gtest_main.cc [00035.986877][4599][4618][klog] INFO: [==========] Running 1 test from 1 test case. [00035.986924][4599][4618][klog] INFO: [----------] Global test environment set-up. [00035.986925][4599][4618][klog] INFO: [----------] 1 test from ScenicTest [00035.986925][4599][4618][klog] INFO: [ RUN ] ScenicTest.SessionCreatedAfterAllSystemsInitialized [00035.987536][4599][4618][klog] INFO: [00035.987567][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2146907734" [00035.988078][4599][4618][klog] INFO: [ OK ] ScenicTest.SessionCreatedAfterAllSystemsInitialized (1 ms) [00035.988914][4599][4618][klog] INFO: [----------] 1 test from ScenicTest (1 ms total) [00035.989544][4599][4618][klog] INFO: [00035.990296][4599][4618][klog] INFO: [----------] Global test environment tear-down [00035.990511][4599][4618][klog] INFO: [==========] 1 test from 1 test case ran. (1 ms total) [00035.998126][4599][4618][klog] INFO: [ PASSED ] 1 test. [00036.026374][4599][4618][klog] INFO: PASSED: /pkgfs/packages/scenic_tests/0/test/scenic_unittests passed [00036.075519][4599][4618][klog] INFO: [00036.075553][4599][4618][klog] INFO: ------------------------------------------------ [00036.075555][4599][4618][klog] INFO: RUNNING TEST: /pkgfs/packages/scenic_tests/0/test/view_manager_apptests [00036.075556][4599][4618][klog] INFO: [00036.196801][4599][4618][klog] INFO: Running main() from gtest_main.cc [00036.196951][4599][4618][klog] INFO: [==========] Running 0 tests from 0 test cases. [00036.196951][4599][4618][klog] INFO: [==========] 0 tests from 0 test cases ran. (0 ms total) [00036.196952][4599][4618][klog] INFO: [ PASSED ] 0 tests. [00036.199400][4599][4618][klog] INFO: PASSED: /pkgfs/packages/scenic_tests/0/test/view_manager_apptests passed [00036.262016][4599][4618][klog] INFO: [00036.262079][4599][4618][klog] INFO: ------------------------------------------------ [00036.262081][4599][4618][klog] INFO: RUNNING TEST: /pkgfs/packages/web_runner_tests/0/test/web_runner_unittests [00036.262082][4599][4618][klog] INFO: [00036.317973][4599][4618][klog] INFO: Running main() from gtest_main.cc [00036.318006][4599][4618][klog] INFO: [==========] Running 2 tests from 1 test case. [00036.318007][4599][4618][klog] INFO: [----------] Global test environment set-up. [00036.318007][4599][4618][klog] INFO: [----------] 2 tests from RunnerTest [00036.318008][4599][4618][klog] INFO: [ RUN ] RunnerTest.Trivial [00036.318011][4599][4618][klog] INFO: [00036.318011][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="1662170191" [00036.344890][4599][4618][klog] INFO: [ OK ] RunnerTest.Trivial (27 ms) [00036.344921][4599][4618][klog] INFO: [ RUN ] RunnerTest.CreatesWebView [00036.344922][4599][4618][klog] INFO: [00036.344923][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="3561655287" [00036.346134][4599][4618][klog] INFO: [ OK ] RunnerTest.CreatesWebView (1 ms) [00036.346157][4599][4618][klog] INFO: [----------] 2 tests from RunnerTest (28 ms total) [00036.346158][4599][4618][klog] INFO: [00036.346158][4599][4618][klog] INFO: [----------] Global test environment tear-down [00036.346159][4599][4618][klog] INFO: [==========] 2 tests from 1 test case ran. (28 ms total) [00036.346159][4599][4618][klog] INFO: [ PASSED ] 2 tests. [00036.382864][4599][4618][klog] INFO: PASSED: /pkgfs/packages/web_runner_tests/0/test/web_runner_unittests passed [00036.400983][4599][4618][klog] INFO: [00036.401003][4599][4618][klog] INFO: ------------------------------------------------ [00036.401005][4599][4618][klog] INFO: RUNNING TEST: /system/test/auth_provider_unittests [00036.401005][4599][4618][klog] INFO: [00036.456036][4599][4618][klog] INFO: Running main() from gtest_main.cc [00036.456065][4599][4618][klog] INFO: [==========] Running 27 tests from 6 test cases. [00036.456065][4599][4618][klog] INFO: [----------] Global test environment set-up. [00036.456066][4599][4618][klog] INFO: [----------] 1 test from GoogleFactoryImplTest [00036.456066][4599][4618][klog] INFO: [ RUN ] GoogleFactoryImplTest.GetAuthProvider [00036.456067][4599][4618][klog] INFO: [00036.456068][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="401296367" [00036.456685][4599][4618][klog] INFO: [ OK ] GoogleFactoryImplTest.GetAuthProvider (1 ms) [00036.456707][4599][4618][klog] INFO: [----------] 1 test from GoogleFactoryImplTest (1 ms total) [00036.456798][4599][4618][klog] INFO: [00036.456819][4599][4618][klog] INFO: [----------] 12 tests from GoogleAuthProviderImplTest [00036.456820][4599][4618][klog] INFO: [ RUN ] GoogleAuthProviderImplTest.EmptyWhenClientDisconnected [00036.456822][4599][4618][klog] INFO: [00036.456823][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="3506102073" [00036.456823][4599][4618][klog] INFO: [ OK ] GoogleAuthProviderImplTest.EmptyWhenClientDisconnected (0 ms) [00036.456927][4599][4618][klog] INFO: [ RUN ] GoogleAuthProviderImplTest.GetAppAccessTokenSuccess [00036.457116][4599][4618][klog] INFO: [00036.457492][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2806382427" [00036.538123][4599][4618][klog] INFO: [ OK ] GoogleAuthProviderImplTest.GetAppAccessTokenSuccess (81 ms) [00036.538470][4599][4618][klog] INFO: [ RUN ] GoogleAuthProviderImplTest.GetAppAccessTokenBadRequestError [00036.538818][4599][4618][klog] INFO: [00036.550643][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2760779676" [00036.560422][4599][4618][klog] INFO: [ OK ] GoogleAuthProviderImplTest.GetAppAccessTokenBadRequestError (22 ms) [00036.560443][4599][4618][klog] INFO: [ RUN ] GoogleAuthProviderImplTest.GetAppAccessTokenInvalidClientError [00036.560443][4599][4618][klog] INFO: [00036.560444][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2479905034" [00036.562219][4599][4618][klog] INFO: [WARNING:topaz/auth_providers/google/google_auth_provider_impl.cc(196)] Got error: OAuth backend returned error: 401 [00036.562262][4599][4618][klog] INFO: [WARNING:topaz/auth_providers/google/google_auth_provider_impl.cc(197)] Got response: { [00036.562263][4599][4618][klog] INFO: "error": "invalid_client" [00036.562263][4599][4618][klog] INFO: } [00036.562266][4599][4618][klog] INFO: [ OK ] GoogleAuthProviderImplTest.GetAppAccessTokenInvalidClientError (2 ms) [00036.562267][4599][4618][klog] INFO: [ RUN ] GoogleAuthProviderImplTest.GetAppAccessTokenInvalidUserError [00036.562267][4599][4618][klog] INFO: [00036.562268][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="3042326631" [00036.564157][4599][4618][klog] INFO: [WARNING:topaz/auth_providers/google/google_auth_provider_impl.cc(196)] Got error: OAuth backend returned error: 401 [00036.564158][4599][4618][klog] INFO: [WARNING:topaz/auth_providers/google/google_auth_provider_impl.cc(197)] Got response: { [00036.564158][4599][4618][klog] INFO: "error": "invalid_credential" [00036.564159][4599][4618][klog] INFO: } [00036.564159][4599][4618][klog] INFO: [ OK ] GoogleAuthProviderImplTest.GetAppAccessTokenInvalidUserError (2 ms) [00036.564160][4599][4618][klog] INFO: [ RUN ] GoogleAuthProviderImplTest.GetAppIdTokenSuccess [00036.564163][4599][4618][klog] INFO: [00036.564165][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2321887987" [00036.566207][4599][4618][klog] INFO: [ OK ] GoogleAuthProviderImplTest.GetAppIdTokenSuccess (2 ms) [00036.566208][4599][4618][klog] INFO: [ RUN ] GoogleAuthProviderImplTest.GetAppIdTokenBadRequestError [00036.566208][4599][4618][klog] INFO: [00036.566208][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="1485051763" [00036.655285][4599][4618][klog] INFO: [ OK ] GoogleAuthProviderImplTest.GetAppIdTokenBadRequestError (0 ms) [00036.655324][4599][4618][klog] INFO: [ RUN ] GoogleAuthProviderImplTest.GetAppIdTokenInvalidAudienceError [00036.655325][4599][4618][klog] INFO: [00036.655326][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2153041339" [00036.655331][4599][4618][klog] INFO: [WARNING:topaz/auth_providers/google/google_auth_provider_impl.cc(234)] Got error: OAuth backend returned error: 401 [00036.655332][4599][4618][klog] INFO: [WARNING:topaz/auth_providers/google/google_auth_provider_impl.cc(235)] Got response: { [00036.655332][4599][4618][klog] INFO: "error": "invalid_client" [00036.655408][4599][4618][klog] INFO: } [00036.655564][4599][4618][klog] INFO: [ OK ] GoogleAuthProviderImplTest.GetAppIdTokenInvalidAudienceError (2 ms) [00036.655565][4599][4618][klog] INFO: [ RUN ] GoogleAuthProviderImplTest.GetAppIdTokenInvalidUserError [00036.655565][4599][4618][klog] INFO: [00036.655565][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2190923942" [00036.655566][4599][4618][klog] INFO: [WARNING:topaz/auth_providers/google/google_auth_provider_impl.cc(234)] Got error: OAuth backend returned error: 401 [00036.655566][4599][4618][klog] INFO: [WARNING:topaz/auth_providers/google/google_auth_provider_impl.cc(235)] Got response: { [00036.655567][4599][4618][klog] INFO: "error": "invalid_credential" [00036.655567][4599][4618][klog] INFO: } [00036.655567][4599][4618][klog] INFO: [ OK ] GoogleAuthProviderImplTest.GetAppIdTokenInvalidUserError (2 ms) [00036.655568][4599][4618][klog] INFO: [ RUN ] GoogleAuthProviderImplTest.GetAppFirebaseTokenSuccess [00036.655568][4599][4618][klog] INFO: [00036.655568][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="296437896" [00036.655569][4599][4618][klog] INFO: [ OK ] GoogleAuthProviderImplTest.GetAppFirebaseTokenSuccess (2 ms) [00036.655570][4599][4618][klog] INFO: [ RUN ] GoogleAuthProviderImplTest.GetAppFirebaseTokenBadRequestError [00036.655571][4599][4618][klog] INFO: [00036.757179][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="4054082063" [00036.757214][4599][4618][klog] INFO: [ OK ] GoogleAuthProviderImplTest.GetAppFirebaseTokenBadRequestError (0 ms) [00036.757215][4599][4618][klog] INFO: [ RUN ] GoogleAuthProviderImplTest.RevokeAppOrPersistentCredentialUnsupported [00036.757215][4599][4618][klog] INFO: [00036.757215][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="794055551" [00036.757216][4599][4618][klog] INFO: [ OK ] GoogleAuthProviderImplTest.RevokeAppOrPersistentCredentialUnsupported (0 ms) [00036.757216][4599][4618][klog] INFO: [----------] 12 tests from GoogleAuthProviderImplTest (116 ms total) [00036.757217][4599][4618][klog] INFO: [00036.757218][4599][4618][klog] INFO: [----------] 6 tests from OAuthRequestBuilderTest [00036.757218][4599][4618][klog] INFO: [ RUN ] OAuthRequestBuilderTest.JsonEncodedPostRequest [00036.757218][4599][4618][klog] INFO: [ OK ] OAuthRequestBuilderTest.JsonEncodedPostRequest (0 ms) [00036.757219][4599][4618][klog] INFO: [ RUN ] OAuthRequestBuilderTest.UrlEncodedPostRequest [00036.757219][4599][4618][klog] INFO: [ OK ] OAuthRequestBuilderTest.UrlEncodedPostRequest (0 ms) [00036.757219][4599][4618][klog] INFO: [ RUN ] OAuthRequestBuilderTest.EmptyBodyPostRequest [00036.757220][4599][4618][klog] INFO: [ OK ] OAuthRequestBuilderTest.EmptyBodyPostRequest (0 ms) [00036.757220][4599][4618][klog] INFO: [ RUN ] OAuthRequestBuilderTest.CheckAuthHeader [00036.757220][4599][4618][klog] INFO: [ OK ] OAuthRequestBuilderTest.CheckAuthHeader (0 ms) [00036.757221][4599][4618][klog] INFO: [ RUN ] OAuthRequestBuilderTest.GetRequest [00036.757222][4599][4618][klog] INFO: [ OK ] OAuthRequestBuilderTest.GetRequest (0 ms) [00036.757228][4599][4618][klog] INFO: [ RUN ] OAuthRequestBuilderTest.GetRequestWithQueryParams [00036.757228][4599][4618][klog] INFO: [ OK ] OAuthRequestBuilderTest.GetRequestWithQueryParams (0 ms) [00036.757229][4599][4618][klog] INFO: [----------] 6 tests from OAuthRequestBuilderTest (0 ms total) [00036.757229][4599][4618][klog] INFO: [00036.757230][4599][4618][klog] INFO: [----------] 1 test from OAuthResponseTest [00036.757230][4599][4618][klog] INFO: [ RUN ] OAuthResponseTest.CheckParseOAuthResponse [00036.757231][4599][4618][klog] INFO: [ERROR:topaz/auth_providers/oauth/oauth_response.cc(22)] Encountered error: -2 ,with description: Bad request [00036.757231][4599][4618][klog] INFO: [ OK ] OAuthResponseTest.CheckParseOAuthResponse (8 ms) [00036.757231][4599][4618][klog] INFO: [----------] 1 test from OAuthResponseTest (8 ms total) [00036.757232][4599][4618][klog] INFO: [00036.757232][4599][4618][klog] INFO: [----------] 1 test from SpotifyFactoryImplTest [00036.757232][4599][4618][klog] INFO: [ RUN ] SpotifyFactoryImplTest.GetAuthProvider [00036.757233][4599][4618][klog] INFO: [00036.757233][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="2686532856" [00036.757233][4599][4618][klog] INFO: [ OK ] SpotifyFactoryImplTest.GetAuthProvider (1 ms) [00036.757234][4599][4618][klog] INFO: [----------] 1 test from SpotifyFactoryImplTest (1 ms total) [00036.757234][4599][4618][klog] INFO: [00036.757234][4599][4618][klog] INFO: [----------] 6 tests from SpotifyAuthProviderImplTest [00036.757235][4599][4618][klog] INFO: [ RUN ] SpotifyAuthProviderImplTest.EmptyWhenClientDisconnected [00036.757235][4599][4618][klog] INFO: [00036.757235][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="1952645343" [00036.757236][4599][4618][klog] INFO: [ OK ] SpotifyAuthProviderImplTest.EmptyWhenClientDisconnected (0 ms) [00036.929306][4599][4618][klog] INFO: [ RUN ] SpotifyAuthProviderImplTest.GetAppAccessTokenSuccess [00036.929360][4599][4618][klog] INFO: [00036.929361][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="1316557687" [00036.929362][4599][4618][klog] INFO: [ OK ] SpotifyAuthProviderImplTest.GetAppAccessTokenSuccess (2 ms) [00036.929363][4599][4618][klog] INFO: [ RUN ] SpotifyAuthProviderImplTest.GetAppAccessTokenError [00036.929363][4599][4618][klog] INFO: [00036.929363][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="4132164241" [00036.929364][4599][4618][klog] INFO: [ OK ] SpotifyAuthProviderImplTest.GetAppAccessTokenError (2 ms) [00036.929365][4599][4618][klog] INFO: [ RUN ] SpotifyAuthProviderImplTest.GetAppIdTokenUnsupported [00036.929365][4599][4618][klog] INFO: [00036.929368][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="366731764" [00036.929431][4599][4618][klog] INFO: [ OK ] SpotifyAuthProviderImplTest.GetAppIdTokenUnsupported (0 ms) [00036.929432][4599][4618][klog] INFO: [ RUN ] SpotifyAuthProviderImplTest.GetAppFirebaseTokenUnsupported [00036.929432][4599][4618][klog] INFO: [00036.929433][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="1472153229" [00036.929433][4599][4618][klog] INFO: [ OK ] SpotifyAuthProviderImplTest.GetAppFirebaseTokenUnsupported (0 ms) [00036.929435][4599][4618][klog] INFO: [ RUN ] SpotifyAuthProviderImplTest.RevokeAppOrPersistentCredentialUnsupported [00036.929435][4599][4618][klog] INFO: [00036.929435][4599][4618][klog] INFO: TEST_LOOP_RANDOM_SEED="1023966949" [00036.929436][4599][4618][klog] INFO: [ OK ] SpotifyAuthProviderImplTest.RevokeAppOrPersistentCredentialUnsupported (0 ms) [00036.929436][4599][4618][klog] INFO: [----------] 6 tests from SpotifyAuthProviderImplTest (4 ms total) [00036.929436][4599][4618][klog] INFO: [00036.929437][4599][4618][klog] INFO: [----------] Global test environment tear-down [00036.929442][4599][4618][klog] INFO: [==========] 27 tests from 6 test cases ran. (130 ms total) [00036.929442][4599][4618][klog] INFO: [ PASSED ] 27 tests. [00036.930589][4599][4618][klog] INFO: PASSED: /system/test/auth_provider_unittests passed [00037.003581][4599][4618][klog] INFO: [00037.003607][4599][4618][klog] INFO: ------------------------------------------------ [00037.003610][4599][4618][klog] INFO: RUNNING TEST: /system/test/web_view_link_test [00037.003611][4599][4618][klog] INFO: [00037.292751][4599][4618][klog] INFO: PASSED: /system/test/web_view_link_test passed