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