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

2018/12/07 02:49:47 running: Args: [/b/s/w/ir/qemu/bin/qemu-system-x86_64 -machine q35 -device isa-debug-exit,iobase=0xf4,iosize=0x04 -cpu host -enable-kvm -m 4096 -smp 4 -nographic -serial stdio -monitor none -net none -append userboot=bin/core-tests userboot.shutdown kernel.halt-on-panic=true devmgr.suspend-timeout-debug=true TERM=dumb kernel.serial=legacy -kernel /b/s/w/ir/multiboot.bin -initrd /b/s/w/ir/zircon.zbi] Env: [] [00000.000] 00000.00000> zbi: @ 0xffffff8000e89000 (12807032 bytes) [00000.000] 00000.00000> UART: FIFO depth 16 [00000.000] 00000.00000> PMM: boot reserve add [0x100000, 0x2d2fff] [00000.000] 00000.00000> PMM: boot reserve add [0xe89000, 0x1abffff] [00000.000] 00000.00000> PMM: boot reserve marking WIRED [0x100000, 0x2d2fff] [00000.000] 00000.00000> PMM: boot reserve marking WIRED [0xe89000, 0x1abffff] [00000.000] 00000.00000> [00000.000] 00000.00000> welcome to Zircon [00000.000] 00000.00000> [00000.000] 00000.00000> KASLR: .text section at 0xffffffff00100000 [00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001b9d80 (global_prng_seed) at level 0x30000, flags 0x1 [00000.000] 00000.00000> initializing vm pre-heap [00000.000] 00000.00000> VM: marking boot alloc used range [0x1ac0000, 0x1ac0080) [00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00152bf0 (elf_build_id) at level 0x4fffe, flags 0x1 [00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00152d90 (version) at level 0x4ffff, flags 0x1 [00000.000] 00000.00000> version: [00000.000] 00000.00000> arch: x86 [00000.000] 00000.00000> platform: pc [00000.000] 00000.00000> target: pc [00000.000] 00000.00000> project: x64 [00000.000] 00000.00000> buildid: git-b6bbb57979e673f6faaa963dc9e548614a855469 [00000.000] 00000.00000> ELF build ID: 3b1a82dfb3345451 [00000.000] 00000.00000> initializing heap [00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001042c0 (x86_resource_init) at level 0x50000, flags 0x1 [00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001b7630 (console) at level 0x50000, flags 0x1 [00000.000] 00000.00000> initializing vm [00000.000] 00000.00000> VM: reserving kernel region [0xffffffff00100000, 0xffffffff00202000) flags 0x28 name 'kernel_code' [00000.000] 00000.00000> VM: reserving kernel region [0xffffffff00202000, 0xffffffff0023e000) flags 0x8 name 'kernel_rodata' [00000.000] 00000.00000> VM: reserving kernel region [0xffffffff0023e000, 0xffffffff0024e000) flags 0x18 name 'kernel_data' [00000.000] 00000.00000> VM: reserving kernel region [0xffffffff0024e000, 0xffffffff002d3000) flags 0x18 name 'kernel_bss' [00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00101170 (acpi_tables) at level 0x60001, flags 0x1 [00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00105170 (display_memtype) at level 0x60001, flags 0x1 [00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00102590 (hpet) at level 0x60002, flags 0x1 [00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00102a10 (apic) at level 0x60002, flags 0x1 [00000.000] 00000.00000> x2APIC enabled [00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00107d10 (timer) at level 0x60003, flags 0x1 [00000.000] 00000.00000> pvclock: Clocksource is stable [00000.000] 00000.00000> HPET frequency: 100000 ticks/ms [00000.000] 00000.00000> pvclock: Fetching TSC frequency [00000.000] 00000.00000> TSC frequency: 2299808 ticks/ms [00000.457] 00000.00000> timer features: constant_tsc 1 invariant_tsc 1 tsc_deadline 1 [00000.457] 00000.00000> Using TSC as wallclock [00000.457] 00000.00000> initializing kernel [00000.457] 00000.00000> initializing mp [00000.457] 00000.00000> initializing timers [00000.457] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00144940 (debuglog) at level 0x7ffff, flags 0x1 [00000.457] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001a1ec0 (thread_set_priority_experiment) at level 0x7ffff, flags 0x1 [00000.457] 00000.00000> thread set priority experiment is : ENABLED [00000.457] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001ba0c0 (global_prng_thread_safe) at level 0x7ffff, flags 0x1 [00000.457] 00000.00000> creating bootstrap completion thread [00000.623] 00000.00000> top of bootstrap2() [00000.623] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00136750 (dpc) at level 0x80000, flags 0x1 [00000.630] 00000.00000> INIT: cpu 0, calling hook 0xffffffff0015fd20 (libobject) at level 0x80000, flags 0x1 [00000.630] 00000.00000> OOM: started thread [00000.637] 00000.00000> Processor Model Info: type 0 family 0x6 model 0xf stepping 0 [00000.637] 00000.00000> display_family 0x6 display_model 0x3f [00000.637] 00000.00000> Vendor: Intel [00000.637] 00000.00000> Microarch: Haswell [00000.637] 00000.00000> F/M/S: 6/3f/0 [00000.637] 00000.00000> Brand: Intel(R) Xeon(R) CPU @ 2.30GHz [00000.637] 00000.00000> Features: fpu sse sse2 sse3 ssse3 sse4.1 sse4.2 mmx avx avx2 fxsr pcid xsave aesni clflush [00000.637] 00000.00000> fsgsbase tsc_adj smep erms rdrand syscall nx rdtscp tsc_deadline x2apic hypervisor [00000.637] 00000.00000> initializing platform [00000.637] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00115810 (x86_perfmon) at level 0x90000, flags 0x1 [00000.637] 00000.00000> x86_perfmon_init_once:495: perfmon: unexpected programmable counter width 0 in cpuid.0AH [00000.637] 00000.00000> UART: started IRQ driven RX [00000.637] 00000.00000> UART: started IRQ driven TX [00000.637] 00000.00000> cpu topology: [00000.637] 00000.00000> 0: apic id 0x0 package 0 node 0 core 0 smt 0 BSP [00000.637] 00000.00000> 1: apic id 0x1 package 1 node 0 core 0 smt 0 [00000.637] 00000.00000> 2: apic id 0x2 package 2 node 0 core 0 smt 0 [00000.637] 00000.00000> 3: apic id 0x3 package 3 node 0 core 0 smt 0 [00000.637] 00000.00000> Found 4 cpus [00000.638] 00000.00000> booting apic ids: 0x1 0x2 0x3 [00000.709] 00000.00000> entering scheduler on cpu 3 [00000.709] 00000.00000> entering scheduler on cpu 1 [00000.709] 00000.00000> entering scheduler on cpu 2 [00000.721] 00000.00000> smbios: manufacturer="QEMU" product="Standard PC (Q35 + ICH9, 2009)" [00000.721] 00000.00000> initializing target [00000.721] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00106d30 (x86_pcie_init) at level 0xa0000, flags 0x1 [00000.721] 00000.00000> moving to last init level [00000.721] 00000.00000> INIT: cpu 0, calling hook 0xffffffff0014dbb0 (ktrace) at level 0xc0000, flags 0x1 [00000.761] 00000.00000> ktrace: buffer at 0xffffff94a9fce000 (33554432 bytes) [00000.761] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00151480 (userboot) at level 0xc0000, flags 0x1 [00000.761] 00000.00000> userboot: ramdisk 0xc37000 @ 0xffffff8000e89000 [00000.777] 00000.00000> userboot: userboot rodata 0 @ [0x58f5e34d000,0x58f5e34f000) [00000.778] 00000.00000> userboot: userboot code 0x2000 @ [0x58f5e34f000,0x58f5e354000) [00000.778] 00000.00000> userboot: vdso/full rodata 0 @ [0x58f5e354000,0x58f5e35b000) [00000.778] 00000.00000> userboot: vdso/full code 0x7000 @ [0x58f5e35b000,0x58f5e35c000) [00000.778] 00000.00000> userboot: entry point @ 0x58f5e34fae0 [00000.778] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001b7b80 (kernel_shell) at level 0xc0000, flags 0x1 [00000.808] 01032.01039> userboot: option "/b/s/w/ir/multiboot.bin=" [00000.808] 01032.01039> userboot: option "userboot=bin/core-tests" [00000.808] 01032.01039> userboot: option "userboot.shutdown=" [00000.808] 01032.01039> userboot: option "kernel.halt-on-panic=true" [00000.808] 01032.01039> userboot: option "devmgr.suspend-timeout-debug=true" [00000.808] 01032.01039> userboot: option "TERM=dumb" [00000.808] 01032.01039> userboot: option "kernel.serial=legacy" [00000.808] 01032.01039> userboot: option "multiboot.boot_loader_name=qemu" [00000.878] 01032.01039> userboot: searching bootfs for 'bin/core-tests' [00000.878] 01032.01039> userboot: 'bin/core-tests' has PT_INTERP "lib/ld.so.1" [00000.878] 01032.01039> userboot: searching bootfs for 'lib/ld.so.1' [00000.878] 01032.01039> userboot: userboot: loaded lib/ld.so.1 at 0x45a292edd000, entry point 0x45a292fa5150 [00000.878] 01032.01039> userboot: userboot: loaded vDSO at 0x7ce20d54d000, entry point 0x7ce20d554000 [00000.878] 01032.01039> userboot: process bin/core-tests started. [00000.878] 01032.01039> userboot: waiting for loader-service requests... [00000.878] 01032.01039> userboot: searching bootfs for 'lib/libunittest.so' [00000.879] 01032.01039> userboot: searching bootfs for 'lib/libmini-process.so' [00000.879] 01046.01049> {{{reset}}} [00000.879] 01046.01049> {{{module:0:<application>:elf:431e3fb8dce737f2}}} [00000.879] 01046.01049> {{{mmap:0x4032e8c27000:0x29000:load:0:r:0}}} [00000.879] 01046.01049> {{{mmap:0x4032e8c50000:0xb9000:load:0:rx:0x29000}}} [00000.879] 01046.01049> {{{mmap:0x4032e8d09000:0x104000:load:0:rw:0xe2000}}} [00000.879] 01046.01049> dso: id=431e3fb8dce737f2 base=0x00004032e8c27000 name=<application> [00000.879] 01046.01049> {{{module:0x3:libunittest.so:elf:c8c80d1098d3a5a9}}} [00000.879] 01046.01049> {{{mmap:0x78d8c63c2000:0x4000:load:0x3:r:0}}} [00000.879] 01046.01049> {{{mmap:0x78d8c63c6000:0x3000:load:0x3:rx:0x4000}}} [00000.879] 01046.01049> {{{mmap:0x78d8c63c9000:0x4000:load:0x3:rw:0x7000}}} [00000.879] 01046.01049> dso: id=c8c80d1098d3a5a9 base=0x000078d8c63c2000 name=libunittest.so [00000.879] 01046.01049> {{{module:0x4:libmini-process.so:elf:b438302df2320fe0}}} [00000.879] 01046.01049> {{{mmap:0x451bd02cf000:0x1000:load:0x4:r:0}}} [00000.879] 01046.01049> {{{mmap:0x451bd02d0000:0x2000:load:0x4:rx:0x1000}}} [00000.879] 01046.01049> {{{mmap:0x451bd02d2000:0x2000:load:0x4:rw:0x3000}}} [00000.879] 01046.01049> dso: id=b438302df2320fe0 base=0x0000451bd02cf000 name=libmini-process.so [00000.879] 01046.01049> {{{module:0x2:<vDSO>:elf:5b217df220fc2bea}}} [00000.879] 01046.01049> {{{mmap:0x7ce20d54d000:0x7000:load:0x2:r:0}}} [00000.879] 01046.01049> {{{mmap:0x7ce20d554000:0x1000:load:0x2:rx:0x7000}}} [00000.879] 01046.01049> dso: id=5b217df220fc2bea base=0x00007ce20d54d000 name=<vDSO> [00000.879] 01046.01049> {{{module:0x1:libc.so:elf:127b2360c53ec148}}} [00000.879] 01046.01049> {{{mmap:0x45a292edd000:0x56000:load:0x1:r:0}}} [00000.879] 01046.01049> {{{mmap:0x45a292f33000:0x76000:load:0x1:rx:0x56000}}} [00000.879] 01046.01049> {{{mmap:0x45a292fa9000:0x7000:load:0x1:rw:0xcc000}}} [00000.879] 01046.01049> dso: id=127b2360c53ec148 base=0x000045a292edd000 name=libc.so [00001.029] 01046.01049> TEST [00001.031] 01046.01049> CASE sync_completion_tests [STARTED] [00001.031] 01046.01049> test_initializer [RUNNING] [00001.031] 01046.01049> [PASSED] (0 ms) [00001.031] 01046.01049> test_completions [RUNNING] [00001.088] 01046.01049> [PASSED] (56 ms) [00001.088] 01046.01049> test_timeout [RUNNING] [00002.201] 01046.01049> [PASSED] (1112 ms) [00002.201] 01046.01049> test_signal_requeue [RUNNING] [00002.232] 01046.01049> [PASSED] (31 ms) [00002.232] 01046.01049> CASE sync_completion_tests [PASSED] [00002.232] 01046.01049> CASE pthread_tests [STARTED] [00002.232] 01046.01049> pthread_test [RUNNING] [00006.058] 01046.01049> [PASSED] (3826 ms) [00006.058] 01046.01049> pthread_self_main_thread_test [RUNNING] [00006.058] 01046.01049> [PASSED] (0 ms) [00006.058] 01046.01049> pthread_big_stack_size [RUNNING] [00006.089] 01046.01049> [PASSED] (30 ms) [00006.089] 01046.01049> pthread_getstack_main_thread [RUNNING] [00006.089] 01046.01049> [PASSED] (0 ms) [00006.089] 01046.01049> pthread_getstack_other_thread [RUNNING] [00006.105] 01046.01049> [PASSED] (16 ms) [00006.105] 01046.01049> CASE pthread_tests [PASSED] [00006.105] 01046.01049> CASE elf_tls_tests [STARTED] [00006.105] 01046.01049> ExecutableTlsTest [RUNNING] [00006.230] 01046.01049> [PASSED] (124 ms) [00006.230] 01046.01049> CASE elf_tls_tests [PASSED] [00006.230] 01046.01049> CASE sync_condition_tests [STARTED] [00006.230] 01046.01049> Condition::condition_test [RUNNING] [00006.252] 01046.01049> [PASSED] (22 ms) [00006.252] 01046.01049> Condition::condition_timeout_test [RUNNING] [00006.260] 01046.01049> [PASSED] (7 ms) [00006.260] 01046.01049> CASE sync_condition_tests [PASSED] [00006.260] 01046.01049> CASE memory_mapping_tests [STARTED] [00006.260] 01046.01049> address_space_limits_test [RUNNING] [00006.260] 01046.01049> [PASSED] (0 ms) [00006.260] 01046.01049> mmap_zerofilled_test [RUNNING] [00006.260] 01046.01049> [PASSED] (0 ms) [00006.260] 01046.01049> mmap_len_test [RUNNING] [00006.260] 01046.01049> [PASSED] (0 ms) [00006.260] 01046.01049> mmap_PROT_EXEC_test [RUNNING] [00006.261] 01046.01049> [PASSED] (0 ms) [00006.261] 01046.01049> mmap_offset_test [RUNNING] [00006.261] 01046.01049> [PASSED] (0 ms) [00006.261] 01046.01049> mmap_prot_test [RUNNING] [00006.261] 01046.01049> [PASSED] (0 ms) [00006.261] 01046.01049> mmap_flags_test [RUNNING] [00006.261] 01046.01049> [PASSED] (0 ms) [00006.261] 01046.01049> mprotect_test [RUNNING] [00006.333] 01046.01049> [PASSED] (72 ms) [00006.334] 01046.01049> CASE memory_mapping_tests [PASSED] [00006.334] 01046.01049> CASE object_info_tests [STARTED] [00006.334] 01046.01049> handle_valid_on_valid_handle_succeeds [RUNNING] [00006.334] 01046.01049> [PASSED] (0 ms) [00006.334] 01046.01049> handle_valid_on_closed_handle_fails [RUNNING] [00006.334] 01046.01049> [PASSED] (0 ms) [00006.334] 01046.01049> (invalid_handle_fails<ZX_INFO_HANDLE_VALID, void*>) [RUNNING] [00006.334] 01046.01049> [PASSED] (0 ms) [00006.334] 01046.01049> task_stats_unstarted [RUNNING] [00006.334] 01046.01049> [PASSED] (0 ms) [00006.334] 01046.01049> task_stats_smoke [RUNNING] [00006.334] 01046.01049> [PASSED] (0 ms) [00006.334] 01046.01049> (invalid_handle_fails<((zx_object_info_topic_t) 12u), zx_info_task_stats_t>) [RUNNING] [00006.334] 01046.01049> [PASSED] (0 ms) [00006.334] 01046.01049> (null_avail_actual_succeeds<((zx_object_info_topic_t) 12u), zx_info_task_stats_t, zx_process_self>) [RUNNING] [00006.334] 01046.01049> [PASSED] (0 ms) [00006.334] 01046.01049> (bad_buffer_fails<((zx_object_info_topic_t) 12u), zx_info_task_stats_t, zx_process_self>) [RUNNING] [00006.334] 01046.01049> [PASSED] (0 ms) [00006.334] 01046.01049> (bad_actual_fails<((zx_object_info_topic_t) 12u), zx_info_task_stats_t, zx_process_self>) [RUNNING] [00006.334] 01046.01049> [PASSED] (0 ms) [00006.334] 01046.01049> (bad_avail_fails<((zx_object_info_topic_t) 12u), zx_info_task_stats_t, zx_process_self>) [RUNNING] [00006.334] 01046.01049> [PASSED] (0 ms) [00006.334] 01046.01049> (single_zero_buffer_fails<((zx_object_info_topic_t) 12u), zx_info_task_stats_t, zx_process_self>) [RUNNING] [00006.334] 01046.01049> [PASSED] (0 ms) [00006.334] 01046.01049> (wrong_handle_type_fails<ZX_INFO_TASK_STATS, zx_info_task_stats_t, get_test_job>) [RUNNING] [00006.334] 01046.01049> [PASSED] (0 ms) [00006.334] 01046.01049> (wrong_handle_type_fails<ZX_INFO_TASK_STATS, zx_info_task_stats_t, zx_thread_self>) [RUNNING] [00006.334] 01046.01049> [PASSED] (0 ms) [00006.334] 01046.01049> process_maps_unstarted [RUNNING] [00006.334] 01046.01049> [PASSED] (0 ms) [00006.334] 01046.01049> process_maps_smoke [RUNNING] [00006.471] 01046.01049> [PASSED] (137 ms) [00006.472] 01046.01049> (invalid_handle_fails<((zx_object_info_topic_t) 13u), zx_info_maps_t>) [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> (null_avail_actual_succeeds<((zx_object_info_topic_t) 13u), zx_info_maps_t, get_test_process>) [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> (bad_buffer_fails<((zx_object_info_topic_t) 13u), zx_info_maps_t, get_test_process>) [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> (bad_actual_fails<((zx_object_info_topic_t) 13u), zx_info_maps_t, get_test_process>) [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> (bad_avail_fails<((zx_object_info_topic_t) 13u), zx_info_maps_t, get_test_process>) [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> (multi_zero_buffer_succeeds<((zx_object_info_topic_t) 13u), get_test_process>) [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> (short_buffer_succeeds<((zx_object_info_topic_t) 13u), zx_info_maps_t, get_test_process>) [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> (partially_unmapped_buffer_fails<((zx_object_info_topic_t) 13u), zx_info_maps_t, get_test_process>) [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> (self_fails<ZX_INFO_PROCESS_MAPS, zx_info_maps_t>) [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> (wrong_handle_type_fails<ZX_INFO_PROCESS_MAPS, zx_info_maps_t, get_test_job>) [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> (wrong_handle_type_fails<ZX_INFO_PROCESS_MAPS, zx_info_maps_t, zx_thread_self>) [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> (missing_rights_fails<ZX_INFO_PROCESS_MAPS, zx_info_maps_t, get_test_process, ZX_RIGHT_INSPECT>) [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> process_vmos_smoke [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> (invalid_handle_fails<((zx_object_info_topic_t) 14u), zx_info_vmo_t>) [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> (null_avail_actual_succeeds<((zx_object_info_topic_t) 14u), zx_info_vmo_t, get_test_process>) [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> (bad_buffer_fails<((zx_object_info_topic_t) 14u), zx_info_vmo_t, get_test_process>) [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> (bad_actual_fails<((zx_object_info_topic_t) 14u), zx_info_vmo_t, get_test_process>) [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> (bad_avail_fails<((zx_object_info_topic_t) 14u), zx_info_vmo_t, get_test_process>) [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> (multi_zero_buffer_succeeds<((zx_object_info_topic_t) 14u), get_test_process>) [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> (short_buffer_succeeds<((zx_object_info_topic_t) 14u), zx_info_vmo_t, get_test_process>) [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> (partially_unmapped_buffer_fails<((zx_object_info_topic_t) 14u), zx_info_vmo_t, get_test_process>) [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> (self_fails<ZX_INFO_PROCESS_VMOS, zx_info_vmo_t>) [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> (wrong_handle_type_fails<ZX_INFO_PROCESS_VMOS, zx_info_vmo_t, get_test_job>) [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> (wrong_handle_type_fails<ZX_INFO_PROCESS_VMOS, zx_info_vmo_t, zx_thread_self>) [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> (missing_rights_fails<ZX_INFO_PROCESS_VMOS, zx_info_vmo_t, get_test_process, ZX_RIGHT_INSPECT>) [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> job_processes_smoke [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> (invalid_handle_fails<((zx_object_info_topic_t) 9u), zx_koid_t>) [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> (null_avail_actual_succeeds<((zx_object_info_topic_t) 9u), zx_koid_t, get_test_job>) [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> (bad_buffer_fails<((zx_object_info_topic_t) 9u), zx_koid_t, get_test_job>) [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> (bad_actual_fails<((zx_object_info_topic_t) 9u), zx_koid_t, get_test_job>) [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> (bad_avail_fails<((zx_object_info_topic_t) 9u), zx_koid_t, get_test_job>) [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> (multi_zero_buffer_succeeds<((zx_object_info_topic_t) 9u), get_test_job>) [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> (short_buffer_succeeds<((zx_object_info_topic_t) 9u), zx_koid_t, get_test_job>) [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> (partially_unmapped_buffer_fails<((zx_object_info_topic_t) 9u), zx_koid_t, get_test_job>) [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> (wrong_handle_type_fails<ZX_INFO_JOB_PROCESSES, zx_koid_t, get_test_process>) [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> (wrong_handle_type_fails<ZX_INFO_JOB_PROCESSES, zx_koid_t, zx_thread_self>) [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> (missing_rights_fails<ZX_INFO_JOB_PROCESSES, zx_koid_t, get_test_job, ZX_RIGHT_ENUMERATE>) [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> job_children_smoke [RUNNING] [00006.472] 01046.01049> [PASSED] (0 ms) [00006.472] 01046.01049> (invalid_handle_fails<((zx_object_info_topic_t) 8u), zx_koid_t>) [RUNNING] [00006.473] 01046.01049> [PASSED] (0 ms) [00006.473] 01046.01049> (null_avail_actual_succeeds<((zx_object_info_topic_t) 8u), zx_koid_t, get_test_job>) [RUNNING] [00006.473] 01046.01049> [PASSED] (0 ms) [00006.832] 01046.01049> (bad_buffer_fails<((zx_object_info_topic_t) 8u), zx_koid_t, get_test_job>) [RUNNING] [00006.832] 01046.01049> [PASSED] (0 ms) [00006.832] 01046.01049> (bad_actual_fails<((zx_object_info_topic_t) 8u), zx_koid_t, get_test_job>) [RUNNING] [00006.832] 01046.01049> [PASSED] (0 ms) [00006.832] 01046.01049> (bad_avail_fails<((zx_object_info_topic_t) 8u), zx_koid_t, get_test_job>) [RUNNING] [00006.832] 01046.01049> [PASSED] (0 ms) [00006.832] 01046.01049> (multi_zero_buffer_succeeds<((zx_object_info_topic_t) 8u), get_test_job>) [RUNNING] [00006.832] 01046.01049> [PASSED] (0 ms) [00006.832] 01046.01049> (short_buffer_succeeds<((zx_object_info_topic_t) 8u), zx_koid_t, get_test_job>) [RUNNING] [00006.832] 01046.01049> [PASSED] (0 ms) [00006.832] 01046.01049> (partially_unmapped_buffer_fails<((zx_object_info_topic_t) 8u), zx_koid_t, get_test_job>) [RUNNING] [00006.832] 01046.01049> [PASSED] (0 ms) [00006.832] 01046.01049> (wrong_handle_type_fails<ZX_INFO_JOB_CHILDREN, zx_koid_t, get_test_process>) [RUNNING] [00006.832] 01046.01049> [PASSED] (0 ms) [00006.832] 01046.01049> (wrong_handle_type_fails<ZX_INFO_JOB_CHILDREN, zx_koid_t, zx_thread_self>) [RUNNING] [00006.832] 01046.01049> [PASSED] (0 ms) [00006.832] 01046.01049> (missing_rights_fails<ZX_INFO_JOB_CHILDREN, zx_koid_t, get_test_job, ZX_RIGHT_ENUMERATE>) [RUNNING] [00006.832] 01046.01049> [PASSED] (0 ms) [00006.832] 01046.01049> (invalid_handle_fails<((zx_object_info_topic_t) 2u), zx_info_handle_basic_t>) [RUNNING] [00006.832] 01046.01049> [PASSED] (0 ms) [00006.832] 01046.01049> (null_avail_actual_succeeds<((zx_object_info_topic_t) 2u), zx_info_handle_basic_t, get_test_job>) [RUNNING] [00006.832] 01046.01049> [PASSED] (0 ms) [00006.832] 01046.01049> (bad_buffer_fails<((zx_object_info_topic_t) 2u), zx_info_handle_basic_t, get_test_job>) [RUNNING] [00006.832] 01046.01049> [PASSED] (0 ms) [00006.832] 01046.01049> (bad_actual_fails<((zx_object_info_topic_t) 2u), zx_info_handle_basic_t, get_test_job>) [RUNNING] [00006.832] 01046.01049> [PASSED] (0 ms) [00006.832] 01046.01049> (bad_avail_fails<((zx_object_info_topic_t) 2u), zx_info_handle_basic_t, get_test_job>) [RUNNING] [00006.832] 01046.01049> [PASSED] (0 ms) [00006.832] 01046.01049> (single_zero_buffer_fails<((zx_object_info_topic_t) 2u), zx_info_handle_basic_t, get_test_job>) [RUNNING] [00006.832] 01046.01049> [PASSED] (0 ms) [00006.832] 01046.01049> (invalid_handle_fails<((zx_object_info_topic_t) 2u), zx_info_handle_basic_t>) [RUNNING] [00006.832] 01046.01049> [PASSED] (0 ms) [00006.832] 01046.01049> (null_avail_actual_succeeds<((zx_object_info_topic_t) 2u), zx_info_handle_basic_t, get_test_process>) [RUNNING] [00006.832] 01046.01049> [PASSED] (0 ms) [00006.832] 01046.01049> (bad_buffer_fails<((zx_object_info_topic_t) 2u), zx_info_handle_basic_t, get_test_process>) [RUNNING] [00006.832] 01046.01049> [PASSED] (0 ms) [00006.832] 01046.01049> (bad_actual_fails<((zx_object_info_topic_t) 2u), zx_info_handle_basic_t, get_test_process>) [RUNNING] [00006.832] 01046.01049> [PASSED] (0 ms) [00006.832] 01046.01049> (bad_avail_fails<((zx_object_info_topic_t) 2u), zx_info_handle_basic_t, get_test_process>) [RUNNING] [00006.832] 01046.01049> [PASSED] (0 ms) [00006.832] 01046.01049> (single_zero_buffer_fails<((zx_object_info_topic_t) 2u), zx_info_handle_basic_t, get_test_process>) [RUNNING] [00006.832] 01046.01049> [PASSED] (0 ms) [00006.832] 01046.01049> (invalid_handle_fails<((zx_object_info_topic_t) 2u), zx_info_handle_basic_t>) [RUNNING] [00006.832] 01046.01049> [PASSED] (0 ms) [00006.832] 01046.01049> (null_avail_actual_succeeds<((zx_object_info_topic_t) 2u), zx_info_handle_basic_t, zx_thread_self>) [RUNNING] [00006.832] 01046.01049> [PASSED] (0 ms) [00006.832] 01046.01049> (bad_buffer_fails<((zx_object_info_topic_t) 2u), zx_info_handle_basic_t, zx_thread_self>) [RUNNING] [00006.832] 01046.01049> [PASSED] (0 ms) [00006.832] 01046.01049> (bad_actual_fails<((zx_object_info_topic_t) 2u), zx_info_handle_basic_t, zx_thread_self>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (bad_avail_fails<((zx_object_info_topic_t) 2u), zx_info_handle_basic_t, zx_thread_self>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (single_zero_buffer_fails<((zx_object_info_topic_t) 2u), zx_info_handle_basic_t, zx_thread_self>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (invalid_handle_fails<((zx_object_info_topic_t) 2u), zx_info_handle_basic_t>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (null_avail_actual_succeeds<((zx_object_info_topic_t) 2u), zx_info_handle_basic_t, zx_vmar_root_self>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (bad_buffer_fails<((zx_object_info_topic_t) 2u), zx_info_handle_basic_t, zx_vmar_root_self>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (bad_actual_fails<((zx_object_info_topic_t) 2u), zx_info_handle_basic_t, zx_vmar_root_self>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (bad_avail_fails<((zx_object_info_topic_t) 2u), zx_info_handle_basic_t, zx_vmar_root_self>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (single_zero_buffer_fails<((zx_object_info_topic_t) 2u), zx_info_handle_basic_t, zx_vmar_root_self>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (invalid_handle_fails<((zx_object_info_topic_t) 19u), zx_info_handle_count_t>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (null_avail_actual_succeeds<((zx_object_info_topic_t) 19u), zx_info_handle_count_t, zx_thread_self>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (bad_buffer_fails<((zx_object_info_topic_t) 19u), zx_info_handle_count_t, zx_thread_self>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (bad_actual_fails<((zx_object_info_topic_t) 19u), zx_info_handle_count_t, zx_thread_self>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (bad_avail_fails<((zx_object_info_topic_t) 19u), zx_info_handle_count_t, zx_thread_self>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (single_zero_buffer_fails<((zx_object_info_topic_t) 19u), zx_info_handle_count_t, zx_thread_self>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (invalid_handle_fails<((zx_object_info_topic_t) 3u), zx_info_process_t>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (null_avail_actual_succeeds<((zx_object_info_topic_t) 3u), zx_info_process_t, get_test_process>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (bad_buffer_fails<((zx_object_info_topic_t) 3u), zx_info_process_t, get_test_process>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (bad_actual_fails<((zx_object_info_topic_t) 3u), zx_info_process_t, get_test_process>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (bad_avail_fails<((zx_object_info_topic_t) 3u), zx_info_process_t, get_test_process>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (single_zero_buffer_fails<((zx_object_info_topic_t) 3u), zx_info_process_t, get_test_process>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (wrong_handle_type_fails<ZX_INFO_PROCESS, zx_info_process_t, get_test_job>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (wrong_handle_type_fails<ZX_INFO_PROCESS, zx_info_process_t, zx_thread_self>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (invalid_handle_fails<((zx_object_info_topic_t) 7u), zx_info_vmar_t>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (null_avail_actual_succeeds<((zx_object_info_topic_t) 7u), zx_info_vmar_t, zx_vmar_root_self>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (bad_buffer_fails<((zx_object_info_topic_t) 7u), zx_info_vmar_t, zx_vmar_root_self>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (bad_actual_fails<((zx_object_info_topic_t) 7u), zx_info_vmar_t, zx_vmar_root_self>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (bad_avail_fails<((zx_object_info_topic_t) 7u), zx_info_vmar_t, zx_vmar_root_self>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (single_zero_buffer_fails<((zx_object_info_topic_t) 7u), zx_info_vmar_t, zx_vmar_root_self>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (wrong_handle_type_fails<ZX_INFO_VMAR, zx_info_vmar_t, get_test_job>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (wrong_handle_type_fails<ZX_INFO_VMAR, zx_info_vmar_t, get_test_process>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (wrong_handle_type_fails<ZX_INFO_VMAR, zx_info_vmar_t, zx_thread_self>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (invalid_handle_fails<((zx_object_info_topic_t) 10u), zx_info_thread_t>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (null_avail_actual_succeeds<((zx_object_info_topic_t) 10u), zx_info_thread_t, zx_thread_self>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (bad_buffer_fails<((zx_object_info_topic_t) 10u), zx_info_thread_t, zx_thread_self>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (bad_actual_fails<((zx_object_info_topic_t) 10u), zx_info_thread_t, zx_thread_self>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (bad_avail_fails<((zx_object_info_topic_t) 10u), zx_info_thread_t, zx_thread_self>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (single_zero_buffer_fails<((zx_object_info_topic_t) 10u), zx_info_thread_t, zx_thread_self>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (wrong_handle_type_fails<ZX_INFO_THREAD, zx_info_thread_t, get_test_job>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (wrong_handle_type_fails<ZX_INFO_THREAD, zx_info_thread_t, get_test_process>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (invalid_handle_fails<((zx_object_info_topic_t) 15u), zx_info_thread_stats_t>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (null_avail_actual_succeeds<((zx_object_info_topic_t) 15u), zx_info_thread_stats_t, zx_thread_self>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (bad_buffer_fails<((zx_object_info_topic_t) 15u), zx_info_thread_stats_t, zx_thread_self>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (bad_actual_fails<((zx_object_info_topic_t) 15u), zx_info_thread_stats_t, zx_thread_self>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (bad_avail_fails<((zx_object_info_topic_t) 15u), zx_info_thread_stats_t, zx_thread_self>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (single_zero_buffer_fails<((zx_object_info_topic_t) 15u), zx_info_thread_stats_t, zx_thread_self>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (wrong_handle_type_fails<ZX_INFO_THREAD_STATS, zx_info_thread_t, get_test_job>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (wrong_handle_type_fails<ZX_INFO_THREAD_STATS, zx_info_thread_t, get_test_process>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (invalid_handle_fails<ZX_INFO_PROCESS_THREADS, zx_koid_t>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (null_avail_actual_succeeds<ZX_INFO_PROCESS_THREADS, zx_koid_t, get_test_process>) [RUNNING] [00006.833] 01046.01049> [PASSED] (0 ms) [00006.833] 01046.01049> (bad_buffer_fails<ZX_INFO_PROCESS_THREADS, zx_koid_t, get_test_process>) [RUNNING] [00007.508] 01046.01049> [PASSED] (0 ms) [00007.508] 01046.01049> (bad_actual_fails<ZX_INFO_PROCESS_THREADS, zx_koid_t, get_test_process>) [RUNNING] [00007.508] 01046.01049> [PASSED] (0 ms) [00007.508] 01046.01049> (bad_avail_fails<ZX_INFO_PROCESS_THREADS, zx_koid_t, get_test_process>) [RUNNING] [00007.508] 01046.01049> [PASSED] (0 ms) [00007.508] 01046.01049> (multi_zero_buffer_succeeds<ZX_INFO_PROCESS_THREADS, get_test_process>) [RUNNING] [00007.508] 01046.01049> [PASSED] (0 ms) [00007.508] 01046.01049> (invalid_handle_fails<ZX_INFO_THREAD_EXCEPTION_REPORT, zx_exception_report_t>) [RUNNING] [00007.508] 01046.01049> [PASSED] (0 ms) [00007.508] 01046.01049> handle_count_valid [RUNNING] [00007.508] 01046.01049> [PASSED] (0 ms) [00007.508] 01046.01049> handle_stats_control [RUNNING] [00007.508] 01046.01049> [PASSED] (0 ms) [00007.508] 01046.01049> CASE object_info_tests [PASSED] [00007.508] 01046.01049> CASE process_tests [STARTED] [00007.508] 01046.01049> mini_process_sanity [RUNNING] [00007.575] 01046.01049> [PASSED] (67 ms) [00007.575] 01046.01049> process_start_fail [RUNNING] [00007.583] 01046.01049> [PASSED] (8 ms) [00007.583] 01046.01049> process_not_killed_via_thread_close [RUNNING] [00007.591] 01046.01049> [PASSED] (7 ms) [00007.591] 01046.01049> process_not_killed_via_process_close [RUNNING] [00007.598] 01046.01049> [PASSED] (7 ms) [00007.598] 01046.01049> kill_process_via_thread_kill [RUNNING] [00007.607] 01046.01049> [PASSED] (8 ms) [00007.607] 01046.01049> kill_process_via_vmar_destroy [RUNNING] [00007.616] 01742.01744> PageFault: 1018443 free pages [00007.616] 01742.01744> PageFault: MemoryUsed: proc 1032 31M 'userboot' [00007.624] 01046.01049> [PASSED] (16 ms) [00007.624] 01046.01049> kill_channel_handle_cycle [RUNNING] [00008.376] 01046.01049> [PASSED] (752 ms) [00008.376] 01046.01049> info_reflects_process_state [RUNNING] [00008.627] 01046.01049> [PASSED] (250 ms) [00008.627] 01046.01049> CASE process_tests [PASSED] [00008.628] 01046.01049> CASE threads_tests [STARTED] [00008.628] 01046.01049> TestBasics [RUNNING] [00008.728] 01046.01049> [PASSED] (100 ms) [00008.728] 01046.01049> TestDetach [RUNNING] [00008.729] 01046.01049> [PASSED] (0 ms) [00008.729] 01046.01049> TestLongNameSucceeds [RUNNING] [00008.743] 01046.01049> [PASSED] (14 ms) [00008.743] 01046.01049> TestThreadStartOnInitialThread [RUNNING] [00008.751] 01046.01049> [PASSED] (7 ms) [00008.751] 01046.01049> TestThreadStartWithZeroInstructionPointer [RUNNING] [00008.760] 01046.01049> [PASSED] (8 ms) [00008.760] 01046.01049> TestKillBusyThread [RUNNING] [00008.861] 01046.01049> [PASSED] (101 ms) [00008.861] 01046.01049> TestKillSleepThread [RUNNING] [00008.962] 01046.01049> [PASSED] (100 ms) [00008.962] 01046.01049> TestKillWaitThread [RUNNING] [00009.063] 01046.01049> [PASSED] (101 ms) [00009.063] 01046.01049> TestBadStateNonstartedThread [RUNNING] [00009.071] 01046.01049> [PASSED] (7 ms) [00009.071] 01046.01049> TestThreadKillsItself [RUNNING] [00009.073] 01046.01049> [PASSED] (2 ms) [00009.073] 01046.01049> TestInfoTaskStatsFails [RUNNING] [00009.174] 01046.01049> [PASSED] (100 ms) [00009.174] 01046.01049> TestResumeSuspended [RUNNING] [00009.277] 01046.01049> [PASSED] (102 ms) [00009.277] 01046.01049> TestSuspendSleeping [RUNNING] [00009.377] 01046.01049> [PASSED] (100 ms) [00009.378] 01046.01049> TestSuspendChannelCall [RUNNING] [00009.388] 01046.01049> [PASSED] (10 ms) [00009.388] 01046.01049> TestSuspendPortCall [RUNNING] [00009.589] 01046.01049> [PASSED] (201 ms) [00009.589] 01046.01049> TestSuspendStopsThread [RUNNING] [00009.640] 01046.01049> [PASSED] (50 ms) [00009.640] 01046.01049> TestSuspendMultiple [RUNNING] [00009.641] 01046.01049> [PASSED] (0 ms) [00009.641] 01046.01049> TestKillSuspendedThread [RUNNING] [00009.641] 01046.01049> [PASSED] (0 ms) [00009.641] 01046.01049> TestSuspendSingleWaitAsyncSignalDelivery [RUNNING] [00009.643] 01046.01049> [PASSED] (1 ms) [00009.643] 01046.01049> TestSuspendRepeatingWaitAsyncSignalDelivery [RUNNING] [00009.647] 01046.01049> [PASSED] (4 ms) [00009.648] 01046.01049> TestReadingGeneralRegisterState [RUNNING] [00009.748] 01046.01049> [PASSED] (100 ms) [00009.748] 01046.01049> TestWritingGeneralRegisterState [RUNNING] [00009.849] 01046.01049> [PASSED] (100 ms) [00009.849] 01046.01049> TestNoncanonicalRipAddress [RUNNING] [00009.950] 01046.01049> [PASSED] (101 ms) [00009.950] 01046.01049> TestWritingArmFlagsRegister [RUNNING] [00009.950] 01046.01049> [PASSED] (0 ms) [00009.950] 01046.01049> CASE threads_tests [PASSED] [00009.950] 01046.01049> CASE port_tests [STARTED] [00009.950] 01046.01049> basic_test [RUNNING] [00009.950] 01046.01049> [PASSED] (0 ms) [00009.950] 01046.01049> queue_and_close_test [RUNNING] [00009.950] 01046.01049> [PASSED] (0 ms) [00009.950] 01046.01049> queue_too_many [RUNNING] [00009.951] 01046.01049> [PASSED] (0 ms) [00009.951] 01046.01049> async_wait_channel_test [RUNNING] [00009.954] 01046.01049> [PASSED] (2 ms) [00009.954] 01046.01049> async_wait_event_test_single [RUNNING] [00009.954] 01046.01049> [PASSED] (0 ms) [00009.954] 01046.01049> async_wait_event_test_repeat [RUNNING] [00009.954] 01046.01049> [PASSED] (0 ms) [00009.954] 01046.01049> async_wait_invalid_option [RUNNING] [00009.954] 01046.01049> [PASSED] (0 ms) [00009.954] 01046.01049> async_wait_close_order_1 [RUNNING] [00009.954] 01046.01049> [PASSED] (0 ms) [00009.954] 01046.01049> async_wait_close_order_2 [RUNNING] [00009.954] 01046.01049> [PASSED] (0 ms) [00009.954] 01046.01049> async_wait_close_order_3 [RUNNING] [00009.954] 01046.01049> [PASSED] (0 ms) [00009.954] 01046.01049> async_wait_close_order_4 [RUNNING] [00009.954] 01046.01049> [PASSED] (0 ms) [00009.954] 01046.01049> async_wait_close_order_5 [RUNNING] [00009.954] 01046.01049> [PASSED] (0 ms) [00009.954] 01046.01049> async_wait_close_order_6 [RUNNING] [00009.954] 01046.01049> [PASSED] (0 ms) [00009.954] 01046.01049> channel_pre_writes_once [RUNNING] [00009.954] 01046.01049> [PASSED] (0 ms) [00009.954] 01046.01049> channel_pre_writes_repeat [RUNNING] [00009.954] 01046.01049> [PASSED] (0 ms) [00009.954] 01046.01049> cancel_event_key_once [RUNNING] [00009.954] 01046.01049> [PASSED] (0 ms) [00009.954] 01046.01049> cancel_event_key_repeat [RUNNING] [00009.954] 01046.01049> [PASSED] (0 ms) [00009.954] 01046.01049> cancel_event_key_once_after [RUNNING] [00009.954] 01046.01049> [PASSED] (0 ms) [00009.954] 01046.01049> cancel_event_key_repeat_after [RUNNING] [00009.954] 01046.01049> [PASSED] (0 ms) [00009.954] 01046.01049> threads_event_once [RUNNING] [00009.958] 01046.01049> [PASSED] (4 ms) [00009.958] 01046.01049> threads_event_repeat [RUNNING] [00009.973] 01046.01049> [PASSED] (14 ms) [00009.973] 01046.01049> cancel_stress [RUNNING] [00015.766] 01046.01049> [PASSED] (5792 ms) [00015.766] 01046.01049> CASE port_tests [PASSED] [00015.766] 01046.01049> CASE object_get_child_tests [STARTED] [00015.766] 01046.01049> handle_invalid [RUNNING] [00015.766] 01046.01049> [PASSED] (0 ms) [00015.766] 01046.01049> CASE object_get_child_tests [PASSED] [00015.766] 01046.01049> CASE vmar_tests [STARTED] [00015.766] 01046.01049> destroy_root_test [RUNNING] [00015.766] 01046.01049> [PASSED] (0 ms) [00015.766] 01046.01049> basic_allocate_test [RUNNING] [00015.766] 01046.01049> [PASSED] (0 ms) [00015.766] 01046.01049> allocate_oob_test [RUNNING] [00015.766] 01046.01049> [PASSED] (0 ms) [00015.766] 01046.01049> allocate_unsatisfiable_test [RUNNING] [00015.766] 01046.01049> [PASSED] (0 ms) [00015.766] 01046.01049> destroyed_vmar_test [RUNNING] [00015.766] 01046.01049> [PASSED] (0 ms) [00015.766] 01046.01049> map_over_destroyed_test [RUNNING] [00015.766] 01046.01049> [PASSED] (0 ms) [00015.766] 01046.01049> map_in_compact_test [RUNNING] [00015.766] 01046.01049> [PASSED] (0 ms) [00015.766] 01046.01049> overmapping_test [RUNNING] [00015.766] 01046.01049> [PASSED] (0 ms) [00015.766] 01046.01049> invalid_args_test [RUNNING] [00015.766] 01046.01049> [PASSED] (0 ms) [00015.766] 01046.01049> unaligned_len_test [RUNNING] [00015.767] 01046.01049> [PASSED] (0 ms) [00015.767] 01046.01049> unaligned_len_map_test [RUNNING] [00015.767] 01046.01049> [PASSED] (0 ms) [00015.767] 01046.01049> rights_drop_test [RUNNING] [00015.767] 01046.01049> [PASSED] (0 ms) [00015.767] 01046.01049> protect_test [RUNNING] [00015.767] 01046.01049> [PASSED] (0 ms) [00015.767] 01046.01049> nested_region_perms_test [RUNNING] [00015.767] 01046.01049> [PASSED] (0 ms) [00015.767] 01046.01049> object_info_test [RUNNING] [00015.767] 01046.01049> [PASSED] (0 ms) [00015.767] 01046.01049> unmap_split_test [RUNNING] [00015.767] 01046.01049> [PASSED] (0 ms) [00015.767] 01046.01049> unmap_multiple_test [RUNNING] [00015.767] 01046.01049> [PASSED] (0 ms) [00015.767] 01046.01049> unmap_base_not_mapped_test [RUNNING] [00015.767] 01046.01049> [PASSED] (0 ms) [00015.767] 01046.01049> map_specific_overwrite_test [RUNNING] [00015.767] 01046.01049> [PASSED] (0 ms) [00015.767] 01046.01049> protect_split_test [RUNNING] [00015.767] 01046.01049> [PASSED] (0 ms) [00015.767] 01046.01049> protect_multiple_test [RUNNING] [00015.767] 01046.01049> [PASSED] (0 ms) [00015.767] 01046.01049> protect_over_demand_paged_test [RUNNING] [00015.783] 01046.01049> [PASSED] (15 ms) [00015.783] 01046.01049> protect_large_uncommitted_test [RUNNING] [00015.798] 01046.01049> [PASSED] (15 ms) [00015.798] 01046.01049> unmap_large_uncommitted_test [RUNNING] [00015.801] 01046.02084> PageFault: 1018018 free pages [00015.801] 01046.02084> PageFault: MemoryUsed: proc 1032 31M 'userboot' [00015.805] 01046.02086> PageFault: 1018018 free pages [00015.805] 01046.02086> PageFault: MemoryUsed: proc 1032 31M 'userboot' [00015.807] 01046.02088> PageFault: 1018018 free pages [00015.807] 01046.02088> PageFault: MemoryUsed: proc 1032 31M 'userboot' [00015.811] 01046.01049> [PASSED] (12 ms) [00015.811] 01046.01049> partial_unmap_and_read [RUNNING] [00015.811] 01046.01049> [PASSED] (0 ms) [00015.811] 01046.01049> partial_unmap_and_write [RUNNING] [00015.811] 01046.01049> [PASSED] (0 ms) [00015.811] 01046.01049> partial_unmap_with_vmar_offset [RUNNING] [00015.811] 01046.01049> [PASSED] (0 ms) [00015.811] 01046.01049> CASE vmar_tests [PASSED] [00015.811] 01046.01049> CASE profile_cpp_tests [STARTED] [00015.811] 01046.01049> profile_failures_test [RUNNING] [00015.811] 01046.01049> [PASSED] (0 ms) [00015.811] 01046.01049> profile_priority_test [RUNNING] [00015.811] 01046.01049> [PASSED] (0 ms) [00015.811] 01046.01049> CASE profile_cpp_tests [PASSED] [00015.811] 01046.01049> CASE resource_tests [STARTED] [00015.811] 01046.01049> probe_address_space [RUNNING] [00015.811] 01046.01049> [PASSED] (0 ms) [00015.811] 01046.01049> TestBasicActions [RUNNING] [00015.811] 01046.01049> [PASSED] (0 ms) [00015.811] 01046.01049> TestExclusiveShared [RUNNING] [00015.812] 01046.01049> [PASSED] (0 ms) [00015.812] 01046.01049> TestSharedExclusive [RUNNING] [00015.812] 01046.01049> [PASSED] (0 ms) [00015.812] 01046.01049> TestInvalidArgs [RUNNING] [00015.812] 01046.01049> [PASSED] (0 ms) [00015.812] 01046.01049> TestVmoCreation [RUNNING] [00015.812] 01046.01049> [PASSED] (0 ms) [00015.812] 01046.01049> TestVmoCreationSmaller [RUNNING] [00015.812] 01046.01049> [PASSED] (0 ms) [00015.812] 01046.01049> TestVmoCreationUnaligned [RUNNING] [00015.812] 01046.01049> [PASSED] (0 ms) [00015.812] 01046.01049> TestVmoReplaceAsExecutable [RUNNING] [00015.812] 01046.01049> [PASSED] (0 ms) [00015.812] 01046.01049> test_ioports [RUNNING] [00015.813] 01046.01049> [PASSED] (1 ms) [00015.813] 01046.01049> CASE resource_tests [PASSED] [00015.813] 01046.01049> CASE futex_tests [STARTED] [00015.813] 01046.01049> TestFutexWaitValueMismatch [RUNNING] [00015.813] 01046.01049> [PASSED] (0 ms) [00015.813] 01046.01049> TestFutexWaitTimeout [RUNNING] [00015.813] 01046.01049> [PASSED] (0 ms) [00015.813] 01046.01049> TestFutexWaitTimeoutElapsed [RUNNING] [00018.314] 01046.01049> [PASSED] (2500 ms) [00018.314] 01046.01049> TestFutexWaitBadAddress [RUNNING] [00018.314] 01046.01049> [PASSED] (0 ms) [00018.314] 01046.01049> TestFutexWakeup [RUNNING] [00018.316] 01046.01049> [PASSED] (1 ms) [00018.316] 01046.01049> TestFutexWakeupLimit [RUNNING] [00018.326] 01046.01049> [PASSED] (10 ms) [00018.326] 01046.01049> TestFutexWakeupAddress [RUNNING] [00018.342] 01046.01049> [PASSED] (15 ms) [00018.342] 01046.01049> TestFutexUnqueuedOnTimeout [RUNNING] [00018.351] 01046.01049> [PASSED] (8 ms) [00018.351] 01046.01049> TestFutexUnqueuedOnTimeout_2 [RUNNING] [00018.555] 01046.01049> [PASSED] (204 ms) [00018.556] 01046.01049> TestFutexUnqueuedOnTimeout_3 [RUNNING] [00018.969] 01046.01049> [PASSED] (413 ms) [00018.969] 01046.01049> TestFutexRequeueValueMismatch [RUNNING] [00018.969] 01046.01049> [PASSED] (0 ms) [00018.969] 01046.01049> TestFutexRequeueSameAddr [RUNNING] [00018.969] 01046.01049> [PASSED] (0 ms) [00018.969] 01046.01049> TestFutexRequeue [RUNNING] [00018.997] 01046.01049> [PASSED] (27 ms) [00018.998] 01046.01049> TestFutexRequeueUnqueuedOnTimeout [RUNNING] [00019.309] 01046.01049> [PASSED] (311 ms) [00019.309] 01046.01049> TestFutexThreadKilled [RUNNING] [00019.320] 01046.01049> [PASSED] (11 ms) [00019.321] 01046.01049> TestFutexThreadSuspended [RUNNING] [00019.349] 01046.01049> [PASSED] (28 ms) [00019.349] 01046.01049> TestFutexMisaligned [RUNNING] [00019.349] 01046.01049> [PASSED] (0 ms) [00019.349] 01046.01049> TestEventSignaling [RUNNING] [00019.654] 01046.01049> [PASSED] (304 ms) [00019.654] 01046.01049> CASE futex_tests [PASSED] [00019.654] 01046.01049> CASE sync_completion_tests [STARTED] [00019.654] 01046.01049> test_initializer [RUNNING] [00019.654] 01046.01049> [PASSED] (0 ms) [00019.654] 01046.01049> test_completions [RUNNING] [00019.692] 01046.01049> [PASSED] (38 ms) [00019.692] 01046.01049> test_timeout [RUNNING] [00019.693] 01046.01049> [PASSED] (0 ms) [00019.693] 01046.01049> CASE sync_completion_tests [PASSED] [00019.693] 01046.01049> CASE ticks_tests [STARTED] [00019.693] 01046.01049> elapsed_time_using_ticks [RUNNING] [00019.693] 01046.01049> [PASSED] (0 ms) [00019.693] 01046.01049> CASE ticks_tests [PASSED] [00019.693] 01046.01049> CASE eventpair_tests [STARTED] [00019.693] 01046.01049> create_test [RUNNING] [00019.693] 01046.01049> [PASSED] (0 ms) [00019.693] 01046.01049> signal_test [RUNNING] [00019.693] 01046.01049> [PASSED] (0 ms) [00019.693] 01046.01049> signal_peer_test [RUNNING] [00019.693] 01046.01049> [PASSED] (0 ms) [00019.693] 01046.01049> signal_peer_closed_test [RUNNING] [00019.693] 01046.01049> [PASSED] (0 ms) [00019.693] 01046.01049> CASE eventpair_tests [PASSED] [00019.693] 01046.01049> CASE interrupt_tests [STARTED] [00019.693] 01046.01049> interrupt_test [RUNNING] [00019.693] 01046.01049> [PASSED] (0 ms) [00019.693] 01046.01049> interrupt_port_bound_test [RUNNING] [00019.693] 01046.01049> [PASSED] (0 ms) [00019.693] 01046.01049> interrupt_port_non_bindable_test [RUNNING] [00019.693] 01046.01049> [PASSED] (0 ms) [00019.693] 01046.01049> interrupt_suspend_test [RUNNING] [00019.697] 01046.01049> [PASSED] (3 ms) [00019.697] 01046.01049> interrupt_bind_vcpu_test [RUNNING] [00019.697] 01046.01049> Guest creation not supported [00019.697] 01046.01049> [PASSED] (0 ms) [00019.697] 01046.01049> interrupt_bind_vcpu_not_supported_test [RUNNING] [00019.697] 01046.01049> Guest creation not supported [00019.697] 01046.01049> [PASSED] (0 ms) [00019.697] 01046.01049> interrupt_bind_vcpu_already_bound_test [RUNNING] [00019.697] 01046.01049> Guest creation not supported [00019.697] 01046.01049> [PASSED] (0 ms) [00019.697] 01046.01049> interrupt_bind_vcpu_multiple_guests_test [RUNNING] [00019.697] 01046.01049> Guest creation not supported [00019.697] 01046.01049> [PASSED] (0 ms) [00019.697] 01046.01049> CASE interrupt_tests [PASSED] [00019.697] 01046.01049> CASE stack_tests [STARTED] [00019.697] 01046.01049> main_thread_stack_tests [RUNNING] [00019.697] 01046.01049> [PASSED] (0 ms) [00019.697] 01046.01049> other_thread_stack_tests [RUNNING] [00019.698] 01046.01049> [PASSED] (1 ms) [00019.698] 01046.01049> CASE stack_tests [PASSED] [00019.698] 01046.01049> CASE mtx_tests [STARTED] [00019.698] 01046.01049> test_initializer [RUNNING] [00019.698] 01046.01049> [PASSED] (0 ms) [00019.698] 01046.01049> test_mutexes [RUNNING] [00019.791] 01046.01049> [PASSED] (92 ms) [00019.791] 01046.01049> test_try_mutexes [RUNNING] [00019.890] 01046.01049> [PASSED] (99 ms) [00019.890] 01046.01049> test_static_initializer [RUNNING] [00019.890] 01046.01049> [PASSED] (0 ms) [00019.890] 01046.01049> test_timeout_elapsed [RUNNING] [00020.404] 01046.01049> [PASSED] (513 ms) [00020.404] 01046.01049> CASE mtx_tests [PASSED] [00020.404] 01046.01049> CASE c11_thread_tests [STARTED] [00020.404] 01046.01049> c11_thread_test [RUNNING] [00021.214] 01046.01049> [PASSED] (810 ms) [00021.214] 01046.01049> long_name_succeeds [RUNNING] [00021.316] 01046.01049> [PASSED] (101 ms) [00021.316] 01046.01049> detach_self_test [RUNNING] [00022.618] 01046.01049> [PASSED] (1301 ms) [00022.618] 01046.01049> CASE c11_thread_tests [PASSED] [00022.618] 01046.01049> CASE echo_tests [STARTED] [00022.618] 01046.01049> echo_test [RUNNING] [00022.618] 01046.01049> [PASSED] (0 ms) [00022.618] 01046.01049> CASE echo_tests [PASSED] [00022.618] 01046.01049> CASE socket_tests [STARTED] [00022.618] 01046.01049> socket_basic [RUNNING] [00022.618] 01046.01049> [PASSED] (0 ms) [00022.618] 01046.01049> socket_signals [RUNNING] [00022.619] 01046.01049> [PASSED] (0 ms) [00022.619] 01046.01049> socket_peer_closed_signal [RUNNING] [00022.619] 01046.01049> [PASSED] (0 ms) [00022.619] 01046.01049> socket_peer_closed_set_property [RUNNING] [00022.619] 01046.01049> [PASSED] (0 ms) [00022.619] 01046.01049> socket_shutdown_write [RUNNING] [00022.619] 01046.01049> [PASSED] (0 ms) [00022.619] 01046.01049> socket_shutdown_read [RUNNING] [00022.619] 01046.01049> [PASSED] (0 ms) [00022.619] 01046.01049> socket_bytes_outstanding [RUNNING] [00022.619] 01046.01049> [PASSED] (0 ms) [00022.619] 01046.01049> socket_bytes_outstanding_shutdown_write [RUNNING] [00022.619] 01046.01049> [PASSED] (0 ms) [00022.619] 01046.01049> socket_bytes_outstanding_shutdown_read [RUNNING] [00022.619] 01046.01049> [PASSED] (0 ms) [00022.619] 01046.01049> socket_short_write [RUNNING] [00022.619] 01046.01049> [PASSED] (0 ms) [00022.619] 01046.01049> socket_datagram [RUNNING] [00022.619] 01046.01049> [PASSED] (0 ms) [00022.619] 01046.01049> socket_datagram_no_short_write [RUNNING] [00022.619] 01046.01049> [PASSED] (0 ms) [00022.619] 01046.01049> socket_control_plane_absent [RUNNING] [00022.619] 01046.01049> [PASSED] (0 ms) [00022.619] 01046.01049> socket_control_plane [RUNNING] [00022.619] 01046.01049> [PASSED] (0 ms) [00022.619] 01046.01049> socket_control_plane_shutdown [RUNNING] [00022.619] 01046.01049> [PASSED] (0 ms) [00022.619] 01046.01049> socket_accept [RUNNING] [00022.619] 01046.01049> [PASSED] (0 ms) [00022.619] 01046.01049> socket_share_invalid_handle [RUNNING] [00022.619] 01046.01049> [PASSED] (0 ms) [00022.619] 01046.01049> socket_share_consumes_on_failure [RUNNING] [00022.619] 01046.01049> [PASSED] (0 ms) [00022.619] 01046.01049> socket_signals2 [RUNNING] [00022.619] 01046.01049> [PASSED] (0 ms) [00022.619] 01046.01049> CASE socket_tests [PASSED] [00022.619] 01046.01049> CASE handle_info_tests [STARTED] [00022.619] 01046.01049> handle_replace_test [RUNNING] [00022.619] 01046.01049> [PASSED] (0 ms) [00022.619] 01046.01049> handle_info_test [RUNNING] [00022.619] 01046.01049> [PASSED] (0 ms) [00022.619] 01046.01049> handle_related_koid_test [RUNNING] [00022.642] 01046.01049> [PASSED] (22 ms) [00022.642] 01046.01049> handle_rights_test [RUNNING] [00022.642] 01046.01049> [PASSED] (0 ms) [00022.642] 01046.01049> CASE handle_info_tests [PASSED] [00022.642] 01046.01049> CASE handle_wait_tests [STARTED] [00022.642] 01046.01049> handle_wait_test [RUNNING] [00022.652] 01046.01049> [PASSED] (10 ms) [00022.652] 01046.01049> CASE handle_wait_tests [PASSED] [00022.652] 01046.01049> CASE pthread_barrier_tests [STARTED] [00022.652] 01046.01049> test_barrier [RUNNING] [00023.053] 01046.01049> [PASSED] (400 ms) [00023.055] 01046.01049> CASE pthread_barrier_tests [PASSED] [00023.055] 01046.01049> CASE handle_transfer_tests [STARTED] [00023.055] 01046.01049> handle_transfer_test [RUNNING] [00023.055] 01046.01049> [PASSED] (0 ms) [00023.055] 01046.01049> handle_transfer_cancel_wait_test [RUNNING] [00023.089] 01046.01049> [PASSED] (33 ms) [00023.089] 01046.01049> CASE handle_transfer_tests [PASSED] [00023.089] 01046.01049> CASE fifo_tests [STARTED] [00023.089] 01046.01049> basic_test [RUNNING] [00023.089] 01046.01049> [PASSED] (0 ms) [00023.089] 01046.01049> peer_closed_test [RUNNING] [00023.089] 01046.01049> [PASSED] (0 ms) [00023.089] 01046.01049> options_test [RUNNING] [00023.089] 01046.01049> [PASSED] (0 ms) [00023.089] 01046.01049> CASE fifo_tests [PASSED] [00023.089] 01046.01049> CASE vmo_signal_tests [STARTED] [00023.089] 01046.01049> vmo_signal_sanity_test [RUNNING] [00023.089] 01046.01049> [PASSED] (0 ms) [00023.089] 01046.01049> vmo_child_signal_clone_test [RUNNING] [00023.089] 01046.01049> [PASSED] (0 ms) [00023.089] 01046.01049> vmo_child_signal_map_test [RUNNING] [00023.089] 01046.01049> [PASSED] (0 ms) [00023.089] 01046.01049> CASE vmo_signal_tests [PASSED] [00023.089] 01046.01049> CASE cookie_tests [STARTED] [00023.089] 01046.01049> test_cookie_actions [RUNNING] [00023.089] 01046.01049> [PASSED] (0 ms) [00023.089] 01046.01049> test_cookie_eventpair [RUNNING] [00023.089] 01046.01049> [PASSED] (0 ms) [00023.089] 01046.01049> CASE cookie_tests [PASSED] [00023.089] 01046.01049> CASE job_tests [STARTED] [00023.089] 01046.01049> basic_test [RUNNING] [00023.089] 01046.01049> [PASSED] (0 ms) [00023.089] 01046.01049> create_missing_rights_test [RUNNING] [00023.089] 01046.01049> [PASSED] (0 ms) [00023.089] 01046.01049> policy_basic_test [RUNNING] [00023.089] 01046.01049> [PASSED] (0 ms) [00023.089] 01046.01049> create_test [RUNNING] [00023.089] 01046.01049> [PASSED] (0 ms) [00023.089] 01046.01049> kill_test [RUNNING] [00023.096] 01046.01049> [PASSED] (6 ms) [00023.096] 01046.01049> kill_job_no_child_test [RUNNING] [00023.096] 01046.01049> [PASSED] (0 ms) [00023.096] 01046.01049> set_job_oom_kill_bit [RUNNING] [00023.096] 01046.01049> [PASSED] (0 ms) [00023.096] 01046.01049> wait_test [RUNNING] [00023.245] 01046.01049> [PASSED] (148 ms) [00023.245] 01046.01049> info_task_stats_fails [RUNNING] [00023.245] 01046.01049> [PASSED] (0 ms) [00023.245] 01046.01049> max_height_smoke [RUNNING] [00023.245] 01046.01049> [PASSED] (0 ms) [00023.245] 01046.01049> CASE job_tests [PASSED] [00023.245] 01046.01049> CASE bad_syscall_tests [STARTED] [00023.245] 01046.01049> bad_access_test [RUNNING] [00023.245] 01046.01049> [PASSED] (0 ms) [00023.245] 01046.01049> bad_syscall_num_test [RUNNING] [00023.291] 01046.01049> [PASSED] (45 ms) [00023.291] 01046.01049> CASE bad_syscall_tests [PASSED] [00023.291] 01046.01049> CASE cnd_tests [STARTED] [00023.291] 01046.01049> cnd_test [RUNNING] [00023.314] 01046.01049> [PASSED] (23 ms) [00023.314] 01046.01049> cnd_timedwait_timeout_test [RUNNING] [00023.316] 01046.01049> [PASSED] (1 ms) [00023.316] 01046.01049> CASE cnd_tests [PASSED] [00023.316] 01046.01049> CASE handle_close_tests [STARTED] [00023.316] 01046.01049> handle_close_many_test [RUNNING] [00023.316] 01046.01049> [PASSED] (0 ms) [00023.316] 01046.01049> handle_close_many_invalid_test [RUNNING] [00023.316] 01046.01049> [PASSED] (0 ms) [00023.316] 01046.01049> handle_close_many_duplicate_test [RUNNING] [00023.316] 01046.01049> [PASSED] (0 ms) [00023.316] 01046.01049> CASE handle_close_tests [PASSED] [00023.316] 01046.01049> CASE sync_mutex_tests [STARTED] [00023.316] 01046.01049> test_mutexes [RUNNING] [00023.401] 01046.01049> [PASSED] (85 ms) [00023.401] 01046.01049> test_try_mutexes [RUNNING] [00023.462] 01046.01049> [PASSED] (60 ms) [00023.462] 01046.01049> test_timeout_elapsed [RUNNING] [00023.964] 01046.01049> [PASSED] (502 ms) [00023.964] 01046.01049> CASE sync_mutex_tests [PASSED] [00023.964] 01046.01049> CASE clock_tests [STARTED] [00023.964] 01046.01049> clock_monotonic_test [RUNNING] [00023.980] 01046.01049> [PASSED] (15 ms) [00023.980] 01046.01049> CASE clock_tests [PASSED] [00023.980] 01046.01049> CASE profile_tests [STARTED] [00023.980] 01046.01049> make_profile_fails [RUNNING] [00023.980] 01046.01049> [PASSED] (0 ms) [00023.980] 01046.01049> change_priority_via_profile [RUNNING] [00023.980] 01046.01049> [PASSED] (0 ms) [00023.980] 01046.01049> CASE profile_tests [PASSED] [00023.980] 01046.01049> CASE tls_tests [STARTED] [00023.980] 01046.01049> tls_test [RUNNING] [00026.077] 01046.01049> [PASSED] (2096 ms) [00026.077] 01046.01049> CASE tls_tests [PASSED] [00026.077] 01046.01049> CASE channel_tests [STARTED] [00026.077] 01046.01049> channel_test [RUNNING] [00026.079] 01046.01049> [PASSED] (1 ms) [00026.079] 01046.01049> channel_read_error_test [RUNNING] [00026.079] 01046.01049> [PASSED] (0 ms) [00026.079] 01046.01049> channel_close_test [RUNNING] [00026.079] 01046.01049> [PASSED] (0 ms) [00026.079] 01046.01049> channel_peer_closed_test [RUNNING] [00026.079] 01046.01049> [PASSED] (0 ms) [00026.079] 01046.01049> channel_non_transferable [RUNNING] [00026.079] 01046.01049> [PASSED] (0 ms) [00026.079] 01046.01049> channel_duplicate_handles [RUNNING] [00026.079] 01046.01049> [PASSED] (0 ms) [00026.079] 01046.01049> channel_multithread_read [RUNNING] [00026.114] 01046.01049> [PASSED] (35 ms) [00026.114] 01046.01049> channel_may_discard [RUNNING] [00026.114] 01046.01049> [PASSED] (0 ms) [00026.114] 01046.01049> channel_call [RUNNING] [00026.124] 01046.01049> [PASSED] (10 ms) [00026.124] 01046.01049> channel_call_consumes_handles [RUNNING] [00026.124] 01046.01049> [PASSED] (0 ms) [00026.124] 01046.01049> channel_call2 [RUNNING] [00026.389] 01046.01049> [PASSED] (264 ms) [00026.389] 01046.01049> bad_channel_call_finish [RUNNING] [00026.389] 01046.01049> [PASSED] (0 ms) [00026.389] 01046.01049> channel_nest [RUNNING] [00026.389] 01046.01049> [PASSED] (0 ms) [00026.389] 01046.01049> channel_disallow_write_to_self [RUNNING] [00026.389] 01046.01049> [PASSED] (0 ms) [00026.389] 01046.01049> channel_read_etc [RUNNING] [00026.389] 01046.01049> [PASSED] (0 ms) [00026.389] 01046.01049> channel_write_different_sizes [RUNNING] [00026.468] 01046.01049> [PASSED] (78 ms) [00026.468] 01046.01049> channel_write_takes_all_handles [RUNNING] [00026.469] 01046.01049> [PASSED] (1 ms) [00026.469] 01046.01049> CASE channel_tests [PASSED] [00026.469] 01046.01049> ==================================================== [00026.469] 01046.01049> Results: [00026.469] 01046.01049> SUCCESS! All test cases passed! [00026.469] 01046.01049> CASES: 39 SUCCESS: 39 FAILED: 0 [00026.469] 01046.01049> ==================================================== [00026.470] 01046.01049> core-tests succeeded RZMm59f7zOSs6aZUIXZR [00026.503] 01032.01039> userboot: loader-service channel peer closed [00026.503] 01032.01039> userboot: Waiting for bin/core-tests to exit... [00026.504] 01032.01039> userboot: Process exited. Executing "poweroff". [00026.517] 01032.01039> Shutting down debuglog 2018/12/07 02:50:14 successful QEMU exit status: 31