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

2019/04/25 14:33:02 QEMU invocation: [/b/s/w/ir/qemu/bin/qemu-system-aarch64 -machine virt,gic_version=host -cpu host -enable-kvm -m 4096 -smp 4 -nographic -serial stdio -monitor none -kernel /b/s/w/ir/qemu-boot-shim.bin -initrd /b/s/w/ir/core-tests-arm64.zbi -netdev user,id=net0 -device virtio-net-pci,netdev=net0 -append kernel.halt-on-panic=true devmgr.suspend-timeout-debug=true TERM=dumb] [00000.000] 00000:00000> PMM: boot reserve add [0x48290000, 0x48455fff] [00000.000] 00000:00000> mem_arena.base 0x40000000 size 0x8000000 [00000.000] 00000:00000> overriding mem arena 0 base from FDT: 0x40000000 [00000.000] 00000:00000> overriding mem arena 0 size from FDT: 0x100000000 [00000.000] 00000:00000> detected GICv3 [00000.000] 00000:00000> PSCI version 1.0 [00000.000] 00000:00000> PSCI supported features: [00000.000] 00000:00000> PSCI64_SYSTEM_OFF 0 [00000.000] 00000:00000> PSCI64_SYSTEM_RESET 0 [00000.000] 00000:00000> PSCI64_CPU_ON 0 [00000.000] 00000:00000> PSCI64_CPU_OFF 0 [00000.000] 00000:00000> arm generic timer freq 200000000 Hz [00001.308] 00000:00000> cntpct_per_ns: 00000000.3333333333333333 [00001.308] 00000:00000> ns_per_cntpct: 00000005.0000000000000000 [00001.308] 00000:00000> test_time_conversion_check_result:253: FAIL, off by 230584300921369395 [00001.308] 00000:00000> reserving ramdisk phys range [0x48000000, 0x4813dfff] [00001.308] 00000:00000> PMM: boot reserve add [0x48000000, 0x4813dfff] [00001.370] 00000:00000> PMM: boot reserve marking WIRED [0x48000000, 0x4813dfff] [00001.370] 00000:00000> PMM: boot reserve marking WIRED [0x48290000, 0x48455fff] [00001.370] 00000:00000> [00001.370] 00000:00000> welcome to Zircon [00001.370] 00000:00000> [00001.370] 00000:00000> KASLR: .text section at 0xffffffff10000000 [00001.370] 00000:00000> INIT: cpu 0, calling hook 0xffffffff1001da40 (global_prng_seed) at level 0x30000, flags 0x1 [00001.385] 00000:00000> initializing vm pre-heap [00001.385] 00000:00000> VM: marking boot alloc used range [0x48456000, 0x4845b040) [00001.387] 00000:00000> INIT: cpu 0, calling hook 0xffffffff1002c724 (elf_build_id) at level 0x4fffe, flags 0x1 [00001.387] 00000:00000> INIT: cpu 0, calling hook 0xffffffff1002c8c8 (version) at level 0x4ffff, flags 0x1 [00001.387] 00000:00000> version: [00001.387] 00000:00000> arch: arm64 [00001.387] 00000:00000> platform: XXX [00001.387] 00000:00000> target: XXX [00001.387] 00000:00000> project: arm64 [00001.387] 00000:00000> buildid: git-71aec42d0c4724cc52eab689479bcf4335695c4c-dirty [00001.387] 00000:00000> ELF build ID: 493e35f11d886ad5 [00001.387] 00000:00000> initializing heap [00001.387] 00000:00000> INIT: cpu 0, calling hook 0xffffffff10002240 (arm_resource_init) at level 0x50000, flags 0x1 [00001.387] 00000:00000> INIT: cpu 0, calling hook 0xffffffff1000366c (console) at level 0x50000, flags 0x1 [00001.387] 00000:00000> initializing vm [00001.387] 00000:00000> VM: reserving kernel region [0xffffffff10000000, 0xffffffff1010c000) flags 0x28 name 'kernel_code' [00001.387] 00000:00000> VM: reserving kernel region [0xffffffff1010c000, 0xffffffff1014a000) flags 0x8 name 'kernel_rodata' [00001.387] 00000:00000> VM: reserving kernel region [0xffffffff1014a000, 0xffffffff1014b000) flags 0x18 name 'kernel_data' [00001.387] 00000:00000> VM: reserving kernel region [0xffffffff10150000, 0xffffffff101c6000) flags 0x18 name 'kernel_bss' [00001.387] 00000:00000> INIT: cpu 0, calling hook 0xffffffff100018b8 (platform_init_pre_thread) at level 0x60000, flags 0x1 [00001.387] 00000:00000> System topology: CPU 0:0:0:0 [00001.387] 00000:00000> System topology: CPU 0:0:0:1 [00001.387] 00000:00000> System topology: CPU 0:0:0:2 [00001.387] 00000:00000> System topology: CPU 0:0:0:3 [00001.387] 00000:00000> INIT: cpu 0, calling hook 0xffffffff10001b74 (platform_postvm) at level 0x60000, flags 0x1 [00001.387] 00000:00000> CreateSubVmarInternal:114: warning: mapping periph has conflicting cache policies: vmo 00 arch_mmu_flags 02. [00001.387] 00000:00000> INIT: cpu 0, calling hook 0xffffffff100f6f7c (percpu_heap_init) at level 0x60003, flags 0x1 [00001.387] 00000:00000> initializing kernel [00001.387] 00000:00000> initializing mp [00001.387] 00000:00000> INIT: cpu 0, calling hook 0xffffffff1001dd04 (global_prng_thread_safe) at level 0x7ffff, flags 0x1 [00001.387] 00000:00000> creating bootstrap completion thread [00001.387] 00000:00000> top of bootstrap2() [00001.387] 00000:00000> INIT: cpu 0, calling hook 0xffffffff1003e9c4 (libobject) at level 0x80000, flags 0x1 [00001.387] 00000:00000> OOM: started thread [00001.387] 00000:00000> INIT: cpu 0, calling hook 0xffffffff100f35d4 (dpc) at level 0x80000, flags 0x1 [00001.387] 00000:00000> INIT: cpu 0, calling hook 0xffffffff1010ac7c (lockdep) at level 0x80000, flags 0x1 [00001.388] 00000:00000> ARM boot EL1 [00001.388] 00000:00000> ARM cpu 0: midr 0x431f0af1 'Cavium CN99XX r1p1' mpidr 0x80000000 aff 0:0:0:0 [00001.388] 00000:00000> ARM Features: fp asimd aes pmull sha1 sha2 crc32 atomics rdm [00001.388] 00000:00000> ARM cache line sizes: icache 64 dcache 64 zva 64 [00001.388] 00000:00000> ==== ARM64 CACHE INFO CORE 0 ==== [00001.388] 00000:00000> Inner Boundary = L0 [00001.388] 00000:00000> Level of Unification Uniprocessor = L1 [00001.388] 00000:00000> Level of Coherence = L3 [00001.388] 00000:00000> Level of Unification Inner Shareable = L1 [00001.388] 00000:00000> L1 Details: Data Cache, sets=64, associativity=8, line size=64 bytes [00001.388] 00000:00000> Instruction Cache, sets=64, associativity=8, line size=64 bytes [00001.388] 00000:00000> L2 Details: Unified Cache, sets=512, associativity=8, line size=64 bytes [00001.388] 00000:00000> L3 Details: Unified Cache, sets=16384, associativity=32, line size=64 bytes [00001.388] 00000:00000> L4 Details: Not Implemented [00001.388] 00000:00000> L5 Details: Not Implemented [00001.388] 00000:00000> L6 Details: Not Implemented [00001.388] 00000:00000> L7 Details: Not Implemented [00001.388] 00000:00000> initializing platform [00001.388] 00000:00000> INIT: cpu 0, calling hook 0xffffffff10098aa8 (arm64_perfmon) at level 0x90000, flags 0x1 [00001.388] 00000:00000> Trying to start cpu 1 returned: 0 [00001.388] 00000:00000> Trying to start cpu 2 returned: 0 [00001.388] 00000:00000> Trying to start cpu 3 returned: 0 [00001.388] 00000:00000> initializing target [00001.388] 00000:00000> INIT: cpu 0, calling hook 0xffffffff1008e57c (platform_dev_init) at level 0xa0000, flags 0x1 [00001.388] 00000:00000> UART: started IRQ driven TX [00001.388] 00000:00000> moving to last init level [00001.388] 00000:00000> INIT: cpu 0, calling hook 0xffffffff1002be80 (debuglog) at level 0xb0000, flags 0x1 [00001.462] 00000:00000> INIT: cpu 0, calling hook 0xffffffff1007f728 (kcounters) at level 0xb0000, flags 0x1 [00001.462] 00000:00000> INIT: cpu 0, calling hook 0xffffffff10003b5c (kernel_shell) at level 0xc0000, flags 0x1 [00001.462] 00000:00000> INIT: cpu 0, calling hook 0xffffffff100efeac (ktrace) at level 0xc0000, flags 0x1 [00001.509] 00000:00000> ktrace: buffer at 0xffff00860888f000 (33554432 bytes) [00001.509] 00000:00000> INIT: cpu 0, calling hook 0xffffffff1010654c (userboot) at level 0xc0000, flags 0x1 [00001.509] 00000:00000> userboot: ramdisk 0x13e000 @ 0xffff000048000000 [00001.509] 00000:00000> userboot: userboot rodata 0 @ [0xeb7bcd4c0000,0xeb7bcd4c2000) [00001.509] 00000:00000> userboot: userboot code 0x2000 @ [0xeb7bcd4c2000,0xeb7bcd4c6000) [00001.509] 00000:00000> userboot: vdso/full rodata 0 @ [0xeb7bcd4c6000,0xeb7bcd4cc000) [00001.509] 00000:00000> userboot: vdso/full code 0x6000 @ [0xeb7bcd4cc000,0xeb7bcd4ce000) [00001.509] 00000:00000> userboot: entry point @ 0xeb7bcd4c2b60 [00001.518] 01032:01039> userboot: option "userboot=bin/core-tests" [00001.518] 01032:01039> userboot: option "userboot.shutdown=" [00001.518] 01032:01039> userboot: option "kernel.halt-on-panic=true" [00001.518] 01032:01039> userboot: option "devmgr.suspend-timeout-debug=true" [00001.518] 01032:01039> userboot: option "TERM=dumb" [00001.525] 01032:01039> userboot: searching bootfs for 'bin/core-tests' [00001.525] 01032:01039> userboot: 'bin/core-tests' has PT_INTERP "lib/ld.so.1" [00001.525] 01032:01039> userboot: searching bootfs for 'lib/ld.so.1' [00001.525] 01032:01039> userboot: userboot: loaded lib/ld.so.1 at 0xb8bdd8dad000, entry point 0xb8bdd8df5318 [00001.525] 01032:01039> userboot: userboot: loaded vDSO at 0xee1fcb84c000, entry point 0xee1fcb852a48 [00001.534] 01032:01039> userboot: process bin/core-tests started. [00001.534] 01032:01039> userboot: waiting for loader-service requests... [00001.538] 01032:01039> userboot: searching bootfs for 'lib/libfdio.so' [00001.539] 01032:01039> userboot: searching bootfs for 'lib/libunittest.so' [00001.540] 01032:01039> userboot: searching bootfs for 'lib/libmini-process.so' [00001.541] 01032:01039> userboot: searching bootfs for 'lib/libinspector.so' [00001.542] 01032:01039> userboot: searching bootfs for 'lib/libbacktrace.so' [00001.544] 01046:01049> {{{reset}}} [00001.544] 01046:01049> {{{module:0:<application>:elf:da73ecd9fb90f3a1}}} [00001.544] 01046:01049> {{{mmap:0x9fa902d26000:0x4c000:load:0:r:0}}} [00001.544] 01046:01049> {{{mmap:0x9fa902d72000:0x139000:load:0:rx:0x4c000}}} [00001.544] 01046:01049> {{{mmap:0x9fa902eab000:0x9000:load:0:rw:0x185000}}} [00001.544] 01046:01049> {{{mmap:0x9fa902eb4000:0x2000:load:0:rw:0x18e000}}} [00001.544] 01046:01049> dso: id=da73ecd9fb90f3a1 base=0x00009fa902d26000 name=<application> [00001.544] 01046:01049> {{{module:0x3:libfdio.so:elf:301ae1d1970af4ed}}} [00001.544] 01046:01049> {{{mmap:0x9afc97218000:0xb000:load:0x3:r:0}}} [00001.544] 01046:01049> {{{mmap:0x9afc97223000:0x1a000:load:0x3:rx:0xb000}}} [00001.544] 01046:01049> {{{mmap:0x9afc9723d000:0x2000:load:0x3:rw:0x25000}}} [00001.544] 01046:01049> {{{mmap:0x9afc9723f000:0x2000:load:0x3:rw:0x27000}}} [00001.544] 01046:01049> dso: id=301ae1d1970af4ed base=0x00009afc97218000 name=libfdio.so [00001.544] 01046:01049> {{{module:0x4:libunittest.so:elf:9e63522bca0bd1c4}}} [00001.544] 01046:01049> {{{mmap:0x51aec0e40000:0x4000:load:0x4:r:0}}} [00001.544] 01046:01049> {{{mmap:0x51aec0e44000:0x3000:load:0x4:rx:0x4000}}} [00001.544] 01046:01049> {{{mmap:0x51aec0e47000:0x1000:load:0x4:rw:0x7000}}} [00001.544] 01046:01049> {{{mmap:0x51aec0e48000:0x2000:load:0x4:rw:0x8000}}} [00001.544] 01046:01049> dso: id=9e63522bca0bd1c4 base=0x000051aec0e40000 name=libunittest.so [00001.544] 01046:01049> {{{module:0x2:<vDSO>:elf:9354a4e159cb64b6}}} [00001.544] 01046:01049> {{{mmap:0xee1fcb84c000:0x6000:load:0x2:r:0}}} [00001.544] 01046:01049> {{{mmap:0xee1fcb852000:0x2000:load:0x2:rx:0x6000}}} [00001.544] 01046:01049> dso: id=9354a4e159cb64b6 base=0x0000ee1fcb84c000 name=<vDSO> [00001.544] 01046:01049> {{{module:0x5:libmini-process.so:elf:45ad6ef8ef82e222}}} [00001.544] 01046:01049> {{{mmap:0xfc443a88a000:0x2000:load:0x5:r:0}}} [00001.544] 01046:01049> {{{mmap:0xfc443a88c000:0x2000:load:0x5:rx:0x2000}}} [00001.544] 01046:01049> {{{mmap:0xfc443a88e000:0x1000:load:0x5:rw:0x4000}}} [00001.544] 01046:01049> {{{mmap:0xfc443a88f000:0x1000:load:0x5:rw:0x5000}}} [00001.544] 01046:01049> dso: id=45ad6ef8ef82e222 base=0x0000fc443a88a000 name=libmini-process.so [00001.544] 01046:01049> {{{module:0x6:libinspector.so:elf:73574addbb26ce48}}} [00001.544] 01046:01049> {{{mmap:0xc42831a4b000:0x6000:load:0x6:r:0}}} [00001.544] 01046:01049> {{{mmap:0xc42831a51000:0x14000:load:0x6:rx:0x6000}}} [00001.544] 01046:01049> {{{mmap:0xc42831a65000:0x1000:load:0x6:rw:0x1a000}}} [00001.544] 01046:01049> {{{mmap:0xc42831a66000:0x37000:load:0x6:rw:0x1b000}}} [00001.544] 01046:01049> dso: id=73574addbb26ce48 base=0x0000c42831a4b000 name=libinspector.so [00001.544] 01046:01049> {{{module:0x7:libbacktrace.so:elf:9c1b7a73933a2f9d}}} [00001.544] 01046:01049> {{{mmap:0xcd2e4ace1000:0x2000:load:0x7:r:0}}} [00001.544] 01046:01049> {{{mmap:0xcd2e4ace3000:0xa000:load:0x7:rx:0x2000}}} [00001.544] 01046:01049> {{{mmap:0xcd2e4aced000:0x1000:load:0x7:rw:0xc000}}} [00001.544] 01046:01049> dso: id=9c1b7a73933a2f9d base=0x0000cd2e4ace1000 name=libbacktrace.so [00001.544] 01046:01049> {{{module:0x1:libc.so:elf:a6b76763f7ad8ae9}}} [00001.544] 01046:01049> {{{mmap:0xb8bdd8dad000:0x42000:load:0x1:r:0}}} [00001.544] 01046:01049> {{{mmap:0xb8bdd8def000:0x53000:load:0x1:rx:0x42000}}} [00001.544] 01046:01049> {{{mmap:0xb8bdd8e42000:0x2000:load:0x1:rw:0x95000}}} [00001.544] 01046:01049> {{{mmap:0xb8bdd8e44000:0x3000:load:0x1:rw:0x97000}}} [00001.544] 01046:01049> dso: id=a6b76763f7ad8ae9 base=0x0000b8bdd8dad000 name=libc.so [00001.545] 01046:01049> TEST [00001.588] 01046:01049> CASE resource_tests [STARTED] [00001.588] 01046:01049> probe_address_space [RUNNING] [00001.588] 01046:01049> [PASSED] (0 ms) [00001.588] 01046:01049> TestBasicActions [RUNNING] [00001.588] 01046:01049> [PASSED] (0 ms) [00001.588] 01046:01049> TestExclusiveShared [RUNNING] [00001.588] 01046:01049> [PASSED] (0 ms) [00001.588] 01046:01049> TestSharedExclusive [RUNNING] [00001.588] 01046:01049> [PASSED] (0 ms) [00001.588] 01046:01049> TestInvalidArgs [RUNNING] [00001.588] 01046:01049> [PASSED] (0 ms) [00001.588] 01046:01049> TestVmoCreation [RUNNING] [00001.588] 01046:01049> [PASSED] (0 ms) [00001.588] 01046:01049> TestVmoCreationSmaller [RUNNING] [00001.588] 01046:01049> [PASSED] (0 ms) [00001.588] 01046:01049> TestVmoCreationUnaligned [RUNNING] [00001.588] 01046:01049> [PASSED] (0 ms) [00001.588] 01046:01049> TestVmoReplaceAsExecutable [RUNNING] [00001.588] 01046:01049> [PASSED] (0 ms) [00001.588] 01046:01049> CASE resource_tests [PASSED] [00001.588] 01046:01049> CASE profile_tests [STARTED] [00001.588] 01046:01049> make_profile_fails [RUNNING] [00001.588] 01046:01049> [PASSED] (0 ms) [00001.588] 01046:01049> change_priority_via_profile [RUNNING] [00001.609] 01046:01049> [PASSED] (20 ms) [00001.609] 01046:01049> CASE profile_tests [PASSED] [00001.609] 01046:01049> CASE interrupt_tests [STARTED] [00001.609] 01046:01049> interrupt_test [RUNNING] [00001.609] 01046:01049> [PASSED] (0 ms) [00001.609] 01046:01049> interrupt_port_bound_test [RUNNING] [00001.609] 01046:01049> [PASSED] (0 ms) [00001.609] 01046:01049> interrupt_port_non_bindable_test [RUNNING] [00001.609] 01046:01049> [PASSED] (0 ms) [00001.609] 01046:01049> interrupt_suspend_test [RUNNING] [00001.617] 01046:01049> [PASSED] (8 ms) [00001.617] 01046:01049> interrupt_bind_vcpu_test [RUNNING] [00001.617] 01046:01049> Guest creation not supported [00001.617] 01046:01049> [PASSED] (0 ms) [00001.617] 01046:01049> interrupt_bind_vcpu_not_supported_test [RUNNING] [00001.617] 01046:01049> Guest creation not supported [00001.617] 01046:01049> [PASSED] (0 ms) [00001.617] 01046:01049> interrupt_bind_vcpu_already_bound_test [RUNNING] [00001.617] 01046:01049> Guest creation not supported [00001.617] 01046:01049> [PASSED] (0 ms) [00001.617] 01046:01049> interrupt_bind_vcpu_multiple_guests_test [RUNNING] [00001.617] 01046:01049> Guest creation not supported [00001.617] 01046:01049> [PASSED] (0 ms) [00001.617] 01046:01049> CASE interrupt_tests [PASSED] [00001.617] 01046:01049> CASE vmo_signal_tests [STARTED] [00001.617] 01046:01049> vmo_signal_sanity_test [RUNNING] [00001.617] 01046:01049> [PASSED] (0 ms) [00001.617] 01046:01049> vmo_child_signal_clone_test [RUNNING] [00001.617] 01046:01049> [PASSED] (0 ms) [00001.617] 01046:01049> vmo_child_signal_map_test [RUNNING] [00001.618] 01046:01049> [PASSED] (0 ms) [00001.618] 01046:01049> CASE vmo_signal_tests [PASSED] [00001.618] 01046:01049> CASE vmar_tests [STARTED] [00001.618] 01046:01049> destroy_root_test [RUNNING] [00001.618] 01046:01049> [PASSED] (0 ms) [00001.618] 01046:01049> basic_allocate_test [RUNNING] [00001.618] 01046:01049> [PASSED] (0 ms) [00001.618] 01046:01049> allocate_oob_test [RUNNING] [00001.618] 01046:01049> [PASSED] (0 ms) [00001.618] 01046:01049> allocate_unsatisfiable_test [RUNNING] [00001.618] 01046:01049> [PASSED] (0 ms) [00001.618] 01046:01049> destroyed_vmar_test [RUNNING] [00001.618] 01046:01049> [PASSED] (0 ms) [00001.618] 01046:01049> map_over_destroyed_test [RUNNING] [00001.618] 01046:01049> [PASSED] (0 ms) [00001.618] 01046:01049> map_in_compact_test [RUNNING] [00001.618] 01046:01049> [PASSED] (0 ms) [00001.618] 01046:01049> overmapping_test [RUNNING] [00001.618] 01046:01049> [PASSED] (0 ms) [00001.618] 01046:01049> invalid_args_test [RUNNING] [00001.618] 01046:01049> [PASSED] (0 ms) [00001.618] 01046:01049> unaligned_len_test [RUNNING] [00001.618] 01046:01049> [PASSED] (0 ms) [00001.618] 01046:01049> unaligned_len_map_test [RUNNING] [00001.618] 01046:01049> [PASSED] (0 ms) [00001.618] 01046:01049> rights_drop_test [RUNNING] [00001.618] 01046:01049> [PASSED] (0 ms) [00001.618] 01046:01049> protect_test [RUNNING] [00001.618] 01046:01049> [PASSED] (0 ms) [00001.618] 01046:01049> nested_region_perms_test [RUNNING] [00001.618] 01046:01049> [PASSED] (0 ms) [00001.618] 01046:01049> object_info_test [RUNNING] [00001.618] 01046:01049> [PASSED] (0 ms) [00001.618] 01046:01049> unmap_split_test [RUNNING] [00001.618] 01046:01049> [PASSED] (0 ms) [00001.618] 01046:01049> unmap_multiple_test [RUNNING] [00001.619] 01046:01049> [PASSED] (0 ms) [00001.619] 01046:01049> unmap_base_not_mapped_test [RUNNING] [00001.619] 01046:01049> [PASSED] (0 ms) [00001.619] 01046:01049> map_specific_overwrite_test [RUNNING] [00001.619] 01046:01049> [PASSED] (0 ms) [00001.619] 01046:01049> protect_split_test [RUNNING] [00001.619] 01046:01049> [PASSED] (0 ms) [00001.619] 01046:01049> protect_multiple_test [RUNNING] [00001.619] 01046:01049> [PASSED] (0 ms) [00001.619] 01046:01049> protect_over_demand_paged_test [RUNNING] [00001.672] 01046:01049> [PASSED] (53 ms) [00001.672] 01046:01049> protect_large_uncommitted_test [RUNNING] [00001.674] 01046:01049> [PASSED] (1 ms) [00001.674] 01046:01049> unmap_large_uncommitted_test [RUNNING] [00001.676] 01046:01282> PageFault: 1030158 free pages [00001.676] 01046:01282> [00001.676] 01046:01282> ZIRCON KERNEL PANIC [00001.676] 01046:01282> Lock validation failed for thread 0xffff000040045e90 pid 1046 tid 1282 (bin/core-tests:vmar_test_addr): [00001.676] 01046:01282> Reason: Already Acquired [00001.676] 01046:01282> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00001.676] 01046:01282> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00001.676] 01046:01282> caller=0xffffffff100449c8 frame=0xffff00860a8a9c90 [00001.676] 01046:01282> BUILDID git-71aec42d0c4724cc52eab689479bcf4335695c4c-dirty [00001.676] 01046:01282> [00001.676] 01046:01282> {{{module:0:kernel:elf:493e35f11d886ad5}}} [00001.676] 01046:01282> {{{mmap:0xffffffff10000000:0x10bcb0:load:0:rx:0xffffffff00000000}}} [00001.676] 01046:01282> {{{mmap:0xffffffff1010c000:0x3e000:load:0:r:0xffffffff0010c000}}} [00001.676] 01046:01282> {{{mmap:0xffffffff1014a000:0xed0:load:0:rw:0xffffffff0014a000}}} [00001.676] 01046:01282> {{{mmap:0xffffffff10150000:0x76000:load:0:rw:0xffffffff00150000}}} [00001.676] 01046:01282> dso: id=493e35f11d886ad5 base=0xffffffff10000000 name=zircon.elf [00001.676] 01046:01282> bt#00: 0xffffffff100449c8 [00001.676] 01046:01282> bt#01: 0xffffffff10044d78 [00001.676] 01046:01282> bt#02: 0xffffffff10031ad4 [00001.676] 01046:01282> bt#03: 0xffffffff100b9f6c [00001.676] 01046:01282> bt#04: 0xffffffff10095554 [00001.676] 01046:01282> bt#05: 0xffffffff1009447c [00001.676] 01046:01282> bt#06: end [00001.676] 01046:01282> {{{bt:0:0xffffffff100449c8}}} [00001.676] 01046:01282> {{{bt:1:0xffffffff10044d78}}} [00001.676] 01046:01282> {{{bt:2:0xffffffff10031ad4}}} [00001.676] 01046:01282> {{{bt:3:0xffffffff100b9f6c}}} [00001.676] 01046:01282> {{{bt:4:0xffffffff10095554}}} [00001.676] 01046:01282> {{{bt:5:0xffffffff1009447c}}} [00001.676] 01046:01282> [00001.700] 01046:01285> PageFault: 1030158 free pages [00001.700] 01046:01285> [00001.700] 01046:01285> ZIRCON KERNEL PANIC [00001.700] 01046:01285> Lock validation failed for thread 0xffff000040045e90 pid 1046 tid 1285 (bin/core-tests:vmar_test_addr): [00001.700] 01046:01285> Reason: Already Acquired [00001.700] 01046:01285> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00001.700] 01046:01285> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00001.700] 01046:01285> caller=0xffffffff100449c8 frame=0xffff00860a8a9c90 [00001.700] 01046:01285> BUILDID git-71aec42d0c4724cc52eab689479bcf4335695c4c-dirty [00001.700] 01046:01285> [00001.700] 01046:01285> {{{module:0:kernel:elf:493e35f11d886ad5}}} [00001.700] 01046:01285> {{{mmap:0xffffffff10000000:0x10bcb0:load:0:rx:0xffffffff00000000}}} [00001.700] 01046:01285> {{{mmap:0xffffffff1010c000:0x3e000:load:0:r:0xffffffff0010c000}}} [00001.700] 01046:01285> {{{mmap:0xffffffff1014a000:0xed0:load:0:rw:0xffffffff0014a000}}} [00001.700] 01046:01285> {{{mmap:0xffffffff10150000:0x76000:load:0:rw:0xffffffff00150000}}} [00001.700] 01046:01285> dso: id=493e35f11d886ad5 base=0xffffffff10000000 name=zircon.elf [00001.700] 01046:01285> bt#00: 0xffffffff100449c8 [00001.700] 01046:01285> bt#01: 0xffffffff10044d78 [00001.700] 01046:01285> bt#02: 0xffffffff10031ad4 [00001.700] 01046:01285> bt#03: 0xffffffff100b9f6c [00001.700] 01046:01285> bt#04: 0xffffffff10095554 [00001.700] 01046:01285> bt#05: 0xffffffff1009447c [00001.700] 01046:01285> bt#06: end [00001.700] 01046:01285> {{{bt:0:0xffffffff100449c8}}} [00001.700] 01046:01285> {{{bt:1:0xffffffff10044d78}}} [00001.700] 01046:01285> {{{bt:2:0xffffffff10031ad4}}} [00001.700] 01046:01285> {{{bt:3:0xffffffff100b9f6c}}} [00001.700] 01046:01285> {{{bt:4:0xffffffff10095554}}} [00001.700] 01046:01285> {{{bt:5:0xffffffff1009447c}}} [00001.700] 01046:01285> [00001.724] 01046:01288> PageFault: 1030158 free pages [00001.724] 01046:01288> [00001.724] 01046:01288> ZIRCON KERNEL PANIC [00001.724] 01046:01288> Lock validation failed for thread 0xffff000040045e90 pid 1046 tid 1288 (bin/core-tests:vmar_test_addr): [00001.724] 01046:01288> Reason: Already Acquired [00001.724] 01046:01288> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00001.724] 01046:01288> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00001.724] 01046:01288> caller=0xffffffff100449c8 frame=0xffff00860a8a9c90 [00001.724] 01046:01288> BUILDID git-71aec42d0c4724cc52eab689479bcf4335695c4c-dirty [00001.724] 01046:01288> [00001.724] 01046:01288> {{{module:0:kernel:elf:493e35f11d886ad5}}} [00001.724] 01046:01288> {{{mmap:0xffffffff10000000:0x10bcb0:load:0:rx:0xffffffff00000000}}} [00001.724] 01046:01288> {{{mmap:0xffffffff1010c000:0x3e000:load:0:r:0xffffffff0010c000}}} [00001.724] 01046:01288> {{{mmap:0xffffffff1014a000:0xed0:load:0:rw:0xffffffff0014a000}}} [00001.724] 01046:01288> {{{mmap:0xffffffff10150000:0x76000:load:0:rw:0xffffffff00150000}}} [00001.724] 01046:01288> dso: id=493e35f11d886ad5 base=0xffffffff10000000 name=zircon.elf [00001.724] 01046:01288> bt#00: 0xffffffff100449c8 [00001.724] 01046:01288> bt#01: 0xffffffff10044d78 [00001.724] 01046:01288> bt#02: 0xffffffff10031ad4 [00001.724] 01046:01288> bt#03: 0xffffffff100b9f6c [00001.724] 01046:01288> bt#04: 0xffffffff10095554 [00001.724] 01046:01288> bt#05: 0xffffffff1009447c [00001.724] 01046:01288> bt#06: end [00001.724] 01046:01288> {{{bt:0:0xffffffff100449c8}}} [00001.724] 01046:01288> {{{bt:1:0xffffffff10044d78}}} [00001.724] 01046:01288> {{{bt:2:0xffffffff10031ad4}}} [00001.724] 01046:01288> {{{bt:3:0xffffffff100b9f6c}}} [00001.724] 01046:01288> {{{bt:4:0xffffffff10095554}}} [00001.724] 01046:01288> {{{bt:5:0xffffffff1009447c}}} [00001.724] 01046:01288> [00001.748] 01046:01049> [PASSED] (73 ms) [00001.748] 01046:01049> partial_unmap_and_read [RUNNING] [00001.748] 01046:01049> [PASSED] (0 ms) [00001.748] 01046:01049> partial_unmap_and_write [RUNNING] [00001.748] 01046:01049> [PASSED] (0 ms) [00001.748] 01046:01049> partial_unmap_with_vmar_offset [RUNNING] [00001.748] 01046:01049> [PASSED] (0 ms) [00001.748] 01046:01049> CASE vmar_tests [PASSED] [00001.748] 01046:01049> CASE ticks_tests [STARTED] [00001.748] 01046:01049> elapsed_time_using_ticks [RUNNING] [00001.748] 01046:01049> [PASSED] (0 ms) [00001.748] 01046:01049> CASE ticks_tests [PASSED] [00001.748] 01046:01049> CASE threads_tests [STARTED] [00001.748] 01046:01049> TestBasics [RUNNING] [00001.848] 01046:01049> [PASSED] (100 ms) [00001.848] 01046:01049> TestInvalidRights [RUNNING] [00001.848] 01046:01049> [PASSED] (0 ms) [00001.848] 01046:01049> TestDetach [RUNNING] [00001.851] 01046:01049> [PASSED] (3 ms) [00001.851] 01046:01049> TestLongNameSucceeds [RUNNING] [00001.851] 01046:01049> [PASSED] (0 ms) [00001.851] 01046:01049> TestThreadStartOnInitialThread [RUNNING] [00001.851] 01046:01049> [PASSED] (0 ms) [00001.851] 01046:01049> TestThreadStartWithZeroInstructionPointer [RUNNING] [00001.856] 01046:01049> [00001.856] 01046:01049> ZIRCON KERNEL PANIC [00001.856] 01046:01049> Lock validation failed for thread 0xffff00004003af00 pid 1046 tid 1049 (bin/core-tests:bin/core-tests): [00001.856] 01046:01049> Reason: Out Of Order [00001.856] 01046:01049> Bad lock: name=lockdep::LockClass<SoloDispatcher<ThreadDispatcher, 316111, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00001.856] 01046:01049> Conflict: name=lockdep::LockClass<SoloDispatcher<ProcessDispatcher, 447439, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00001.856] 01046:01049> caller=0xffffffff1005b570 frame=0xffff00860a899d60 [00001.856] 01046:01049> BUILDID git-71aec42d0c4724cc52eab689479bcf4335695c4c-dirty [00001.856] 01046:01049> [00001.856] 01046:01049> {{{module:0:kernel:elf:493e35f11d886ad5}}} [00001.856] 01046:01049> {{{mmap:0xffffffff10000000:0x10bcb0:load:0:rx:0xffffffff00000000}}} [00001.856] 01046:01049> {{{mmap:0xffffffff1010c000:0x3e000:load:0:r:0xffffffff0010c000}}} [00001.856] 01046:01049> {{{mmap:0xffffffff1014a000:0xed0:load:0:rw:0xffffffff0014a000}}} [00001.856] 01046:01049> {{{mmap:0xffffffff10150000:0x76000:load:0:rw:0xffffffff00150000}}} [00001.856] 01046:01049> dso: id=493e35f11d886ad5 base=0xffffffff10000000 name=zircon.elf [00001.856] 01046:01049> bt#00: 0xffffffff1005b570 [00001.856] 01046:01049> bt#01: 0xffffffff10051a80 [00001.856] 01046:01049> bt#02: 0xffffffff1005206c [00001.856] 01046:01049> bt#03: 0xffffffff100d932c [00001.856] 01046:01049> bt#04: 0xffffffff100e253c [00001.856] 01046:01049> bt#05: 0xffffffff10094b70 [00001.856] 01046:01049> bt#06: end [00001.856] 01046:01049> {{{bt:0:0xffffffff1005b570}}} [00001.856] 01046:01049> {{{bt:1:0xffffffff10051a80}}} [00001.856] 01046:01049> {{{bt:2:0xffffffff1005206c}}} [00001.856] 01046:01049> {{{bt:3:0xffffffff100d932c}}} [00001.856] 01046:01049> {{{bt:4:0xffffffff100e253c}}} [00001.856] 01046:01049> {{{bt:5:0xffffffff10094b70}}} [00001.856] 01046:01049> [00001.880] 01046:01049> [PASSED] (28 ms) [00001.880] 01046:01049> TestKillBusyThread [RUNNING] [00001.991] 01046:01049> [PASSED] (111 ms) [00001.991] 01046:01049> TestKillSleepThread [RUNNING] [00002.093] 01046:01049> [PASSED] (101 ms) [00002.093] 01046:01049> TestKillWaitThread [RUNNING] [00002.195] 01046:01049> [PASSED] (101 ms) [00002.195] 01046:01049> TestNonstartedThread [RUNNING] [00002.195] 01046:01049> [PASSED] (0 ms) [00002.195] 01046:01049> TestThreadKillsItself [RUNNING] [00002.198] 01046:01049> [PASSED] (3 ms) [00002.198] 01046:01049> TestInfoTaskStatsFails [RUNNING] [00002.298] 01046:01049> [PASSED] (100 ms) [00002.298] 01046:01049> TestResumeSuspended [RUNNING] [00002.402] 01046:01049> [PASSED] (103 ms) [00002.402] 01046:01049> TestSuspendSleeping [RUNNING] [00002.480] 00000:00000> INIT: cpu 3, calling hook 0xffffffff10090bac (arm_generic_timer_init_secondary_cpu) at level 0x7ffff, flags 0x2 [00002.480] 00000:00000> ARM cpu 3: midr 0x431f0af1 'Cavium CN99XX r1p1' mpidr 0x80000003 aff 0:0:0:3 [00002.480] 00000:00000> entering scheduler on cpu 3 [00002.500] 00000:00000> INIT: cpu 1, calling hook 0xffffffff10090bac (arm_generic_timer_init_secondary_cpu) at level 0x7ffff, flags 0x2 [00002.500] 00000:00000> ARM cpu 1: midr 0x431f0af1 'Cavium CN99XX r1p1' mpidr 0x80000001 aff 0:0:0:1 [00002.500] 00000:00000> entering scheduler on cpu 1 [00002.502] 01046:01049> [PASSED] (100 ms) [00002.502] 01046:01049> TestSuspendChannelCall [RUNNING] [00002.503] 01046:01049> [PASSED] (0 ms) [00002.503] 01046:01049> TestSuspendPortCall [RUNNING] [00002.519] 00000:00000> INIT: cpu 2, calling hook 0xffffffff10090bac (arm_generic_timer_init_secondary_cpu) at level 0x7ffff, flags 0x2 [00002.519] 00000:00000> ARM cpu 2: midr 0x431f0af1 'Cavium CN99XX r1p1' mpidr 0x80000002 aff 0:0:0:2 [00002.519] 00000:00000> entering scheduler on cpu 2 [00002.703] 01046:01049> [PASSED] (200 ms) [00002.703] 01046:01049> TestSuspendStopsThread [RUNNING] [00002.753] 01046:01049> [PASSED] (50 ms) [00002.753] 01046:01049> TestSuspendMultiple [RUNNING] [00002.753] 01046:01049> [PASSED] (0 ms) [00002.753] 01046:01049> TestSuspendSelf [RUNNING] [00002.753] 01046:01049> [PASSED] (0 ms) [00002.753] 01046:01049> TestKillSuspendedThread [RUNNING] [00002.753] 01046:01049> [PASSED] (0 ms) [00002.753] 01046:01049> TestStartSuspendedThread [RUNNING] [00002.754] 01046:01049> [PASSED] (0 ms) [00002.754] 01046:01049> TestStartSuspendedAndResumedThread [RUNNING] [00002.754] 01046:01049> [PASSED] (0 ms) [00002.754] 01046:01049> TestSuspendSingleWaitAsyncSignalDelivery [RUNNING] [00002.754] 01046:01049> [PASSED] (0 ms) [00002.754] 01046:01049> TestSuspendRepeatingWaitAsyncSignalDelivery [RUNNING] [00002.754] 01046:01049> [PASSED] (0 ms) [00002.754] 01046:01049> TestReadingGeneralRegisterState [RUNNING] [00002.854] 01046:01049> [PASSED] (100 ms) [00002.854] 01046:01049> TestWritingGeneralRegisterState [RUNNING] [00002.954] 01046:01049> [PASSED] (100 ms) [00002.954] 01046:01049> TestThreadLocalRegisterState [RUNNING] [00003.055] 01046:01049> [PASSED] (100 ms) [00003.055] 01046:01049> TestNoncanonicalRipAddress [RUNNING] [00003.055] 01046:01049> [PASSED] (0 ms) [00003.055] 01046:01049> TestWritingArmFlagsRegister [RUNNING] [00003.055] 01046:01049> [PASSED] (0 ms) [00003.055] 01046:01049> CASE threads_tests [PASSED] [00003.055] 01046:01049> CASE sync_mutex_tests [STARTED] [00003.055] 01046:01049> test_mutexes [RUNNING] [00003.063] 01046:01049> [PASSED] (8 ms) [00003.063] 01046:01049> test_try_mutexes [RUNNING] [00003.068] 01046:01049> [PASSED] (4 ms) [00003.068] 01046:01049> test_timeout_elapsed [RUNNING] [00003.568] 01046:01049> [PASSED] (500 ms) [00003.568] 01046:01049> CASE sync_mutex_tests [PASSED] [00003.568] 01046:01049> CASE sync_condition_tests [STARTED] [00003.568] 01046:01049> Condition::condition_test [RUNNING] [00003.569] 01046:01049> [PASSED] (0 ms) [00003.569] 01046:01049> Condition::condition_timeout_test [RUNNING] [00003.570] 01046:01049> [PASSED] (1 ms) [00003.570] 01046:01049> CASE sync_condition_tests [PASSED] [00003.570] 01046:01049> CASE sync_completion_tests [STARTED] [00003.570] 01046:01049> test_initializer [RUNNING] [00003.570] 01046:01049> [PASSED] (0 ms) [00003.570] 01046:01049> test_completions [RUNNING] [00003.581] 01046:01049> [PASSED] (11 ms) [00003.581] 01046:01049> test_timeout [RUNNING] [00003.582] 01046:01049> [PASSED] (0 ms) [00003.582] 01046:01049> CASE sync_completion_tests [PASSED] [00003.602] 01046:01049> CASE sync_completion_tests [STARTED] [00003.602] 01046:01049> test_initializer [RUNNING] [00003.602] 01046:01049> [PASSED] (0 ms) [00003.602] 01046:01049> test_completions [RUNNING] [00003.613] 01046:01049> [PASSED] (11 ms) [00003.613] 01046:01049> test_timeout [RUNNING] [00004.632] 01046:01049> [PASSED] (1018 ms) [00004.632] 01046:01049> test_signal_requeue [RUNNING] [00004.645] 01046:01049> [PASSED] (12 ms) [00004.645] 01046:01049> test_completion_signaled [RUNNING] [00004.645] 01046:01049> [PASSED] (0 ms) [00004.645] 01046:01049> CASE sync_completion_tests [PASSED] [00004.645] 01046:01049> CASE stack_tests [STARTED] [00004.645] 01046:01049> main_thread_stack_tests [RUNNING] [00004.645] 01046:01049> [PASSED] (0 ms) [00004.645] 01046:01049> other_thread_stack_tests [RUNNING] [00004.645] 01046:01049> [PASSED] (0 ms) [00004.645] 01046:01049> CASE stack_tests [PASSED] [00004.645] 01046:01049> CASE tls_tests [STARTED] [00004.645] 01046:01049> tls_test [RUNNING] [00006.652] 01046:01049> [PASSED] (2006 ms) [00006.652] 01046:01049> CASE tls_tests [PASSED] [00006.652] 01046:01049> CASE pthread_barrier_tests [STARTED] [00006.652] 01046:01049> test_barrier [RUNNING] [00006.674] 01046:01049> [PASSED] (22 ms) [00006.674] 01046:01049> CASE pthread_barrier_tests [PASSED] [00006.674] 01046:01049> CASE pthread_tests [STARTED] [00006.674] 01046:01049> pthread_test [RUNNING] [00009.778] 01046:01049> [PASSED] (3104 ms) [00009.778] 01046:01049> pthread_self_main_thread_test [RUNNING] [00009.778] 01046:01049> [PASSED] (0 ms) [00009.778] 01046:01049> pthread_big_stack_size [RUNNING] [00009.814] 01046:01049> [PASSED] (35 ms) [00009.814] 01046:01049> pthread_getstack_main_thread [RUNNING] [00009.814] 01046:01049> [PASSED] (0 ms) [00009.814] 01046:01049> pthread_getstack_other_thread [RUNNING] [00009.814] 01046:01049> [PASSED] (0 ms) [00009.814] 01046:01049> CASE pthread_tests [PASSED] [00009.814] 01046:01049> CASE process_tests [STARTED] [00009.814] 01046:01049> mini_process_sanity [RUNNING] [00009.814] 01046:01049> [PASSED] (0 ms) [00009.814] 01046:01049> process_start_fail [RUNNING] [00009.814] 01046:01049> [PASSED] (0 ms) [00009.814] 01046:01049> process_start_no_handle [RUNNING] [00009.815] 01046:01049> [PASSED] (0 ms) [00009.815] 01046:01049> process_not_killed_via_thread_close [RUNNING] [00009.816] 01046:01049> [PASSED] (1 ms) [00009.816] 01046:01049> process_not_killed_via_process_close [RUNNING] [00009.817] 01046:01049> [PASSED] (1 ms) [00009.817] 01046:01049> kill_process_via_thread_kill [RUNNING] [00009.817] 01046:01049> [PASSED] (0 ms) [00009.817] 01046:01049> kill_process_via_vmar_destroy [RUNNING] [00009.817] 02073:02075> PageFault: 1029951 free pages [00009.817] 02073:02075> [00009.817] 02073:02075> ZIRCON KERNEL PANIC [00009.817] 02073:02075> Lock validation failed for thread 0xffff00004005d3d0 pid 2073 tid 2075 (ttp:th): [00009.817] 02073:02075> Reason: Already Acquired [00009.817] 02073:02075> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00009.817] 02073:02075> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00009.817] 02073:02075> caller=0xffffffff100449c8 frame=0xffff00860a911c90 [00009.817] 02073:02075> BUILDID git-71aec42d0c4724cc52eab689479bcf4335695c4c-dirty [00009.817] 02073:02075> [00009.817] 02073:02075> {{{module:0:kernel:elf:493e35f11d886ad5}}} [00009.817] 02073:02075> {{{mmap:0xffffffff10000000:0x10bcb0:load:0:rx:0xffffffff00000000}}} [00009.817] 02073:02075> {{{mmap:0xffffffff1010c000:0x3e000:load:0:r:0xffffffff0010c000}}} [00009.817] 02073:02075> {{{mmap:0xffffffff1014a000:0xed0:load:0:rw:0xffffffff0014a000}}} [00009.818] 02073:02075> {{{mmap:0xffffffff10150000:0x76000:load:0:rw:0xffffffff00150000}}} [00009.818] 02073:02075> dso: id=493e35f11d886ad5 base=0xffffffff10000000 name=zircon.elf [00009.818] 02073:02075> bt#00: 0xffffffff100449c8 [00009.818] 02073:02075> bt#01: 0xffffffff10044d78 [00009.818] 02073:02075> bt#02: 0xffffffff10031ad4 [00009.818] 02073:02075> bt#03: 0xffffffff100b9f6c [00009.818] 02073:02075> bt#04: 0xffffffff10095188 [00009.818] 02073:02075> bt#05: 0xffffffff1009447c [00009.818] 02073:02075> bt#06: end [00009.818] 02073:02075> {{{bt:0:0xffffffff100449c8}}} [00009.818] 02073:02075> {{{bt:1:0xffffffff10044d78}}} [00009.818] 02073:02075> {{{bt:2:0xffffffff10031ad4}}} [00009.818] 02073:02075> {{{bt:3:0xffffffff100b9f6c}}} [00009.818] 02073:02075> {{{bt:4:0xffffffff10095188}}} [00009.818] 02073:02075> {{{bt:5:0xffffffff1009447c}}} [00009.818] 02073:02075> [00009.818] 01046:01049> [PASSED] (0 ms) [00009.818] 01046:01049> kill_channel_handle_cycle [RUNNING] [00010.568] 01046:01049> [PASSED] (750 ms) [00010.568] 01046:01049> info_reflects_process_state [RUNNING] [00010.905] 01046:01049> [PASSED] (336 ms) [00010.905] 01046:01049> suspend [RUNNING] [00010.905] 01046:01049> [PASSED] (0 ms) [00010.905] 01046:01049> suspend_self [RUNNING] [00010.905] 01046:01049> [PASSED] (0 ms) [00010.905] 01046:01049> suspend_multiple_threads [RUNNING] [00010.910] 01046:01049> [PASSED] (4 ms) [00010.910] 01046:01049> suspend_before_creating_threads [RUNNING] [00010.910] 01046:01049> [PASSED] (0 ms) [00010.910] 01046:01049> suspend_before_starting_threads [RUNNING] [00010.910] 01046:01049> [PASSED] (0 ms) [00010.910] 01046:01049> suspend_process_then_thread [RUNNING] [00011.160] 01046:01049> [PASSED] (250 ms) [00011.160] 01046:01049> suspend_thread_then_process [RUNNING] [00011.410] 01046:01049> [PASSED] (250 ms) [00011.410] 01046:01049> suspend_thread_and_process_before_starting_process [RUNNING] [00011.661] 01046:01049> [PASSED] (250 ms) [00011.661] 01046:01049> suspend_twice [RUNNING] [00011.911] 01046:01049> [PASSED] (250 ms) [00011.911] 01046:01049> suspend_twice_before_creating_threads [RUNNING] [00012.161] 01046:01049> [PASSED] (250 ms) [00012.161] 01046:01049> suspend_with_dying_thread [RUNNING] [00012.163] 01046:01049> [PASSED] (1 ms) [00012.163] 01046:01049> create_and_kill_job_race_stress [RUNNING] [00013.164] 01046:01049> [PASSED] (1000 ms) [00013.164] 01046:01049> CASE process_tests [PASSED] [00013.164] 01046:01049> CASE port_tests [STARTED] [00013.164] 01046:01049> basic_test [RUNNING] [00013.164] 01046:01049> [PASSED] (0 ms) [00013.164] 01046:01049> queue_and_close_test [RUNNING] [00013.164] 01046:01049> [PASSED] (0 ms) [00013.164] 01046:01049> queue_too_many [RUNNING] [00013.165] 01046:01049> [PASSED] (1 ms) [00013.165] 01046:01049> async_wait_channel_test [RUNNING] [00013.167] 01046:01049> [PASSED] (1 ms) [00013.167] 01046:01049> async_wait_event_test_single [RUNNING] [00013.167] 01046:01049> [PASSED] (0 ms) [00013.167] 01046:01049> async_wait_event_test_repeat [RUNNING] [00013.167] 01046:01049> [PASSED] (0 ms) [00013.167] 01046:01049> async_wait_event_all_processed [RUNNING] [00013.243] 01046:01049> [PASSED] (76 ms) [00013.243] 01046:01049> async_wait_invalid_option [RUNNING] [00013.243] 01046:01049> [PASSED] (0 ms) [00013.243] 01046:01049> async_wait_close_order_1 [RUNNING] [00013.243] 01046:01049> [PASSED] (0 ms) [00013.243] 01046:01049> async_wait_close_order_2 [RUNNING] [00013.243] 01046:01049> [PASSED] (0 ms) [00013.243] 01046:01049> async_wait_close_order_3 [RUNNING] [00013.243] 01046:01049> [PASSED] (0 ms) [00013.243] 01046:01049> async_wait_close_order_4 [RUNNING] [00013.243] 01046:01049> [PASSED] (0 ms) [00013.243] 01046:01049> async_wait_close_order_5 [RUNNING] [00013.243] 01046:01049> [PASSED] (0 ms) [00013.243] 01046:01049> async_wait_close_order_6 [RUNNING] [00013.243] 01046:01049> [PASSED] (0 ms) [00013.243] 01046:01049> channel_pre_writes_test [RUNNING] [00013.243] 01046:01049> [PASSED] (0 ms) [00013.243] 01046:01049> cancel_event_key [RUNNING] [00013.243] 01046:01049> [PASSED] (0 ms) [00013.243] 01046:01049> cancel_event_key_after [RUNNING] [00013.243] 01046:01049> [PASSED] (0 ms) [00013.243] 01046:01049> threads_event [RUNNING] [00013.246] 01046:01049> [PASSED] (2 ms) [00013.246] 01046:01049> cancel_stress [RUNNING] [00013.506] 01046:01049> [PASSED] (259 ms) [00013.506] 01046:01049> signal_close_stress [RUNNING] [00014.511] 01046:01049> [PASSED] (1004 ms) [00014.511] 01046:01049> port_close_wait_race_stress [RUNNING] [00015.512] 01046:01049> [PASSED] (1000 ms) [00015.512] 01046:01049> CASE port_tests [PASSED] [00015.512] 01046:01049> CASE api_violations [STARTED] [00015.512] 01046:01049> invalid_pager_create [RUNNING] [00015.512] 01046:01049> [PASSED] (0 ms) [00015.512] 01046:01049> invalid_pager_create_vmo [RUNNING] [00015.512] 01046:01049> [PASSED] (0 ms) [00015.512] 01046:01049> invalid_pager_detach_vmo [RUNNING] [00015.512] 01046:01049> [PASSED] (0 ms) [00015.512] 01046:01049> invalid_pager_supply_pages [RUNNING] [00015.512] 01046:01049> [PASSED] (0 ms) [00015.512] 01046:01049> resize_nonresizable_vmo [RUNNING] [00015.512] 01046:01049> [PASSED] (0 ms) [00015.512] 01046:01049> CASE api_violations [PASSED] [00015.512] 01046:01049> CASE commit_tests [STARTED] [00015.512] 01046:01049> simple_commit_test [RUNNING] [00015.516] 01046:01049> [PASSED] (3 ms) [00015.516] 01046:01049> split_commit_test [RUNNING] [00015.516] 01046:01049> [PASSED] (0 ms) [00015.516] 01046:01049> overlap_commit_test [RUNNING] [00015.517] 01046:01049> [PASSED] (0 ms) [00015.517] 01046:01049> overlap_commit_supply_test [RUNNING] [00015.533] 01046:01049> [PASSED] (16 ms) [00015.533] 01046:01049> multisupply_commit_test [RUNNING] [00015.533] 01046:01049> [PASSED] (0 ms) [00015.533] 01046:01049> multicommit_supply_test [RUNNING] [00015.534] 01046:01049> [PASSED] (0 ms) [00015.534] 01046:01049> commit_redundant_supply_test [RUNNING] [00015.534] 01046:01049> [PASSED] (0 ms) [00015.534] 01046:01049> supply_decommit_test [RUNNING] [00015.535] 01046:01049> [PASSED] (0 ms) [00015.535] 01046:01049> resize_commit_test [RUNNING] [00015.535] 01046:01049> [PASSED] (0 ms) [00015.535] 01046:01049> suspend_commit_test [RUNNING] [00015.535] 01046:01049> [PASSED] (0 ms) [00015.535] 01046:01049> CASE commit_tests [PASSED] [00015.535] 01046:01049> CASE clone_tests [STARTED] [00015.535] 01046:01049> clone_read_from_clone_test_vmar [RUNNING] [00015.535] 01046:01049> [PASSED] (0 ms) [00015.535] 01046:01049> clone_read_from_clone_test_vmo [RUNNING] [00015.536] 01046:01049> [PASSED] (0 ms) [00015.536] 01046:01049> clone_read_from_parent_test_vmar [RUNNING] [00015.536] 01046:01049> [PASSED] (0 ms) [00015.536] 01046:01049> clone_read_from_parent_test_vmo [RUNNING] [00015.536] 01046:01049> [PASSED] (0 ms) [00015.536] 01046:01049> clone_simultaneous_read_test_vmar [RUNNING] [00015.537] 01046:01049> [PASSED] (0 ms) [00015.537] 01046:01049> clone_simultaneous_read_test_vmo [RUNNING] [00015.537] 01046:01049> [PASSED] (0 ms) [00015.537] 01046:01049> clone_simultaneous_child_read_test_vmar [RUNNING] [00015.538] 01046:01049> [PASSED] (0 ms) [00015.538] 01046:01049> clone_simultaneous_child_read_test_vmo [RUNNING] [00015.538] 01046:01049> [PASSED] (0 ms) [00015.538] 01046:01049> clone_write_to_clone_test [RUNNING] [00015.538] 01046:01049> [PASSED] (0 ms) [00015.538] 01046:01049> clone_detach_test [RUNNING] [00015.539] 01046:01049> [PASSED] (0 ms) [00015.539] 01046:01049> clone_commit_test [RUNNING] [00015.539] 01046:01049> [PASSED] (0 ms) [00015.539] 01046:01049> clone_split_commit_test [RUNNING] [00015.539] 01046:01049> [PASSED] (0 ms) [00015.539] 01046:01049> clone_decommit_test [RUNNING] [00015.539] 01046:01049> [PASSED] (0 ms) [00015.539] 01046:01049> CASE clone_tests [PASSED] [00015.539] 01046:01049> CASE lifecycle_tests [STARTED] [00015.539] 01046:01049> vmo_info_pager_test [RUNNING] [00015.539] 01046:01049> [PASSED] (0 ms) [00015.539] 01046:01049> detach_page_complete_test [RUNNING] [00015.539] 01046:01049> [PASSED] (0 ms) [00015.539] 01046:01049> close_page_complete_test [RUNNING] [00015.539] 01046:01049> [PASSED] (0 ms) [00015.539] 01046:01049> read_detach_interrupt_late_test_vmar [RUNNING] [00015.540] 01046:66413> PageFault: 1028268 free pages [00015.540] 01046:66413> [00015.540] 01046:66413> ZIRCON KERNEL PANIC [00015.540] 01046:66413> Lock validation failed for thread 0xffff00004005d898 pid 1046 tid 66413 (bin/core-tests:test_thread): [00015.540] 01046:66413> Reason: Already Acquired [00015.540] 01046:66413> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.540] 01046:66413> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.540] 01046:66413> caller=0xffffffff100449c8 frame=0xffff00860a911c90 [00015.540] 01046:66413> BUILDID git-71aec42d0c4724cc52eab689479bcf4335695c4c-dirty [00015.540] 01046:66413> [00015.540] 01046:66413> {{{module:0:kernel:elf:493e35f11d886ad5}}} [00015.540] 01046:66413> {{{mmap:0xffffffff10000000:0x10bcb0:load:0:rx:0xffffffff00000000}}} [00015.540] 01046:66413> {{{mmap:0xffffffff1010c000:0x3e000:load:0:r:0xffffffff0010c000}}} [00015.540] 01046:66413> {{{mmap:0xffffffff1014a000:0xed0:load:0:rw:0xffffffff0014a000}}} [00015.540] 01046:66413> {{{mmap:0xffffffff10150000:0x76000:load:0:rw:0xffffffff00150000}}} [00015.540] 01046:66413> dso: id=493e35f11d886ad5 base=0xffffffff10000000 name=zircon.elf [00015.540] 01046:66413> bt#00: 0xffffffff100449c8 [00015.540] 01046:66413> bt#01: 0xffffffff10044d78 [00015.540] 01046:66413> bt#02: 0xffffffff10031ad4 [00015.540] 01046:66413> bt#03: 0xffffffff100b9f6c [00015.540] 01046:66413> bt#04: 0xffffffff10095554 [00015.540] 01046:66413> bt#05: 0xffffffff1009447c [00015.540] 01046:66413> bt#06: end [00015.540] 01046:66413> {{{bt:0:0xffffffff100449c8}}} [00015.540] 01046:66413> {{{bt:1:0xffffffff10044d78}}} [00015.540] 01046:66413> {{{bt:2:0xffffffff10031ad4}}} [00015.540] 01046:66413> {{{bt:3:0xffffffff100b9f6c}}} [00015.540] 01046:66413> {{{bt:4:0xffffffff10095554}}} [00015.540] 01046:66413> {{{bt:5:0xffffffff1009447c}}} [00015.540] 01046:66413> [00015.540] 01046:66413> [00015.540] 01046:66413> ZIRCON KERNEL PANIC [00015.540] 01046:66413> Lock validation failed for thread 0xffff00004005d898 pid 1046 tid 66413 (bin/core-tests:test_thread): [00015.540] 01046:66413> Reason: Already Acquired [00015.540] 01046:66413> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.540] 01046:66413> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.540] 01046:66413> caller=0xffffffff100449c8 frame=0xffff00860a911bf0 [00015.540] 01046:66413> BUILDID git-71aec42d0c4724cc52eab689479bcf4335695c4c-dirty [00015.540] 01046:66413> [00015.540] 01046:66413> {{{module:0:kernel:elf:493e35f11d886ad5}}} [00015.540] 01046:66413> {{{mmap:0xffffffff10000000:0x10bcb0:load:0:rx:0xffffffff00000000}}} [00015.540] 01046:66413> {{{mmap:0xffffffff1010c000:0x3e000:load:0:r:0xffffffff0010c000}}} [00015.540] 01046:66413> {{{mmap:0xffffffff1014a000:0xed0:load:0:rw:0xffffffff0014a000}}} [00015.540] 01046:66413> {{{mmap:0xffffffff10150000:0x76000:load:0:rw:0xffffffff00150000}}} [00015.540] 01046:66413> dso: id=493e35f11d886ad5 base=0xffffffff10000000 name=zircon.elf [00015.540] 01046:66413> bt#00: 0xffffffff100449c8 [00015.540] 01046:66413> bt#01: 0xffffffff10044d78 [00015.540] 01046:66413> bt#02: 0xffffffff10044d78 [00015.540] 01046:66413> bt#03: 0xffffffff10031ad4 [00015.540] 01046:66413> bt#04: 0xffffffff100b9f6c [00015.540] 01046:66413> bt#05: 0xffffffff10095554 [00015.540] 01046:66413> bt#06: 0xffffffff1009447c [00015.540] 01046:66413> bt#07: end [00015.540] 01046:66413> {{{bt:0:0xffffffff100449c8}}} [00015.540] 01046:66413> {{{bt:1:0xffffffff10044d78}}} [00015.540] 01046:66413> {{{bt:2:0xffffffff10044d78}}} [00015.540] 01046:66413> {{{bt:3:0xffffffff10031ad4}}} [00015.540] 01046:66413> {{{bt:4:0xffffffff100b9f6c}}} [00015.540] 01046:66413> {{{bt:5:0xffffffff10095554}}} [00015.540] 01046:66413> {{{bt:6:0xffffffff1009447c}}} [00015.540] 01046:66413> [00015.540] 01046:66413> [00015.540] 01046:66413> ZIRCON KERNEL PANIC [00015.540] 01046:66413> Lock validation failed for thread 0xffff00004005d898 pid 1046 tid 66413 (bin/core-tests:test_thread): [00015.540] 01046:66413> Reason: Already Acquired [00015.540] 01046:66413> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.540] 01046:66413> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.540] 01046:66413> caller=0xffffffff100449c8 frame=0xffff00860a911bf0 [00015.540] 01046:66413> BUILDID git-71aec42d0c4724cc52eab689479bcf4335695c4c-dirty [00015.540] 01046:66413> [00015.540] 01046:66413> {{{module:0:kernel:elf:493e35f11d886ad5}}} [00015.540] 01046:66413> {{{mmap:0xffffffff10000000:0x10bcb0:load:0:rx:0xffffffff00000000}}} [00015.540] 01046:66413> {{{mmap:0xffffffff1010c000:0x3e000:load:0:r:0xffffffff0010c000}}} [00015.540] 01046:66413> {{{mmap:0xffffffff1014a000:0xed0:load:0:rw:0xffffffff0014a000}}} [00015.540] 01046:66413> {{{mmap:0xffffffff10150000:0x76000:load:0:rw:0xffffffff00150000}}} [00015.540] 01046:66413> dso: id=493e35f11d886ad5 base=0xffffffff10000000 name=zircon.elf [00015.540] 01046:66413> bt#00: 0xffffffff100449c8 [00015.540] 01046:66413> bt#01: 0xffffffff10044d78 [00015.540] 01046:66413> bt#02: 0xffffffff10044d78 [00015.540] 01046:66413> bt#03: 0xffffffff10031ad4 [00015.540] 01046:66413> bt#04: 0xffffffff100b9f6c [00015.540] 01046:66413> bt#05: 0xffffffff10095554 [00015.540] 01046:66413> bt#06: 0xffffffff1009447c [00015.540] 01046:66413> bt#07: end [00015.540] 01046:66413> {{{bt:0:0xffffffff100449c8}}} [00015.540] 01046:66413> {{{bt:1:0xffffffff10044d78}}} [00015.540] 01046:66413> {{{bt:2:0xffffffff10044d78}}} [00015.540] 01046:66413> {{{bt:3:0xffffffff10031ad4}}} [00015.540] 01046:66413> {{{bt:4:0xffffffff100b9f6c}}} [00015.540] 01046:66413> {{{bt:5:0xffffffff10095554}}} [00015.540] 01046:66413> {{{bt:6:0xffffffff1009447c}}} [00015.540] 01046:66413> [00015.540] 01046:01049> [PASSED] (0 ms) [00015.540] 01046:01049> read_detach_interrupt_late_test_vmo [RUNNING] [00015.540] 01046:01049> [PASSED] (0 ms) [00015.540] 01046:01049> read_close_interrupt_late_test_vmar [RUNNING] [00015.540] 01046:66433> PageFault: 1028268 free pages [00015.540] 01046:66433> [00015.540] 01046:66433> ZIRCON KERNEL PANIC [00015.540] 01046:66433> Lock validation failed for thread 0xffff00004005d898 pid 1046 tid 66433 (bin/core-tests:test_thread): [00015.540] 01046:66433> Reason: Already Acquired [00015.540] 01046:66433> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.540] 01046:66433> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.540] 01046:66433> caller=0xffffffff100449c8 frame=0xffff00860a911c90 [00015.540] 01046:66433> BUILDID git-71aec42d0c4724cc52eab689479bcf4335695c4c-dirty [00015.540] 01046:66433> [00015.540] 01046:66433> {{{module:0:kernel:elf:493e35f11d886ad5}}} [00015.540] 01046:66433> {{{mmap:0xffffffff10000000:0x10bcb0:load:0:rx:0xffffffff00000000}}} [00015.540] 01046:66433> {{{mmap:0xffffffff1010c000:0x3e000:load:0:r:0xffffffff0010c000}}} [00015.540] 01046:66433> {{{mmap:0xffffffff1014a000:0xed0:load:0:rw:0xffffffff0014a000}}} [00015.540] 01046:66433> {{{mmap:0xffffffff10150000:0x76000:load:0:rw:0xffffffff00150000}}} [00015.540] 01046:66433> dso: id=493e35f11d886ad5 base=0xffffffff10000000 name=zircon.elf [00015.540] 01046:66433> bt#00: 0xffffffff100449c8 [00015.540] 01046:66433> bt#01: 0xffffffff10044d78 [00015.540] 01046:66433> bt#02: 0xffffffff10031ad4 [00015.540] 01046:66433> bt#03: 0xffffffff100b9f6c [00015.540] 01046:66433> bt#04: 0xffffffff10095554 [00015.540] 01046:66433> bt#05: 0xffffffff1009447c [00015.540] 01046:66433> bt#06: end [00015.540] 01046:66433> {{{bt:0:0xffffffff100449c8}}} [00015.540] 01046:66433> {{{bt:1:0xffffffff10044d78}}} [00015.540] 01046:66433> {{{bt:2:0xffffffff10031ad4}}} [00015.540] 01046:66433> {{{bt:3:0xffffffff100b9f6c}}} [00015.540] 01046:66433> {{{bt:4:0xffffffff10095554}}} [00015.540] 01046:66433> {{{bt:5:0xffffffff1009447c}}} [00015.540] 01046:66433> [00015.540] 01046:66433> [00015.540] 01046:66433> ZIRCON KERNEL PANIC [00015.540] 01046:66433> Lock validation failed for thread 0xffff00004005d898 pid 1046 tid 66433 (bin/core-tests:test_thread): [00015.540] 01046:66433> Reason: Already Acquired [00015.540] 01046:66433> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.540] 01046:66433> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.540] 01046:66433> caller=0xffffffff100449c8 frame=0xffff00860a911bf0 [00015.540] 01046:66433> BUILDID git-71aec42d0c4724cc52eab689479bcf4335695c4c-dirty [00015.540] 01046:66433> [00015.540] 01046:66433> {{{module:0:kernel:elf:493e35f11d886ad5}}} [00015.540] 01046:66433> {{{mmap:0xffffffff10000000:0x10bcb0:load:0:rx:0xffffffff00000000}}} [00015.540] 01046:66433> {{{mmap:0xffffffff1010c000:0x3e000:load:0:r:0xffffffff0010c000}}} [00015.540] 01046:66433> {{{mmap:0xffffffff1014a000:0xed0:load:0:rw:0xffffffff0014a000}}} [00015.541] 01046:66433> {{{mmap:0xffffffff10150000:0x76000:load:0:rw:0xffffffff00150000}}} [00015.541] 01046:66433> dso: id=493e35f11d886ad5 base=0xffffffff10000000 name=zircon.elf [00015.541] 01046:66433> bt#00: 0xffffffff100449c8 [00015.541] 01046:66433> bt#01: 0xffffffff10044d78 [00015.541] 01046:66433> bt#02: 0xffffffff10044d78 [00015.541] 01046:66433> bt#03: 0xffffffff10031ad4 [00015.541] 01046:66433> bt#04: 0xffffffff100b9f6c [00015.541] 01046:66433> bt#05: 0xffffffff10095554 [00015.541] 01046:66433> bt#06: 0xffffffff1009447c [00015.541] 01046:66433> bt#07: end [00015.541] 01046:66433> {{{bt:0:0xffffffff100449c8}}} [00015.541] 01046:66433> {{{bt:1:0xffffffff10044d78}}} [00015.541] 01046:66433> {{{bt:2:0xffffffff10044d78}}} [00015.541] 01046:66433> {{{bt:3:0xffffffff10031ad4}}} [00015.541] 01046:66433> {{{bt:4:0xffffffff100b9f6c}}} [00015.541] 01046:66433> {{{bt:5:0xffffffff10095554}}} [00015.541] 01046:66433> {{{bt:6:0xffffffff1009447c}}} [00015.541] 01046:66433> [00015.541] 01046:66433> [00015.541] 01046:66433> ZIRCON KERNEL PANIC [00015.541] 01046:66433> Lock validation failed for thread 0xffff00004005d898 pid 1046 tid 66433 (bin/core-tests:test_thread): [00015.541] 01046:66433> Reason: Already Acquired [00015.541] 01046:66433> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.541] 01046:66433> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.541] 01046:66433> caller=0xffffffff100449c8 frame=0xffff00860a911bf0 [00015.541] 01046:66433> BUILDID git-71aec42d0c4724cc52eab689479bcf4335695c4c-dirty [00015.541] 01046:66433> [00015.541] 01046:66433> {{{module:0:kernel:elf:493e35f11d886ad5}}} [00015.541] 01046:66433> {{{mmap:0xffffffff10000000:0x10bcb0:load:0:rx:0xffffffff00000000}}} [00015.541] 01046:66433> {{{mmap:0xffffffff1010c000:0x3e000:load:0:r:0xffffffff0010c000}}} [00015.541] 01046:66433> {{{mmap:0xffffffff1014a000:0xed0:load:0:rw:0xffffffff0014a000}}} [00015.541] 01046:66433> {{{mmap:0xffffffff10150000:0x76000:load:0:rw:0xffffffff00150000}}} [00015.541] 01046:66433> dso: id=493e35f11d886ad5 base=0xffffffff10000000 name=zircon.elf [00015.541] 01046:66433> bt#00: 0xffffffff100449c8 [00015.541] 01046:66433> bt#01: 0xffffffff10044d78 [00015.541] 01046:66433> bt#02: 0xffffffff10044d78 [00015.541] 01046:66433> bt#03: 0xffffffff10031ad4 [00015.541] 01046:66433> bt#04: 0xffffffff100b9f6c [00015.541] 01046:66433> bt#05: 0xffffffff10095554 [00015.541] 01046:66433> bt#06: 0xffffffff1009447c [00015.541] 01046:66433> bt#07: end [00015.541] 01046:66433> {{{bt:0:0xffffffff100449c8}}} [00015.541] 01046:66433> {{{bt:1:0xffffffff10044d78}}} [00015.541] 01046:66433> {{{bt:2:0xffffffff10044d78}}} [00015.541] 01046:66433> {{{bt:3:0xffffffff10031ad4}}} [00015.541] 01046:66433> {{{bt:4:0xffffffff100b9f6c}}} [00015.541] 01046:66433> {{{bt:5:0xffffffff10095554}}} [00015.541] 01046:66433> {{{bt:6:0xffffffff1009447c}}} [00015.541] 01046:66433> [00015.541] 01046:01049> [PASSED] (0 ms) [00015.541] 01046:01049> read_close_interrupt_late_test_vmo [RUNNING] [00015.541] 01046:01049> [PASSED] (0 ms) [00015.541] 01046:01049> read_detach_interrupt_early_test_vmar [RUNNING] [00015.541] 01046:66453> PageFault: 1028269 free pages [00015.541] 01046:66453> [00015.541] 01046:66453> ZIRCON KERNEL PANIC [00015.541] 01046:66453> Lock validation failed for thread 0xffff00004005d898 pid 1046 tid 66453 (bin/core-tests:test_thread): [00015.541] 01046:66453> Reason: Already Acquired [00015.541] 01046:66453> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.541] 01046:66453> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.541] 01046:66453> caller=0xffffffff100449c8 frame=0xffff00860a911c90 [00015.541] 01046:66453> BUILDID git-71aec42d0c4724cc52eab689479bcf4335695c4c-dirty [00015.541] 01046:66453> [00015.541] 01046:66453> {{{module:0:kernel:elf:493e35f11d886ad5}}} [00015.541] 01046:66453> {{{mmap:0xffffffff10000000:0x10bcb0:load:0:rx:0xffffffff00000000}}} [00015.541] 01046:66453> {{{mmap:0xffffffff1010c000:0x3e000:load:0:r:0xffffffff0010c000}}} [00015.541] 01046:66453> {{{mmap:0xffffffff1014a000:0xed0:load:0:rw:0xffffffff0014a000}}} [00015.541] 01046:66453> {{{mmap:0xffffffff10150000:0x76000:load:0:rw:0xffffffff00150000}}} [00015.541] 01046:66453> dso: id=493e35f11d886ad5 base=0xffffffff10000000 name=zircon.elf [00015.541] 01046:66453> bt#00: 0xffffffff100449c8 [00015.541] 01046:66453> bt#01: 0xffffffff10044d78 [00015.541] 01046:66453> bt#02: 0xffffffff10031ad4 [00015.541] 01046:66453> bt#03: 0xffffffff100b9f6c [00015.541] 01046:66453> bt#04: 0xffffffff10095554 [00015.541] 01046:66453> bt#05: 0xffffffff1009447c [00015.541] 01046:66453> bt#06: end [00015.541] 01046:66453> {{{bt:0:0xffffffff100449c8}}} [00015.541] 01046:66453> {{{bt:1:0xffffffff10044d78}}} [00015.541] 01046:66453> {{{bt:2:0xffffffff10031ad4}}} [00015.541] 01046:66453> {{{bt:3:0xffffffff100b9f6c}}} [00015.541] 01046:66453> {{{bt:4:0xffffffff10095554}}} [00015.541] 01046:66453> {{{bt:5:0xffffffff1009447c}}} [00015.541] 01046:66453> [00015.541] 01046:66453> [00015.541] 01046:66453> ZIRCON KERNEL PANIC [00015.541] 01046:66453> Lock validation failed for thread 0xffff00004005d898 pid 1046 tid 66453 (bin/core-tests:test_thread): [00015.541] 01046:66453> Reason: Already Acquired [00015.541] 01046:66453> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.541] 01046:66453> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.541] 01046:66453> caller=0xffffffff100449c8 frame=0xffff00860a911bf0 [00015.541] 01046:66453> BUILDID git-71aec42d0c4724cc52eab689479bcf4335695c4c-dirty [00015.541] 01046:66453> [00015.541] 01046:66453> {{{module:0:kernel:elf:493e35f11d886ad5}}} [00015.541] 01046:66453> {{{mmap:0xffffffff10000000:0x10bcb0:load:0:rx:0xffffffff00000000}}} [00015.541] 01046:66453> {{{mmap:0xffffffff1010c000:0x3e000:load:0:r:0xffffffff0010c000}}} [00015.541] 01046:66453> {{{mmap:0xffffffff1014a000:0xed0:load:0:rw:0xffffffff0014a000}}} [00015.541] 01046:66453> {{{mmap:0xffffffff10150000:0x76000:load:0:rw:0xffffffff00150000}}} [00015.541] 01046:66453> dso: id=493e35f11d886ad5 base=0xffffffff10000000 name=zircon.elf [00015.541] 01046:66453> bt#00: 0xffffffff100449c8 [00015.541] 01046:66453> bt#01: 0xffffffff10044d78 [00015.541] 01046:66453> bt#02: 0xffffffff10044d78 [00015.541] 01046:66453> bt#03: 0xffffffff10031ad4 [00015.541] 01046:66453> bt#04: 0xffffffff100b9f6c [00015.541] 01046:66453> bt#05: 0xffffffff10095554 [00015.541] 01046:66453> bt#06: 0xffffffff1009447c [00015.541] 01046:66453> bt#07: end [00015.541] 01046:66453> {{{bt:0:0xffffffff100449c8}}} [00015.541] 01046:66453> {{{bt:1:0xffffffff10044d78}}} [00015.541] 01046:66453> {{{bt:2:0xffffffff10044d78}}} [00015.541] 01046:66453> {{{bt:3:0xffffffff10031ad4}}} [00015.541] 01046:66453> {{{bt:4:0xffffffff100b9f6c}}} [00015.541] 01046:66453> {{{bt:5:0xffffffff10095554}}} [00015.541] 01046:66453> {{{bt:6:0xffffffff1009447c}}} [00015.541] 01046:66453> [00015.541] 01046:66453> [00015.541] 01046:66453> ZIRCON KERNEL PANIC [00015.541] 01046:66453> Lock validation failed for thread 0xffff00004005d898 pid 1046 tid 66453 (bin/core-tests:test_thread): [00015.541] 01046:66453> Reason: Already Acquired [00015.541] 01046:66453> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.541] 01046:66453> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.541] 01046:66453> caller=0xffffffff100449c8 frame=0xffff00860a911bf0 [00015.541] 01046:66453> BUILDID git-71aec42d0c4724cc52eab689479bcf4335695c4c-dirty [00015.541] 01046:66453> [00015.541] 01046:66453> {{{module:0:kernel:elf:493e35f11d886ad5}}} [00015.541] 01046:66453> {{{mmap:0xffffffff10000000:0x10bcb0:load:0:rx:0xffffffff00000000}}} [00015.541] 01046:66453> {{{mmap:0xffffffff1010c000:0x3e000:load:0:r:0xffffffff0010c000}}} [00015.541] 01046:66453> {{{mmap:0xffffffff1014a000:0xed0:load:0:rw:0xffffffff0014a000}}} [00015.541] 01046:66453> {{{mmap:0xffffffff10150000:0x76000:load:0:rw:0xffffffff00150000}}} [00015.541] 01046:66453> dso: id=493e35f11d886ad5 base=0xffffffff10000000 name=zircon.elf [00015.541] 01046:66453> bt#00: 0xffffffff100449c8 [00015.541] 01046:66453> bt#01: 0xffffffff10044d78 [00015.541] 01046:66453> bt#02: 0xffffffff10044d78 [00015.541] 01046:66453> bt#03: 0xffffffff10031ad4 [00015.541] 01046:66453> bt#04: 0xffffffff100b9f6c [00015.541] 01046:66453> bt#05: 0xffffffff10095554 [00015.541] 01046:66453> bt#06: 0xffffffff1009447c [00015.541] 01046:66453> bt#07: end [00015.541] 01046:66453> {{{bt:0:0xffffffff100449c8}}} [00015.541] 01046:66453> {{{bt:1:0xffffffff10044d78}}} [00015.541] 01046:66453> {{{bt:2:0xffffffff10044d78}}} [00015.541] 01046:66453> {{{bt:3:0xffffffff10031ad4}}} [00015.541] 01046:66453> {{{bt:4:0xffffffff100b9f6c}}} [00015.541] 01046:66453> {{{bt:5:0xffffffff10095554}}} [00015.541] 01046:66453> {{{bt:6:0xffffffff1009447c}}} [00015.541] 01046:66453> [00015.541] 01046:01049> [PASSED] (0 ms) [00015.541] 01046:01049> read_detach_interrupt_early_test_vmo [RUNNING] [00015.542] 01046:01049> [PASSED] (0 ms) [00015.542] 01046:01049> read_close_interrupt_early_test_vmar [RUNNING] [00015.542] 01046:66473> PageFault: 1028269 free pages [00015.542] 01046:66473> [00015.542] 01046:66473> ZIRCON KERNEL PANIC [00015.542] 01046:66473> Lock validation failed for thread 0xffff00004005d898 pid 1046 tid 66473 (bin/core-tests:test_thread): [00015.542] 01046:66473> Reason: Already Acquired [00015.542] 01046:66473> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.542] 01046:66473> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.542] 01046:66473> caller=0xffffffff100449c8 frame=0xffff00860a911c90 [00015.542] 01046:66473> BUILDID git-71aec42d0c4724cc52eab689479bcf4335695c4c-dirty [00015.542] 01046:66473> [00015.542] 01046:66473> {{{module:0:kernel:elf:493e35f11d886ad5}}} [00015.542] 01046:66473> {{{mmap:0xffffffff10000000:0x10bcb0:load:0:rx:0xffffffff00000000}}} [00015.542] 01046:66473> {{{mmap:0xffffffff1010c000:0x3e000:load:0:r:0xffffffff0010c000}}} [00015.542] 01046:66473> {{{mmap:0xffffffff1014a000:0xed0:load:0:rw:0xffffffff0014a000}}} [00015.542] 01046:66473> {{{mmap:0xffffffff10150000:0x76000:load:0:rw:0xffffffff00150000}}} [00015.542] 01046:66473> dso: id=493e35f11d886ad5 base=0xffffffff10000000 name=zircon.elf [00015.542] 01046:66473> bt#00: 0xffffffff100449c8 [00015.542] 01046:66473> bt#01: 0xffffffff10044d78 [00015.542] 01046:66473> bt#02: 0xffffffff10031ad4 [00015.542] 01046:66473> bt#03: 0xffffffff100b9f6c [00015.542] 01046:66473> bt#04: 0xffffffff10095554 [00015.542] 01046:66473> bt#05: 0xffffffff1009447c [00015.542] 01046:66473> bt#06: end [00015.542] 01046:66473> {{{bt:0:0xffffffff100449c8}}} [00015.542] 01046:66473> {{{bt:1:0xffffffff10044d78}}} [00015.542] 01046:66473> {{{bt:2:0xffffffff10031ad4}}} [00015.542] 01046:66473> {{{bt:3:0xffffffff100b9f6c}}} [00015.542] 01046:66473> {{{bt:4:0xffffffff10095554}}} [00015.542] 01046:66473> {{{bt:5:0xffffffff1009447c}}} [00015.542] 01046:66473> [00015.542] 01046:66473> [00015.542] 01046:66473> ZIRCON KERNEL PANIC [00015.542] 01046:66473> Lock validation failed for thread 0xffff00004005d898 pid 1046 tid 66473 (bin/core-tests:test_thread): [00015.542] 01046:66473> Reason: Already Acquired [00015.542] 01046:66473> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.542] 01046:66473> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.542] 01046:66473> caller=0xffffffff100449c8 frame=0xffff00860a911bf0 [00015.542] 01046:66473> BUILDID git-71aec42d0c4724cc52eab689479bcf4335695c4c-dirty [00015.542] 01046:66473> [00015.542] 01046:66473> {{{module:0:kernel:elf:493e35f11d886ad5}}} [00015.542] 01046:66473> {{{mmap:0xffffffff10000000:0x10bcb0:load:0:rx:0xffffffff00000000}}} [00015.542] 01046:66473> {{{mmap:0xffffffff1010c000:0x3e000:load:0:r:0xffffffff0010c000}}} [00015.542] 01046:66473> {{{mmap:0xffffffff1014a000:0xed0:load:0:rw:0xffffffff0014a000}}} [00015.542] 01046:66473> {{{mmap:0xffffffff10150000:0x76000:load:0:rw:0xffffffff00150000}}} [00015.542] 01046:66473> dso: id=493e35f11d886ad5 base=0xffffffff10000000 name=zircon.elf [00015.542] 01046:66473> bt#00: 0xffffffff100449c8 [00015.542] 01046:66473> bt#01: 0xffffffff10044d78 [00015.542] 01046:66473> bt#02: 0xffffffff10044d78 [00015.542] 01046:66473> bt#03: 0xffffffff10031ad4 [00015.542] 01046:66473> bt#04: 0xffffffff100b9f6c [00015.542] 01046:66473> bt#05: 0xffffffff10095554 [00015.542] 01046:66473> bt#06: 0xffffffff1009447c [00015.542] 01046:66473> bt#07: end [00015.542] 01046:66473> {{{bt:0:0xffffffff100449c8}}} [00015.542] 01046:66473> {{{bt:1:0xffffffff10044d78}}} [00015.542] 01046:66473> {{{bt:2:0xffffffff10044d78}}} [00015.542] 01046:66473> {{{bt:3:0xffffffff10031ad4}}} [00015.542] 01046:66473> {{{bt:4:0xffffffff100b9f6c}}} [00015.542] 01046:66473> {{{bt:5:0xffffffff10095554}}} [00015.542] 01046:66473> {{{bt:6:0xffffffff1009447c}}} [00015.542] 01046:66473> [00015.542] 01046:66473> [00015.542] 01046:66473> ZIRCON KERNEL PANIC [00015.542] 01046:66473> Lock validation failed for thread 0xffff00004005d898 pid 1046 tid 66473 (bin/core-tests:test_thread): [00015.542] 01046:66473> Reason: Already Acquired [00015.542] 01046:66473> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.542] 01046:66473> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.542] 01046:66473> caller=0xffffffff100449c8 frame=0xffff00860a911bf0 [00015.542] 01046:66473> BUILDID git-71aec42d0c4724cc52eab689479bcf4335695c4c-dirty [00015.542] 01046:66473> [00015.542] 01046:66473> {{{module:0:kernel:elf:493e35f11d886ad5}}} [00015.542] 01046:66473> {{{mmap:0xffffffff10000000:0x10bcb0:load:0:rx:0xffffffff00000000}}} [00015.542] 01046:66473> {{{mmap:0xffffffff1010c000:0x3e000:load:0:r:0xffffffff0010c000}}} [00015.542] 01046:66473> {{{mmap:0xffffffff1014a000:0xed0:load:0:rw:0xffffffff0014a000}}} [00015.542] 01046:66473> {{{mmap:0xffffffff10150000:0x76000:load:0:rw:0xffffffff00150000}}} [00015.542] 01046:66473> dso: id=493e35f11d886ad5 base=0xffffffff10000000 name=zircon.elf [00015.542] 01046:66473> bt#00: 0xffffffff100449c8 [00015.542] 01046:66473> bt#01: 0xffffffff10044d78 [00015.542] 01046:66473> bt#02: 0xffffffff10044d78 [00015.542] 01046:66473> bt#03: 0xffffffff10031ad4 [00015.542] 01046:66473> bt#04: 0xffffffff100b9f6c [00015.542] 01046:66473> bt#05: 0xffffffff10095554 [00015.542] 01046:66473> bt#06: 0xffffffff1009447c [00015.542] 01046:66473> bt#07: end [00015.542] 01046:66473> {{{bt:0:0xffffffff100449c8}}} [00015.542] 01046:66473> {{{bt:1:0xffffffff10044d78}}} [00015.542] 01046:66473> {{{bt:2:0xffffffff10044d78}}} [00015.542] 01046:66473> {{{bt:3:0xffffffff10031ad4}}} [00015.542] 01046:66473> {{{bt:4:0xffffffff100b9f6c}}} [00015.542] 01046:66473> {{{bt:5:0xffffffff10095554}}} [00015.542] 01046:66473> {{{bt:6:0xffffffff1009447c}}} [00015.542] 01046:66473> [00015.542] 01046:01049> [PASSED] (0 ms) [00015.542] 01046:01049> read_close_interrupt_early_test_vmo [RUNNING] [00015.543] 01046:01049> [PASSED] (0 ms) [00015.543] 01046:01049> thread_kill_test_vmar [RUNNING] [00015.543] 01046:01049> [PASSED] (0 ms) [00015.543] 01046:01049> thread_kill_test_vmo [RUNNING] [00015.544] 01046:01049> [PASSED] (0 ms) [00015.544] 01046:01049> thread_kill_overlap_test_vmar [RUNNING] [00015.544] 01046:01049> [PASSED] (0 ms) [00015.544] 01046:01049> thread_kill_overlap_test_vmo [RUNNING] [00015.545] 01046:01049> [PASSED] (0 ms) [00015.545] 01046:01049> close_pager_test [RUNNING] [00015.545] 01046:66564> PageFault: 1028191 free pages [00015.545] 01046:66564> [00015.545] 01046:66564> ZIRCON KERNEL PANIC [00015.545] 01046:66564> Lock validation failed for thread 0xffff00004298d6e0 pid 1046 tid 66564 (bin/core-tests:test_thread): [00015.545] 01046:66564> Reason: Already Acquired [00015.545] 01046:66564> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.545] 01046:66564> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.545] 01046:66564> caller=0xffffffff100449c8 frame=0xffff00860a931c90 [00015.545] 01046:66564> BUILDID git-71aec42d0c4724cc52eab689479bcf4335695c4c-dirty [00015.545] 01046:66564> [00015.545] 01046:66564> {{{module:0:kernel:elf:493e35f11d886ad5}}} [00015.545] 01046:66564> {{{mmap:0xffffffff10000000:0x10bcb0:load:0:rx:0xffffffff00000000}}} [00015.545] 01046:66564> {{{mmap:0xffffffff1010c000:0x3e000:load:0:r:0xffffffff0010c000}}} [00015.545] 01046:66564> {{{mmap:0xffffffff1014a000:0xed0:load:0:rw:0xffffffff0014a000}}} [00015.545] 01046:66564> {{{mmap:0xffffffff10150000:0x76000:load:0:rw:0xffffffff00150000}}} [00015.545] 01046:66564> dso: id=493e35f11d886ad5 base=0xffffffff10000000 name=zircon.elf [00015.545] 01046:66564> bt#00: 0xffffffff100449c8 [00015.545] 01046:66564> bt#01: 0xffffffff10044d78 [00015.545] 01046:66564> bt#02: 0xffffffff10031ad4 [00015.545] 01046:66564> bt#03: 0xffffffff100b9f6c [00015.545] 01046:66564> bt#04: 0xffffffff10095554 [00015.545] 01046:66564> bt#05: 0xffffffff1009447c [00015.545] 01046:66564> bt#06: end [00015.545] 01046:66564> {{{bt:0:0xffffffff100449c8}}} [00015.545] 01046:66564> {{{bt:1:0xffffffff10044d78}}} [00015.545] 01046:66564> {{{bt:2:0xffffffff10031ad4}}} [00015.545] 01046:66564> {{{bt:3:0xffffffff100b9f6c}}} [00015.545] 01046:66564> {{{bt:4:0xffffffff10095554}}} [00015.545] 01046:66564> {{{bt:5:0xffffffff1009447c}}} [00015.545] 01046:66564> [00015.545] 01046:66564> [00015.545] 01046:66564> ZIRCON KERNEL PANIC [00015.545] 01046:66564> Lock validation failed for thread 0xffff00004298d6e0 pid 1046 tid 66564 (bin/core-tests:test_thread): [00015.545] 01046:66564> Reason: Already Acquired [00015.545] 01046:66564> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.545] 01046:66564> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.545] 01046:66564> caller=0xffffffff100449c8 frame=0xffff00860a931bf0 [00015.545] 01046:66564> BUILDID git-71aec42d0c4724cc52eab689479bcf4335695c4c-dirty [00015.545] 01046:66564> [00015.545] 01046:66564> {{{module:0:kernel:elf:493e35f11d886ad5}}} [00015.545] 01046:66564> {{{mmap:0xffffffff10000000:0x10bcb0:load:0:rx:0xffffffff00000000}}} [00015.545] 01046:66564> {{{mmap:0xffffffff1010c000:0x3e000:load:0:r:0xffffffff0010c000}}} [00015.545] 01046:66564> {{{mmap:0xffffffff1014a000:0xed0:load:0:rw:0xffffffff0014a000}}} [00015.545] 01046:66564> {{{mmap:0xffffffff10150000:0x76000:load:0:rw:0xffffffff00150000}}} [00015.545] 01046:66564> dso: id=493e35f11d886ad5 base=0xffffffff10000000 name=zircon.elf [00015.545] 01046:66564> bt#00: 0xffffffff100449c8 [00015.545] 01046:66564> bt#01: 0xffffffff10044d78 [00015.545] 01046:66564> bt#02: 0xffffffff10044d78 [00015.545] 01046:66564> bt#03: 0xffffffff10031ad4 [00015.545] 01046:66564> bt#04: 0xffffffff100b9f6c [00015.545] 01046:66564> bt#05: 0xffffffff10095554 [00015.545] 01046:66564> bt#06: 0xffffffff1009447c [00015.545] 01046:66564> bt#07: end [00015.545] 01046:66564> {{{bt:0:0xffffffff100449c8}}} [00015.545] 01046:66564> {{{bt:1:0xffffffff10044d78}}} [00015.545] 01046:66564> {{{bt:2:0xffffffff10044d78}}} [00015.545] 01046:66564> {{{bt:3:0xffffffff10031ad4}}} [00015.545] 01046:66564> {{{bt:4:0xffffffff100b9f6c}}} [00015.545] 01046:66564> {{{bt:5:0xffffffff10095554}}} [00015.545] 01046:66564> {{{bt:6:0xffffffff1009447c}}} [00015.545] 01046:66564> [00015.545] 01046:66564> [00015.545] 01046:66564> ZIRCON KERNEL PANIC [00015.545] 01046:66564> Lock validation failed for thread 0xffff00004298d6e0 pid 1046 tid 66564 (bin/core-tests:test_thread): [00015.545] 01046:66564> Reason: Already Acquired [00015.545] 01046:66564> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.545] 01046:66564> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.545] 01046:66564> caller=0xffffffff100449c8 frame=0xffff00860a931bf0 [00015.545] 01046:66564> BUILDID git-71aec42d0c4724cc52eab689479bcf4335695c4c-dirty [00015.545] 01046:66564> [00015.545] 01046:66564> {{{module:0:kernel:elf:493e35f11d886ad5}}} [00015.545] 01046:66564> {{{mmap:0xffffffff10000000:0x10bcb0:load:0:rx:0xffffffff00000000}}} [00015.545] 01046:66564> {{{mmap:0xffffffff1010c000:0x3e000:load:0:r:0xffffffff0010c000}}} [00015.545] 01046:66564> {{{mmap:0xffffffff1014a000:0xed0:load:0:rw:0xffffffff0014a000}}} [00015.545] 01046:66564> {{{mmap:0xffffffff10150000:0x76000:load:0:rw:0xffffffff00150000}}} [00015.545] 01046:66564> dso: id=493e35f11d886ad5 base=0xffffffff10000000 name=zircon.elf [00015.545] 01046:66564> bt#00: 0xffffffff100449c8 [00015.545] 01046:66564> bt#01: 0xffffffff10044d78 [00015.545] 01046:66564> bt#02: 0xffffffff10044d78 [00015.545] 01046:66564> bt#03: 0xffffffff10031ad4 [00015.545] 01046:66564> bt#04: 0xffffffff100b9f6c [00015.545] 01046:66564> bt#05: 0xffffffff10095554 [00015.545] 01046:66564> bt#06: 0xffffffff1009447c [00015.545] 01046:66564> bt#07: end [00015.545] 01046:66564> {{{bt:0:0xffffffff100449c8}}} [00015.545] 01046:66564> {{{bt:1:0xffffffff10044d78}}} [00015.545] 01046:66564> {{{bt:2:0xffffffff10044d78}}} [00015.545] 01046:66564> {{{bt:3:0xffffffff10031ad4}}} [00015.545] 01046:66564> {{{bt:4:0xffffffff100b9f6c}}} [00015.545] 01046:66564> {{{bt:5:0xffffffff10095554}}} [00015.545] 01046:66564> {{{bt:6:0xffffffff1009447c}}} [00015.545] 01046:66564> [00015.545] 01046:01049> [PASSED] (0 ms) [00015.545] 01046:01049> detach_close_pager_test [RUNNING] [00015.545] 01046:01049> [PASSED] (0 ms) [00015.545] 01046:01049> close_port_test [RUNNING] [00015.545] 01046:66577> PageFault: 1028189 free pages [00015.545] 01046:66577> [00015.545] 01046:66577> ZIRCON KERNEL PANIC [00015.545] 01046:66577> Lock validation failed for thread 0xffff00004298d620 pid 1046 tid 66577 (bin/core-tests:test_thread): [00015.545] 01046:66577> Reason: Already Acquired [00015.545] 01046:66577> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.545] 01046:66577> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.545] 01046:66577> caller=0xffffffff100449c8 frame=0xffff00860a931c90 [00015.545] 01046:66577> BUILDID git-71aec42d0c4724cc52eab689479bcf4335695c4c-dirty [00015.545] 01046:66577> [00015.545] 01046:66577> {{{module:0:kernel:elf:493e35f11d886ad5}}} [00015.546] 01046:66577> {{{mmap:0xffffffff10000000:0x10bcb0:load:0:rx:0xffffffff00000000}}} [00015.546] 01046:66577> {{{mmap:0xffffffff1010c000:0x3e000:load:0:r:0xffffffff0010c000}}} [00015.546] 01046:66577> {{{mmap:0xffffffff1014a000:0xed0:load:0:rw:0xffffffff0014a000}}} [00015.546] 01046:66577> {{{mmap:0xffffffff10150000:0x76000:load:0:rw:0xffffffff00150000}}} [00015.546] 01046:66577> dso: id=493e35f11d886ad5 base=0xffffffff10000000 name=zircon.elf [00015.546] 01046:66577> bt#00: 0xffffffff100449c8 [00015.546] 01046:66577> bt#01: 0xffffffff10044d78 [00015.546] 01046:66577> bt#02: 0xffffffff10031ad4 [00015.546] 01046:66577> bt#03: 0xffffffff100b9f6c [00015.546] 01046:66577> bt#04: 0xffffffff10095554 [00015.546] 01046:66577> bt#05: 0xffffffff1009447c [00015.546] 01046:66577> bt#06: end [00015.546] 01046:66577> {{{bt:0:0xffffffff100449c8}}} [00015.546] 01046:66577> {{{bt:1:0xffffffff10044d78}}} [00015.546] 01046:66577> {{{bt:2:0xffffffff10031ad4}}} [00015.546] 01046:66577> {{{bt:3:0xffffffff100b9f6c}}} [00015.546] 01046:66577> {{{bt:4:0xffffffff10095554}}} [00015.546] 01046:66577> {{{bt:5:0xffffffff1009447c}}} [00015.546] 01046:66577> [00015.546] 01046:66577> [00015.546] 01046:66577> ZIRCON KERNEL PANIC [00015.546] 01046:66577> Lock validation failed for thread 0xffff00004298d620 pid 1046 tid 66577 (bin/core-tests:test_thread): [00015.546] 01046:66577> Reason: Already Acquired [00015.546] 01046:66577> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.546] 01046:66577> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.546] 01046:66577> caller=0xffffffff100449c8 frame=0xffff00860a931bf0 [00015.546] 01046:66577> BUILDID git-71aec42d0c4724cc52eab689479bcf4335695c4c-dirty [00015.546] 01046:66577> [00015.546] 01046:66577> {{{module:0:kernel:elf:493e35f11d886ad5}}} [00015.546] 01046:66577> {{{mmap:0xffffffff10000000:0x10bcb0:load:0:rx:0xffffffff00000000}}} [00015.546] 01046:66577> {{{mmap:0xffffffff1010c000:0x3e000:load:0:r:0xffffffff0010c000}}} [00015.546] 01046:66577> {{{mmap:0xffffffff1014a000:0xed0:load:0:rw:0xffffffff0014a000}}} [00015.546] 01046:66577> {{{mmap:0xffffffff10150000:0x76000:load:0:rw:0xffffffff00150000}}} [00015.546] 01046:66577> dso: id=493e35f11d886ad5 base=0xffffffff10000000 name=zircon.elf [00015.546] 01046:66577> bt#00: 0xffffffff100449c8 [00015.546] 01046:66577> bt#01: 0xffffffff10044d78 [00015.546] 01046:66577> bt#02: 0xffffffff10044d78 [00015.546] 01046:66577> bt#03: 0xffffffff10031ad4 [00015.546] 01046:66577> bt#04: 0xffffffff100b9f6c [00015.546] 01046:66577> bt#05: 0xffffffff10095554 [00015.546] 01046:66577> bt#06: 0xffffffff1009447c [00015.546] 01046:66577> bt#07: end [00015.546] 01046:66577> {{{bt:0:0xffffffff100449c8}}} [00015.546] 01046:66577> {{{bt:1:0xffffffff10044d78}}} [00015.546] 01046:66577> {{{bt:2:0xffffffff10044d78}}} [00015.546] 01046:66577> {{{bt:3:0xffffffff10031ad4}}} [00015.546] 01046:66577> {{{bt:4:0xffffffff100b9f6c}}} [00015.546] 01046:66577> {{{bt:5:0xffffffff10095554}}} [00015.546] 01046:66577> {{{bt:6:0xffffffff1009447c}}} [00015.546] 01046:66577> [00015.546] 01046:66577> [00015.546] 01046:66577> ZIRCON KERNEL PANIC [00015.546] 01046:66577> Lock validation failed for thread 0xffff00004298d620 pid 1046 tid 66577 (bin/core-tests:test_thread): [00015.546] 01046:66577> Reason: Already Acquired [00015.546] 01046:66577> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.546] 01046:66577> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.546] 01046:66577> caller=0xffffffff100449c8 frame=0xffff00860a931bf0 [00015.546] 01046:66577> BUILDID git-71aec42d0c4724cc52eab689479bcf4335695c4c-dirty [00015.546] 01046:66577> [00015.546] 01046:66577> {{{module:0:kernel:elf:493e35f11d886ad5}}} [00015.546] 01046:66577> {{{mmap:0xffffffff10000000:0x10bcb0:load:0:rx:0xffffffff00000000}}} [00015.546] 01046:66577> {{{mmap:0xffffffff1010c000:0x3e000:load:0:r:0xffffffff0010c000}}} [00015.546] 01046:66577> {{{mmap:0xffffffff1014a000:0xed0:load:0:rw:0xffffffff0014a000}}} [00015.546] 01046:66577> {{{mmap:0xffffffff10150000:0x76000:load:0:rw:0xffffffff00150000}}} [00015.546] 01046:66577> dso: id=493e35f11d886ad5 base=0xffffffff10000000 name=zircon.elf [00015.546] 01046:66577> bt#00: 0xffffffff100449c8 [00015.546] 01046:66577> bt#01: 0xffffffff10044d78 [00015.546] 01046:66577> bt#02: 0xffffffff10044d78 [00015.546] 01046:66577> bt#03: 0xffffffff10031ad4 [00015.546] 01046:66577> bt#04: 0xffffffff100b9f6c [00015.546] 01046:66577> bt#05: 0xffffffff10095554 [00015.546] 01046:66577> bt#06: 0xffffffff1009447c [00015.546] 01046:66577> bt#07: end [00015.546] 01046:66577> {{{bt:0:0xffffffff100449c8}}} [00015.546] 01046:66577> {{{bt:1:0xffffffff10044d78}}} [00015.546] 01046:66577> {{{bt:2:0xffffffff10044d78}}} [00015.546] 01046:66577> {{{bt:3:0xffffffff10031ad4}}} [00015.546] 01046:66577> {{{bt:4:0xffffffff100b9f6c}}} [00015.546] 01046:66577> {{{bt:5:0xffffffff10095554}}} [00015.546] 01046:66577> {{{bt:6:0xffffffff1009447c}}} [00015.546] 01046:66577> [00015.546] 01046:01049> [PASSED] (0 ms) [00015.546] 01046:01049> CASE lifecycle_tests [PASSED] [00015.546] 01046:01049> CASE pager_read_tests [STARTED] [00015.546] 01046:01049> single_page_test_vmar [RUNNING] [00015.546] 01046:01049> [PASSED] (0 ms) [00015.546] 01046:01049> single_page_test_vmo [RUNNING] [00015.546] 01046:01049> [PASSED] (0 ms) [00015.546] 01046:01049> presupply_test_vmar [RUNNING] [00015.546] 01046:01049> [PASSED] (0 ms) [00015.546] 01046:01049> presupply_test_vmo [RUNNING] [00015.547] 01046:01049> [PASSED] (0 ms) [00015.547] 01046:01049> early_supply_test_vmar [RUNNING] [00015.547] 01046:01049> [PASSED] (0 ms) [00015.547] 01046:01049> early_supply_test_vmo [RUNNING] [00015.548] 01046:01049> [PASSED] (0 ms) [00015.548] 01046:01049> sequential_multipage_test_vmar [RUNNING] [00015.549] 01046:01049> [PASSED] (1 ms) [00015.549] 01046:01049> sequential_multipage_test_vmo [RUNNING] [00015.550] 01046:01049> [PASSED] (0 ms) [00015.550] 01046:01049> concurrent_multipage_access_test_vmar [RUNNING] [00015.550] 01046:01049> [PASSED] (0 ms) [00015.550] 01046:01049> concurrent_multipage_access_test_vmo [RUNNING] [00015.551] 01046:01049> [PASSED] (0 ms) [00015.551] 01046:01049> concurrent_overlapping_access_test_vmar [RUNNING] [00015.576] 01046:01049> [PASSED] (24 ms) [00015.576] 01046:01049> concurrent_overlapping_access_test_vmo [RUNNING] [00015.589] 01046:01049> [PASSED] (12 ms) [00015.589] 01046:01049> bulk_single_supply_test_vmar [RUNNING] [00015.590] 01046:01049> [PASSED] (1 ms) [00015.590] 01046:01049> bulk_single_supply_test_vmo [RUNNING] [00015.592] 01046:01049> [PASSED] (1 ms) [00015.592] 01046:01049> bulk_odd_length_supply_test_vmar [RUNNING] [00015.614] 01046:01049> [PASSED] (21 ms) [00015.614] 01046:01049> bulk_odd_length_supply_test_vmo [RUNNING] [00015.637] 01046:01049> [PASSED] (23 ms) [00015.637] 01046:01049> bulk_odd_offset_supply_test_vmar [RUNNING] [00015.653] 01046:01049> [PASSED] (15 ms) [00015.653] 01046:01049> bulk_odd_offset_supply_test_vmo [RUNNING] [00015.674] 01046:01049> [PASSED] (20 ms) [00015.674] 01046:01049> overlap_supply_test_vmar [RUNNING] [00015.674] 01046:01049> [PASSED] (0 ms) [00015.674] 01046:01049> overlap_supply_test_vmo [RUNNING] [00015.675] 01046:01049> [PASSED] (0 ms) [00015.675] 01046:01049> many_request_test_vmar [RUNNING] [00015.768] 01046:01049> [PASSED] (93 ms) [00015.768] 01046:01049> many_request_test_vmo [RUNNING] [00015.862] 01046:01049> [PASSED] (93 ms) [00015.862] 01046:01049> successive_vmo_test [RUNNING] [00015.873] 01046:01049> [PASSED] (11 ms) [00015.873] 01046:01049> multiple_concurrent_vmo_test [RUNNING] [00015.874] 01046:01049> [PASSED] (1 ms) [00015.874] 01046:01049> vmar_unmap_test [RUNNING] [00015.875] 01046:74298> PageFault: 1028187 free pages [00015.875] 01046:74298> [00015.875] 01046:74298> ZIRCON KERNEL PANIC [00015.875] 01046:74298> Lock validation failed for thread 0xffff00004298d7d0 pid 1046 tid 74298 (bin/core-tests:test_thread): [00015.875] 01046:74298> Reason: Already Acquired [00015.875] 01046:74298> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.875] 01046:74298> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.875] 01046:74298> caller=0xffffffff100449c8 frame=0xffff00860a931c90 [00015.875] 01046:74298> BUILDID git-71aec42d0c4724cc52eab689479bcf4335695c4c-dirty [00015.875] 01046:74298> [00015.875] 01046:74298> {{{module:0:kernel:elf:493e35f11d886ad5}}} [00015.875] 01046:74298> {{{mmap:0xffffffff10000000:0x10bcb0:load:0:rx:0xffffffff00000000}}} [00015.875] 01046:74298> {{{mmap:0xffffffff1010c000:0x3e000:load:0:r:0xffffffff0010c000}}} [00015.875] 01046:74298> {{{mmap:0xffffffff1014a000:0xed0:load:0:rw:0xffffffff0014a000}}} [00015.875] 01046:74298> {{{mmap:0xffffffff10150000:0x76000:load:0:rw:0xffffffff00150000}}} [00015.875] 01046:74298> dso: id=493e35f11d886ad5 base=0xffffffff10000000 name=zircon.elf [00015.875] 01046:74298> bt#00: 0xffffffff100449c8 [00015.875] 01046:74298> bt#01: 0xffffffff10044d78 [00015.875] 01046:74298> bt#02: 0xffffffff10031ad4 [00015.875] 01046:74298> bt#03: 0xffffffff100b9f6c [00015.875] 01046:74298> bt#04: 0xffffffff10095554 [00015.875] 01046:74298> bt#05: 0xffffffff1009447c [00015.875] 01046:74298> bt#06: end [00015.875] 01046:74298> {{{bt:0:0xffffffff100449c8}}} [00015.875] 01046:74298> {{{bt:1:0xffffffff10044d78}}} [00015.875] 01046:74298> {{{bt:2:0xffffffff10031ad4}}} [00015.875] 01046:74298> {{{bt:3:0xffffffff100b9f6c}}} [00015.875] 01046:74298> {{{bt:4:0xffffffff10095554}}} [00015.875] 01046:74298> {{{bt:5:0xffffffff1009447c}}} [00015.875] 01046:74298> [00015.875] 01046:74298> [00015.875] 01046:74298> ZIRCON KERNEL PANIC [00015.875] 01046:74298> Lock validation failed for thread 0xffff00004298d7d0 pid 1046 tid 74298 (bin/core-tests:test_thread): [00015.875] 01046:74298> Reason: Already Acquired [00015.875] 01046:74298> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.875] 01046:74298> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.875] 01046:74298> caller=0xffffffff100449c8 frame=0xffff00860a931bf0 [00015.875] 01046:74298> BUILDID git-71aec42d0c4724cc52eab689479bcf4335695c4c-dirty [00015.875] 01046:74298> [00015.875] 01046:74298> {{{module:0:kernel:elf:493e35f11d886ad5}}} [00015.875] 01046:74298> {{{mmap:0xffffffff10000000:0x10bcb0:load:0:rx:0xffffffff00000000}}} [00015.875] 01046:74298> {{{mmap:0xffffffff1010c000:0x3e000:load:0:r:0xffffffff0010c000}}} [00015.875] 01046:74298> {{{mmap:0xffffffff1014a000:0xed0:load:0:rw:0xffffffff0014a000}}} [00015.875] 01046:74298> {{{mmap:0xffffffff10150000:0x76000:load:0:rw:0xffffffff00150000}}} [00015.875] 01046:74298> dso: id=493e35f11d886ad5 base=0xffffffff10000000 name=zircon.elf [00015.875] 01046:74298> bt#00: 0xffffffff100449c8 [00015.875] 01046:74298> bt#01: 0xffffffff10044d78 [00015.875] 01046:74298> bt#02: 0xffffffff10044d78 [00015.875] 01046:74298> bt#03: 0xffffffff10031ad4 [00015.875] 01046:74298> bt#04: 0xffffffff100b9f6c [00015.875] 01046:74298> bt#05: 0xffffffff10095554 [00015.875] 01046:74298> bt#06: 0xffffffff1009447c [00015.875] 01046:74298> bt#07: end [00015.875] 01046:74298> {{{bt:0:0xffffffff100449c8}}} [00015.875] 01046:74298> {{{bt:1:0xffffffff10044d78}}} [00015.875] 01046:74298> {{{bt:2:0xffffffff10044d78}}} [00015.875] 01046:74298> {{{bt:3:0xffffffff10031ad4}}} [00015.875] 01046:74298> {{{bt:4:0xffffffff100b9f6c}}} [00015.875] 01046:74298> {{{bt:5:0xffffffff10095554}}} [00015.875] 01046:74298> {{{bt:6:0xffffffff1009447c}}} [00015.875] 01046:74298> [00015.875] 01046:74298> [00015.875] 01046:74298> ZIRCON KERNEL PANIC [00015.875] 01046:74298> Lock validation failed for thread 0xffff00004298d7d0 pid 1046 tid 74298 (bin/core-tests:test_thread): [00015.875] 01046:74298> Reason: Already Acquired [00015.875] 01046:74298> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.875] 01046:74298> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.875] 01046:74298> caller=0xffffffff100449c8 frame=0xffff00860a931bf0 [00015.875] 01046:74298> BUILDID git-71aec42d0c4724cc52eab689479bcf4335695c4c-dirty [00015.875] 01046:74298> [00015.875] 01046:74298> {{{module:0:kernel:elf:493e35f11d886ad5}}} [00015.875] 01046:74298> {{{mmap:0xffffffff10000000:0x10bcb0:load:0:rx:0xffffffff00000000}}} [00015.875] 01046:74298> {{{mmap:0xffffffff1010c000:0x3e000:load:0:r:0xffffffff0010c000}}} [00015.875] 01046:74298> {{{mmap:0xffffffff1014a000:0xed0:load:0:rw:0xffffffff0014a000}}} [00015.875] 01046:74298> {{{mmap:0xffffffff10150000:0x76000:load:0:rw:0xffffffff00150000}}} [00015.875] 01046:74298> dso: id=493e35f11d886ad5 base=0xffffffff10000000 name=zircon.elf [00015.875] 01046:74298> bt#00: 0xffffffff100449c8 [00015.875] 01046:74298> bt#01: 0xffffffff10044d78 [00015.875] 01046:74298> bt#02: 0xffffffff10044d78 [00015.875] 01046:74298> bt#03: 0xffffffff10031ad4 [00015.875] 01046:74298> bt#04: 0xffffffff100b9f6c [00015.875] 01046:74298> bt#05: 0xffffffff10095554 [00015.875] 01046:74298> bt#06: 0xffffffff1009447c [00015.875] 01046:74298> bt#07: end [00015.875] 01046:74298> {{{bt:0:0xffffffff100449c8}}} [00015.875] 01046:74298> {{{bt:1:0xffffffff10044d78}}} [00015.875] 01046:74298> {{{bt:2:0xffffffff10044d78}}} [00015.875] 01046:74298> {{{bt:3:0xffffffff10031ad4}}} [00015.875] 01046:74298> {{{bt:4:0xffffffff100b9f6c}}} [00015.875] 01046:74298> {{{bt:5:0xffffffff10095554}}} [00015.875] 01046:74298> {{{bt:6:0xffffffff1009447c}}} [00015.875] 01046:74298> [00015.875] 01046:01049> [PASSED] (0 ms) [00015.875] 01046:01049> vmar_remap_test [RUNNING] [00015.876] 01046:01049> [PASSED] (1 ms) [00015.876] 01046:01049> vmar_map_range_test [RUNNING] [00015.877] 01046:01049> [PASSED] (0 ms) [00015.877] 01046:01049> read_resize_test_vmar [RUNNING] [00015.877] 01046:01049> [PASSED] (0 ms) [00015.877] 01046:01049> read_resize_test_vmo [RUNNING] [00015.877] 01046:01049> [PASSED] (0 ms) [00015.877] 01046:01049> suspend_read_test_vmar [RUNNING] [00015.877] 01046:01049> [PASSED] (0 ms) [00015.877] 01046:01049> suspend_read_test_vmo [RUNNING] [00015.878] 01046:01049> [PASSED] (0 ms) [00015.878] 01046:01049> CASE pager_read_tests [PASSED] [00015.878] 01046:01049> CASE object_get_child_tests [STARTED] [00015.878] 01046:01049> handle_invalid [RUNNING] [00015.878] 01046:01049> [PASSED] (0 ms) [00015.878] 01046:01049> CASE object_get_child_tests [PASSED] [00015.878] 01046:01049> CASE memory_mapping_tests [STARTED] [00015.878] 01046:01049> address_space_limits_test [RUNNING] [00015.878] 01046:01049> [PASSED] (0 ms) [00015.878] 01046:01049> mmap_zerofilled_test [RUNNING] [00015.878] 01046:01049> [PASSED] (0 ms) [00015.878] 01046:01049> mmap_len_test [RUNNING] [00015.878] 01046:01049> [PASSED] (0 ms) [00015.878] 01046:01049> mmap_PROT_EXEC_test [RUNNING] [00015.878] 01046:01049> [PASSED] (0 ms) [00015.878] 01046:01049> mmap_offset_test [RUNNING] [00015.878] 01046:01049> [PASSED] (0 ms) [00015.878] 01046:01049> mmap_prot_test [RUNNING] [00015.878] 01046:01049> [PASSED] (0 ms) [00015.878] 01046:01049> mmap_flags_test [RUNNING] [00015.878] 01046:01049> [PASSED] (0 ms) [00015.878] 01046:01049> mprotect_test [RUNNING] [00015.878] 01046:01049> [00015.878] 01046:01049> ZIRCON KERNEL PANIC [00015.878] 01046:01049> Lock validation failed for thread 0xffff00004003af00 pid 1046 tid 1049 (bin/core-tests:bin/core-tests): [00015.878] 01046:01049> Reason: Already Acquired [00015.878] 01046:01049> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.878] 01046:01049> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00015.878] 01046:01049> caller=0xffffffff100449c8 frame=0xffff00860a899b90 [00015.878] 01046:01049> BUILDID git-71aec42d0c4724cc52eab689479bcf4335695c4c-dirty [00015.878] 01046:01049> [00015.878] 01046:01049> {{{module:0:kernel:elf:493e35f11d886ad5}}} [00015.878] 01046:01049> {{{mmap:0xffffffff10000000:0x10bcb0:load:0:rx:0xffffffff00000000}}} [00015.878] 01046:01049> {{{mmap:0xffffffff1010c000:0x3e000:load:0:r:0xffffffff0010c000}}} [00015.878] 01046:01049> {{{mmap:0xffffffff1014a000:0xed0:load:0:rw:0xffffffff0014a000}}} [00015.878] 01046:01049> {{{mmap:0xffffffff10150000:0x76000:load:0:rw:0xffffffff00150000}}} [00015.878] 01046:01049> dso: id=493e35f11d886ad5 base=0xffffffff10000000 name=zircon.elf [00015.878] 01046:01049> bt#00: 0xffffffff100449c8 [00015.878] 01046:01049> bt#01: 0xffffffff10044d78 [00015.878] 01046:01049> bt#02: 0xffffffff10045e50 [00015.878] 01046:01049> bt#03: 0xffffffff1003a1e8 [00015.878] 01046:01049> bt#04: 0xffffffff1004ece0 [00015.878] 01046:01049> bt#05: 0xffffffff1003fdc4 [00015.878] 01046:01049> bt#06: 0xffffffff100ce880 [00015.878] 01046:01049> bt#07: 0xffffffff100dd65c [00015.878] 01046:01049> bt#08: 0xffffffff10094958 [00015.878] 01046:01049> bt#09: end [00015.878] 01046:01049> {{{bt:0:0xffffffff100449c8}}} [00015.878] 01046:01049> {{{bt:1:0xffffffff10044d78}}} [00015.878] 01046:01049> {{{bt:2:0xffffffff10045e50}}} [00015.878] 01046:01049> {{{bt:3:0xffffffff1003a1e8}}} [00015.878] 01046:01049> {{{bt:4:0xffffffff1004ece0}}} [00015.878] 01046:01049> {{{bt:5:0xffffffff1003fdc4}}} [00015.878] 01046:01049> {{{bt:6:0xffffffff100ce880}}} [00015.878] 01046:01049> {{{bt:7:0xffffffff100dd65c}}} [00015.878] 01046:01049> {{{bt:8:0xffffffff10094958}}} [00015.878] 01046:01049> [00015.878] 01046:01049> [PASSED] (0 ms) [00015.878] 01046:01049> CASE memory_mapping_tests [PASSED] [00015.879] 01046:01049> CASE job_tests [STARTED] [00015.879] 01046:01049> basic_test [RUNNING] [00015.879] 01046:01049> [PASSED] (0 ms) [00015.879] 01046:01049> create_missing_rights_test [RUNNING] [00015.879] 01046:01049> [PASSED] (0 ms) [00015.879] 01046:01049> policy_invalid_topic_test [RUNNING] [00015.879] 01046:01049> [PASSED] (0 ms) [00015.879] 01046:01049> policy_basic_test [RUNNING] [00015.879] 01046:01049> [PASSED] (0 ms) [00015.879] 01046:01049> policy_timer_slack_invalid_options_test [RUNNING] [00015.879] 01046:01049> [PASSED] (0 ms) [00015.879] 01046:01049> policy_timer_slack_invalid_count_test [RUNNING] [00015.879] 01046:01049> [PASSED] (0 ms) [00015.879] 01046:01049> policy_timer_slack_invalid_policy_test [RUNNING] [00015.879] 01046:01049> [PASSED] (0 ms) [00015.879] 01046:01049> policy_timer_slack_non_empty_test [RUNNING] [00015.879] 01046:01049> [PASSED] (0 ms) [00015.879] 01046:01049> policy_timer_slack_valid [RUNNING] [00015.879] 01046:01049> [PASSED] (0 ms) [00015.879] 01046:01049> create_test [RUNNING] [00015.879] 01046:01049> [PASSED] (0 ms) [00015.879] 01046:01049> kill_test [RUNNING] [00015.879] 01046:01049> [PASSED] (0 ms) [00015.879] 01046:01049> kill_job_no_child_test [RUNNING] [00015.879] 01046:01049> [PASSED] (0 ms) [00015.879] 01046:01049> kill_job_removes_from_tree [RUNNING] [00015.879] 01046:01049> [PASSED] (0 ms) [00015.879] 01046:01049> close_job_removes_from_tree [RUNNING] [00015.879] 01046:01049> [PASSED] (0 ms) [00015.879] 01046:01049> kill_job_chain [RUNNING] [00016.234] 01046:01049> [PASSED] (355 ms) [00016.234] 01046:01049> set_job_oom_kill_bit [RUNNING] [00016.234] 01046:01049> [PASSED] (0 ms) [00016.234] 01046:01049> wait_test [RUNNING] [00016.240] 01046:01049> [PASSED] (5 ms) [00016.240] 01046:01049> info_task_stats_fails [RUNNING] [00016.240] 01046:01049> [PASSED] (0 ms) [00016.240] 01046:01049> max_height_smoke [RUNNING] [00016.240] 01046:01049> [PASSED] (0 ms) [00016.240] 01046:01049> CASE job_tests [PASSED] [00016.240] 01046:01049> CASE handle_wait_tests [STARTED] [00016.240] 01046:01049> handle_wait_test [RUNNING] [00016.240] 01046:01049> [PASSED] (0 ms) [00016.240] 01046:01049> CASE handle_wait_tests [PASSED] [00016.240] 01046:01049> CASE eventpair_tests [STARTED] [00016.240] 01046:01049> create_test [RUNNING] [00016.240] 01046:01049> [PASSED] (0 ms) [00016.240] 01046:01049> signal_test [RUNNING] [00016.240] 01046:01049> [PASSED] (0 ms) [00016.240] 01046:01049> signal_peer_test [RUNNING] [00016.240] 01046:01049> [PASSED] (0 ms) [00016.240] 01046:01049> signal_peer_closed_test [RUNNING] [00016.240] 01046:01049> [PASSED] (0 ms) [00016.240] 01046:01049> CASE eventpair_tests [PASSED] [00016.240] 01046:01049> CASE elf_tls_tests [STARTED] [00016.240] 01046:01049> ExecutableTlsTest [RUNNING] [00016.389] 01046:01049> [PASSED] (148 ms) [00016.389] 01046:01049> CASE elf_tls_tests [PASSED] [00016.389] 01046:01049> CASE channel_tests [STARTED] [00016.389] 01046:01049> channel_test [RUNNING] [00016.389] 01046:01049> [PASSED] (0 ms) [00016.390] 01046:01049> channel_read_error_test [RUNNING] [00016.390] 01046:01049> [PASSED] (0 ms) [00016.390] 01046:01049> channel_close_test [RUNNING] [00016.390] 01046:01049> [PASSED] (0 ms) [00016.390] 01046:01049> channel_peer_closed_test [RUNNING] [00016.390] 01046:01049> [PASSED] (0 ms) [00016.390] 01046:01049> channel_non_transferable [RUNNING] [00016.390] 01046:01049> [PASSED] (0 ms) [00016.390] 01046:01049> channel_duplicate_handles [RUNNING] [00016.390] 01046:01049> [PASSED] (0 ms) [00016.390] 01046:01049> channel_multithread_read [RUNNING] [00016.397] 01046:01049> [PASSED] (6 ms) [00016.397] 01046:01049> channel_may_discard [RUNNING] [00016.397] 01046:01049> [PASSED] (0 ms) [00016.397] 01046:01049> channel_call [RUNNING] [00016.398] 01046:01049> [PASSED] (1 ms) [00016.398] 01046:01049> channel_call_consumes_handles [RUNNING] [00016.398] 01046:01049> [PASSED] (0 ms) [00016.398] 01046:01049> channel_call2 [RUNNING] [00016.648] 01046:01049> [PASSED] (250 ms) [00016.648] 01046:01049> bad_channel_call_finish [RUNNING] [00016.648] 01046:01049> [PASSED] (0 ms) [00016.648] 01046:01049> channel_nest [RUNNING] [00016.649] 01046:01049> [PASSED] (0 ms) [00016.649] 01046:01049> channel_disallow_write_to_self [RUNNING] [00016.649] 01046:01049> [PASSED] (0 ms) [00016.649] 01046:01049> channel_read_etc [RUNNING] [00016.649] 01046:01049> [PASSED] (0 ms) [00016.649] 01046:01049> channel_write_different_sizes [RUNNING] [00016.767] 01046:01049> [PASSED] (118 ms) [00016.767] 01046:01049> channel_write_takes_all_handles [RUNNING] [00016.770] 01046:01049> [PASSED] (2 ms) [00016.770] 01046:01049> CASE channel_tests [PASSED] [00016.770] 01046:01049> CASE c11_thread_tests [STARTED] [00016.770] 01046:01049> c11_thread_test [RUNNING] [00017.572] 01046:01049> [PASSED] (801 ms) [00017.572] 01046:01049> long_name_succeeds [RUNNING] [00017.672] 01046:01049> [PASSED] (100 ms) [00017.672] 01046:01049> detach_self_test [RUNNING] [00017.807] 01046:01049> [PASSED] (135 ms) [00017.807] 01046:01049> CASE c11_thread_tests [PASSED] [00017.807] 01046:01049> CASE mtx_tests [STARTED] [00017.807] 01046:01049> test_initializer [RUNNING] [00017.807] 01046:01049> [PASSED] (0 ms) [00017.807] 01046:01049> test_mutexes [RUNNING] [00017.819] 01046:01049> [PASSED] (11 ms) [00017.819] 01046:01049> test_try_mutexes [RUNNING] [00017.823] 01046:01049> [PASSED] (3 ms) [00017.823] 01046:01049> test_static_initializer [RUNNING] [00017.823] 01046:01049> [PASSED] (0 ms) [00017.823] 01046:01049> test_timeout_elapsed [RUNNING] [00018.323] 01046:01049> [PASSED] (500 ms) [00018.323] 01046:01049> CASE mtx_tests [PASSED] [00018.323] 01046:01049> CASE cnd_tests [STARTED] [00018.323] 01046:01049> cnd_test [RUNNING] [00018.324] 01046:01049> [PASSED] (0 ms) [00018.324] 01046:01049> cnd_timedwait_timeout_test [RUNNING] [00018.325] 01046:01049> [PASSED] (1 ms) [00018.325] 01046:01049> CASE cnd_tests [PASSED] [00018.325] 01046:01049> CASE bad_syscall_tests [STARTED] [00018.325] 01046:01049> bad_access_test [RUNNING] [00018.325] 01046:01049> [PASSED] (0 ms) [00018.325] 01046:01049> bad_syscall_num_test [RUNNING] [00018.325] 01046:01049> [PASSED] (0 ms) [00018.325] 01046:01049> CASE bad_syscall_tests [PASSED] [00018.326] 01046:01049> ==================================================== [00018.326] 01046:01049> Results: [00018.326] 01046:01049> SUCCESS! All test cases passed! [00018.326] 01046:01049> CASES: 33 SUCCESS: 33 FAILED: 0 [00018.326] 01046:01049> ==================================================== [00018.326] 01046:01049> Flags: [00018.326] 01046:01049> --gtest_random_seed = 18 [00018.326] 01046:01049> [00018.326] 01046:01049> [00018.326] 01046:01049> [==========] Running 242 tests from 13 test cases [00018.326] 01046:01049> . [00018.326] 01046:01049> [----------] Global test environment set-up. [00018.326] 01046:01049> [----------] 1 test from ClockTest [00018.326] 01046:01049> [00018.326] 01046:01049> [ RUN ] ClockTest.ClockMonotonic [00018.326] 01046:01049> [00018.428] 01046:01049> [ OK ] ClockTest.ClockMonotonic (102 [00018.428] 01046:01049> ms) [00018.428] 01046:01049> [----------] 1 test from ClockTest (102 [00018.428] 01046:01049> ms total) [00018.428] 01046:01049> [----------] 9 tests from FifoTest [00018.428] 01046:01049> [00018.428] 01046:01049> [ RUN ] FifoTest.InvalidParametersReturnOutOfRange [00018.428] 01046:01049> [00018.428] 01046:01049> [ OK ] FifoTest.InvalidParametersReturnOutOfRange (0 [00018.428] 01046:01049> ms) [00018.428] 01046:01049> [ RUN ] FifoTest.EndpointsAreRelated [00018.428] 01046:01049> [00018.428] 01046:01049> [ OK ] FifoTest.EndpointsAreRelated (0 [00018.428] 01046:01049> ms) [00018.428] 01046:01049> [ RUN ] FifoTest.EmptyQueueReturnsErrShouldWait [00018.428] 01046:01049> [00018.428] 01046:01049> [ OK ] FifoTest.EmptyQueueReturnsErrShouldWait (0 [00018.428] 01046:01049> ms) [00018.428] 01046:01049> [ RUN ] FifoTest.ReadAndWriteValidatesSizeAndElementCount [00018.428] 01046:01049> [00018.428] 01046:01049> [ OK ] FifoTest.ReadAndWriteValidatesSizeAndElementCount (0 [00018.428] 01046:01049> ms) [00018.428] 01046:01049> [ RUN ] FifoTest.DequeueSignalsWriteable [00018.428] 01046:01049> [00018.428] 01046:01049> [ OK ] FifoTest.DequeueSignalsWriteable (0 [00018.428] 01046:01049> ms) [00018.428] 01046:01049> [ RUN ] FifoTest.FifoOrderIsPreserved [00018.428] 01046:01049> [00018.428] 01046:01049> [ OK ] FifoTest.FifoOrderIsPreserved (0 [00018.428] 01046:01049> ms) [00018.428] 01046:01049> [ RUN ] FifoTest.PartialWriteQueuesElementsThatFit [00018.428] 01046:01049> [00018.428] 01046:01049> [ OK ] FifoTest.PartialWriteQueuesElementsThatFit (0 [00018.428] 01046:01049> ms) [00018.428] 01046:01049> [ RUN ] FifoTest.IndividualReadsPreserveOrder [00018.428] 01046:01049> [00018.428] 01046:01049> [ OK ] FifoTest.IndividualReadsPreserveOrder (0 [00018.428] 01046:01049> ms) [00018.428] 01046:01049> [ RUN ] FifoTest.EndpointCloseSignalsPeerClosed [00018.428] 01046:01049> [00018.428] 01046:01049> [ OK ] FifoTest.EndpointCloseSignalsPeerClosed (0 [00018.428] 01046:01049> ms) [00018.428] 01046:01049> [----------] 9 tests from FifoTest (0 [00018.428] 01046:01049> ms total) [00018.428] 01046:01049> [----------] 15 tests from FutexTest [00018.428] 01046:01049> [00018.429] 01046:01049> [ RUN ] FutexTest.WaitValueMismatch [00018.429] 01046:01049> [00018.429] 01046:01049> [ OK ] FutexTest.WaitValueMismatch (0 [00018.429] 01046:01049> ms) [00018.429] 01046:01049> [ RUN ] FutexTest.WaitTimeout [00018.429] 01046:01049> [00018.429] 01046:01049> [ OK ] FutexTest.WaitTimeout (0 [00018.429] 01046:01049> ms) [00018.429] 01046:01049> [ RUN ] FutexTest.WaitTimeoutElapsed [00018.429] 01046:01049> [00018.929] 01046:01049> [ OK ] FutexTest.WaitTimeoutElapsed (500 [00018.929] 01046:01049> ms) [00018.929] 01046:01049> [ RUN ] FutexTest.WaitBadAddress [00018.929] 01046:01049> [00018.929] 01046:01049> [ OK ] FutexTest.WaitBadAddress (0 [00018.929] 01046:01049> ms) [00018.929] 01046:01049> [ RUN ] FutexTest.Wakeup [00018.929] 01046:01049> [00018.929] 01046:01049> [ OK ] FutexTest.Wakeup (0 [00018.929] 01046:01049> ms) [00018.929] 01046:01049> [ RUN ] FutexTest.WakeupLimit [00018.929] 01046:01049> [00019.530] 01046:01049> [ OK ] FutexTest.WakeupLimit (601 [00019.530] 01046:01049> ms) [00019.530] 01046:01049> [ RUN ] FutexTest.WakeupAddress [00019.530] 01046:01049> [00019.531] 01046:01049> [ OK ] FutexTest.WakeupAddress (0 [00019.531] 01046:01049> ms) [00019.531] 01046:01049> [ RUN ] FutexTest.RequeueValueMismatch [00019.531] 01046:01049> [00019.531] 01046:01049> [ OK ] FutexTest.RequeueValueMismatch (0 [00019.531] 01046:01049> ms) [00019.531] 01046:01049> [ RUN ] FutexTest.RequeueSameAddr [00019.531] 01046:01049> [00019.531] 01046:01049> [ OK ] FutexTest.RequeueSameAddr (0 [00019.531] 01046:01049> ms) [00019.531] 01046:01049> [ RUN ] FutexTest.Requeue [00019.531] 01046:01049> [00020.433] 01046:01049> [ OK ] FutexTest.Requeue (901 [00020.433] 01046:01049> ms) [00020.433] 01046:01049> [ RUN ] FutexTest.RequeueUnqueuedOnTimeout [00020.433] 01046:01049> [00020.733] 01046:01049> [ OK ] FutexTest.RequeueUnqueuedOnTimeout (300 [00020.733] 01046:01049> ms) [00020.733] 01046:01049> [ RUN ] FutexTest.ThreadKilled [00020.733] 01046:01049> [00020.734] 01046:01049> [ OK ] FutexTest.ThreadKilled (0 [00020.734] 01046:01049> ms) [00020.734] 01046:01049> [ RUN ] FutexTest.ThreadSuspended [00020.734] 01046:01049> [00021.034] 01046:01049> [ OK ] FutexTest.ThreadSuspended (300 [00021.034] 01046:01049> ms) [00021.034] 01046:01049> [ RUN ] FutexTest.MisalignedFutextAddr [00021.034] 01046:01049> [00021.034] 01046:01049> [ OK ] FutexTest.MisalignedFutextAddr (0 [00021.034] 01046:01049> ms) [00021.034] 01046:01049> [ RUN ] FutexTest.EventSignaling [00021.034] 01046:01049> [00021.034] 01046:01049> [00000021.34739180]: starting signal threads [00021.034] 01046:146871> [00000021.34889535]: thread 1 waiting on event [00021.035] 01046:146876> [00000021.34993055]: thread 2 waiting on event [00021.035] 01046:146881> [00000021.35069705]: thread 3 waiting on event [00021.035] 01046:01049> [00000021.35201220]: signaling event [00021.035] 01046:01049> [00000021.35230430]: joining signal threads [00021.035] 01046:146881> [00000021.35242015]: thread 3 done [00021.035] 01046:146871> [00000021.35304100]: thread 1 done [00021.035] 01046:146876> [00000021.35356640]: thread 2 done [00021.035] 01046:01049> [00000021.35366705]: signal_thread 1 joined [00021.035] 01046:01049> [00000021.35423785]: signal_thread 2 joined [00021.035] 01046:01049> [00000021.35447845]: signal_thread 3 joined [00021.035] 01046:01049> [ OK ] FutexTest.EventSignaling (0 [00021.035] 01046:01049> ms) [00021.035] 01046:01049> [----------] 15 tests from FutexTest (2606 [00021.035] 01046:01049> ms total) [00021.035] 01046:01049> [----------] 3 tests from HandleCloseTest [00021.035] 01046:01049> [00021.035] 01046:01049> [ RUN ] HandleCloseTest.Many [00021.035] 01046:01049> [00021.035] 01046:01049> [ OK ] HandleCloseTest.Many (0 [00021.035] 01046:01049> ms) [00021.035] 01046:01049> [ RUN ] HandleCloseTest.ManyInvalidHandlesShouldNotFail [00021.035] 01046:01049> [00021.035] 01046:01049> [ OK ] HandleCloseTest.ManyInvalidHandlesShouldNotFail (0 [00021.035] 01046:01049> ms) [00021.035] 01046:01049> [ RUN ] HandleCloseTest.ManyDuplicateTest [00021.035] 01046:01049> [00021.035] 01046:01049> [ OK ] HandleCloseTest.ManyDuplicateTest (0 [00021.035] 01046:01049> ms) [00021.035] 01046:01049> [----------] 3 tests from HandleCloseTest (0 [00021.035] 01046:01049> ms total) [00021.035] 01046:01049> [----------] 6 tests from HandleInfoTest [00021.035] 01046:01049> [00021.035] 01046:01049> [ RUN ] HandleInfoTest.ReplaceSuccessOrigInvalid [00021.035] 01046:01049> [00021.035] 01046:01049> [ OK ] HandleInfoTest.ReplaceSuccessOrigInvalid (0 [00021.035] 01046:01049> ms) [00021.035] 01046:01049> [ RUN ] HandleInfoTest.ReplaceFailureBothInvalid [00021.035] 01046:01049> [00021.035] 01046:01049> [ OK ] HandleInfoTest.ReplaceFailureBothInvalid (0 [00021.035] 01046:01049> ms) [00021.035] 01046:01049> [ RUN ] HandleInfoTest.DupAndInfoRights [00021.035] 01046:01049> [00021.035] 01046:01049> [ OK ] HandleInfoTest.DupAndInfoRights (0 [00021.035] 01046:01049> ms) [00021.035] 01046:01049> [ RUN ] HandleInfoTest.RelatedKoid [00021.035] 01046:01049> [00021.035] 01046:01049> [ OK ] HandleInfoTest.RelatedKoid (0 [00021.035] 01046:01049> ms) [00021.035] 01046:01049> [ RUN ] HandleInfoTest.DuplicateRights [00021.035] 01046:01049> [00021.035] 01046:01049> [ OK ] HandleInfoTest.DuplicateRights (0 [00021.035] 01046:01049> ms) [00021.035] 01046:01049> [ RUN ] HandleInfoTest.ReplaceRights [00021.035] 01046:01049> [00021.035] 01046:01049> [ OK ] HandleInfoTest.ReplaceRights (0 [00021.035] 01046:01049> ms) [00021.035] 01046:01049> [----------] 6 tests from HandleInfoTest (0 [00021.035] 01046:01049> ms total) [00021.035] 01046:01049> [----------] 2 tests from HandleTransferTest [00021.035] 01046:01049> [00021.036] 01046:01049> [ RUN ] HandleTransferTest.OverChannelThenRead [00021.036] 01046:01049> [00021.036] 01046:01049> [ OK ] HandleTransferTest.OverChannelThenRead (0 [00021.036] 01046:01049> ms) [00021.036] 01046:01049> [ RUN ] HandleTransferTest.CancelsWait [00021.036] 01046:01049> [00021.037] 01046:01049> [ OK ] HandleTransferTest.CancelsWait (1 [00021.037] 01046:01049> ms) [00021.037] 01046:01049> [----------] 2 tests from HandleTransferTest (1 [00021.037] 01046:01049> ms total) [00021.037] 01046:01049> [----------] 4 tests from ObjectGetInfoTest [00021.037] 01046:01049> [00021.037] 01046:01049> [ RUN ] ObjectGetInfoTest.OpenValidHandleSuceeds [00021.037] 01046:01049> [00021.037] 01046:01049> [ OK ] ObjectGetInfoTest.OpenValidHandleSuceeds (0 [00021.037] 01046:01049> ms) [00021.037] 01046:01049> [ RUN ] ObjectGetInfoTest.ClosedValidHandleFails [00021.037] 01046:01049> [00021.037] 01046:01049> [ OK ] ObjectGetInfoTest.ClosedValidHandleFails (0 [00021.037] 01046:01049> ms) [00021.037] 01046:01049> [ RUN ] ObjectGetInfoTest.HandleCountCorrectness [00021.037] 01046:01049> [00021.037] 01046:01049> [ OK ] ObjectGetInfoTest.HandleCountCorrectness (0 [00021.037] 01046:01049> ms) [00021.037] 01046:01049> [ RUN ] ObjectGetInfoTest.InvalidHandleFails [00021.037] 01046:01049> [00021.037] 01046:01049> [ OK ] ObjectGetInfoTest.InvalidHandleFails (0 [00021.037] 01046:01049> ms) [00021.037] 01046:01049> [----------] 4 tests from ObjectGetInfoTest (0 [00021.037] 01046:01049> ms total) [00021.037] 01046:01049> [----------] 39 tests from JobGetInfoTest [00021.037] 01046:01049> [00021.037] 01046:01049> [ RUN ] JobGetInfoTest.InfoJobProcessesGetChild [00021.037] 01046:01049> [00021.037] 01046:01049> [ OK ] JobGetInfoTest.InfoJobProcessesGetChild (0 [00021.037] 01046:01049> ms) [00021.037] 01046:01049> [ RUN ] JobGetInfoTest.InfoJobChildJobsGetChild [00021.037] 01046:01049> [00021.037] 01046:01049> [ OK ] JobGetInfoTest.InfoJobChildJobsGetChild (0 [00021.037] 01046:01049> ms) [00021.037] 01046:01049> [ RUN ] JobGetInfoTest.InfoJobProcessesOnSelfSuceeds [00021.037] 01046:01049> [00021.037] 01046:01049> [ OK ] JobGetInfoTest.InfoJobProcessesOnSelfSuceeds (0 [00021.037] 01046:01049> ms) [00021.037] 01046:01049> [ RUN ] JobGetInfoTest.InfoJobProcessesInvalidHandleFails [00021.037] 01046:01049> [00021.037] 01046:01049> [ OK ] JobGetInfoTest.InfoJobProcessesInvalidHandleFails (0 [00021.037] 01046:01049> ms) [00021.037] 01046:01049> [ RUN ] JobGetInfoTest.InfoJobProcessesNullAvailSucceeds [00021.037] 01046:01049> [00021.037] 01046:01049> [ OK ] JobGetInfoTest.InfoJobProcessesNullAvailSucceeds (0 [00021.037] 01046:01049> ms) [00021.037] 01046:01049> [ RUN ] JobGetInfoTest.InfoJobProcessesNullActualSucceeds [00021.037] 01046:01049> [00021.037] 01046:01049> [ OK ] JobGetInfoTest.InfoJobProcessesNullActualSucceeds (0 [00021.037] 01046:01049> ms) [00021.037] 01046:01049> [ RUN ] JobGetInfoTest.InfoJobProcessesNullActualAndAvailSucceeds [00021.037] 01046:01049> [00021.037] 01046:01049> [ OK ] JobGetInfoTest.InfoJobProcessesNullActualAndAvailSucceeds (0 [00021.037] 01046:01049> ms) [00021.037] 01046:01049> [ RUN ] JobGetInfoTest.InfoJobProcessesInvalidBufferPointerFails [00021.037] 01046:01049> [00021.037] 01046:01049> [ OK ] JobGetInfoTest.InfoJobProcessesInvalidBufferPointerFails (0 [00021.037] 01046:01049> ms) [00021.037] 01046:01049> [ RUN ] JobGetInfoTest.InfoJobProcessesBadActualgIsInvalidArg [00021.037] 01046:01049> [00021.037] 01046:01049> [ OK ] JobGetInfoTest.InfoJobProcessesBadActualgIsInvalidArg (0 [00021.037] 01046:01049> ms) [00021.037] 01046:01049> [ RUN ] JobGetInfoTest.InfoJobProcessesBadAvailIsInvalidArg [00021.037] 01046:01049> [00021.037] 01046:01049> [ OK ] JobGetInfoTest.InfoJobProcessesBadAvailIsInvalidArg (0 [00021.037] 01046:01049> ms) [00021.037] 01046:01049> [ RUN ] JobGetInfoTest.InfoJobProcessesZeroSizedBufferIsOk [00021.037] 01046:01049> [00021.037] 01046:01049> [ OK ] JobGetInfoTest.InfoJobProcessesZeroSizedBufferIsOk (0 [00021.037] 01046:01049> ms) [00021.037] 01046:01049> [ RUN ] JobGetInfoTest.InfoJobProcessesSmallBufferIsOk [00021.037] 01046:01049> [00021.037] 01046:01049> [ OK ] JobGetInfoTest.InfoJobProcessesSmallBufferIsOk (0 [00021.037] 01046:01049> ms) [00021.037] 01046:01049> [ RUN ] JobGetInfoTest.InfoJobProcessesPartiallyUnmappedBufferIsInvalidArgs [00021.037] 01046:01049> [00021.038] 01046:01049> [ OK ] JobGetInfoTest.InfoJobProcessesPartiallyUnmappedBufferIsInvalidArgs (0 [00021.038] 01046:01049> ms) [00021.038] 01046:01049> [ RUN ] JobGetInfoTest.InfoJobProcessesRequiresEnumerateRights [00021.038] 01046:01049> [00021.038] 01046:01049> [ OK ] JobGetInfoTest.InfoJobProcessesRequiresEnumerateRights (0 [00021.038] 01046:01049> ms) [00021.038] 01046:01049> [ RUN ] JobGetInfoTest.InfoJobProcessesProcessbHandleIsBadHandle [00021.038] 01046:01049> [00021.038] 01046:01049> [ OK ] JobGetInfoTest.InfoJobProcessesProcessbHandleIsBadHandle (0 [00021.038] 01046:01049> ms) [00021.038] 01046:01049> [ RUN ] JobGetInfoTest.InfoJobProcessesThreadHandleIsBadHandle [00021.038] 01046:01049> [00021.038] 01046:01049> [ OK ] JobGetInfoTest.InfoJobProcessesThreadHandleIsBadHandle (0 [00021.038] 01046:01049> ms) [00021.038] 01046:01049> [ RUN ] JobGetInfoTest.InfoJobChildrenOnSelfSuceeds [00021.038] 01046:01049> [00021.038] 01046:01049> [ OK ] JobGetInfoTest.InfoJobChildrenOnSelfSuceeds (0 [00021.038] 01046:01049> ms) [00021.038] 01046:01049> [ RUN ] JobGetInfoTest.InfoJobChildrenInvalidHandleFails [00021.038] 01046:01049> [00021.038] 01046:01049> [ OK ] JobGetInfoTest.InfoJobChildrenInvalidHandleFails (0 [00021.038] 01046:01049> ms) [00021.038] 01046:01049> [ RUN ] JobGetInfoTest.InfoJobChildrenNullAvailSucceeds [00021.038] 01046:01049> [00021.038] 01046:01049> [ OK ] JobGetInfoTest.InfoJobChildrenNullAvailSucceeds (0 [00021.038] 01046:01049> ms) [00021.038] 01046:01049> [ RUN ] JobGetInfoTest.InfoJobChildrenNullActualSucceeds [00021.038] 01046:01049> [00021.038] 01046:01049> [ OK ] JobGetInfoTest.InfoJobChildrenNullActualSucceeds (0 [00021.038] 01046:01049> ms) [00021.038] 01046:01049> [ RUN ] JobGetInfoTest.InfoJobChildrenNullActualAndAvailSucceeds [00021.038] 01046:01049> [00021.038] 01046:01049> [ OK ] JobGetInfoTest.InfoJobChildrenNullActualAndAvailSucceeds (0 [00021.038] 01046:01049> ms) [00021.038] 01046:01049> [ RUN ] JobGetInfoTest.InfoJobChildrenInvalidBufferPointerFails [00021.038] 01046:01049> [00021.038] 01046:01049> [ OK ] JobGetInfoTest.InfoJobChildrenInvalidBufferPointerFails (0 [00021.038] 01046:01049> ms) [00021.038] 01046:01049> [ RUN ] JobGetInfoTest.InfoJobChildrenBadActualgIsInvalidArg [00021.038] 01046:01049> [00021.038] 01046:01049> [ OK ] JobGetInfoTest.InfoJobChildrenBadActualgIsInvalidArg (0 [00021.038] 01046:01049> ms) [00021.038] 01046:01049> [ RUN ] JobGetInfoTest.InfoJobChildrenBadAvailIsInvalidArg [00021.038] 01046:01049> [00021.038] 01046:01049> [ OK ] JobGetInfoTest.InfoJobChildrenBadAvailIsInvalidArg (0 [00021.038] 01046:01049> ms) [00021.038] 01046:01049> [ RUN ] JobGetInfoTest.InfoJobChildrenZeroSizedBufferIsOk [00021.038] 01046:01049> [00021.038] 01046:01049> [ OK ] JobGetInfoTest.InfoJobChildrenZeroSizedBufferIsOk (0 [00021.038] 01046:01049> ms) [00021.038] 01046:01049> [ RUN ] JobGetInfoTest.InfoJobChildrenSmallBufferIsOk [00021.038] 01046:01049> [00021.038] 01046:01049> [ OK ] JobGetInfoTest.InfoJobChildrenSmallBufferIsOk (0 [00021.038] 01046:01049> ms) [00021.038] 01046:01049> [ RUN ] JobGetInfoTest.InfoJobChildrenPartiallyUnmappedBufferIsInvalidArgs [00021.038] 01046:01049> [00021.038] 01046:01049> [ OK ] JobGetInfoTest.InfoJobChildrenPartiallyUnmappedBufferIsInvalidArgs (0 [00021.038] 01046:01049> ms) [00021.038] 01046:01049> [ RUN ] JobGetInfoTest.InfoJobChildrenRequiresEnumerateRights [00021.038] 01046:01049> [00021.038] 01046:01049> [ OK ] JobGetInfoTest.InfoJobChildrenRequiresEnumerateRights (0 [00021.038] 01046:01049> ms) [00021.038] 01046:01049> [ RUN ] JobGetInfoTest.InfoJobChildrenJobHandleIsBadHandle [00021.038] 01046:01049> [00021.038] 01046:01049> [ OK ] JobGetInfoTest.InfoJobChildrenJobHandleIsBadHandle (0 [00021.038] 01046:01049> ms) [00021.038] 01046:01049> [ RUN ] JobGetInfoTest.InfoJobChildrenThreadHandleIsBadHandle [00021.038] 01046:01049> [00021.038] 01046:01049> [ OK ] JobGetInfoTest.InfoJobChildrenThreadHandleIsBadHandle (0 [00021.038] 01046:01049> ms) [00021.038] 01046:01049> [ RUN ] JobGetInfoTest.InfoHandleBasicOnSelfSuceeds [00021.038] 01046:01049> [00021.038] 01046:01049> [ OK ] JobGetInfoTest.InfoHandleBasicOnSelfSuceeds (0 [00021.038] 01046:01049> ms) [00021.038] 01046:01049> [ RUN ] JobGetInfoTest.InfoHandleBasicInvalidHandleFails [00021.038] 01046:01049> [00021.038] 01046:01049> [ OK ] JobGetInfoTest.InfoHandleBasicInvalidHandleFails (0 [00021.038] 01046:01049> ms) [00021.038] 01046:01049> [ RUN ] JobGetInfoTest.InfoHandleBasicNullAvailSucceeds [00021.038] 01046:01049> [00021.038] 01046:01049> [ OK ] JobGetInfoTest.InfoHandleBasicNullAvailSucceeds (0 [00021.038] 01046:01049> ms) [00021.038] 01046:01049> [ RUN ] JobGetInfoTest.InfoHandleBasicNullActualSucceeds [00021.038] 01046:01049> [00021.038] 01046:01049> [ OK ] JobGetInfoTest.InfoHandleBasicNullActualSucceeds (0 [00021.038] 01046:01049> ms) [00021.038] 01046:01049> [ RUN ] JobGetInfoTest.InfoHandleBasicNullActualAndAvailSucceeds [00021.038] 01046:01049> [00021.038] 01046:01049> [ OK ] JobGetInfoTest.InfoHandleBasicNullActualAndAvailSucceeds (0 [00021.038] 01046:01049> ms) [00021.038] 01046:01049> [ RUN ] JobGetInfoTest.InfoHandleBasicInvalidBufferPointerFails [00021.038] 01046:01049> [00021.038] 01046:01049> [ OK ] JobGetInfoTest.InfoHandleBasicInvalidBufferPointerFails (0 [00021.038] 01046:01049> ms) [00021.038] 01046:01049> [ RUN ] JobGetInfoTest.InfoHandleBasicBadActualgIsInvalidArg [00021.038] 01046:01049> [00021.038] 01046:01049> [ OK ] JobGetInfoTest.InfoHandleBasicBadActualgIsInvalidArg (0 [00021.038] 01046:01049> ms) [00021.038] 01046:01049> [ RUN ] JobGetInfoTest.InfoHandleBasicBadAvailIsInvalidArg [00021.038] 01046:01049> [00021.038] 01046:01049> [ OK ] JobGetInfoTest.InfoHandleBasicBadAvailIsInvalidArg (0 [00021.038] 01046:01049> ms) [00021.038] 01046:01049> [ RUN ] JobGetInfoTest.InfoHandleBasicZeroSizedBufferFails [00021.038] 01046:01049> [00021.038] 01046:01049> [ OK ] JobGetInfoTest.InfoHandleBasicZeroSizedBufferFails (0 [00021.038] 01046:01049> ms) [00021.038] 01046:01049> [----------] 39 tests from JobGetInfoTest (1 [00021.038] 01046:01049> ms total) [00021.038] 01046:01049> [----------] 61 tests from ProcessGetInfoTest [00021.038] 01046:01049> [00021.039] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessMapsUnstartedSuceeds [00021.039] 01046:01049> [00021.039] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessMapsUnstartedSuceeds (0 [00021.039] 01046:01049> ms) [00021.039] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessMapsSmokeTest [00021.039] 01046:01049> [00021.039] 01046:01049> [00021.039] 01046:01049> ZIRCON KERNEL PANIC [00021.039] 01046:01049> Lock validation failed for thread 0xffff00004003af00 pid 1046 tid 1049 (bin/core-tests:bin/core-tests): [00021.039] 01046:01049> Reason: Already Acquired [00021.039] 01046:01049> Bad lock: name=lockdep::LockClass<VmAspace, Mutex, 199, lockdep::LockFlagsNone> order=0 [00021.039] 01046:01049> Conflict: name=lockdep::LockClass<VmAspace, Mutex, 199, lockdep::LockFlagsNone> order=0 [00021.039] 01046:01049> caller=0xffffffff100ab264 frame=0xffff00860a8998d0 [00021.039] 01046:01049> BUILDID git-71aec42d0c4724cc52eab689479bcf4335695c4c-dirty [00021.039] 01046:01049> [00021.039] 01046:01049> {{{module:0:kernel:elf:493e35f11d886ad5}}} [00021.039] 01046:01049> {{{mmap:0xffffffff10000000:0x10bcb0:load:0:rx:0xffffffff00000000}}} [00021.039] 01046:01049> {{{mmap:0xffffffff1010c000:0x3e000:load:0:r:0xffffffff0010c000}}} [00021.039] 01046:01049> {{{mmap:0xffffffff1014a000:0xed0:load:0:rw:0xffffffff0014a000}}} [00021.039] 01046:01049> {{{mmap:0xffffffff10150000:0x76000:load:0:rw:0xffffffff00150000}}} [00021.039] 01046:01049> dso: id=493e35f11d886ad5 base=0xffffffff10000000 name=zircon.elf [00021.039] 01046:01049> bt#00: 0xffffffff100ab264 [00021.039] 01046:01049> bt#01: 0xffffffff100b9f3c [00021.039] 01046:01049> bt#02: 0xffffffff10095554 [00021.039] 01046:01049> bt#03: 0xffffffff10094270 [00021.039] 01046:01049> bt#04: 0xffffffff100a5cd4 [00021.039] 01046:01049> bt#05: 0xffffffff100ab570 [00021.039] 01046:01049> bt#06: 0xffffffff100314a4 [00021.039] 01046:01049> bt#07: 0xffffffff10053354 [00021.039] 01046:01049> bt#08: 0xffffffff100d0d78 [00021.039] 01046:01049> bt#09: 0xffffffff100de8d0 [00021.039] 01046:01049> bt#10: 0xffffffff100949dc [00021.039] 01046:01049> bt#11: end [00021.039] 01046:01049> {{{bt:0:0xffffffff100ab264}}} [00021.039] 01046:01049> {{{bt:1:0xffffffff100b9f3c}}} [00021.039] 01046:01049> {{{bt:2:0xffffffff10095554}}} [00021.039] 01046:01049> {{{bt:3:0xffffffff10094270}}} [00021.039] 01046:01049> {{{bt:4:0xffffffff100a5cd4}}} [00021.039] 01046:01049> {{{bt:5:0xffffffff100ab570}}} [00021.039] 01046:01049> {{{bt:6:0xffffffff100314a4}}} [00021.039] 01046:01049> {{{bt:7:0xffffffff10053354}}} [00021.039] 01046:01049> {{{bt:8:0xffffffff100d0d78}}} [00021.039] 01046:01049> {{{bt:9:0xffffffff100de8d0}}} [00021.039] 01046:01049> {{{bt:10:0xffffffff100949dc}}} [00021.039] 01046:01049> [00021.039] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessMapsSmokeTest (0 [00021.039] 01046:01049> ms) [00021.039] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessHandleStats [00021.039] 01046:01049> [00021.039] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessHandleStats (0 [00021.039] 01046:01049> ms) [00021.039] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessMapsOnSelfFails [00021.039] 01046:01049> [00021.039] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessMapsOnSelfFails (0 [00021.039] 01046:01049> ms) [00021.039] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessMapsInvalidHandleFails [00021.039] 01046:01049> [00021.039] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessMapsInvalidHandleFails (0 [00021.039] 01046:01049> ms) [00021.039] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessMapsNullAvailSucceeds [00021.039] 01046:01049> [00021.039] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessMapsNullAvailSucceeds (0 [00021.039] 01046:01049> ms) [00021.039] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessMapsNullActualSucceeds [00021.039] 01046:01049> [00021.039] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessMapsNullActualSucceeds (0 [00021.039] 01046:01049> ms) [00021.039] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessMapsNullActualAndAvailSucceeds [00021.039] 01046:01049> [00021.039] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessMapsNullActualAndAvailSucceeds (0 [00021.039] 01046:01049> ms) [00021.039] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessMapsInvalidBufferPointerFails [00021.039] 01046:01049> [00021.039] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessMapsInvalidBufferPointerFails (0 [00021.039] 01046:01049> ms) [00021.039] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessMapsBadActualgIsInvalidArg [00021.039] 01046:01049> [00021.039] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessMapsBadActualgIsInvalidArg (0 [00021.039] 01046:01049> ms) [00021.039] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessMapsBadAvailIsInvalidArg [00021.039] 01046:01049> [00021.039] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessMapsBadAvailIsInvalidArg (0 [00021.039] 01046:01049> ms) [00021.039] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessMapsZeroSizedBufferIsOk [00021.039] 01046:01049> [00021.039] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessMapsZeroSizedBufferIsOk (0 [00021.039] 01046:01049> ms) [00021.039] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessMapsSmallBufferIsOk [00021.039] 01046:01049> [00021.039] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessMapsSmallBufferIsOk (0 [00021.039] 01046:01049> ms) [00021.039] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessMapsPartiallyUnmappedBufferIsInvalidArgs [00021.039] 01046:01049> [00021.039] 01046:01049> [00021.039] 01046:01049> ZIRCON KERNEL PANIC [00021.039] 01046:01049> Lock validation failed for thread 0xffff00004003af00 pid 1046 tid 1049 (bin/core-tests:bin/core-tests): [00021.039] 01046:01049> Reason: Already Acquired [00021.039] 01046:01049> Bad lock: name=lockdep::LockClass<VmAspace, Mutex, 199, lockdep::LockFlagsNone> order=0 [00021.039] 01046:01049> Conflict: name=lockdep::LockClass<VmAspace, Mutex, 199, lockdep::LockFlagsNone> order=0 [00021.039] 01046:01049> caller=0xffffffff100ab264 frame=0xffff00860a899970 [00021.039] 01046:01049> BUILDID git-71aec42d0c4724cc52eab689479bcf4335695c4c-dirty [00021.039] 01046:01049> [00021.039] 01046:01049> {{{module:0:kernel:elf:493e35f11d886ad5}}} [00021.039] 01046:01049> {{{mmap:0xffffffff10000000:0x10bcb0:load:0:rx:0xffffffff00000000}}} [00021.039] 01046:01049> {{{mmap:0xffffffff1010c000:0x3e000:load:0:r:0xffffffff0010c000}}} [00021.039] 01046:01049> {{{mmap:0xffffffff1014a000:0xed0:load:0:rw:0xffffffff0014a000}}} [00021.039] 01046:01049> {{{mmap:0xffffffff10150000:0x76000:load:0:rw:0xffffffff00150000}}} [00021.039] 01046:01049> dso: id=493e35f11d886ad5 base=0xffffffff10000000 name=zircon.elf [00021.039] 01046:01049> bt#00: 0xffffffff100ab264 [00021.039] 01046:01049> bt#01: 0xffffffff100b9f3c [00021.039] 01046:01049> bt#02: 0xffffffff10095554 [00021.039] 01046:01049> bt#03: 0xffffffff10094270 [00021.039] 01046:01049> bt#04: 0xffffffff100ab550 [00021.039] 01046:01049> bt#05: 0xffffffff100314a4 [00021.039] 01046:01049> bt#06: 0xffffffff10053354 [00021.039] 01046:01049> bt#07: 0xffffffff100d0d78 [00021.039] 01046:01049> bt#08: 0xffffffff100de8d0 [00021.039] 01046:01049> bt#09: 0xffffffff100949dc [00021.039] 01046:01049> bt#10: end [00021.039] 01046:01049> {{{bt:0:0xffffffff100ab264}}} [00021.039] 01046:01049> {{{bt:1:0xffffffff100b9f3c}}} [00021.039] 01046:01049> {{{bt:2:0xffffffff10095554}}} [00021.039] 01046:01049> {{{bt:3:0xffffffff10094270}}} [00021.039] 01046:01049> {{{bt:4:0xffffffff100ab550}}} [00021.039] 01046:01049> {{{bt:5:0xffffffff100314a4}}} [00021.039] 01046:01049> {{{bt:6:0xffffffff10053354}}} [00021.039] 01046:01049> {{{bt:7:0xffffffff100d0d78}}} [00021.039] 01046:01049> {{{bt:8:0xffffffff100de8d0}}} [00021.040] 01046:01049> {{{bt:9:0xffffffff100949dc}}} [00021.040] 01046:01049> [00021.040] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessMapsPartiallyUnmappedBufferIsInvalidArgs (0 [00021.040] 01046:01049> ms) [00021.040] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessMapsRequiresInspectRights [00021.040] 01046:01049> [00021.040] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessMapsRequiresInspectRights (0 [00021.040] 01046:01049> ms) [00021.040] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessMapsJobHandleIsBadHandle [00021.040] 01046:01049> [00021.040] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessMapsJobHandleIsBadHandle (0 [00021.040] 01046:01049> ms) [00021.040] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessMapsThreadHandleIsBadHandle [00021.040] 01046:01049> [00021.040] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessMapsThreadHandleIsBadHandle (0 [00021.040] 01046:01049> ms) [00021.040] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessVmosSmokeTest [00021.040] 01046:01049> [00021.040] 01046:01049> [00021.040] 01046:01049> ZIRCON KERNEL PANIC [00021.040] 01046:01049> Lock validation failed for thread 0xffff00004003af00 pid 1046 tid 1049 (bin/core-tests:bin/core-tests): [00021.040] 01046:01049> Reason: Out Of Order [00021.040] 01046:01049> Bad lock: name=lockdep::LockClass<ProcessDispatcher, BrwLock, 360, lockdep::LockFlagsNone> order=0 [00021.040] 01046:01049> Conflict: name=lockdep::LockClass<SoloDispatcher<ProcessDispatcher, 447439, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00021.040] 01046:01049> caller=0xffffffff100316fc frame=0xffff00860a899c20 [00021.040] 01046:01049> BUILDID git-71aec42d0c4724cc52eab689479bcf4335695c4c-dirty [00021.040] 01046:01049> [00021.040] 01046:01049> {{{module:0:kernel:elf:493e35f11d886ad5}}} [00021.040] 01046:01049> {{{mmap:0xffffffff10000000:0x10bcb0:load:0:rx:0xffffffff00000000}}} [00021.040] 01046:01049> {{{mmap:0xffffffff1010c000:0x3e000:load:0:r:0xffffffff0010c000}}} [00021.040] 01046:01049> {{{mmap:0xffffffff1014a000:0xed0:load:0:rw:0xffffffff0014a000}}} [00021.040] 01046:01049> {{{mmap:0xffffffff10150000:0x76000:load:0:rw:0xffffffff00150000}}} [00021.040] 01046:01049> dso: id=493e35f11d886ad5 base=0xffffffff10000000 name=zircon.elf [00021.040] 01046:01049> bt#00: 0xffffffff100316fc [00021.040] 01046:01049> bt#01: 0xffffffff100534e4 [00021.040] 01046:01049> bt#02: 0xffffffff100d0350 [00021.040] 01046:01049> bt#03: 0xffffffff100de8d0 [00021.040] 01046:01049> bt#04: 0xffffffff100949dc [00021.040] 01046:01049> bt#05: end [00021.040] 01046:01049> {{{bt:0:0xffffffff100316fc}}} [00021.040] 01046:01049> {{{bt:1:0xffffffff100534e4}}} [00021.040] 01046:01049> {{{bt:2:0xffffffff100d0350}}} [00021.040] 01046:01049> {{{bt:3:0xffffffff100de8d0}}} [00021.040] 01046:01049> {{{bt:4:0xffffffff100949dc}}} [00021.040] 01046:01049> [00021.040] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessVmosSmokeTest (0 [00021.040] 01046:01049> ms) [00021.040] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessVmosOnSelfFails [00021.040] 01046:01049> [00021.040] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessVmosOnSelfFails (0 [00021.040] 01046:01049> ms) [00021.040] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessVmosInvalidHandleFails [00021.040] 01046:01049> [00021.040] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessVmosInvalidHandleFails (0 [00021.040] 01046:01049> ms) [00021.040] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessVmosNullAvailSucceeds [00021.040] 01046:01049> [00021.040] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessVmosNullAvailSucceeds (0 [00021.040] 01046:01049> ms) [00021.040] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessVmosNullActualSucceeds [00021.040] 01046:01049> [00021.040] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessVmosNullActualSucceeds (0 [00021.040] 01046:01049> ms) [00021.040] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessVmosNullActualAndAvailSucceeds [00021.040] 01046:01049> [00021.040] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessVmosNullActualAndAvailSucceeds (0 [00021.040] 01046:01049> ms) [00021.040] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessVmosInvalidBufferPointerFails [00021.040] 01046:01049> [00021.040] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessVmosInvalidBufferPointerFails (0 [00021.040] 01046:01049> ms) [00021.040] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessVmosBadActualgIsInvalidArg [00021.040] 01046:01049> [00021.040] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessVmosBadActualgIsInvalidArg (0 [00021.040] 01046:01049> ms) [00021.040] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessVmosBadAvailIsInvalidArg [00021.040] 01046:01049> [00021.040] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessVmosBadAvailIsInvalidArg (0 [00021.040] 01046:01049> ms) [00021.040] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessVmosZeroSizedBufferIsOk [00021.040] 01046:01049> [00021.040] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessVmosZeroSizedBufferIsOk (0 [00021.040] 01046:01049> ms) [00021.040] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessVmosSmallBufferIsOk [00021.040] 01046:01049> [00021.040] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessVmosSmallBufferIsOk (0 [00021.040] 01046:01049> ms) [00021.040] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessVmosPartiallyUnmappedBufferIsInvalidArgs [00021.040] 01046:01049> [00021.040] 01046:01049> [00021.040] 01046:01049> ZIRCON KERNEL PANIC [00021.040] 01046:01049> Lock validation failed for thread 0xffff00004003af00 pid 1046 tid 1049 (bin/core-tests:bin/core-tests): [00021.040] 01046:01049> Reason: Already Acquired [00021.040] 01046:01049> Bad lock: name=lockdep::LockClass<VmAspace, Mutex, 199, lockdep::LockFlagsNone> order=0 [00021.040] 01046:01049> Conflict: name=lockdep::LockClass<VmAspace, Mutex, 199, lockdep::LockFlagsNone> order=0 [00021.040] 01046:01049> caller=0xffffffff100ab264 frame=0xffff00860a8998f0 [00021.040] 01046:01049> BUILDID git-71aec42d0c4724cc52eab689479bcf4335695c4c-dirty [00021.040] 01046:01049> [00021.040] 01046:01049> {{{module:0:kernel:elf:493e35f11d886ad5}}} [00021.040] 01046:01049> {{{mmap:0xffffffff10000000:0x10bcb0:load:0:rx:0xffffffff00000000}}} [00021.040] 01046:01049> {{{mmap:0xffffffff1010c000:0x3e000:load:0:r:0xffffffff0010c000}}} [00021.040] 01046:01049> {{{mmap:0xffffffff1014a000:0xed0:load:0:rw:0xffffffff0014a000}}} [00021.040] 01046:01049> {{{mmap:0xffffffff10150000:0x76000:load:0:rw:0xffffffff00150000}}} [00021.040] 01046:01049> dso: id=493e35f11d886ad5 base=0xffffffff10000000 name=zircon.elf [00021.040] 01046:01049> bt#00: 0xffffffff100ab264 [00021.040] 01046:01049> bt#01: 0xffffffff100b9f3c [00021.040] 01046:01049> bt#02: 0xffffffff10095554 [00021.040] 01046:01049> bt#03: 0xffffffff10094270 [00021.040] 01046:01049> bt#04: 0xffffffff100a5cd4 [00021.040] 01046:01049> bt#05: 0xffffffff100ab570 [00021.040] 01046:01049> bt#06: 0xffffffff100315bc [00021.040] 01046:01049> bt#07: 0xffffffff10053558 [00021.040] 01046:01049> bt#08: 0xffffffff100d0350 [00021.040] 01046:01049> bt#09: 0xffffffff100de8d0 [00021.040] 01046:01049> bt#10: 0xffffffff100949dc [00021.040] 01046:01049> bt#11: end [00021.040] 01046:01049> {{{bt:0:0xffffffff100ab264}}} [00021.040] 01046:01049> {{{bt:1:0xffffffff100b9f3c}}} [00021.040] 01046:01049> {{{bt:2:0xffffffff10095554}}} [00021.040] 01046:01049> {{{bt:3:0xffffffff10094270}}} [00021.040] 01046:01049> {{{bt:4:0xffffffff100a5cd4}}} [00021.040] 01046:01049> {{{bt:5:0xffffffff100ab570}}} [00021.040] 01046:01049> {{{bt:6:0xffffffff100315bc}}} [00021.040] 01046:01049> {{{bt:7:0xffffffff10053558}}} [00021.040] 01046:01049> {{{bt:8:0xffffffff100d0350}}} [00021.040] 01046:01049> {{{bt:9:0xffffffff100de8d0}}} [00021.040] 01046:01049> {{{bt:10:0xffffffff100949dc}}} [00021.040] 01046:01049> [00021.040] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessVmosPartiallyUnmappedBufferIsInvalidArgs (0 [00021.040] 01046:01049> ms) [00021.040] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessVmosRequiresInspectRights [00021.040] 01046:01049> [00021.040] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessVmosRequiresInspectRights (0 [00021.040] 01046:01049> ms) [00021.040] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessVmosJobHandleIsBadHandle [00021.040] 01046:01049> [00021.040] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessVmosJobHandleIsBadHandle (0 [00021.040] 01046:01049> ms) [00021.040] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessVmosThreadHandleIsBadHandle [00021.040] 01046:01049> [00021.040] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessVmosThreadHandleIsBadHandle (0 [00021.040] 01046:01049> ms) [00021.040] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoHandleBasicOnSelfSuceeds [00021.040] 01046:01049> [00021.040] 01046:01049> [ OK ] ProcessGetInfoTest.InfoHandleBasicOnSelfSuceeds (0 [00021.040] 01046:01049> ms) [00021.040] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoHandleBasicInvalidHandleFails [00021.040] 01046:01049> [00021.040] 01046:01049> [ OK ] ProcessGetInfoTest.InfoHandleBasicInvalidHandleFails (0 [00021.040] 01046:01049> ms) [00021.040] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoHandleBasicNullAvailSucceeds [00021.041] 01046:01049> [00021.041] 01046:01049> [ OK ] ProcessGetInfoTest.InfoHandleBasicNullAvailSucceeds (0 [00021.041] 01046:01049> ms) [00021.041] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoHandleBasicNullActualSucceeds [00021.041] 01046:01049> [00021.041] 01046:01049> [ OK ] ProcessGetInfoTest.InfoHandleBasicNullActualSucceeds (0 [00021.041] 01046:01049> ms) [00021.041] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoHandleBasicNullActualAndAvailSucceeds [00021.041] 01046:01049> [00021.041] 01046:01049> [ OK ] ProcessGetInfoTest.InfoHandleBasicNullActualAndAvailSucceeds (0 [00021.041] 01046:01049> ms) [00021.041] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoHandleBasicInvalidBufferPointerFails [00021.041] 01046:01049> [00021.041] 01046:01049> [ OK ] ProcessGetInfoTest.InfoHandleBasicInvalidBufferPointerFails (0 [00021.041] 01046:01049> ms) [00021.041] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoHandleBasicBadActualgIsInvalidArg [00021.041] 01046:01049> [00021.041] 01046:01049> [ OK ] ProcessGetInfoTest.InfoHandleBasicBadActualgIsInvalidArg (0 [00021.041] 01046:01049> ms) [00021.041] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoHandleBasicBadAvailIsInvalidArg [00021.041] 01046:01049> [00021.041] 01046:01049> [ OK ] ProcessGetInfoTest.InfoHandleBasicBadAvailIsInvalidArg (0 [00021.041] 01046:01049> ms) [00021.041] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoHandleBasicZeroSizedFails [00021.041] 01046:01049> [00021.041] 01046:01049> [ OK ] ProcessGetInfoTest.InfoHandleBasicZeroSizedFails (0 [00021.041] 01046:01049> ms) [00021.041] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessOnSelfSuceeds [00021.041] 01046:01049> [00021.041] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessOnSelfSuceeds (0 [00021.041] 01046:01049> ms) [00021.041] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessInvalidHandleFails [00021.041] 01046:01049> [00021.041] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessInvalidHandleFails (0 [00021.041] 01046:01049> ms) [00021.041] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessNullAvailSucceeds [00021.041] 01046:01049> [00021.041] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessNullAvailSucceeds (0 [00021.041] 01046:01049> ms) [00021.041] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessNullActualSucceeds [00021.041] 01046:01049> [00021.041] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessNullActualSucceeds (0 [00021.041] 01046:01049> ms) [00021.041] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessNullActualAndAvailSucceeds [00021.041] 01046:01049> [00021.041] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessNullActualAndAvailSucceeds (0 [00021.041] 01046:01049> ms) [00021.041] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessInvalidBufferPointerFails [00021.041] 01046:01049> [00021.041] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessInvalidBufferPointerFails (0 [00021.041] 01046:01049> ms) [00021.041] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessBadActualgIsInvalidArg [00021.041] 01046:01049> [00021.041] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessBadActualgIsInvalidArg (0 [00021.041] 01046:01049> ms) [00021.041] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessBadAvailIsInvalidArg [00021.041] 01046:01049> [00021.041] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessBadAvailIsInvalidArg (0 [00021.041] 01046:01049> ms) [00021.041] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessZeroSizedBufferFails [00021.041] 01046:01049> [00021.041] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessZeroSizedBufferFails (0 [00021.041] 01046:01049> ms) [00021.041] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessJobHandleIsBadHandle [00021.041] 01046:01049> [00021.041] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessJobHandleIsBadHandle (0 [00021.041] 01046:01049> ms) [00021.041] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessThreadHandleIsBadHandle [00021.041] 01046:01049> [00021.041] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessThreadHandleIsBadHandle (0 [00021.041] 01046:01049> ms) [00021.041] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessThreadsSelfSuceeds [00021.041] 01046:01049> [00021.041] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessThreadsSelfSuceeds (0 [00021.041] 01046:01049> ms) [00021.041] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessThreadsInvalidHandleFails [00021.041] 01046:01049> [00021.041] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessThreadsInvalidHandleFails (0 [00021.041] 01046:01049> ms) [00021.041] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessThreadsNullAvailSucceeds [00021.041] 01046:01049> [00021.041] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessThreadsNullAvailSucceeds (0 [00021.041] 01046:01049> ms) [00021.041] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessThreadsNullActualSucceeds [00021.041] 01046:01049> [00021.041] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessThreadsNullActualSucceeds (0 [00021.041] 01046:01049> ms) [00021.041] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessThreadsNullActualAndAvailSucceeds [00021.041] 01046:01049> [00021.041] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessThreadsNullActualAndAvailSucceeds (0 [00021.041] 01046:01049> ms) [00021.041] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessThreadsInvalidBufferPointerFails [00021.041] 01046:01049> [00021.041] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessThreadsInvalidBufferPointerFails (0 [00021.041] 01046:01049> ms) [00021.041] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessThreadsBadActualgIsInvalidArg [00021.041] 01046:01049> [00021.041] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessThreadsBadActualgIsInvalidArg (0 [00021.041] 01046:01049> ms) [00021.041] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessThreadsBadAvailIsInvalidArg [00021.041] 01046:01049> [00021.041] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessThreadsBadAvailIsInvalidArg (0 [00021.041] 01046:01049> ms) [00021.041] 01046:01049> [ RUN ] ProcessGetInfoTest.InfoProcessThreadsZeroSizedBufferSucceeds [00021.041] 01046:01049> [00021.041] 01046:01049> [ OK ] ProcessGetInfoTest.InfoProcessThreadsZeroSizedBufferSucceeds (0 [00021.041] 01046:01049> ms) [00021.041] 01046:01049> [----------] 61 tests from ProcessGetInfoTest (2 [00021.041] 01046:01049> ms total) [00021.041] 01046:01049> [----------] 12 tests from TaskGetInfoTest [00021.041] 01046:01049> [00021.041] 01046:01049> [ RUN ] TaskGetInfoTest.InfoStatsUnstartedSuceeds [00021.041] 01046:01049> [00021.041] 01046:01049> [ OK ] TaskGetInfoTest.InfoStatsUnstartedSuceeds (0 [00021.041] 01046:01049> ms) [00021.041] 01046:01049> [ RUN ] TaskGetInfoTest.InfoStatsSmokeTest [00021.041] 01046:01049> [00021.042] 01046:01049> [ OK ] TaskGetInfoTest.InfoStatsSmokeTest (0 [00021.042] 01046:01049> ms) [00021.042] 01046:01049> [ RUN ] TaskGetInfoTest.InfoTaskStatsInvalidHandleFails [00021.042] 01046:01049> [00021.042] 01046:01049> [ OK ] TaskGetInfoTest.InfoTaskStatsInvalidHandleFails (0 [00021.042] 01046:01049> ms) [00021.042] 01046:01049> [ RUN ] TaskGetInfoTest.InfoTaskStatsNullAvailSucceeds [00021.042] 01046:01049> [00021.042] 01046:01049> [ OK ] TaskGetInfoTest.InfoTaskStatsNullAvailSucceeds (0 [00021.042] 01046:01049> ms) [00021.042] 01046:01049> [ RUN ] TaskGetInfoTest.InfoTaskStatsNullActualSucceeds [00021.042] 01046:01049> [00021.042] 01046:01049> [ OK ] TaskGetInfoTest.InfoTaskStatsNullActualSucceeds (0 [00021.042] 01046:01049> ms) [00021.042] 01046:01049> [ RUN ] TaskGetInfoTest.InfoTaskStatsNullActualAndAvailSucceeds [00021.042] 01046:01049> [00021.042] 01046:01049> [ OK ] TaskGetInfoTest.InfoTaskStatsNullActualAndAvailSucceeds (0 [00021.042] 01046:01049> ms) [00021.042] 01046:01049> [ RUN ] TaskGetInfoTest.InfoTaskStatsInvalidBufferPointerFails [00021.042] 01046:01049> [00021.042] 01046:01049> [ OK ] TaskGetInfoTest.InfoTaskStatsInvalidBufferPointerFails (0 [00021.042] 01046:01049> ms) [00021.042] 01046:01049> [ RUN ] TaskGetInfoTest.InfoTaskStatsBadActualgIsInvalidArg [00021.042] 01046:01049> [00021.042] 01046:01049> [ OK ] TaskGetInfoTest.InfoTaskStatsBadActualgIsInvalidArg (0 [00021.042] 01046:01049> ms) [00021.042] 01046:01049> [ RUN ] TaskGetInfoTest.InfoTaskStatsBadAvailIsInvalidArg [00021.042] 01046:01049> [00021.042] 01046:01049> [ OK ] TaskGetInfoTest.InfoTaskStatsBadAvailIsInvalidArg (0 [00021.042] 01046:01049> ms) [00021.042] 01046:01049> [ RUN ] TaskGetInfoTest.InfoTaskStatsZeroSizedBufferIsTooSmall [00021.042] 01046:01049> [00021.042] 01046:01049> [ OK ] TaskGetInfoTest.InfoTaskStatsZeroSizedBufferIsTooSmall (0 [00021.042] 01046:01049> ms) [00021.042] 01046:01049> [ RUN ] TaskGetInfoTest.InfoTaskStatsJobHandleIsBadHandle [00021.042] 01046:01049> [00021.042] 01046:01049> [ OK ] TaskGetInfoTest.InfoTaskStatsJobHandleIsBadHandle (0 [00021.042] 01046:01049> ms) [00021.042] 01046:01049> [ RUN ] TaskGetInfoTest.InfoTaskStatsThreadHandleIsBadHandle [00021.042] 01046:01049> [00021.042] 01046:01049> [ OK ] TaskGetInfoTest.InfoTaskStatsThreadHandleIsBadHandle (0 [00021.042] 01046:01049> ms) [00021.042] 01046:01049> [----------] 12 tests from TaskGetInfoTest (0 [00021.042] 01046:01049> ms total) [00021.042] 01046:01049> [----------] 41 tests from ThreadGetInfoTest [00021.042] 01046:01049> [00021.042] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoHandleBasicOnSelfSuceeds [00021.042] 01046:01049> [00021.042] 01046:01049> [ OK ] ThreadGetInfoTest.InfoHandleBasicOnSelfSuceeds (0 [00021.042] 01046:01049> ms) [00021.042] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoHandleBasicInvalidHandleFails [00021.042] 01046:01049> [00021.042] 01046:01049> [ OK ] ThreadGetInfoTest.InfoHandleBasicInvalidHandleFails (0 [00021.042] 01046:01049> ms) [00021.042] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoHandleBasicNullAvailSucceeds [00021.042] 01046:01049> [00021.042] 01046:01049> [ OK ] ThreadGetInfoTest.InfoHandleBasicNullAvailSucceeds (0 [00021.042] 01046:01049> ms) [00021.042] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoHandleBasicNullActualSucceeds [00021.042] 01046:01049> [00021.042] 01046:01049> [ OK ] ThreadGetInfoTest.InfoHandleBasicNullActualSucceeds (0 [00021.042] 01046:01049> ms) [00021.042] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoHandleBasicNullActualAndAvailSucceeds [00021.042] 01046:01049> [00021.042] 01046:01049> [ OK ] ThreadGetInfoTest.InfoHandleBasicNullActualAndAvailSucceeds (0 [00021.042] 01046:01049> ms) [00021.042] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoHandleBasicInvalidBufferPointerFails [00021.042] 01046:01049> [00021.042] 01046:01049> [ OK ] ThreadGetInfoTest.InfoHandleBasicInvalidBufferPointerFails (0 [00021.042] 01046:01049> ms) [00021.042] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoHandleBasicBadActualgIsInvalidArg [00021.042] 01046:01049> [00021.042] 01046:01049> [ OK ] ThreadGetInfoTest.InfoHandleBasicBadActualgIsInvalidArg (0 [00021.042] 01046:01049> ms) [00021.042] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoHandleBasicBadAvailIsInvalidArg [00021.042] 01046:01049> [00021.042] 01046:01049> [ OK ] ThreadGetInfoTest.InfoHandleBasicBadAvailIsInvalidArg (0 [00021.042] 01046:01049> ms) [00021.042] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoHandleBasicZeroSizedBufferFails [00021.042] 01046:01049> [00021.042] 01046:01049> [ OK ] ThreadGetInfoTest.InfoHandleBasicZeroSizedBufferFails (0 [00021.042] 01046:01049> ms) [00021.042] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoHandleCountOnSelfSuceeds [00021.042] 01046:01049> [00021.042] 01046:01049> [ OK ] ThreadGetInfoTest.InfoHandleCountOnSelfSuceeds (0 [00021.042] 01046:01049> ms) [00021.043] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoHandleCountInvalidHandleFails [00021.043] 01046:01049> [00021.043] 01046:01049> [ OK ] ThreadGetInfoTest.InfoHandleCountInvalidHandleFails (0 [00021.043] 01046:01049> ms) [00021.043] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoHandleCountNullAvailSucceeds [00021.043] 01046:01049> [00021.043] 01046:01049> [ OK ] ThreadGetInfoTest.InfoHandleCountNullAvailSucceeds (0 [00021.043] 01046:01049> ms) [00021.043] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoHandleCountNullActualSucceeds [00021.043] 01046:01049> [00021.043] 01046:01049> [ OK ] ThreadGetInfoTest.InfoHandleCountNullActualSucceeds (0 [00021.043] 01046:01049> ms) [00021.043] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoHandleCountNullActualAndAvailSucceeds [00021.043] 01046:01049> [00021.043] 01046:01049> [ OK ] ThreadGetInfoTest.InfoHandleCountNullActualAndAvailSucceeds (0 [00021.043] 01046:01049> ms) [00021.043] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoHandleCountInvalidBufferPointerFails [00021.043] 01046:01049> [00021.043] 01046:01049> [ OK ] ThreadGetInfoTest.InfoHandleCountInvalidBufferPointerFails (0 [00021.043] 01046:01049> ms) [00021.043] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoHandleCountBadActualgIsInvalidArg [00021.043] 01046:01049> [00021.043] 01046:01049> [ OK ] ThreadGetInfoTest.InfoHandleCountBadActualgIsInvalidArg (0 [00021.043] 01046:01049> ms) [00021.043] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoHandleCountBadAvailIsInvalidArg [00021.043] 01046:01049> [00021.043] 01046:01049> [ OK ] ThreadGetInfoTest.InfoHandleCountBadAvailIsInvalidArg (0 [00021.043] 01046:01049> ms) [00021.043] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoHandleCountZeroSizedBufferFails [00021.043] 01046:01049> [00021.043] 01046:01049> [ OK ] ThreadGetInfoTest.InfoHandleCountZeroSizedBufferFails (0 [00021.043] 01046:01049> ms) [00021.043] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoThreadOnSelfSuceeds [00021.043] 01046:01049> [00021.043] 01046:01049> [ OK ] ThreadGetInfoTest.InfoThreadOnSelfSuceeds (0 [00021.043] 01046:01049> ms) [00021.043] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoThreadInvalidHandleFails [00021.043] 01046:01049> [00021.043] 01046:01049> [ OK ] ThreadGetInfoTest.InfoThreadInvalidHandleFails (0 [00021.043] 01046:01049> ms) [00021.043] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoThreadNullAvailSucceeds [00021.043] 01046:01049> [00021.043] 01046:01049> [ OK ] ThreadGetInfoTest.InfoThreadNullAvailSucceeds (0 [00021.043] 01046:01049> ms) [00021.043] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoThreadNullActualSucceeds [00021.043] 01046:01049> [00021.043] 01046:01049> [ OK ] ThreadGetInfoTest.InfoThreadNullActualSucceeds (0 [00021.043] 01046:01049> ms) [00021.043] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoThreadNullActualAndAvailSucceeds [00021.043] 01046:01049> [00021.043] 01046:01049> [ OK ] ThreadGetInfoTest.InfoThreadNullActualAndAvailSucceeds (0 [00021.043] 01046:01049> ms) [00021.043] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoThreadInvalidBufferPointerFails [00021.043] 01046:01049> [00021.043] 01046:01049> [ OK ] ThreadGetInfoTest.InfoThreadInvalidBufferPointerFails (0 [00021.043] 01046:01049> ms) [00021.043] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoThreadBadActualgIsInvalidArg [00021.043] 01046:01049> [00021.043] 01046:01049> [ OK ] ThreadGetInfoTest.InfoThreadBadActualgIsInvalidArg (0 [00021.043] 01046:01049> ms) [00021.043] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoThreadBadAvailIsInvalidArg [00021.043] 01046:01049> [00021.043] 01046:01049> [ OK ] ThreadGetInfoTest.InfoThreadBadAvailIsInvalidArg (0 [00021.043] 01046:01049> ms) [00021.043] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoThreadZeroSizedBufferFails [00021.043] 01046:01049> [00021.043] 01046:01049> [ OK ] ThreadGetInfoTest.InfoThreadZeroSizedBufferFails (0 [00021.043] 01046:01049> ms) [00021.043] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoThreadJobHandleIsBadHandle [00021.043] 01046:01049> [00021.043] 01046:01049> [ OK ] ThreadGetInfoTest.InfoThreadJobHandleIsBadHandle (0 [00021.043] 01046:01049> ms) [00021.043] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoThreadProcessHandleIsBadHandle [00021.043] 01046:01049> [00021.043] 01046:01049> [ OK ] ThreadGetInfoTest.InfoThreadProcessHandleIsBadHandle (0 [00021.043] 01046:01049> ms) [00021.043] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoThreadStatsOnSelfSuceeds [00021.043] 01046:01049> [00021.043] 01046:01049> [ OK ] ThreadGetInfoTest.InfoThreadStatsOnSelfSuceeds (0 [00021.043] 01046:01049> ms) [00021.043] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoThreadStatsInvalidHandleFails [00021.043] 01046:01049> [00021.043] 01046:01049> [ OK ] ThreadGetInfoTest.InfoThreadStatsInvalidHandleFails (0 [00021.043] 01046:01049> ms) [00021.043] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoThreadStatsNullAvailSucceeds [00021.043] 01046:01049> [00021.043] 01046:01049> [ OK ] ThreadGetInfoTest.InfoThreadStatsNullAvailSucceeds (0 [00021.043] 01046:01049> ms) [00021.043] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoThreadStatsNullActualSucceeds [00021.043] 01046:01049> [00021.043] 01046:01049> [ OK ] ThreadGetInfoTest.InfoThreadStatsNullActualSucceeds (0 [00021.043] 01046:01049> ms) [00021.043] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoThreadStatsNullActualAndAvailSucceeds [00021.043] 01046:01049> [00021.043] 01046:01049> [ OK ] ThreadGetInfoTest.InfoThreadStatsNullActualAndAvailSucceeds (0 [00021.043] 01046:01049> ms) [00021.043] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoThreadStatsInvalidBufferPointerFails [00021.043] 01046:01049> [00021.043] 01046:01049> [ OK ] ThreadGetInfoTest.InfoThreadStatsInvalidBufferPointerFails (0 [00021.043] 01046:01049> ms) [00021.043] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoThreadStatsBadActualgIsInvalidArg [00021.043] 01046:01049> [00021.043] 01046:01049> [ OK ] ThreadGetInfoTest.InfoThreadStatsBadActualgIsInvalidArg (0 [00021.043] 01046:01049> ms) [00021.043] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoThreadStatsBadAvailIsInvalidArg [00021.043] 01046:01049> [00021.043] 01046:01049> [ OK ] ThreadGetInfoTest.InfoThreadStatsBadAvailIsInvalidArg (0 [00021.043] 01046:01049> ms) [00021.043] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoThreadStatsZeroSizedBufferFails [00021.043] 01046:01049> [00021.043] 01046:01049> [ OK ] ThreadGetInfoTest.InfoThreadStatsZeroSizedBufferFails (0 [00021.043] 01046:01049> ms) [00021.043] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoThreadStatsJobHandleIsBadHandle [00021.043] 01046:01049> [00021.043] 01046:01049> [ OK ] ThreadGetInfoTest.InfoThreadStatsJobHandleIsBadHandle (0 [00021.043] 01046:01049> ms) [00021.043] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoThreadStatsProcessHandleIsBadHandle [00021.043] 01046:01049> [00021.043] 01046:01049> [ OK ] ThreadGetInfoTest.InfoThreadStatsProcessHandleIsBadHandle (0 [00021.043] 01046:01049> ms) [00021.043] 01046:01049> [ RUN ] ThreadGetInfoTest.InfoThreadExceptionReportInvalidHandleFails [00021.043] 01046:01049> [00021.043] 01046:01049> [ OK ] ThreadGetInfoTest.InfoThreadExceptionReportInvalidHandleFails (0 [00021.043] 01046:01049> ms) [00021.043] 01046:01049> [----------] 41 tests from ThreadGetInfoTest (1 [00021.043] 01046:01049> ms total) [00021.043] 01046:01049> [----------] 21 tests from VmarGetInfoTest [00021.043] 01046:01049> [00021.043] 01046:01049> [ RUN ] VmarGetInfoTest.InfoHandleBasicOnSelfSuceeds [00021.043] 01046:01049> [00021.043] 01046:01049> [ OK ] VmarGetInfoTest.InfoHandleBasicOnSelfSuceeds (0 [00021.043] 01046:01049> ms) [00021.044] 01046:01049> [ RUN ] VmarGetInfoTest.InfoHandleBasicInvalidHandleFails [00021.044] 01046:01049> [00021.044] 01046:01049> [ OK ] VmarGetInfoTest.InfoHandleBasicInvalidHandleFails (0 [00021.044] 01046:01049> ms) [00021.044] 01046:01049> [ RUN ] VmarGetInfoTest.InfoHandleBasicNullAvailSucceeds [00021.044] 01046:01049> [00021.044] 01046:01049> [ OK ] VmarGetInfoTest.InfoHandleBasicNullAvailSucceeds (0 [00021.044] 01046:01049> ms) [00021.044] 01046:01049> [ RUN ] VmarGetInfoTest.InfoHandleBasicNullActualSucceeds [00021.044] 01046:01049> [00021.044] 01046:01049> [ OK ] VmarGetInfoTest.InfoHandleBasicNullActualSucceeds (0 [00021.044] 01046:01049> ms) [00021.044] 01046:01049> [ RUN ] VmarGetInfoTest.InfoHandleBasicNullActualAndAvailSucceeds [00021.044] 01046:01049> [00021.044] 01046:01049> [ OK ] VmarGetInfoTest.InfoHandleBasicNullActualAndAvailSucceeds (0 [00021.044] 01046:01049> ms) [00021.044] 01046:01049> [ RUN ] VmarGetInfoTest.InfoHandleBasicInvalidBufferPointerFails [00021.044] 01046:01049> [00021.044] 01046:01049> [ OK ] VmarGetInfoTest.InfoHandleBasicInvalidBufferPointerFails (0 [00021.044] 01046:01049> ms) [00021.044] 01046:01049> [ RUN ] VmarGetInfoTest.InfoHandleBasicBadActualgIsInvalidArg [00021.044] 01046:01049> [00021.044] 01046:01049> [ OK ] VmarGetInfoTest.InfoHandleBasicBadActualgIsInvalidArg (0 [00021.044] 01046:01049> ms) [00021.044] 01046:01049> [ RUN ] VmarGetInfoTest.InfoHandleBasicBadAvailIsInvalidArg [00021.044] 01046:01049> [00021.044] 01046:01049> [ OK ] VmarGetInfoTest.InfoHandleBasicBadAvailIsInvalidArg (0 [00021.044] 01046:01049> ms) [00021.044] 01046:01049> [ RUN ] VmarGetInfoTest.InfoHandleBasicZeroSizedBufferFails [00021.044] 01046:01049> [00021.044] 01046:01049> [ OK ] VmarGetInfoTest.InfoHandleBasicZeroSizedBufferFails (0 [00021.044] 01046:01049> ms) [00021.044] 01046:01049> [ RUN ] VmarGetInfoTest.InfoVmarOnSelfFails [00021.044] 01046:01049> [00021.044] 01046:01049> [ OK ] VmarGetInfoTest.InfoVmarOnSelfFails (0 [00021.044] 01046:01049> ms) [00021.044] 01046:01049> [ RUN ] VmarGetInfoTest.InfoVmarInvalidHandleFails [00021.044] 01046:01049> [00021.044] 01046:01049> [ OK ] VmarGetInfoTest.InfoVmarInvalidHandleFails (0 [00021.044] 01046:01049> ms) [00021.044] 01046:01049> [ RUN ] VmarGetInfoTest.InfoVmarNullAvailSucceeds [00021.044] 01046:01049> [00021.044] 01046:01049> [ OK ] VmarGetInfoTest.InfoVmarNullAvailSucceeds (0 [00021.044] 01046:01049> ms) [00021.044] 01046:01049> [ RUN ] VmarGetInfoTest.InfoVmarNullActualSucceeds [00021.044] 01046:01049> [00021.044] 01046:01049> [ OK ] VmarGetInfoTest.InfoVmarNullActualSucceeds (0 [00021.044] 01046:01049> ms) [00021.044] 01046:01049> [ RUN ] VmarGetInfoTest.InfoVmarNullActualAndAvailSucceeds [00021.044] 01046:01049> [00021.044] 01046:01049> [ OK ] VmarGetInfoTest.InfoVmarNullActualAndAvailSucceeds (0 [00021.044] 01046:01049> ms) [00021.044] 01046:01049> [ RUN ] VmarGetInfoTest.InfoVmarInvalidBufferPointerFails [00021.044] 01046:01049> [00021.044] 01046:01049> [ OK ] VmarGetInfoTest.InfoVmarInvalidBufferPointerFails (0 [00021.044] 01046:01049> ms) [00021.044] 01046:01049> [ RUN ] VmarGetInfoTest.InfoVmarBadActualgIsInvalidArg [00021.044] 01046:01049> [00021.044] 01046:01049> [ OK ] VmarGetInfoTest.InfoVmarBadActualgIsInvalidArg (0 [00021.044] 01046:01049> ms) [00021.044] 01046:01049> [ RUN ] VmarGetInfoTest.InfoVmarBadAvailIsInvalidArg [00021.044] 01046:01049> [00021.044] 01046:01049> [ OK ] VmarGetInfoTest.InfoVmarBadAvailIsInvalidArg (0 [00021.044] 01046:01049> ms) [00021.044] 01046:01049> [ RUN ] VmarGetInfoTest.InfoVmarZeroSizedBufferFails [00021.044] 01046:01049> [00021.044] 01046:01049> [ OK ] VmarGetInfoTest.InfoVmarZeroSizedBufferFails (0 [00021.044] 01046:01049> ms) [00021.044] 01046:01049> [ RUN ] VmarGetInfoTest.InfoVmarJobHandleIsBadHandle [00021.044] 01046:01049> [00021.044] 01046:01049> [ OK ] VmarGetInfoTest.InfoVmarJobHandleIsBadHandle (0 [00021.044] 01046:01049> ms) [00021.044] 01046:01049> [ RUN ] VmarGetInfoTest.InfoVmarProcessHandleIsBadHandle [00021.044] 01046:01049> [00021.044] 01046:01049> [ OK ] VmarGetInfoTest.InfoVmarProcessHandleIsBadHandle (0 [00021.044] 01046:01049> ms) [00021.044] 01046:01049> [ RUN ] VmarGetInfoTest.InfoVmarThreadHandleIsBadHandle [00021.044] 01046:01049> [00021.044] 01046:01049> [ OK ] VmarGetInfoTest.InfoVmarThreadHandleIsBadHandle (0 [00021.044] 01046:01049> ms) [00021.044] 01046:01049> [----------] 21 tests from VmarGetInfoTest (0 [00021.044] 01046:01049> ms total) [00021.044] 01046:01049> [----------] 28 tests from SocketTest [00021.044] 01046:01049> [00021.044] 01046:01049> [ RUN ] SocketTest.EndpointsAreRelated [00021.044] 01046:01049> [00021.044] 01046:01049> [ OK ] SocketTest.EndpointsAreRelated (0 [00021.044] 01046:01049> ms) [00021.044] 01046:01049> [ RUN ] SocketTest.EmptySocketShouldWait [00021.044] 01046:01049> [00021.044] 01046:01049> [ OK ] SocketTest.EmptySocketShouldWait (0 [00021.044] 01046:01049> ms) [00021.044] 01046:01049> [ RUN ] SocketTest.WriteReadDataVerify [00021.044] 01046:01049> [00021.044] 01046:01049> [ OK ] SocketTest.WriteReadDataVerify (0 [00021.044] 01046:01049> ms) [00021.044] 01046:01049> [ RUN ] SocketTest.PeerClosedError [00021.044] 01046:01049> [00021.044] 01046:01049> [ OK ] SocketTest.PeerClosedError (0 [00021.044] 01046:01049> ms) [00021.044] 01046:01049> [ RUN ] SocketTest.PeekingLeavesData [00021.044] 01046:01049> [00021.044] 01046:01049> [ OK ] SocketTest.PeekingLeavesData (0 [00021.044] 01046:01049> ms) [00021.044] 01046:01049> [ RUN ] SocketTest.PeekingIntoEmpty [00021.044] 01046:01049> [00021.044] 01046:01049> [ OK ] SocketTest.PeekingIntoEmpty (0 [00021.044] 01046:01049> ms) [00021.044] 01046:01049> [ RUN ] SocketTest.Signals [00021.044] 01046:01049> [00021.045] 01046:01049> [ OK ] SocketTest.Signals (0 [00021.045] 01046:01049> ms) [00021.045] 01046:01049> [ RUN ] SocketTest.SetThreshholdsProp [00021.045] 01046:01049> [00021.045] 01046:01049> [ OK ] SocketTest.SetThreshholdsProp (0 [00021.045] 01046:01049> ms) [00021.045] 01046:01049> [ RUN ] SocketTest.SetThreshholdsAndCheckSignals [00021.045] 01046:01049> [00021.045] 01046:01049> [ OK ] SocketTest.SetThreshholdsAndCheckSignals (0 [00021.045] 01046:01049> ms) [00021.045] 01046:01049> [ RUN ] SocketTest.SignalClosedPeer [00021.045] 01046:01049> [00021.045] 01046:01049> [ OK ] SocketTest.SignalClosedPeer (0 [00021.045] 01046:01049> ms) [00021.045] 01046:01049> [ RUN ] SocketTest.PeerClosedSetProperty [00021.045] 01046:01049> [00021.045] 01046:01049> [ OK ] SocketTest.PeerClosedSetProperty (0 [00021.045] 01046:01049> ms) [00021.045] 01046:01049> [ RUN ] SocketTest.ShutdownWrite [00021.045] 01046:01049> [00021.045] 01046:01049> [ OK ] SocketTest.ShutdownWrite (0 [00021.045] 01046:01049> ms) [00021.045] 01046:01049> [ RUN ] SocketTest.ShutdownRead [00021.045] 01046:01049> [00021.045] 01046:01049> [ OK ] SocketTest.ShutdownRead (0 [00021.045] 01046:01049> ms) [00021.045] 01046:01049> [ RUN ] SocketTest.BytesOutstanding [00021.045] 01046:01049> [00021.045] 01046:01049> [ OK ] SocketTest.BytesOutstanding (0 [00021.045] 01046:01049> ms) [00021.046] 01046:01049> [ RUN ] SocketTest.ShutdownWriteBytesOutstanding [00021.046] 01046:01049> [00021.046] 01046:01049> [ OK ] SocketTest.ShutdownWriteBytesOutstanding (0 [00021.046] 01046:01049> ms) [00021.046] 01046:01049> [ RUN ] SocketTest.ShutdownReadBytesOutstanding [00021.046] 01046:01049> [00021.046] 01046:01049> [ OK ] SocketTest.ShutdownReadBytesOutstanding (0 [00021.046] 01046:01049> ms) [00021.046] 01046:01049> [ RUN ] SocketTest.ShortWrite [00021.046] 01046:01049> [00021.046] 01046:01049> [ OK ] SocketTest.ShortWrite (0 [00021.046] 01046:01049> ms) [00021.046] 01046:01049> [ RUN ] SocketTest.Datagram [00021.046] 01046:01049> [00021.046] 01046:01049> [ OK ] SocketTest.Datagram (0 [00021.046] 01046:01049> ms) [00021.046] 01046:01049> [ RUN ] SocketTest.DatagramPeek [00021.046] 01046:01049> [00021.046] 01046:01049> [ OK ] SocketTest.DatagramPeek (0 [00021.046] 01046:01049> ms) [00021.046] 01046:01049> [ RUN ] SocketTest.DatagramPeekEmpty [00021.046] 01046:01049> [00021.046] 01046:01049> [ OK ] SocketTest.DatagramPeekEmpty (0 [00021.046] 01046:01049> ms) [00021.046] 01046:01049> [ RUN ] SocketTest.DatagramNoShortWrite [00021.046] 01046:01049> [00021.046] 01046:01049> [ OK ] SocketTest.DatagramNoShortWrite (0 [00021.046] 01046:01049> ms) [00021.046] 01046:01049> [ RUN ] SocketTest.ControlPlaneAbsent [00021.046] 01046:01049> [00021.046] 01046:01049> [ OK ] SocketTest.ControlPlaneAbsent (0 [00021.046] 01046:01049> ms) [00021.047] 01046:01049> [ RUN ] SocketTest.ControlPlane [00021.047] 01046:01049> [00021.047] 01046:01049> [ OK ] SocketTest.ControlPlane (0 [00021.047] 01046:01049> ms) [00021.047] 01046:01049> [ RUN ] SocketTest.ControlPlanePeek [00021.047] 01046:01049> [00021.047] 01046:01049> [ OK ] SocketTest.ControlPlanePeek (0 [00021.047] 01046:01049> ms) [00021.047] 01046:01049> [ RUN ] SocketTest.ControlPlanePeekEmpty [00021.047] 01046:01049> [00021.047] 01046:01049> [ OK ] SocketTest.ControlPlanePeekEmpty (0 [00021.047] 01046:01049> ms) [00021.047] 01046:01049> [ RUN ] SocketTest.ControlPlaneShutdown [00021.047] 01046:01049> [00021.047] 01046:01049> [ OK ] SocketTest.ControlPlaneShutdown (0 [00021.047] 01046:01049> ms) [00021.047] 01046:01049> [ RUN ] SocketTest.Accept [00021.047] 01046:01049> [00021.047] 01046:01049> [00021.047] 01046:01049> ZIRCON KERNEL PANIC [00021.047] 01046:01049> Lock validation failed for thread 0xffff00004003af00 pid 1046 tid 1049 (bin/core-tests:bin/core-tests): [00021.047] 01046:01049> Reason: Already Acquired [00021.047] 01046:01049> Bad lock: name=lockdep::LockClass<PeerHolder<SocketDispatcher>, Mutex, 319, lockdep::LockFlagsNone> order=0 [00021.047] 01046:01049> Conflict: name=lockdep::LockClass<PeerHolder<SocketDispatcher>, Mutex, 319, lockdep::LockFlagsNone> order=0 [00021.047] 01046:01049> caller=0xffffffff100345f4 frame=0xffff00860a899d10 [00021.047] 01046:01049> BUILDID git-71aec42d0c4724cc52eab689479bcf4335695c4c-dirty [00021.047] 01046:01049> [00021.047] 01046:01049> {{{module:0:kernel:elf:493e35f11d886ad5}}} [00021.047] 01046:01049> {{{mmap:0xffffffff10000000:0x10bcb0:load:0:rx:0xffffffff00000000}}} [00021.047] 01046:01049> {{{mmap:0xffffffff1010c000:0x3e000:load:0:r:0xffffffff0010c000}}} [00021.047] 01046:01049> {{{mmap:0xffffffff1014a000:0xed0:load:0:rw:0xffffffff0014a000}}} [00021.047] 01046:01049> {{{mmap:0xffffffff10150000:0x76000:load:0:rw:0xffffffff00150000}}} [00021.047] 01046:01049> dso: id=493e35f11d886ad5 base=0xffffffff10000000 name=zircon.elf [00021.047] 01046:01049> bt#00: 0xffffffff100345f4 [00021.047] 01046:01049> bt#01: 0xffffffff1003fd28 [00021.047] 01046:01049> bt#02: 0xffffffff10058b64 [00021.047] 01046:01049> bt#03: 0xffffffff100d6804 [00021.047] 01046:01049> bt#04: 0xffffffff100e0170 [00021.047] 01046:01049> bt#05: 0xffffffff10094a80 [00021.047] 01046:01049> bt#06: end [00021.047] 01046:01049> {{{bt:0:0xffffffff100345f4}}} [00021.047] 01046:01049> {{{bt:1:0xffffffff1003fd28}}} [00021.047] 01046:01049> {{{bt:2:0xffffffff10058b64}}} [00021.047] 01046:01049> {{{bt:3:0xffffffff100d6804}}} [00021.047] 01046:01049> {{{bt:4:0xffffffff100e0170}}} [00021.047] 01046:01049> {{{bt:5:0xffffffff10094a80}}} [00021.047] 01046:01049> [00021.047] 01046:01049> [00021.047] 01046:01049> ZIRCON KERNEL PANIC [00021.047] 01046:01049> Lock validation failed for thread 0xffff00004003af00 pid 1046 tid 1049 (bin/core-tests:bin/core-tests): [00021.047] 01046:01049> Reason: Already Acquired [00021.047] 01046:01049> Bad lock: name=lockdep::LockClass<PeerHolder<SocketDispatcher>, Mutex, 319, lockdep::LockFlagsNone> order=0 [00021.047] 01046:01049> Conflict: name=lockdep::LockClass<PeerHolder<SocketDispatcher>, Mutex, 319, lockdep::LockFlagsNone> order=0 [00021.047] 01046:01049> caller=0xffffffff10059594 frame=0xffff00860a899d40 [00021.047] 01046:01049> BUILDID git-71aec42d0c4724cc52eab689479bcf4335695c4c-dirty [00021.047] 01046:01049> [00021.047] 01046:01049> {{{module:0:kernel:elf:493e35f11d886ad5}}} [00021.047] 01046:01049> {{{mmap:0xffffffff10000000:0x10bcb0:load:0:rx:0xffffffff00000000}}} [00021.047] 01046:01049> {{{mmap:0xffffffff1010c000:0x3e000:load:0:r:0xffffffff0010c000}}} [00021.047] 01046:01049> {{{mmap:0xffffffff1014a000:0xed0:load:0:rw:0xffffffff0014a000}}} [00021.047] 01046:01049> {{{mmap:0xffffffff10150000:0x76000:load:0:rw:0xffffffff00150000}}} [00021.047] 01046:01049> dso: id=493e35f11d886ad5 base=0xffffffff10000000 name=zircon.elf [00021.047] 01046:01049> bt#00: 0xffffffff10059594 [00021.047] 01046:01049> bt#01: 0xffffffff1003fdc4 [00021.047] 01046:01049> bt#02: 0xffffffff10058b64 [00021.047] 01046:01049> bt#03: 0xffffffff100d6804 [00021.047] 01046:01049> bt#04: 0xffffffff100e0170 [00021.047] 01046:01049> bt#05: 0xffffffff10094a80 [00021.047] 01046:01049> bt#06: end [00021.047] 01046:01049> {{{bt:0:0xffffffff10059594}}} [00021.047] 01046:01049> {{{bt:1:0xffffffff1003fdc4}}} [00021.047] 01046:01049> {{{bt:2:0xffffffff10058b64}}} [00021.047] 01046:01049> {{{bt:3:0xffffffff100d6804}}} [00021.047] 01046:01049> {{{bt:4:0xffffffff100e0170}}} [00021.047] 01046:01049> {{{bt:5:0xffffffff10094a80}}} [00021.047] 01046:01049> [00021.047] 01046:01049> [ OK ] SocketTest.Accept (0 [00021.047] 01046:01049> ms) [00021.047] 01046:01049> [ RUN ] SocketTest.ZeroSize [00021.047] 01046:01049> [00021.047] 01046:01049> [ OK ] SocketTest.ZeroSize (0 [00021.047] 01046:01049> ms) [00021.047] 01046:01049> [----------] 28 tests from SocketTest (2 [00021.047] 01046:01049> ms total) [00021.047] 01046:01049> [----------] Global test environment tear-down. [00021.047] 01046:01049> [==========] 242 tests from 13 test cases ran (2721 [00021.047] 01046:01049> ms total). [00021.047] 01046:01049> [ PASSED ] 242 tests [00021.047] 01046:01049> [00021.047] 01046:01049> core-tests succeeded RZMm59f7zOSs6aZUIXZR [00021.048] 01032:01039> userboot: loader-service channel peer closed [00021.048] 01032:01039> userboot: Waiting for bin/core-tests to exit... [00021.049] 01032:01039> userboot: Process exited. Executing "poweroff". [00021.050] 01032:01039> mp_unplug_cpu_mask_single_locked:362: WARNING: leaking thread for cpu 3 [00021.831] 01032:01039> mp_unplug_cpu_mask_single_locked:362: WARNING: leaking thread for cpu 2 [00021.833] 01032:01039> mp_unplug_cpu_mask_single_locked:362: WARNING: leaking thread for cpu 1 [00021.833] 01032:01039> Shutting down debuglog