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

2019/04/20 02:13:24 QEMU invocation: [/b/s/w/ir/qemu/bin/qemu-system-x86_64 -machine q35 -device isa-debug-exit,iobase=0xf4,iosize=0x04 -cpu host -enable-kvm -m 4096 -smp 4 -nographic -serial stdio -monitor none -kernel /b/s/w/ir/multiboot.bin -initrd /b/s/w/ir/core-tests-x64.zbi -netdev user,id=net0 -device virtio-net-pci,netdev=net0 -append kernel.halt-on-panic=true devmgr.suspend-timeout-debug=true TERM=dumb kernel.serial=legacy] [0.000] 00000.00000> zbi: @ 0xffffff8000407000 (3161832 bytes) [0.000] 00000.00000> UART: FIFO depth 16 [0.000] 00000.00000> PMM: boot reserve add [0x100000, 0x333fff] [0.000] 00000.00000> PMM: boot reserve add [0x407000, 0x70afff] [0.000] 00000.00000> PMM: boot reserve marking WIRED [0x100000, 0x333fff] [0.000] 00000.00000> PMM: boot reserve marking WIRED [0x407000, 0x70afff] [0.000] 00000.00000> welcome to Zircon [0.000] 00000.00000> KASLR: .text section at 0xffffffff00100000 [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001206d0 (global_prng_seed) at level 0x30000, flags 0x1 [0.000] 00000.00000> initializing vm pre-heap [0.000] 00000.00000> VM: marking boot alloc used range [0x70b000, 0x70b080) [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00131800 (elf_build_id) at level 0x4fffe, flags 0x1 [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001319a0 (version) at level 0x4ffff, flags 0x1 [0.000] 00000.00000> version: [0.000] 00000.00000> arch: x86 [0.000] 00000.00000> platform: XXX [0.000] 00000.00000> target: XXX [0.000] 00000.00000> project: x64 [0.000] 00000.00000> buildid: git-808b66d9daaaacd45b64fe427fa28a1682088ff2-dirty [0.000] 00000.00000> ELF build ID: 3776c8a9518e2b61 [0.000] 00000.00000> initializing heap [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001020d0 (console) at level 0x50000, flags 0x1 [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff0019e860 (x86_resource_init) at level 0x50000, flags 0x1 [0.000] 00000.00000> initializing vm [0.000] 00000.00000> VM: reserving kernel region [0xffffffff00100000, 0xffffffff0024d000) flags 0x28 name 'kernel_code' [0.000] 00000.00000> VM: reserving kernel region [0xffffffff0024d000, 0xffffffff00297000) flags 0x8 name 'kernel_rodata' [0.000] 00000.00000> VM: reserving kernel region [0xffffffff00297000, 0xffffffff0029e000) flags 0x18 name 'kernel_data' [0.000] 00000.00000> VM: reserving kernel region [0xffffffff0029e000, 0xffffffff00334000) flags 0x18 name 'kernel_bss' [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff0019f680 (display_memtype) at level 0x60001, flags 0x1 [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001a3760 (acpi_tables) at level 0x60001, flags 0x1 [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff0019cc80 (hpet) at level 0x60002, flags 0x1 [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff0019d1d0 (apic) at level 0x60002, flags 0x1 [0.000] 00000.00000> x2APIC enabled [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001cd4f0 (system_topology_init) at level 0x60002, flags 0x1 [0.000] 00000.00000> WARNING: unable to find any cache levels. [0.000] 00000.00000> WARNING: unable to find any cache levels. [0.000] 00000.00000> Could not find SRAT table. ACPICA returned: 5 [0.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001a2610 (timer) at level 0x60003, flags 0x1 [0.000] 00000.00000> pvclock: Clocksource is stable [0.000] 00000.00000> HPET frequency: 100000 ticks/ms [0.000] 00000.00000> pvclock: Fetching TSC frequency [0.000] 00000.00000> TSC frequency: 2299802 ticks/ms [0.430] 00000.00000> timer features: constant_tsc 1 invariant_tsc 1 tsc_deadline 1 [0.430] 00000.00000> Using TSC as wallclock [0.430] 00000.00000> INIT: cpu 0, calling hook 0xffffffff002344e0 (percpu_heap_init) at level 0x60003, flags 0x1 [0.430] 00000.00000> initializing kernel [0.430] 00000.00000> initializing mp [0.430] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00120a10 (global_prng_thread_safe) at level 0x7ffff, flags 0x1 [0.430] 00000.00000> creating bootstrap completion thread [0.430] 00000.00000> top of bootstrap2() [0.430] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00144ff0 (libobject) at level 0x80000, flags 0x1 [0.430] 00000.00000> OOM: started thread [0.430] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001a89c0 (lockdep) at level 0x80000, flags 0x1 [0.430] 00000.00000> INIT: cpu 0, calling hook 0xffffffff002306b0 (dpc) at level 0x80000, flags 0x1 [0.430] 00000.00000> Processor Model Info: type 0 family 0x6 model 0xf stepping 0 [0.430] 00000.00000> display_family 0x6 display_model 0x3f [0.430] 00000.00000> Vendor: Intel [0.430] 00000.00000> Microarch: Haswell [0.430] 00000.00000> F/M/S: 6/3f/0 [0.430] 00000.00000> patch_level: 0 [0.430] 00000.00000> Brand: Intel(R) Xeon(R) CPU @ 2.30GHz [0.430] 00000.00000> Features: fpu sse sse2 sse3 ssse3 sse4.1 sse4.2 mmx avx avx2 fxsr pcid xsave aesni clflush [0.430] 00000.00000> fsgsbase tsc_adj smep erms rdrand syscall nx rdtscp tsc_deadline x2apic hypervisor [0.430] 00000.00000> Properties: meltdown l1tf pcid_good [0.430] 00000.00000> initializing platform [0.430] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001c5fd0 (x86_perfmon) at level 0x90000, flags 0x1 [0.430] 00000.00000> x86_perfmon_init_once:454: perfmon: unexpected programmable counter width 0 in cpuid.0AH [0.431] 00000.00000> UART: started IRQ driven RX [0.431] 00000.00000> UART: started IRQ driven TX [0.431] 00000.00000> cpu topology: [0.431] 00000.00000> 0: apic id 0x0 BSP [0.431] 00000.00000> 1: apic id 0x1 [0.431] 00000.00000> 2: apic id 0x2 [0.431] 00000.00000> 3: apic id 0x3 [0.431] 00000.00000> Found 4 cpus [0.431] 00000.00000> booting apic ids: 0x1 0x2 0x3 [0.443] 00000.00000> entering scheduler on cpu 3 [0.443] 00000.00000> entering scheduler on cpu 1 [0.443] 00000.00000> entering scheduler on cpu 2 [0.457] 00000.00000> smbios: manufacturer="QEMU" product="Standard PC (Q35 + ICH9, 2009)" [0.457] 00000.00000> initializing target [0.457] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001a1630 (x86_pcie_init) at level 0xa0000, flags 0x1 [0.457] 00000.00000> moving to last init level [0.457] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00130ec0 (debuglog) at level 0xb0000, flags 0x1 [0.457] 00000.00000> INIT: cpu 0, calling hook 0xffffffff0018c630 (kcounters) at level 0xb0000, flags 0x1 [0.457] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00102610 (kernel_shell) at level 0xc0000, flags 0x1 [0.457] 00000.00000> INIT: cpu 0, calling hook 0xffffffff0022ce80 (ktrace) at level 0xc0000, flags 0x1 [0.815] 00000.00000> ktrace: buffer at 0xffffff98079c2000 (33554432 bytes) [0.815] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00247b80 (userboot) at level 0xc0000, flags 0x1 [0.815] 00000.00000> userboot: ramdisk 0x304000 @ 0xffffff8000407000 [0.830] 00000.00000> userboot: userboot rodata 0 @ [0x5600830f000,0x56008311000) [0.830] 00000.00000> userboot: userboot code 0x2000 @ [0x56008311000,0x56008315000) [0.830] 00000.00000> userboot: vdso/full rodata 0 @ [0x56008315000,0x5600831b000) [0.830] 00000.00000> userboot: vdso/full code 0x6000 @ [0x5600831b000,0x5600831c000) [0.831] 00000.00000> userboot: entry point @ 0x56008311b10 [0.854] 01034.01041> userboot: option "userboot=bin/core-tests" [0.854] 01034.01041> userboot: option "userboot.shutdown=" [0.854] 01034.01041> userboot: option "/b/s/w/ir/multiboot.bin=" [0.854] 01034.01041> userboot: option "kernel.halt-on-panic=true" [0.854] 01034.01041> userboot: option "devmgr.suspend-timeout-debug=true" [0.854] 01034.01041> userboot: option "TERM=dumb" [0.854] 01034.01041> userboot: option "kernel.serial=legacy" [0.854] 01034.01041> userboot: option "multiboot.boot_loader_name=qemu" [0.860] 01034.01041> userboot: searching bootfs for 'bin/core-tests' [0.860] 01034.01041> userboot: 'bin/core-tests' has PT_INTERP "lib/ld.so.1" [0.860] 01034.01041> userboot: searching bootfs for 'lib/ld.so.1' [0.860] 01034.01041> userboot: userboot: loaded lib/ld.so.1 at 0x73a9e015d000, entry point 0x73a9e01a7ef8 [0.860] 01034.01041> userboot: userboot: loaded vDSO at 0x4342c9b8f000, entry point 0x4342c9b95842 [0.860] 01034.01041> userboot: process bin/core-tests started. [0.860] 01034.01041> userboot: waiting for loader-service requests... [0.913] 01034.01041> userboot: searching bootfs for 'lib/libfdio.so' [0.914] 01034.01041> userboot: searching bootfs for 'lib/libunittest.so' [0.914] 01034.01041> userboot: searching bootfs for 'lib/libmini-process.so' [0.915] 01034.01041> userboot: searching bootfs for 'lib/libinspector.so' [0.931] 01034.01041> userboot: searching bootfs for 'lib/libbacktrace.so' [0.932] 01048.01051> dso: id=025704a287dc082a base=0x00001bf98bfbe000 name=<application> [0.932] 01048.01051> dso: id=282003844db08664 base=0x0000361586c35000 name=libfdio.so [0.932] 01048.01051> dso: id=1c101fe9cf91e4a8 base=0x00001eb4555ab000 name=libunittest.so [0.932] 01048.01051> dso: id=1b7804b5b4b7d4e9 base=0x00004342c9b8f000 name=<vDSO> [0.932] 01048.01051> dso: id=961c9ec3b6f98782 base=0x00001e3242323000 name=libmini-process.so [0.932] 01048.01051> dso: id=d2d8950b17dc8a15 base=0x0000126fb5766000 name=libinspector.so [1.087] 01048.01051> dso: id=4b017048bddb2a3c base=0x00000f8d240bc000 name=libbacktrace.so [1.087] 01048.01051> dso: id=015cc27bccc48dd1 base=0x000073a9e015d000 name=libc.so [1.121] 01048.01051> TEST CASE resource_tests [STARTED] [1.122] 01048.01051> probe_address_space [RUNNING] [1.122] 01048.01051> [PASSED] (0 ms) [1.122] 01048.01051> TestBasicActions [RUNNING] [1.122] 01048.01051> [PASSED] (0 ms) [1.122] 01048.01051> TestExclusiveShared [RUNNING] [1.122] 01048.01051> [PASSED] (0 ms) [1.122] 01048.01051> TestSharedExclusive [RUNNING] [1.122] 01048.01051> [PASSED] (0 ms) [1.122] 01048.01051> TestInvalidArgs [RUNNING] [1.122] 01048.01051> [PASSED] (0 ms) [1.122] 01048.01051> TestVmoCreation [RUNNING] [1.122] 01048.01051> [PASSED] (0 ms) [1.122] 01048.01051> TestVmoCreationSmaller [RUNNING] [1.122] 01048.01051> [PASSED] (0 ms) [1.122] 01048.01051> TestVmoCreationUnaligned [RUNNING] [1.122] 01048.01051> [PASSED] (0 ms) [1.122] 01048.01051> TestVmoReplaceAsExecutable [RUNNING] [1.122] 01048.01051> [PASSED] (0 ms) [1.122] 01048.01051> test_ioports [RUNNING] [1.196] 01048.01051> [PASSED] (74 ms) [1.196] 01048.01051> CASE resource_tests [PASSED] CASE profile_tests [STARTED] [1.196] 01048.01051> make_profile_fails [RUNNING] [1.196] 01048.01051> [PASSED] (0 ms) [1.196] 01048.01051> change_priority_via_profile [RUNNING] [1.196] 01048.01051> [PASSED] (0 ms) [1.196] 01048.01051> CASE profile_tests [PASSED] CASE interrupt_tests [STARTED] [1.196] 01048.01051> interrupt_test [RUNNING] [1.197] 01048.01051> [PASSED] (0 ms) [1.197] 01048.01051> interrupt_port_bound_test [RUNNING] [1.197] 01048.01051> [PASSED] (0 ms) [1.197] 01048.01051> interrupt_port_non_bindable_test [RUNNING] [1.197] 01048.01051> [PASSED] (0 ms) [1.197] 01048.01051> interrupt_suspend_test [RUNNING] [1.260] 01048.01051> [PASSED] (63 ms) [1.260] 01048.01051> interrupt_bind_vcpu_test [RUNNING] [1.260] 01048.01051> Guest creation not supported [1.260] 01048.01051> [PASSED] (0 ms) [1.260] 01048.01051> interrupt_bind_vcpu_not_supported_test [RUNNING] [1.260] 01048.01051> Guest creation not supported [1.260] 01048.01051> [PASSED] (0 ms) [1.260] 01048.01051> interrupt_bind_vcpu_already_bound_test [RUNNING] [1.260] 01048.01051> Guest creation not supported [1.261] 01048.01051> [PASSED] (0 ms) [1.261] 01048.01051> interrupt_bind_vcpu_multiple_guests_test [RUNNING] [1.261] 01048.01051> Guest creation not supported [1.261] 01048.01051> [PASSED] (0 ms) [1.261] 01048.01051> CASE interrupt_tests [PASSED] CASE vmo_signal_tests [STARTED] [1.261] 01048.01051> vmo_signal_sanity_test [RUNNING] [1.261] 01048.01051> [PASSED] (0 ms) [1.261] 01048.01051> vmo_child_signal_clone_test [RUNNING] [1.261] 01048.01051> [PASSED] (0 ms) [1.261] 01048.01051> vmo_child_signal_map_test [RUNNING] [1.261] 01048.01051> [PASSED] (0 ms) [1.261] 01048.01051> CASE vmo_signal_tests [PASSED] CASE vmar_tests [STARTED] [1.261] 01048.01051> destroy_root_test [RUNNING] [1.261] 01048.01051> [PASSED] (0 ms) [1.261] 01048.01051> basic_allocate_test [RUNNING] [1.261] 01048.01051> [PASSED] (0 ms) [1.261] 01048.01051> allocate_oob_test [RUNNING] [1.262] 01048.01051> [PASSED] (0 ms) [1.262] 01048.01051> allocate_unsatisfiable_test [RUNNING] [1.262] 01048.01051> [PASSED] (0 ms) [1.262] 01048.01051> destroyed_vmar_test [RUNNING] [1.380] 01048.01051> [PASSED] (118 ms) [1.380] 01048.01051> map_over_destroyed_test [RUNNING] [1.380] 01048.01051> [PASSED] (0 ms) [1.380] 01048.01051> map_in_compact_test [RUNNING] [1.380] 01048.01051> [PASSED] (0 ms) [1.380] 01048.01051> overmapping_test [RUNNING] [1.381] 01048.01051> [PASSED] (0 ms) [1.381] 01048.01051> invalid_args_test [RUNNING] [1.381] 01048.01051> [PASSED] (0 ms) [1.381] 01048.01051> unaligned_len_test [RUNNING] [1.381] 01048.01051> [PASSED] (0 ms) [1.381] 01048.01051> unaligned_len_map_test [RUNNING] [1.381] 01048.01051> [PASSED] (0 ms) [1.381] 01048.01051> rights_drop_test [RUNNING] [1.381] 01048.01051> [PASSED] (0 ms) [1.381] 01048.01051> protect_test [RUNNING] [1.381] 01048.01051> [PASSED] (0 ms) [1.381] 01048.01051> nested_region_perms_test [RUNNING] [1.381] 01048.01051> [PASSED] (0 ms) [1.381] 01048.01051> object_info_test [RUNNING] [1.381] 01048.01051> [PASSED] (0 ms) [1.381] 01048.01051> unmap_split_test [RUNNING] [1.381] 01048.01051> [PASSED] (0 ms) [1.381] 01048.01051> unmap_multiple_test [RUNNING] [1.381] 01048.01051> [PASSED] (0 ms) [1.381] 01048.01051> unmap_base_not_mapped_test [RUNNING] [1.381] 01048.01051> [PASSED] (0 ms) [1.478] 01048.01051> map_specific_overwrite_test [RUNNING] [1.478] 01048.01051> [PASSED] (0 ms) [1.478] 01048.01051> protect_split_test [RUNNING] [1.478] 01048.01051> [PASSED] (0 ms) [1.478] 01048.01051> protect_multiple_test [RUNNING] [1.478] 01048.01051> [PASSED] (0 ms) [1.478] 01048.01051> protect_over_demand_paged_test [RUNNING] [1.507] 01048.01051> [PASSED] (28 ms) [1.507] 01048.01051> protect_large_uncommitted_test [RUNNING] [1.517] 01048.01051> [PASSED] (9 ms) [1.517] 01048.01051> unmap_large_uncommitted_test [RUNNING] [1.525] 01048.01285> PageFault: 1029306 free pages [1.525] 01048.01285> ZIRCON KERNEL PANIC [1.525] 01048.01285> Lock validation failed for thread 0xffffff8000759b08 pid 1048 tid 1285 (bin/core-tests:vmar_test_addr): [1.525] 01048.01285> Reason: Already Acquired [1.525] 01048.01285> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [1.525] 01048.01285> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [1.525] 01048.01285> caller=0xffffffff0014b65b frame=0xffffff98099ccd60 [1.525] 01048.01285> BUILDID git-808b66d9daaaacd45b64fe427fa28a1682088ff2-dirty [1.525] 01048.01285> dso: id=3776c8a9518e2b61 base=0xffffffff00100000 name=zircon.elf [1.525] 01048.01285> bt#00: 0xffffffff0014b65b [1.525] 01048.01285> bt#01: 0xffffffff0014ba8f [1.525] 01048.01285> bt#02: 0xffffffff0013732e [1.525] 01048.01285> bt#03: 0xffffffff001f6db4 [1.525] 01048.01285> bt#04: 0xffffffff001bd5fe [1.525] 01048.01285> bt#05: 0xffffffff001bd24b [1.525] 01048.01285> bt#06: end [1.525] 01048.01285> #0 0xffffffff0014b65a in <kernel>+0xffffffff8014b65a [1.525] 01048.01285> #1 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [1.525] 01048.01285> #2 0xffffffff0013732d in <kernel>+0xffffffff8013732d [1.525] 01048.01285> #3 0xffffffff001f6db3 in <kernel>+0xffffffff801f6db3 [1.525] 01048.01285> #4 0xffffffff001bd5fd in <kernel>+0xffffffff801bd5fd [1.525] 01048.01285> #5 0xffffffff001bd24a in <kernel>+0xffffffff801bd24a [1.636] 01048.01288> PageFault: 1029306 free pages [1.636] 01048.01288> ZIRCON KERNEL PANIC [1.636] 01048.01288> Lock validation failed for thread 0xffffff8000759b08 pid 1048 tid 1288 (bin/core-tests:vmar_test_addr): [1.636] 01048.01288> Reason: Already Acquired [1.636] 01048.01288> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [1.636] 01048.01288> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [1.636] 01048.01288> caller=0xffffffff0014b65b frame=0xffffff98099ccd60 [1.636] 01048.01288> BUILDID git-808b66d9daaaacd45b64fe427fa28a1682088ff2-dirty [1.636] 01048.01288> dso: id=3776c8a9518e2b61 base=0xffffffff00100000 name=zircon.elf [1.636] 01048.01288> bt#00: 0xffffffff0014b65b [1.636] 01048.01288> bt#01: 0xffffffff0014ba8f [1.636] 01048.01288> bt#02: 0xffffffff0013732e [1.636] 01048.01288> bt#03: 0xffffffff001f6db4 [1.636] 01048.01288> bt#04: 0xffffffff001bd5fe [1.636] 01048.01288> bt#05: 0xffffffff001bd24b [1.636] 01048.01288> bt#06: end [1.636] 01048.01288> #0 0xffffffff0014b65a in <kernel>+0xffffffff8014b65a [1.636] 01048.01288> #1 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [1.636] 01048.01288> #2 0xffffffff0013732d in <kernel>+0xffffffff8013732d [1.636] 01048.01288> #3 0xffffffff001f6db3 in <kernel>+0xffffffff801f6db3 [1.636] 01048.01288> #4 0xffffffff001bd5fd in <kernel>+0xffffffff801bd5fd [1.636] 01048.01288> #5 0xffffffff001bd24a in <kernel>+0xffffffff801bd24a [1.742] 01048.01291> PageFault: 1029306 free pages [1.742] 01048.01291> ZIRCON KERNEL PANIC [1.742] 01048.01291> Lock validation failed for thread 0xffffff8000759b08 pid 1048 tid 1291 (bin/core-tests:vmar_test_addr): [1.742] 01048.01291> Reason: Already Acquired [1.742] 01048.01291> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [1.742] 01048.01291> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [1.742] 01048.01291> caller=0xffffffff0014b65b frame=0xffffff98099ccd60 [1.742] 01048.01291> BUILDID git-808b66d9daaaacd45b64fe427fa28a1682088ff2-dirty [1.742] 01048.01291> dso: id=3776c8a9518e2b61 base=0xffffffff00100000 name=zircon.elf [1.742] 01048.01291> bt#00: 0xffffffff0014b65b [1.742] 01048.01291> bt#01: 0xffffffff0014ba8f [1.742] 01048.01291> bt#02: 0xffffffff0013732e [1.742] 01048.01291> bt#03: 0xffffffff001f6db4 [1.742] 01048.01291> bt#04: 0xffffffff001bd5fe [1.742] 01048.01291> bt#05: 0xffffffff001bd24b [1.742] 01048.01291> bt#06: end [1.742] 01048.01291> #0 0xffffffff0014b65a in <kernel>+0xffffffff8014b65a [1.742] 01048.01291> #1 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [1.742] 01048.01291> #2 0xffffffff0013732d in <kernel>+0xffffffff8013732d [1.742] 01048.01291> #3 0xffffffff001f6db3 in <kernel>+0xffffffff801f6db3 [1.742] 01048.01291> #4 0xffffffff001bd5fd in <kernel>+0xffffffff801bd5fd [1.742] 01048.01291> #5 0xffffffff001bd24a in <kernel>+0xffffffff801bd24a [1.847] 01048.01051> [PASSED] (330 ms) [1.847] 01048.01051> partial_unmap_and_read [RUNNING] [1.847] 01048.01051> [PASSED] (0 ms) [1.847] 01048.01051> partial_unmap_and_write [RUNNING] [1.847] 01048.01051> [PASSED] (0 ms) [1.847] 01048.01051> partial_unmap_with_vmar_offset [RUNNING] [1.848] 01048.01051> [PASSED] (0 ms) [1.848] 01048.01051> CASE vmar_tests [PASSED] CASE ticks_tests [STARTED] [1.848] 01048.01051> elapsed_time_using_ticks [RUNNING] [1.848] 01048.01051> [PASSED] (0 ms) [1.848] 01048.01051> CASE ticks_tests [PASSED] CASE threads_tests [STARTED] [1.848] 01048.01051> TestBasics [RUNNING] [1.949] 01048.01051> [PASSED] (100 ms) [1.949] 01048.01051> TestInvalidRights [RUNNING] [1.949] 01048.01051> [PASSED] (0 ms) [1.949] 01048.01051> TestDetach [RUNNING] [1.949] 01048.01051> [PASSED] (0 ms) [1.949] 01048.01051> TestLongNameSucceeds [RUNNING] [1.970] 01048.01051> [PASSED] (21 ms) [1.970] 01048.01051> TestThreadStartOnInitialThread [RUNNING] [1.978] 01048.01051> [PASSED] (8 ms) [1.978] 01048.01051> TestThreadStartWithZeroInstructionPointer [RUNNING] [1.979] 01048.01051> ZIRCON KERNEL PANIC [1.979] 01048.01051> Lock validation failed for thread 0xffffff800074e978 pid 1048 tid 1051 (bin/core-tests:bin/core-tests): [1.979] 01048.01051> Reason: Out Of Order [1.979] 01048.01051> Bad lock: name=lockdep::LockClass<SoloDispatcher<ThreadDispatcher, 316111, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [1.979] 01048.01051> Conflict: name=lockdep::LockClass<SoloDispatcher<ProcessDispatcher, 447439, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [1.979] 01048.01051> caller=0xffffffff0016498b frame=0xffffff98069a7ec0 [1.979] 01048.01051> BUILDID git-808b66d9daaaacd45b64fe427fa28a1682088ff2-dirty [1.979] 01048.01051> dso: id=3776c8a9518e2b61 base=0xffffffff00100000 name=zircon.elf [1.979] 01048.01051> bt#00: 0xffffffff0016498b [1.979] 01048.01051> bt#01: 0xffffffff00159c5c [1.979] 01048.01051> bt#02: 0xffffffff0015a48d [1.979] 01048.01051> bt#03: 0xffffffff00217a26 [1.979] 01048.01051> bt#04: 0xffffffff00220e9a [1.979] 01048.01051> bt#05: 0xffffffff001ce98e [1.979] 01048.01051> bt#06: end [1.979] 01048.01051> #0 0xffffffff0016498a in <kernel>+0xffffffff8016498a [1.979] 01048.01051> #1 0xffffffff00159c5b in <kernel>+0xffffffff80159c5b [1.979] 01048.01051> #2 0xffffffff0015a48c in <kernel>+0xffffffff8015a48c [1.979] 01048.01051> #3 0xffffffff00217a25 in <kernel>+0xffffffff80217a25 [1.979] 01048.01051> #4 0xffffffff00220e99 in <kernel>+0xffffffff80220e99 [1.979] 01048.01051> #5 0xffffffff001ce98d in <kernel>+0xffffffff801ce98d [1.980] 01048.01051> [PASSED] (1 ms) [1.980] 01048.01051> TestKillBusyThread [RUNNING] [2.091] 01048.01051> [PASSED] (111 ms) [2.091] 01048.01051> TestKillSleepThread [RUNNING] [2.192] 01048.01051> [PASSED] (101 ms) [2.192] 01048.01051> TestKillWaitThread [RUNNING] [2.293] 01048.01051> [PASSED] (101 ms) [2.293] 01048.01051> TestNonstartedThread [RUNNING] [2.296] 01048.01051> [PASSED] (2 ms) [2.296] 01048.01051> TestThreadKillsItself [RUNNING] [2.309] 01048.01051> [PASSED] (12 ms) [2.309] 01048.01051> TestInfoTaskStatsFails [RUNNING] [2.410] 01048.01051> [PASSED] (100 ms) [2.410] 01048.01051> TestResumeSuspended [RUNNING] [2.519] 01048.01051> [PASSED] (109 ms) [2.519] 01048.01051> TestSuspendSleeping [RUNNING] [2.620] 01048.01051> [PASSED] (100 ms) [2.620] 01048.01051> TestSuspendChannelCall [RUNNING] [2.628] 01048.01051> [PASSED] (7 ms) [2.628] 01048.01051> TestSuspendPortCall [RUNNING] [2.830] 01048.01051> [PASSED] (201 ms) [2.830] 01048.01051> TestSuspendStopsThread [RUNNING] [2.881] 01048.01051> [PASSED] (51 ms) [2.881] 01048.01051> TestSuspendMultiple [RUNNING] [2.882] 01048.01051> [PASSED] (1 ms) [2.882] 01048.01051> TestSuspendSelf [RUNNING] [2.882] 01048.01051> [PASSED] (0 ms) [2.882] 01048.01051> TestKillSuspendedThread [RUNNING] [2.884] 01048.01051> [PASSED] (2 ms) [2.884] 01048.01051> TestStartSuspendedThread [RUNNING] [2.910] 01048.01051> [PASSED] (25 ms) [2.910] 01048.01051> TestStartSuspendedAndResumedThread [RUNNING] [2.910] 01048.01051> [PASSED] (0 ms) [2.910] 01048.01051> TestSuspendSingleWaitAsyncSignalDelivery [RUNNING] [2.918] 01048.01051> [PASSED] (7 ms) [2.918] 01048.01051> TestSuspendRepeatingWaitAsyncSignalDelivery [RUNNING] [2.933] 01048.01051> [PASSED] (15 ms) [2.933] 01048.01051> TestReadingGeneralRegisterState [RUNNING] [3.034] 01048.01051> [PASSED] (100 ms) [3.034] 01048.01051> TestWritingGeneralRegisterState [RUNNING] [3.135] 01048.01051> [PASSED] (100 ms) [3.135] 01048.01051> TestThreadLocalRegisterState [RUNNING] [3.236] 01048.01051> [PASSED] (100 ms) [3.236] 01048.01051> TestNoncanonicalRipAddress [RUNNING] [3.337] 01048.01051> [PASSED] (101 ms) [3.337] 01048.01051> TestWritingArmFlagsRegister [RUNNING] [3.337] 01048.01051> [PASSED] (0 ms) [3.337] 01048.01051> CASE threads_tests [PASSED] CASE sync_mutex_tests [STARTED] [3.337] 01048.01051> test_mutexes [RUNNING] [3.429] 01048.01051> [PASSED] (91 ms) [3.429] 01048.01051> test_try_mutexes [RUNNING] [3.514] 01048.01051> [PASSED] (85 ms) [3.514] 01048.01051> test_timeout_elapsed [RUNNING] [4.024] 01048.01051> [PASSED] (509 ms) [4.024] 01048.01051> CASE sync_mutex_tests [PASSED] CASE sync_condition_tests [STARTED] [4.024] 01048.01051> Condition::condition_test [RUNNING] [4.044] 01048.01051> [PASSED] (19 ms) [4.044] 01048.01051> Condition::condition_timeout_test [RUNNING] [4.045] 01048.01051> [PASSED] (1 ms) [4.045] 01048.01051> CASE sync_condition_tests [PASSED] CASE sync_completion_tests [STARTED] [4.045] 01048.01051> test_initializer [RUNNING] [4.045] 01048.01051> [PASSED] (0 ms) [4.045] 01048.01051> test_completions [RUNNING] [4.064] 01048.01051> [PASSED] (18 ms) [4.064] 01048.01051> test_timeout [RUNNING] [4.064] 01048.01051> [PASSED] (0 ms) [4.064] 01048.01051> CASE sync_completion_tests [PASSED] CASE sync_completion_tests [STARTED] [4.064] 01048.01051> test_initializer [RUNNING] [4.064] 01048.01051> [PASSED] (0 ms) [4.064] 01048.01051> test_completions [RUNNING] [4.124] 01048.01051> [PASSED] (59 ms) [4.124] 01048.01051> test_timeout [RUNNING] [5.273] 01048.01051> [PASSED] (1149 ms) [5.273] 01048.01051> test_signal_requeue [RUNNING] [5.294] 01048.01051> [PASSED] (20 ms) [5.294] 01048.01051> test_completion_signaled [RUNNING] [5.294] 01048.01051> [PASSED] (0 ms) [5.294] 01048.01051> CASE sync_completion_tests [PASSED] CASE stack_tests [STARTED] [5.294] 01048.01051> main_thread_stack_tests [RUNNING] [5.294] 01048.01051> [PASSED] (0 ms) [5.294] 01048.01051> other_thread_stack_tests [RUNNING] [5.330] 01048.01051> [PASSED] (35 ms) [5.330] 01048.01051> CASE stack_tests [PASSED] CASE tls_tests [STARTED] [5.330] 01048.01051> tls_test [RUNNING] [7.365] 01048.01051> [PASSED] (2034 ms) [7.365] 01048.01051> CASE tls_tests [PASSED] CASE pthread_barrier_tests [STARTED] [7.365] 01048.01051> test_barrier [RUNNING] [7.522] 01048.01051> [PASSED] (157 ms) [7.522] 01048.01051> CASE pthread_barrier_tests [PASSED] CASE pthread_tests [STARTED] [7.522] 01048.01051> pthread_test [RUNNING] [10.648] 01048.01051> [PASSED] (3125 ms) [10.648] 01048.01051> pthread_self_main_thread_test [RUNNING] [10.648] 01048.01051> [PASSED] (0 ms) [10.648] 01048.01051> pthread_big_stack_size [RUNNING] [10.666] 01048.01051> [PASSED] (17 ms) [10.666] 01048.01051> pthread_getstack_main_thread [RUNNING] [10.666] 01048.01051> [PASSED] (0 ms) [10.666] 01048.01051> pthread_getstack_other_thread [RUNNING] [10.666] 01048.01051> [PASSED] (0 ms) [10.667] 01048.01051> CASE pthread_tests [PASSED] CASE process_tests [STARTED] [10.667] 01048.01051> mini_process_sanity [RUNNING] [10.699] 01048.01051> [PASSED] (32 ms) [10.699] 01048.01051> process_start_fail [RUNNING] [10.707] 01048.01051> [PASSED] (8 ms) [10.707] 01048.01051> process_start_no_handle [RUNNING] [10.708] 01048.01051> [PASSED] (0 ms) [10.708] 01048.01051> process_not_killed_via_thread_close [RUNNING] [10.725] 01048.01051> [PASSED] (17 ms) [10.725] 01048.01051> process_not_killed_via_process_close [RUNNING] [10.734] 01048.01051> [PASSED] (8 ms) [10.734] 01048.01051> kill_process_via_thread_kill [RUNNING] [10.742] 01048.01051> [PASSED] (7 ms) [10.742] 01048.01051> kill_process_via_vmar_destroy [RUNNING] [10.742] 02077.02079> PageFault: 1029098 free pages [10.742] 02077.02079> ZIRCON KERNEL PANIC [10.742] 02077.02079> Lock validation failed for thread 0xffffff8000771480 pid 2077 tid 2079 (ttp:th): [10.742] 02077.02079> Reason: Already Acquired [10.742] 02077.02079> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [10.742] 02077.02079> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [10.742] 02077.02079> caller=0xffffffff0014b65b frame=0xffffff9809a34d60 [10.742] 02077.02079> BUILDID git-808b66d9daaaacd45b64fe427fa28a1682088ff2-dirty [10.742] 02077.02079> dso: id=3776c8a9518e2b61 base=0xffffffff00100000 name=zircon.elf [10.742] 02077.02079> bt#00: 0xffffffff0014b65b [10.742] 02077.02079> bt#01: 0xffffffff0014ba8f [10.742] 02077.02079> bt#02: 0xffffffff0013732e [10.742] 02077.02079> bt#03: 0xffffffff001f6db4 [10.742] 02077.02079> bt#04: 0xffffffff001bd5fe [10.742] 02077.02079> bt#05: 0xffffffff001bd24b [10.742] 02077.02079> bt#06: end [10.742] 02077.02079> #0 0xffffffff0014b65a in <kernel>+0xffffffff8014b65a [10.742] 02077.02079> #1 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [10.742] 02077.02079> #2 0xffffffff0013732d in <kernel>+0xffffffff8013732d [10.742] 02077.02079> #3 0xffffffff001f6db3 in <kernel>+0xffffffff801f6db3 [10.742] 02077.02079> #4 0xffffffff001bd5fd in <kernel>+0xffffffff801bd5fd [10.742] 02077.02079> #5 0xffffffff001bd24a in <kernel>+0xffffffff801bd24a [10.858] 01048.01051> [PASSED] (116 ms) [10.858] 01048.01051> kill_channel_handle_cycle [RUNNING] [11.611] 01048.01051> [PASSED] (752 ms) [11.611] 01048.01051> info_reflects_process_state [RUNNING] [11.870] 01048.01051> [PASSED] (258 ms) [11.870] 01048.01051> suspend [RUNNING] [11.871] 01048.01051> [PASSED] (0 ms) [11.871] 01048.01051> suspend_self [RUNNING] [11.871] 01048.01051> [PASSED] (0 ms) [11.871] 01048.01051> suspend_multiple_threads [RUNNING] [11.895] 01048.01051> [PASSED] (23 ms) [11.895] 01048.01051> suspend_before_creating_threads [RUNNING] [11.904] 01048.01051> [PASSED] (9 ms) [11.904] 01048.01051> suspend_before_starting_threads [RUNNING] [11.913] 01048.01051> [PASSED] (8 ms) [11.913] 01048.01051> suspend_process_then_thread [RUNNING] [12.172] 01048.01051> [PASSED] (258 ms) [12.172] 01048.01051> suspend_thread_then_process [RUNNING] [12.423] 01048.01051> [PASSED] (251 ms) [12.423] 01048.01051> suspend_thread_and_process_before_starting_process [RUNNING] [12.674] 01048.01051> [PASSED] (251 ms) [12.675] 01048.01051> suspend_twice [RUNNING] [12.936] 01048.01051> [PASSED] (261 ms) [12.936] 01048.01051> suspend_twice_before_creating_threads [RUNNING] [13.190] 01048.01051> [PASSED] (254 ms) [13.190] 01048.01051> suspend_with_dying_thread [RUNNING] [13.201] 01048.01051> [PASSED] (10 ms) [13.201] 01048.01051> create_and_kill_job_race_stress [RUNNING] [14.211] 01048.01051> [PASSED] (1010 ms) [14.211] 01048.01051> CASE process_tests [PASSED] CASE port_tests [STARTED] [14.211] 01048.01051> basic_test [RUNNING] [14.211] 01048.01051> [PASSED] (0 ms) [14.211] 01048.01051> queue_and_close_test [RUNNING] [14.212] 01048.01051> [PASSED] (0 ms) [14.212] 01048.01051> queue_too_many [RUNNING] [14.245] 01048.01051> [PASSED] (33 ms) [14.245] 01048.01051> async_wait_channel_test [RUNNING] [14.254] 01048.01051> [PASSED] (9 ms) [14.254] 01048.01051> async_wait_event_test_single [RUNNING] [14.254] 01048.01051> [PASSED] (0 ms) [14.254] 01048.01051> async_wait_event_test_repeat [RUNNING] [14.255] 01048.01051> [PASSED] (0 ms) [14.255] 01048.01051> async_wait_event_all_processed [RUNNING] [14.322] 01048.01051> [PASSED] (67 ms) [14.322] 01048.01051> async_wait_invalid_option [RUNNING] [14.322] 01048.01051> [PASSED] (0 ms) [14.322] 01048.01051> async_wait_close_order_1 [RUNNING] [14.322] 01048.01051> [PASSED] (0 ms) [14.322] 01048.01051> async_wait_close_order_2 [RUNNING] [14.322] 01048.01051> [PASSED] (0 ms) [14.322] 01048.01051> async_wait_close_order_3 [RUNNING] [14.322] 01048.01051> [PASSED] (0 ms) [14.322] 01048.01051> async_wait_close_order_4 [RUNNING] [14.322] 01048.01051> [PASSED] (0 ms) [14.322] 01048.01051> async_wait_close_order_5 [RUNNING] [14.322] 01048.01051> [PASSED] (0 ms) [14.322] 01048.01051> async_wait_close_order_6 [RUNNING] [14.322] 01048.01051> [PASSED] (0 ms) [14.322] 01048.01051> channel_pre_writes_test [RUNNING] [14.322] 01048.01051> [PASSED] (0 ms) [14.322] 01048.01051> cancel_event_key [RUNNING] [14.322] 01048.01051> [PASSED] (0 ms) [14.322] 01048.01051> cancel_event_key_after [RUNNING] [14.322] 01048.01051> [PASSED] (0 ms) [14.322] 01048.01051> threads_event [RUNNING] [14.403] 01048.01051> [PASSED] (80 ms) [14.403] 01048.01051> cancel_stress [RUNNING] [19.463] 01048.01051> [PASSED] (5059 ms) [19.463] 01048.01051> signal_close_stress [RUNNING] [20.500] 01048.01051> [PASSED] (1037 ms) [20.500] 01048.01051> port_close_wait_race_stress [RUNNING] [21.515] 01048.01051> [PASSED] (1014 ms) [21.515] 01048.01051> CASE port_tests [PASSED] CASE api_violations [STARTED] [21.515] 01048.01051> invalid_pager_create [RUNNING] [21.515] 01048.01051> [PASSED] (0 ms) [21.515] 01048.01051> invalid_pager_create_vmo [RUNNING] [21.515] 01048.01051> [PASSED] (0 ms) [21.515] 01048.01051> invalid_pager_detach_vmo [RUNNING] [21.515] 01048.01051> [PASSED] (0 ms) [21.515] 01048.01051> invalid_pager_supply_pages [RUNNING] [21.515] 01048.01051> [PASSED] (0 ms) [21.515] 01048.01051> resize_nonresizable_vmo [RUNNING] [21.515] 01048.01051> [PASSED] (0 ms) [21.515] 01048.01051> CASE api_violations [PASSED] CASE commit_tests [STARTED] [21.515] 01048.01051> simple_commit_test [RUNNING] [21.580] 01048.01051> [PASSED] (64 ms) [21.580] 01048.01051> split_commit_test [RUNNING] [21.587] 01048.01051> [PASSED] (7 ms) [21.587] 01048.01051> overlap_commit_test [RUNNING] [21.596] 01048.01051> [PASSED] (8 ms) [21.596] 01048.01051> overlap_commit_supply_test [RUNNING] [21.629] 01048.01051> [PASSED] (32 ms) [21.629] 01048.01051> multisupply_commit_test [RUNNING] [21.631] 01048.01051> [PASSED] (2 ms) [21.631] 01048.01051> multicommit_supply_test [RUNNING] [21.643] 01048.01051> [PASSED] (12 ms) [21.644] 01048.01051> commit_redundant_supply_test [RUNNING] [21.646] 01048.01051> [PASSED] (2 ms) [21.646] 01048.01051> supply_decommit_test [RUNNING] [21.650] 01048.01051> [PASSED] (4 ms) [21.650] 01048.01051> resize_commit_test [RUNNING] [21.653] 01048.01051> [PASSED] (2 ms) [21.653] 01048.01051> suspend_commit_test [RUNNING] [21.658] 01048.01051> [PASSED] (4 ms) [21.658] 01048.01051> CASE commit_tests [PASSED] CASE clone_tests [STARTED] [21.658] 01048.01051> clone_read_from_clone_test_vmar [RUNNING] [21.660] 01048.01051> [PASSED] (2 ms) [21.660] 01048.01051> clone_read_from_clone_test_vmo [RUNNING] [21.664] 01048.01051> [PASSED] (4 ms) [21.665] 01048.01051> clone_read_from_parent_test_vmar [RUNNING] [21.667] 01048.01051> [PASSED] (2 ms) [21.667] 01048.01051> clone_read_from_parent_test_vmo [RUNNING] [21.674] 01048.01051> [PASSED] (6 ms) [21.674] 01048.01051> clone_simultaneous_read_test_vmar [RUNNING] [21.683] 01048.01051> [PASSED] (8 ms) [21.683] 01048.01051> clone_simultaneous_read_test_vmo [RUNNING] [21.688] 01048.01051> [PASSED] (4 ms) [21.688] 01048.01051> clone_simultaneous_child_read_test_vmar [RUNNING] [21.695] 01048.01051> [PASSED] (6 ms) [21.695] 01048.01051> clone_simultaneous_child_read_test_vmo [RUNNING] [21.702] 01048.01051> [PASSED] (6 ms) [21.702] 01048.01051> clone_write_to_clone_test [RUNNING] [21.704] 01048.01051> [PASSED] (2 ms) [21.704] 01048.01051> clone_detach_test [RUNNING] [21.709] 01048.01051> [PASSED] (4 ms) [21.709] 01048.01051> clone_commit_test [RUNNING] [21.711] 01048.01051> [PASSED] (2 ms) [21.711] 01048.01051> clone_split_commit_test [RUNNING] [21.716] 01048.01051> [PASSED] (4 ms) [21.716] 01048.01051> clone_decommit_test [RUNNING] [21.716] 01048.01051> [PASSED] (0 ms) [21.716] 01048.01051> CASE clone_tests [PASSED] CASE lifecycle_tests [STARTED] [21.716] 01048.01051> vmo_info_pager_test [RUNNING] [21.716] 01048.01051> [PASSED] (0 ms) [21.716] 01048.01051> detach_page_complete_test [RUNNING] [21.716] 01048.01051> [PASSED] (0 ms) [21.716] 01048.01051> close_page_complete_test [RUNNING] [21.716] 01048.01051> [PASSED] (0 ms) [21.716] 01048.01051> read_detach_interrupt_late_test_vmar [RUNNING] [21.717] 01048.26113> PageFault: 1027417 free pages [21.717] 01048.26113> ZIRCON KERNEL PANIC [21.717] 01048.26113> Lock validation failed for thread 0xffffff8002edfdb0 pid 1048 tid 26113 (bin/core-tests:test_thread): [21.717] 01048.26113> Reason: Already Acquired [21.717] 01048.26113> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [21.717] 01048.26113> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [21.717] 01048.26113> caller=0xffffffff0014b65b frame=0xffffff9809a34d60 [21.717] 01048.26113> BUILDID git-808b66d9daaaacd45b64fe427fa28a1682088ff2-dirty [21.717] 01048.26113> dso: id=3776c8a9518e2b61 base=0xffffffff00100000 name=zircon.elf [21.717] 01048.26113> bt#00: 0xffffffff0014b65b [21.717] 01048.26113> bt#01: 0xffffffff0014ba8f [21.717] 01048.26113> bt#02: 0xffffffff0013732e [21.717] 01048.26113> bt#03: 0xffffffff001f6db4 [21.717] 01048.26113> bt#04: 0xffffffff001bd5fe [21.717] 01048.26113> bt#05: 0xffffffff001bd24b [21.717] 01048.26113> bt#06: end [21.717] 01048.26113> #0 0xffffffff0014b65a in <kernel>+0xffffffff8014b65a [21.717] 01048.26113> #1 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [21.717] 01048.26113> #2 0xffffffff0013732d in <kernel>+0xffffffff8013732d [21.717] 01048.26113> #3 0xffffffff001f6db3 in <kernel>+0xffffffff801f6db3 [21.717] 01048.26113> #4 0xffffffff001bd5fd in <kernel>+0xffffffff801bd5fd [21.717] 01048.26113> #5 0xffffffff001bd24a in <kernel>+0xffffffff801bd24a [21.717] 01048.26113> ZIRCON KERNEL PANIC [21.717] 01048.26113> Lock validation failed for thread 0xffffff8002edfdb0 pid 1048 tid 26113 (bin/core-tests:test_thread): [21.717] 01048.26113> Reason: Already Acquired [21.717] 01048.26113> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [21.717] 01048.26113> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [21.717] 01048.26113> caller=0xffffffff0014b65b frame=0xffffff9809a34cd0 [21.717] 01048.26113> BUILDID git-808b66d9daaaacd45b64fe427fa28a1682088ff2-dirty [21.717] 01048.26113> dso: id=3776c8a9518e2b61 base=0xffffffff00100000 name=zircon.elf [21.717] 01048.26113> bt#00: 0xffffffff0014b65b [21.717] 01048.26113> bt#01: 0xffffffff0014ba8f [21.717] 01048.26113> bt#02: 0xffffffff0014ba8f [21.717] 01048.26113> bt#03: 0xffffffff0013732e [21.717] 01048.26113> bt#04: 0xffffffff001f6db4 [21.717] 01048.26113> bt#05: 0xffffffff001bd5fe [21.717] 01048.26113> bt#06: 0xffffffff001bd24b [21.717] 01048.26113> bt#07: end [21.717] 01048.26113> #0 0xffffffff0014b65a in <kernel>+0xffffffff8014b65a [21.717] 01048.26113> #1 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [21.717] 01048.26113> #2 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [21.717] 01048.26113> #3 0xffffffff0013732d in <kernel>+0xffffffff8013732d [21.717] 01048.26113> #4 0xffffffff001f6db3 in <kernel>+0xffffffff801f6db3 [21.717] 01048.26113> #5 0xffffffff001bd5fd in <kernel>+0xffffffff801bd5fd [21.717] 01048.26113> #6 0xffffffff001bd24a in <kernel>+0xffffffff801bd24a [21.717] 01048.26113> ZIRCON KERNEL PANIC [21.717] 01048.26113> Lock validation failed for thread 0xffffff8002edfdb0 pid 1048 tid 26113 (bin/core-tests:test_thread): [21.717] 01048.26113> Reason: Already Acquired [21.717] 01048.26113> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [21.717] 01048.26113> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [21.717] 01048.26113> caller=0xffffffff0014b65b frame=0xffffff9809a34cd0 [21.717] 01048.26113> BUILDID git-808b66d9daaaacd45b64fe427fa28a1682088ff2-dirty [21.717] 01048.26113> dso: id=3776c8a9518e2b61 base=0xffffffff00100000 name=zircon.elf [21.717] 01048.26113> bt#00: 0xffffffff0014b65b [21.717] 01048.26113> bt#01: 0xffffffff0014ba8f [21.717] 01048.26113> bt#02: 0xffffffff0014ba8f [21.717] 01048.26113> bt#03: 0xffffffff0013732e [21.717] 01048.26113> bt#04: 0xffffffff001f6db4 [21.717] 01048.26113> bt#05: 0xffffffff001bd5fe [21.717] 01048.26113> bt#06: 0xffffffff001bd24b [21.717] 01048.26113> bt#07: end [21.717] 01048.26113> #0 0xffffffff0014b65a in <kernel>+0xffffffff8014b65a [21.717] 01048.26113> #1 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [21.717] 01048.26113> #2 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [21.717] 01048.26113> #3 0xffffffff0013732d in <kernel>+0xffffffff8013732d [21.717] 01048.26113> #4 0xffffffff001f6db3 in <kernel>+0xffffffff801f6db3 [21.717] 01048.26113> #5 0xffffffff001bd5fd in <kernel>+0xffffffff801bd5fd [21.717] 01048.26113> #6 0xffffffff001bd24a in <kernel>+0xffffffff801bd24a [21.718] 01048.01051> [PASSED] (1 ms) [21.718] 01048.01051> read_detach_interrupt_late_test_vmo [RUNNING] [21.723] 01048.01051> [PASSED] (4 ms) [21.723] 01048.01051> read_close_interrupt_late_test_vmar [RUNNING] [21.723] 01048.26133> PageFault: 1027416 free pages [21.723] 01048.26133> ZIRCON KERNEL PANIC [21.723] 01048.26133> Lock validation failed for thread 0xffffff8002edfdb0 pid 1048 tid 26133 (bin/core-tests:test_thread): [21.723] 01048.26133> Reason: Already Acquired [21.723] 01048.26133> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [21.723] 01048.26133> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [21.723] 01048.26133> caller=0xffffffff0014b65b frame=0xffffff9809a34d60 [21.723] 01048.26133> BUILDID git-808b66d9daaaacd45b64fe427fa28a1682088ff2-dirty [21.723] 01048.26133> dso: id=3776c8a9518e2b61 base=0xffffffff00100000 name=zircon.elf [21.723] 01048.26133> bt#00: 0xffffffff0014b65b [21.723] 01048.26133> bt#01: 0xffffffff0014ba8f [21.723] 01048.26133> bt#02: 0xffffffff0013732e [21.723] 01048.26133> bt#03: 0xffffffff001f6db4 [21.723] 01048.26133> bt#04: 0xffffffff001bd5fe [21.723] 01048.26133> bt#05: 0xffffffff001bd24b [21.723] 01048.26133> bt#06: end [21.723] 01048.26133> #0 0xffffffff0014b65a in <kernel>+0xffffffff8014b65a [21.723] 01048.26133> #1 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [21.723] 01048.26133> #2 0xffffffff0013732d in <kernel>+0xffffffff8013732d [21.723] 01048.26133> #3 0xffffffff001f6db3 in <kernel>+0xffffffff801f6db3 [21.723] 01048.26133> #4 0xffffffff001bd5fd in <kernel>+0xffffffff801bd5fd [21.723] 01048.26133> #5 0xffffffff001bd24a in <kernel>+0xffffffff801bd24a [21.723] 01048.26133> ZIRCON KERNEL PANIC [21.723] 01048.26133> Lock validation failed for thread 0xffffff8002edfdb0 pid 1048 tid 26133 (bin/core-tests:test_thread): [21.723] 01048.26133> Reason: Already Acquired [21.723] 01048.26133> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [21.723] 01048.26133> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [21.723] 01048.26133> caller=0xffffffff0014b65b frame=0xffffff9809a34cd0 [21.723] 01048.26133> BUILDID git-808b66d9daaaacd45b64fe427fa28a1682088ff2-dirty [21.723] 01048.26133> dso: id=3776c8a9518e2b61 base=0xffffffff00100000 name=zircon.elf [21.723] 01048.26133> bt#00: 0xffffffff0014b65b [21.723] 01048.26133> bt#01: 0xffffffff0014ba8f [21.723] 01048.26133> bt#02: 0xffffffff0014ba8f [21.723] 01048.26133> bt#03: 0xffffffff0013732e [21.723] 01048.26133> bt#04: 0xffffffff001f6db4 [21.723] 01048.26133> bt#05: 0xffffffff001bd5fe [21.723] 01048.26133> bt#06: 0xffffffff001bd24b [21.723] 01048.26133> bt#07: end [21.723] 01048.26133> #0 0xffffffff0014b65a in <kernel>+0xffffffff8014b65a [21.723] 01048.26133> #1 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [21.723] 01048.26133> #2 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [21.723] 01048.26133> #3 0xffffffff0013732d in <kernel>+0xffffffff8013732d [21.723] 01048.26133> #4 0xffffffff001f6db3 in <kernel>+0xffffffff801f6db3 [21.723] 01048.26133> #5 0xffffffff001bd5fd in <kernel>+0xffffffff801bd5fd [21.723] 01048.26133> #6 0xffffffff001bd24a in <kernel>+0xffffffff801bd24a [21.723] 01048.26133> ZIRCON KERNEL PANIC [21.723] 01048.26133> Lock validation failed for thread 0xffffff8002edfdb0 pid 1048 tid 26133 (bin/core-tests:test_thread): [21.723] 01048.26133> Reason: Already Acquired [21.723] 01048.26133> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [21.723] 01048.26133> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [21.723] 01048.26133> caller=0xffffffff0014b65b frame=0xffffff9809a34cd0 [21.723] 01048.26133> BUILDID git-808b66d9daaaacd45b64fe427fa28a1682088ff2-dirty [21.723] 01048.26133> dso: id=3776c8a9518e2b61 base=0xffffffff00100000 name=zircon.elf [21.723] 01048.26133> bt#00: 0xffffffff0014b65b [21.723] 01048.26133> bt#01: 0xffffffff0014ba8f [21.723] 01048.26133> bt#02: 0xffffffff0014ba8f [21.723] 01048.26133> bt#03: 0xffffffff0013732e [21.723] 01048.26133> bt#04: 0xffffffff001f6db4 [21.723] 01048.26133> bt#05: 0xffffffff001bd5fe [21.723] 01048.26133> bt#06: 0xffffffff001bd24b [21.723] 01048.26133> bt#07: end [21.723] 01048.26133> #0 0xffffffff0014b65a in <kernel>+0xffffffff8014b65a [21.723] 01048.26133> #1 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [21.724] 01048.26133> #2 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [21.724] 01048.26133> #3 0xffffffff0013732d in <kernel>+0xffffffff8013732d [21.724] 01048.26133> #4 0xffffffff001f6db3 in <kernel>+0xffffffff801f6db3 [21.724] 01048.26133> #5 0xffffffff001bd5fd in <kernel>+0xffffffff801bd5fd [21.724] 01048.26133> #6 0xffffffff001bd24a in <kernel>+0xffffffff801bd24a [21.725] 01048.01051> [PASSED] (2 ms) [21.725] 01048.01051> read_close_interrupt_late_test_vmo [RUNNING] [21.730] 01048.01051> [PASSED] (4 ms) [21.730] 01048.01051> read_detach_interrupt_early_test_vmar [RUNNING] [21.730] 01048.26153> PageFault: 1027416 free pages [21.730] 01048.26153> ZIRCON KERNEL PANIC [21.730] 01048.26153> Lock validation failed for thread 0xffffff8002edfdb0 pid 1048 tid 26153 (bin/core-tests:test_thread): [21.730] 01048.26153> Reason: Already Acquired [21.730] 01048.26153> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [21.730] 01048.26153> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [21.730] 01048.26153> caller=0xffffffff0014b65b frame=0xffffff9809a34d60 [21.730] 01048.26153> BUILDID git-808b66d9daaaacd45b64fe427fa28a1682088ff2-dirty [21.730] 01048.26153> dso: id=3776c8a9518e2b61 base=0xffffffff00100000 name=zircon.elf [21.730] 01048.26153> bt#00: 0xffffffff0014b65b [21.730] 01048.26153> bt#01: 0xffffffff0014ba8f [21.730] 01048.26153> bt#02: 0xffffffff0013732e [21.730] 01048.26153> bt#03: 0xffffffff001f6db4 [21.730] 01048.26153> bt#04: 0xffffffff001bd5fe [21.730] 01048.26153> bt#05: 0xffffffff001bd24b [21.730] 01048.26153> bt#06: end [21.730] 01048.26153> #0 0xffffffff0014b65a in <kernel>+0xffffffff8014b65a [21.730] 01048.26153> #1 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [21.730] 01048.26153> #2 0xffffffff0013732d in <kernel>+0xffffffff8013732d [21.730] 01048.26153> #3 0xffffffff001f6db3 in <kernel>+0xffffffff801f6db3 [21.730] 01048.26153> #4 0xffffffff001bd5fd in <kernel>+0xffffffff801bd5fd [21.730] 01048.26153> #5 0xffffffff001bd24a in <kernel>+0xffffffff801bd24a [21.730] 01048.26153> ZIRCON KERNEL PANIC [21.730] 01048.26153> Lock validation failed for thread 0xffffff8002edfdb0 pid 1048 tid 26153 (bin/core-tests:test_thread): [21.730] 01048.26153> Reason: Already Acquired [21.730] 01048.26153> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [21.730] 01048.26153> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [21.730] 01048.26153> caller=0xffffffff0014b65b frame=0xffffff9809a34cd0 [21.730] 01048.26153> BUILDID git-808b66d9daaaacd45b64fe427fa28a1682088ff2-dirty [21.730] 01048.26153> dso: id=3776c8a9518e2b61 base=0xffffffff00100000 name=zircon.elf [21.730] 01048.26153> bt#00: 0xffffffff0014b65b [21.730] 01048.26153> bt#01: 0xffffffff0014ba8f [21.730] 01048.26153> bt#02: 0xffffffff0014ba8f [21.730] 01048.26153> bt#03: 0xffffffff0013732e [21.730] 01048.26153> bt#04: 0xffffffff001f6db4 [21.730] 01048.26153> bt#05: 0xffffffff001bd5fe [21.730] 01048.26153> bt#06: 0xffffffff001bd24b [21.730] 01048.26153> bt#07: end [21.730] 01048.26153> #0 0xffffffff0014b65a in <kernel>+0xffffffff8014b65a [21.730] 01048.26153> #1 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [21.730] 01048.26153> #2 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [21.730] 01048.26153> #3 0xffffffff0013732d in <kernel>+0xffffffff8013732d [21.730] 01048.26153> #4 0xffffffff001f6db3 in <kernel>+0xffffffff801f6db3 [21.730] 01048.26153> #5 0xffffffff001bd5fd in <kernel>+0xffffffff801bd5fd [21.730] 01048.26153> #6 0xffffffff001bd24a in <kernel>+0xffffffff801bd24a [21.730] 01048.26153> ZIRCON KERNEL PANIC [21.730] 01048.26153> Lock validation failed for thread 0xffffff8002edfdb0 pid 1048 tid 26153 (bin/core-tests:test_thread): [21.730] 01048.26153> Reason: Already Acquired [21.730] 01048.26153> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [21.730] 01048.26153> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [21.730] 01048.26153> caller=0xffffffff0014b65b frame=0xffffff9809a34cd0 [21.730] 01048.26153> BUILDID git-808b66d9daaaacd45b64fe427fa28a1682088ff2-dirty [21.730] 01048.26153> dso: id=3776c8a9518e2b61 base=0xffffffff00100000 name=zircon.elf [21.730] 01048.26153> bt#00: 0xffffffff0014b65b [21.730] 01048.26153> bt#01: 0xffffffff0014ba8f [21.730] 01048.26153> bt#02: 0xffffffff0014ba8f [21.730] 01048.26153> bt#03: 0xffffffff0013732e [21.730] 01048.26153> bt#04: 0xffffffff001f6db4 [21.730] 01048.26153> bt#05: 0xffffffff001bd5fe [21.730] 01048.26153> bt#06: 0xffffffff001bd24b [21.730] 01048.26153> bt#07: end [21.730] 01048.26153> #0 0xffffffff0014b65a in <kernel>+0xffffffff8014b65a [21.730] 01048.26153> #1 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [21.730] 01048.26153> #2 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [21.730] 01048.26153> #3 0xffffffff0013732d in <kernel>+0xffffffff8013732d [21.730] 01048.26153> #4 0xffffffff001f6db3 in <kernel>+0xffffffff801f6db3 [21.730] 01048.26153> #5 0xffffffff001bd5fd in <kernel>+0xffffffff801bd5fd [21.730] 01048.26153> #6 0xffffffff001bd24a in <kernel>+0xffffffff801bd24a [21.732] 01048.01051> [PASSED] (2 ms) [21.732] 01048.01051> read_detach_interrupt_early_test_vmo [RUNNING] [21.737] 01048.01051> [PASSED] (4 ms) [21.737] 01048.01051> read_close_interrupt_early_test_vmar [RUNNING] [21.737] 01048.26173> PageFault: 1027417 free pages [21.737] 01048.26173> ZIRCON KERNEL PANIC [21.737] 01048.26173> Lock validation failed for thread 0xffffff8002edfdb0 pid 1048 tid 26173 (bin/core-tests:test_thread): [21.737] 01048.26173> Reason: Already Acquired [21.737] 01048.26173> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [21.737] 01048.26173> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [21.737] 01048.26173> caller=0xffffffff0014b65b frame=0xffffff9809a34d60 [21.737] 01048.26173> BUILDID git-808b66d9daaaacd45b64fe427fa28a1682088ff2-dirty [21.737] 01048.26173> dso: id=3776c8a9518e2b61 base=0xffffffff00100000 name=zircon.elf [21.737] 01048.26173> bt#00: 0xffffffff0014b65b [21.737] 01048.26173> bt#01: 0xffffffff0014ba8f [21.737] 01048.26173> bt#02: 0xffffffff0013732e [21.737] 01048.26173> bt#03: 0xffffffff001f6db4 [21.737] 01048.26173> bt#04: 0xffffffff001bd5fe [21.737] 01048.26173> bt#05: 0xffffffff001bd24b [21.737] 01048.26173> bt#06: end [21.737] 01048.26173> #0 0xffffffff0014b65a in <kernel>+0xffffffff8014b65a [21.737] 01048.26173> #1 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [21.737] 01048.26173> #2 0xffffffff0013732d in <kernel>+0xffffffff8013732d [21.737] 01048.26173> #3 0xffffffff001f6db3 in <kernel>+0xffffffff801f6db3 [21.737] 01048.26173> #4 0xffffffff001bd5fd in <kernel>+0xffffffff801bd5fd [21.737] 01048.26173> #5 0xffffffff001bd24a in <kernel>+0xffffffff801bd24a [21.737] 01048.26173> ZIRCON KERNEL PANIC [21.737] 01048.26173> Lock validation failed for thread 0xffffff8002edfdb0 pid 1048 tid 26173 (bin/core-tests:test_thread): [21.737] 01048.26173> Reason: Already Acquired [21.737] 01048.26173> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [21.737] 01048.26173> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [21.737] 01048.26173> caller=0xffffffff0014b65b frame=0xffffff9809a34cd0 [21.737] 01048.26173> BUILDID git-808b66d9daaaacd45b64fe427fa28a1682088ff2-dirty [21.737] 01048.26173> dso: id=3776c8a9518e2b61 base=0xffffffff00100000 name=zircon.elf [21.737] 01048.26173> bt#00: 0xffffffff0014b65b [21.737] 01048.26173> bt#01: 0xffffffff0014ba8f [21.737] 01048.26173> bt#02: 0xffffffff0014ba8f [21.737] 01048.26173> bt#03: 0xffffffff0013732e [21.737] 01048.26173> bt#04: 0xffffffff001f6db4 [21.737] 01048.26173> bt#05: 0xffffffff001bd5fe [21.737] 01048.26173> bt#06: 0xffffffff001bd24b [21.737] 01048.26173> bt#07: end [21.737] 01048.26173> #0 0xffffffff0014b65a in <kernel>+0xffffffff8014b65a [21.737] 01048.26173> #1 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [21.737] 01048.26173> #2 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [21.737] 01048.26173> #3 0xffffffff0013732d in <kernel>+0xffffffff8013732d [21.737] 01048.26173> #4 0xffffffff001f6db3 in <kernel>+0xffffffff801f6db3 [21.737] 01048.26173> #5 0xffffffff001bd5fd in <kernel>+0xffffffff801bd5fd [21.737] 01048.26173> #6 0xffffffff001bd24a in <kernel>+0xffffffff801bd24a [21.737] 01048.26173> ZIRCON KERNEL PANIC [21.737] 01048.26173> Lock validation failed for thread 0xffffff8002edfdb0 pid 1048 tid 26173 (bin/core-tests:test_thread): [21.737] 01048.26173> Reason: Already Acquired [21.737] 01048.26173> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [21.737] 01048.26173> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [21.737] 01048.26173> caller=0xffffffff0014b65b frame=0xffffff9809a34cd0 [21.737] 01048.26173> BUILDID git-808b66d9daaaacd45b64fe427fa28a1682088ff2-dirty [21.737] 01048.26173> dso: id=3776c8a9518e2b61 base=0xffffffff00100000 name=zircon.elf [21.737] 01048.26173> bt#00: 0xffffffff0014b65b [21.737] 01048.26173> bt#01: 0xffffffff0014ba8f [21.737] 01048.26173> bt#02: 0xffffffff0014ba8f [21.737] 01048.26173> bt#03: 0xffffffff0013732e [21.737] 01048.26173> bt#04: 0xffffffff001f6db4 [21.737] 01048.26173> bt#05: 0xffffffff001bd5fe [21.737] 01048.26173> bt#06: 0xffffffff001bd24b [21.737] 01048.26173> bt#07: end [21.737] 01048.26173> #0 0xffffffff0014b65a in <kernel>+0xffffffff8014b65a [21.737] 01048.26173> #1 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [21.737] 01048.26173> #2 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [21.737] 01048.26173> #3 0xffffffff0013732d in <kernel>+0xffffffff8013732d [21.737] 01048.26173> #4 0xffffffff001f6db3 in <kernel>+0xffffffff801f6db3 [21.737] 01048.26173> #5 0xffffffff001bd5fd in <kernel>+0xffffffff801bd5fd [21.737] 01048.26173> #6 0xffffffff001bd24a in <kernel>+0xffffffff801bd24a [21.739] 01048.01051> [PASSED] (2 ms) [21.739] 01048.01051> read_close_interrupt_early_test_vmo [RUNNING] [21.744] 01048.01051> [PASSED] (4 ms) [21.744] 01048.01051> thread_kill_test_vmar [RUNNING] [21.746] 01048.01051> [PASSED] (2 ms) [21.746] 01048.01051> thread_kill_test_vmo [RUNNING] [21.751] 01048.01051> [PASSED] (4 ms) [21.751] 01048.01051> thread_kill_overlap_test_vmar [RUNNING] [21.754] 01048.01051> [PASSED] (2 ms) [21.754] 01048.01051> thread_kill_overlap_test_vmo [RUNNING] [21.758] 01048.01051> [PASSED] (4 ms) [21.759] 01048.01051> close_pager_test [RUNNING] [21.759] 01048.26264> PageFault: 1027346 free pages [21.759] 01048.26264> ZIRCON KERNEL PANIC [21.759] 01048.26264> Lock validation failed for thread 0xffffff8002fe6690 pid 1048 tid 26264 (bin/core-tests:test_thread): [21.759] 01048.26264> Reason: Already Acquired [21.759] 01048.26264> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [21.759] 01048.26264> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [21.759] 01048.26264> caller=0xffffffff0014b65b frame=0xffffff9809a54d60 [21.759] 01048.26264> BUILDID git-808b66d9daaaacd45b64fe427fa28a1682088ff2-dirty [21.759] 01048.26264> dso: id=3776c8a9518e2b61 base=0xffffffff00100000 name=zircon.elf [21.759] 01048.26264> bt#00: 0xffffffff0014b65b [21.759] 01048.26264> bt#01: 0xffffffff0014ba8f [21.759] 01048.26264> bt#02: 0xffffffff0013732e [21.759] 01048.26264> bt#03: 0xffffffff001f6db4 [21.759] 01048.26264> bt#04: 0xffffffff001bd5fe [21.759] 01048.26264> bt#05: 0xffffffff001bd24b [21.759] 01048.26264> bt#06: end [21.759] 01048.26264> #0 0xffffffff0014b65a in <kernel>+0xffffffff8014b65a [21.759] 01048.26264> #1 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [21.759] 01048.26264> #2 0xffffffff0013732d in <kernel>+0xffffffff8013732d [21.759] 01048.26264> #3 0xffffffff001f6db3 in <kernel>+0xffffffff801f6db3 [21.759] 01048.26264> #4 0xffffffff001bd5fd in <kernel>+0xffffffff801bd5fd [21.759] 01048.26264> #5 0xffffffff001bd24a in <kernel>+0xffffffff801bd24a [21.759] 01048.26264> ZIRCON KERNEL PANIC [21.759] 01048.26264> Lock validation failed for thread 0xffffff8002fe6690 pid 1048 tid 26264 (bin/core-tests:test_thread): [21.759] 01048.26264> Reason: Already Acquired [21.759] 01048.26264> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [21.759] 01048.26264> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [21.759] 01048.26264> caller=0xffffffff0014b65b frame=0xffffff9809a54cd0 [21.759] 01048.26264> BUILDID git-808b66d9daaaacd45b64fe427fa28a1682088ff2-dirty [21.759] 01048.26264> dso: id=3776c8a9518e2b61 base=0xffffffff00100000 name=zircon.elf [21.759] 01048.26264> bt#00: 0xffffffff0014b65b [21.759] 01048.26264> bt#01: 0xffffffff0014ba8f [21.759] 01048.26264> bt#02: 0xffffffff0014ba8f [21.759] 01048.26264> bt#03: 0xffffffff0013732e [21.759] 01048.26264> bt#04: 0xffffffff001f6db4 [21.759] 01048.26264> bt#05: 0xffffffff001bd5fe [21.759] 01048.26264> bt#06: 0xffffffff001bd24b [21.759] 01048.26264> bt#07: end [21.759] 01048.26264> #0 0xffffffff0014b65a in <kernel>+0xffffffff8014b65a [21.759] 01048.26264> #1 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [21.759] 01048.26264> #2 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [21.759] 01048.26264> #3 0xffffffff0013732d in <kernel>+0xffffffff8013732d [21.759] 01048.26264> #4 0xffffffff001f6db3 in <kernel>+0xffffffff801f6db3 [21.759] 01048.26264> #5 0xffffffff001bd5fd in <kernel>+0xffffffff801bd5fd [21.759] 01048.26264> #6 0xffffffff001bd24a in <kernel>+0xffffffff801bd24a [21.759] 01048.26264> ZIRCON KERNEL PANIC [21.759] 01048.26264> Lock validation failed for thread 0xffffff8002fe6690 pid 1048 tid 26264 (bin/core-tests:test_thread): [21.759] 01048.26264> Reason: Already Acquired [21.759] 01048.26264> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [21.759] 01048.26264> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [21.759] 01048.26264> caller=0xffffffff0014b65b frame=0xffffff9809a54cd0 [21.759] 01048.26264> BUILDID git-808b66d9daaaacd45b64fe427fa28a1682088ff2-dirty [21.759] 01048.26264> dso: id=3776c8a9518e2b61 base=0xffffffff00100000 name=zircon.elf [21.759] 01048.26264> bt#00: 0xffffffff0014b65b [21.759] 01048.26264> bt#01: 0xffffffff0014ba8f [21.759] 01048.26264> bt#02: 0xffffffff0014ba8f [21.759] 01048.26264> bt#03: 0xffffffff0013732e [21.759] 01048.26264> bt#04: 0xffffffff001f6db4 [21.759] 01048.26264> bt#05: 0xffffffff001bd5fe [21.759] 01048.26264> bt#06: 0xffffffff001bd24b [21.759] 01048.26264> bt#07: end [21.759] 01048.26264> #0 0xffffffff0014b65a in <kernel>+0xffffffff8014b65a [21.759] 01048.26264> #1 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [21.759] 01048.26264> #2 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [21.759] 01048.26264> #3 0xffffffff0013732d in <kernel>+0xffffffff8013732d [21.759] 01048.26264> #4 0xffffffff001f6db3 in <kernel>+0xffffffff801f6db3 [21.759] 01048.26264> #5 0xffffffff001bd5fd in <kernel>+0xffffffff801bd5fd [21.759] 01048.26264> #6 0xffffffff001bd24a in <kernel>+0xffffffff801bd24a [21.761] 01048.01051> [PASSED] (2 ms) [21.761] 01048.01051> detach_close_pager_test [RUNNING] [21.761] 01048.01051> [PASSED] (0 ms) [21.761] 01048.01051> close_port_test [RUNNING] [21.762] 01048.26277> PageFault: 1027345 free pages [21.762] 01048.26277> ZIRCON KERNEL PANIC [21.762] 01048.26277> Lock validation failed for thread 0xffffff8002fe65d0 pid 1048 tid 26277 (bin/core-tests:test_thread): [21.762] 01048.26277> Reason: Already Acquired [21.762] 01048.26277> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [21.762] 01048.26277> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [21.762] 01048.26277> caller=0xffffffff0014b65b frame=0xffffff9809a54d60 [21.762] 01048.26277> BUILDID git-808b66d9daaaacd45b64fe427fa28a1682088ff2-dirty [21.762] 01048.26277> dso: id=3776c8a9518e2b61 base=0xffffffff00100000 name=zircon.elf [21.762] 01048.26277> bt#00: 0xffffffff0014b65b [21.762] 01048.26277> bt#01: 0xffffffff0014ba8f [21.762] 01048.26277> bt#02: 0xffffffff0013732e [21.762] 01048.26277> bt#03: 0xffffffff001f6db4 [21.762] 01048.26277> bt#04: 0xffffffff001bd5fe [21.762] 01048.26277> bt#05: 0xffffffff001bd24b [21.762] 01048.26277> bt#06: end [21.762] 01048.26277> #0 0xffffffff0014b65a in <kernel>+0xffffffff8014b65a [21.762] 01048.26277> #1 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [21.762] 01048.26277> #2 0xffffffff0013732d in <kernel>+0xffffffff8013732d [21.762] 01048.26277> #3 0xffffffff001f6db3 in <kernel>+0xffffffff801f6db3 [21.762] 01048.26277> #4 0xffffffff001bd5fd in <kernel>+0xffffffff801bd5fd [21.762] 01048.26277> #5 0xffffffff001bd24a in <kernel>+0xffffffff801bd24a [21.762] 01048.26277> ZIRCON KERNEL PANIC [21.762] 01048.26277> Lock validation failed for thread 0xffffff8002fe65d0 pid 1048 tid 26277 (bin/core-tests:test_thread): [21.762] 01048.26277> Reason: Already Acquired [21.762] 01048.26277> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [21.762] 01048.26277> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [21.762] 01048.26277> caller=0xffffffff0014b65b frame=0xffffff9809a54cd0 [21.762] 01048.26277> BUILDID git-808b66d9daaaacd45b64fe427fa28a1682088ff2-dirty [21.762] 01048.26277> dso: id=3776c8a9518e2b61 base=0xffffffff00100000 name=zircon.elf [21.762] 01048.26277> bt#00: 0xffffffff0014b65b [21.762] 01048.26277> bt#01: 0xffffffff0014ba8f [21.762] 01048.26277> bt#02: 0xffffffff0014ba8f [21.762] 01048.26277> bt#03: 0xffffffff0013732e [21.762] 01048.26277> bt#04: 0xffffffff001f6db4 [21.762] 01048.26277> bt#05: 0xffffffff001bd5fe [21.762] 01048.26277> bt#06: 0xffffffff001bd24b [21.762] 01048.26277> bt#07: end [21.762] 01048.26277> #0 0xffffffff0014b65a in <kernel>+0xffffffff8014b65a [21.762] 01048.26277> #1 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [21.762] 01048.26277> #2 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [21.762] 01048.26277> #3 0xffffffff0013732d in <kernel>+0xffffffff8013732d [21.762] 01048.26277> #4 0xffffffff001f6db3 in <kernel>+0xffffffff801f6db3 [21.762] 01048.26277> #5 0xffffffff001bd5fd in <kernel>+0xffffffff801bd5fd [21.762] 01048.26277> #6 0xffffffff001bd24a in <kernel>+0xffffffff801bd24a [21.762] 01048.26277> ZIRCON KERNEL PANIC [21.762] 01048.26277> Lock validation failed for thread 0xffffff8002fe65d0 pid 1048 tid 26277 (bin/core-tests:test_thread): [21.762] 01048.26277> Reason: Already Acquired [21.762] 01048.26277> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [21.762] 01048.26277> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [21.762] 01048.26277> caller=0xffffffff0014b65b frame=0xffffff9809a54cd0 [21.762] 01048.26277> BUILDID git-808b66d9daaaacd45b64fe427fa28a1682088ff2-dirty [21.762] 01048.26277> dso: id=3776c8a9518e2b61 base=0xffffffff00100000 name=zircon.elf [21.762] 01048.26277> bt#00: 0xffffffff0014b65b [21.762] 01048.26277> bt#01: 0xffffffff0014ba8f [21.762] 01048.26277> bt#02: 0xffffffff0014ba8f [21.762] 01048.26277> bt#03: 0xffffffff0013732e [21.762] 01048.26277> bt#04: 0xffffffff001f6db4 [21.762] 01048.26277> bt#05: 0xffffffff001bd5fe [21.762] 01048.26277> bt#06: 0xffffffff001bd24b [21.762] 01048.26277> bt#07: end [21.762] 01048.26277> #0 0xffffffff0014b65a in <kernel>+0xffffffff8014b65a [21.762] 01048.26277> #1 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [21.762] 01048.26277> #2 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [21.762] 01048.26277> #3 0xffffffff0013732d in <kernel>+0xffffffff8013732d [21.762] 01048.26277> #4 0xffffffff001f6db3 in <kernel>+0xffffffff801f6db3 [21.762] 01048.26277> #5 0xffffffff001bd5fd in <kernel>+0xffffffff801bd5fd [21.762] 01048.26277> #6 0xffffffff001bd24a in <kernel>+0xffffffff801bd24a [21.765] 01048.01051> [PASSED] (4 ms) [21.766] 01048.01051> CASE lifecycle_tests [PASSED] CASE pager_read_tests [STARTED] [21.766] 01048.01051> single_page_test_vmar [RUNNING] [21.768] 01048.01051> [PASSED] (2 ms) [21.768] 01048.01051> single_page_test_vmo [RUNNING] [21.773] 01048.01051> [PASSED] (4 ms) [21.773] 01048.01051> presupply_test_vmar [RUNNING] [21.775] 01048.01051> [PASSED] (2 ms) [21.775] 01048.01051> presupply_test_vmo [RUNNING] [21.779] 01048.01051> [PASSED] (4 ms) [21.779] 01048.01051> early_supply_test_vmar [RUNNING] [21.788] 01048.01051> [PASSED] (9 ms) [21.788] 01048.01051> early_supply_test_vmo [RUNNING] [21.796] 01048.01051> [PASSED] (7 ms) [21.796] 01048.01051> sequential_multipage_test_vmar [RUNNING] [21.803] 01048.01051> [PASSED] (7 ms) [21.803] 01048.01051> sequential_multipage_test_vmo [RUNNING] [21.810] 01048.01051> [PASSED] (6 ms) [21.810] 01048.01051> concurrent_multipage_access_test_vmar [RUNNING] [21.814] 01048.01051> [PASSED] (3 ms) [21.814] 01048.01051> concurrent_multipage_access_test_vmo [RUNNING] [21.816] 01048.01051> [PASSED] (1 ms) [21.816] 01048.01051> concurrent_overlapping_access_test_vmar [RUNNING] [21.910] 01048.01051> [PASSED] (94 ms) [21.910] 01048.01051> concurrent_overlapping_access_test_vmo [RUNNING] [22.001] 01048.01051> [PASSED] (91 ms) [22.001] 01048.01051> bulk_single_supply_test_vmar [RUNNING] [22.025] 01048.01051> [PASSED] (23 ms) [22.025] 01048.01051> bulk_single_supply_test_vmo [RUNNING] [22.053] 01048.01051> [PASSED] (28 ms) [22.053] 01048.01051> bulk_odd_length_supply_test_vmar [RUNNING] [22.330] 01048.01051> [PASSED] (276 ms) [22.330] 01048.01051> bulk_odd_length_supply_test_vmo [RUNNING] [22.592] 01048.01051> [PASSED] (261 ms) [22.592] 01048.01051> bulk_odd_offset_supply_test_vmar [RUNNING] [22.841] 01048.01051> [PASSED] (249 ms) [22.841] 01048.01051> bulk_odd_offset_supply_test_vmo [RUNNING] [23.101] 01048.01051> [PASSED] (259 ms) [23.101] 01048.01051> overlap_supply_test_vmar [RUNNING] [23.105] 01048.01051> [PASSED] (4 ms) [23.105] 01048.01051> overlap_supply_test_vmo [RUNNING] [23.107] 01048.01051> [PASSED] (1 ms) [23.107] 01048.01051> many_request_test_vmar [RUNNING] [23.764] 01048.01051> [PASSED] (656 ms) [23.764] 01048.01051> many_request_test_vmo [RUNNING] [24.069] 01048.01051> [PASSED] (305 ms) [24.069] 01048.01051> successive_vmo_test [RUNNING] [24.106] 01048.01051> [PASSED] (36 ms) [24.106] 01048.01051> multiple_concurrent_vmo_test [RUNNING] [24.114] 01048.01051> [PASSED] (8 ms) [24.114] 01048.01051> vmar_unmap_test [RUNNING] [24.115] 01048.33998> PageFault: 1027342 free pages [24.115] 01048.33998> ZIRCON KERNEL PANIC [24.115] 01048.33998> Lock validation failed for thread 0xffffff8002fe6880 pid 1048 tid 33998 (bin/core-tests:test_thread): [24.115] 01048.33998> Reason: Already Acquired [24.115] 01048.33998> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [24.115] 01048.33998> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [24.115] 01048.33998> caller=0xffffffff0014b65b frame=0xffffff9809a54d60 [24.115] 01048.33998> BUILDID git-808b66d9daaaacd45b64fe427fa28a1682088ff2-dirty [24.115] 01048.33998> dso: id=3776c8a9518e2b61 base=0xffffffff00100000 name=zircon.elf [24.115] 01048.33998> bt#00: 0xffffffff0014b65b [24.115] 01048.33998> bt#01: 0xffffffff0014ba8f [24.115] 01048.33998> bt#02: 0xffffffff0013732e [24.115] 01048.33998> bt#03: 0xffffffff001f6db4 [24.115] 01048.33998> bt#04: 0xffffffff001bd5fe [24.115] 01048.33998> bt#05: 0xffffffff001bd24b [24.115] 01048.33998> bt#06: end [24.115] 01048.33998> #0 0xffffffff0014b65a in <kernel>+0xffffffff8014b65a [24.115] 01048.33998> #1 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [24.115] 01048.33998> #2 0xffffffff0013732d in <kernel>+0xffffffff8013732d [24.115] 01048.33998> #3 0xffffffff001f6db3 in <kernel>+0xffffffff801f6db3 [24.115] 01048.33998> #4 0xffffffff001bd5fd in <kernel>+0xffffffff801bd5fd [24.115] 01048.33998> #5 0xffffffff001bd24a in <kernel>+0xffffffff801bd24a [24.115] 01048.33998> ZIRCON KERNEL PANIC [24.115] 01048.33998> Lock validation failed for thread 0xffffff8002fe6880 pid 1048 tid 33998 (bin/core-tests:test_thread): [24.115] 01048.33998> Reason: Already Acquired [24.115] 01048.33998> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [24.115] 01048.33998> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [24.115] 01048.33998> caller=0xffffffff0014b65b frame=0xffffff9809a54cd0 [24.115] 01048.33998> BUILDID git-808b66d9daaaacd45b64fe427fa28a1682088ff2-dirty [24.115] 01048.33998> dso: id=3776c8a9518e2b61 base=0xffffffff00100000 name=zircon.elf [24.115] 01048.33998> bt#00: 0xffffffff0014b65b [24.115] 01048.33998> bt#01: 0xffffffff0014ba8f [24.115] 01048.33998> bt#02: 0xffffffff0014ba8f [24.115] 01048.33998> bt#03: 0xffffffff0013732e [24.115] 01048.33998> bt#04: 0xffffffff001f6db4 [24.115] 01048.33998> bt#05: 0xffffffff001bd5fe [24.115] 01048.33998> bt#06: 0xffffffff001bd24b [24.115] 01048.33998> bt#07: end [24.115] 01048.33998> #0 0xffffffff0014b65a in <kernel>+0xffffffff8014b65a [24.115] 01048.33998> #1 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [24.115] 01048.33998> #2 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [24.115] 01048.33998> #3 0xffffffff0013732d in <kernel>+0xffffffff8013732d [24.115] 01048.33998> #4 0xffffffff001f6db3 in <kernel>+0xffffffff801f6db3 [24.115] 01048.33998> #5 0xffffffff001bd5fd in <kernel>+0xffffffff801bd5fd [24.115] 01048.33998> #6 0xffffffff001bd24a in <kernel>+0xffffffff801bd24a [24.115] 01048.33998> ZIRCON KERNEL PANIC [24.115] 01048.33998> Lock validation failed for thread 0xffffff8002fe6880 pid 1048 tid 33998 (bin/core-tests:test_thread): [24.115] 01048.33998> Reason: Already Acquired [24.115] 01048.33998> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [24.115] 01048.33998> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [24.115] 01048.33998> caller=0xffffffff0014b65b frame=0xffffff9809a54cd0 [24.115] 01048.33998> BUILDID git-808b66d9daaaacd45b64fe427fa28a1682088ff2-dirty [24.115] 01048.33998> dso: id=3776c8a9518e2b61 base=0xffffffff00100000 name=zircon.elf [24.115] 01048.33998> bt#00: 0xffffffff0014b65b [24.115] 01048.33998> bt#01: 0xffffffff0014ba8f [24.115] 01048.33998> bt#02: 0xffffffff0014ba8f [24.115] 01048.33998> bt#03: 0xffffffff0013732e [24.115] 01048.33998> bt#04: 0xffffffff001f6db4 [24.115] 01048.33998> bt#05: 0xffffffff001bd5fe [24.115] 01048.33998> bt#06: 0xffffffff001bd24b [24.115] 01048.33998> bt#07: end [24.115] 01048.33998> #0 0xffffffff0014b65a in <kernel>+0xffffffff8014b65a [24.115] 01048.33998> #1 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [24.115] 01048.33998> #2 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [24.115] 01048.33998> #3 0xffffffff0013732d in <kernel>+0xffffffff8013732d [24.115] 01048.33998> #4 0xffffffff001f6db3 in <kernel>+0xffffffff801f6db3 [24.115] 01048.33998> #5 0xffffffff001bd5fd in <kernel>+0xffffffff801bd5fd [24.115] 01048.33998> #6 0xffffffff001bd24a in <kernel>+0xffffffff801bd24a [24.116] 01048.01051> [PASSED] (2 ms) [24.117] 01048.01051> vmar_remap_test [RUNNING] [24.135] 01048.01051> [PASSED] (18 ms) [24.135] 01048.01051> vmar_map_range_test [RUNNING] [24.144] 01048.01051> [PASSED] (9 ms) [24.144] 01048.01051> read_resize_test_vmar [RUNNING] [24.152] 01048.01051> [PASSED] (7 ms) [24.152] 01048.01051> read_resize_test_vmo [RUNNING] [24.156] 01048.01051> [PASSED] (3 ms) [24.156] 01048.01051> suspend_read_test_vmar [RUNNING] [24.160] 01048.01051> [PASSED] (4 ms) [24.160] 01048.01051> suspend_read_test_vmo [RUNNING] [24.161] 01048.01051> [PASSED] (1 ms) [24.161] 01048.01051> CASE pager_read_tests [PASSED] CASE object_get_child_tests [STARTED] [24.162] 01048.01051> handle_invalid [RUNNING] [24.162] 01048.01051> [PASSED] (0 ms) [24.162] 01048.01051> CASE object_get_child_tests [PASSED] CASE memory_mapping_tests [STARTED] [24.162] 01048.01051> address_space_limits_test [RUNNING] [24.162] 01048.01051> [PASSED] (0 ms) [24.162] 01048.01051> mmap_zerofilled_test [RUNNING] [24.162] 01048.01051> [PASSED] (0 ms) [24.162] 01048.01051> mmap_len_test [RUNNING] [24.162] 01048.01051> [PASSED] (0 ms) [24.162] 01048.01051> mmap_PROT_EXEC_test [RUNNING] [24.162] 01048.01051> [PASSED] (0 ms) [24.162] 01048.01051> mmap_offset_test [RUNNING] [24.162] 01048.01051> [PASSED] (0 ms) [24.162] 01048.01051> mmap_prot_test [RUNNING] [24.162] 01048.01051> [PASSED] (0 ms) [24.162] 01048.01051> mmap_flags_test [RUNNING] [24.162] 01048.01051> [PASSED] (0 ms) [24.162] 01048.01051> mprotect_test [RUNNING] [24.162] 01048.01051> ZIRCON KERNEL PANIC [24.162] 01048.01051> Lock validation failed for thread 0xffffff800074e978 pid 1048 tid 1051 (bin/core-tests:bin/core-tests): [24.162] 01048.01051> Reason: Already Acquired [24.162] 01048.01051> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [24.162] 01048.01051> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [24.162] 01048.01051> caller=0xffffffff0014b65b frame=0xffffff98069a7d50 [24.162] 01048.01051> BUILDID git-808b66d9daaaacd45b64fe427fa28a1682088ff2-dirty [24.162] 01048.01051> dso: id=3776c8a9518e2b61 base=0xffffffff00100000 name=zircon.elf [24.162] 01048.01051> bt#00: 0xffffffff0014b65b [24.162] 01048.01051> bt#01: 0xffffffff0014ba8f [24.162] 01048.01051> bt#02: 0xffffffff0014cc92 [24.162] 01048.01051> bt#03: 0xffffffff00140196 [24.162] 01048.01051> bt#04: 0xffffffff0015676b [24.163] 01048.01051> bt#05: 0xffffffff00146448 [24.163] 01048.01051> bt#06: 0xffffffff0020c34e [24.163] 01048.01051> bt#07: 0xffffffff0021bd2a [24.163] 01048.01051> bt#08: 0xffffffff001ce63b [24.163] 01048.01051> bt#09: end [24.163] 01048.01051> #0 0xffffffff0014b65a in <kernel>+0xffffffff8014b65a [24.163] 01048.01051> #1 0xffffffff0014ba8e in <kernel>+0xffffffff8014ba8e [24.163] 01048.01051> #2 0xffffffff0014cc91 in <kernel>+0xffffffff8014cc91 [24.163] 01048.01051> #3 0xffffffff00140195 in <kernel>+0xffffffff80140195 [24.163] 01048.01051> #4 0xffffffff0015676a in <kernel>+0xffffffff8015676a [24.163] 01048.01051> #5 0xffffffff00146447 in <kernel>+0xffffffff80146447 [24.163] 01048.01051> #6 0xffffffff0020c34d in <kernel>+0xffffffff8020c34d [24.163] 01048.01051> #7 0xffffffff0021bd29 in <kernel>+0xffffffff8021bd29 [24.163] 01048.01051> #8 0xffffffff001ce63a in <kernel>+0xffffffff801ce63a [24.163] 01048.01051> [PASSED] (0 ms) [24.163] 01048.01051> CASE memory_mapping_tests [PASSED] CASE job_tests [STARTED] [24.163] 01048.01051> basic_test [RUNNING] [24.163] 01048.01051> [PASSED] (0 ms) [24.163] 01048.01051> create_missing_rights_test [RUNNING] [24.163] 01048.01051> [PASSED] (0 ms) [24.163] 01048.01051> policy_invalid_topic_test [RUNNING] [24.163] 01048.01051> [PASSED] (0 ms) [24.163] 01048.01051> policy_basic_test [RUNNING] [24.163] 01048.01051> [PASSED] (0 ms) [24.163] 01048.01051> policy_timer_slack_invalid_options_test [RUNNING] [24.163] 01048.01051> [PASSED] (0 ms) [24.163] 01048.01051> policy_timer_slack_invalid_count_test [RUNNING] [24.163] 01048.01051> [PASSED] (0 ms) [24.163] 01048.01051> policy_timer_slack_invalid_policy_test [RUNNING] [24.163] 01048.01051> [PASSED] (0 ms) [24.163] 01048.01051> policy_timer_slack_non_empty_test [RUNNING] [24.163] 01048.01051> [PASSED] (0 ms) [24.163] 01048.01051> policy_timer_slack_valid [RUNNING] [24.163] 01048.01051> [PASSED] (0 ms) [24.163] 01048.01051> create_test [RUNNING] [24.163] 01048.01051> [PASSED] (0 ms) [24.163] 01048.01051> kill_test [RUNNING] [24.174] 01048.01051> [PASSED] (11 ms) [24.174] 01048.01051> kill_job_no_child_test [RUNNING] [24.174] 01048.01051> [PASSED] (0 ms) [24.174] 01048.01051> set_job_oom_kill_bit [RUNNING] [24.174] 01048.01051> [PASSED] (0 ms) [24.175] 01048.01051> wait_test [RUNNING] [24.183] 01048.01051> [PASSED] (8 ms) [24.183] 01048.01051> info_task_stats_fails [RUNNING] [24.183] 01048.01051> [PASSED] (0 ms) [24.183] 01048.01051> max_height_smoke [RUNNING] [24.183] 01048.01051> [PASSED] (0 ms) [24.183] 01048.01051> CASE job_tests [PASSED] CASE handle_wait_tests [STARTED] [24.183] 01048.01051> handle_wait_test [RUNNING] [24.185] 01048.01051> [PASSED] (1 ms) [24.185] 01048.01051> CASE handle_wait_tests [PASSED] CASE handle_transfer_tests [STARTED] [24.185] 01048.01051> handle_transfer_test [RUNNING] [24.185] 01048.01051> [PASSED] (0 ms) [24.185] 01048.01051> handle_transfer_cancel_wait_test [RUNNING] [24.201] 01048.01051> [PASSED] (16 ms) [24.201] 01048.01051> CASE handle_transfer_tests [PASSED] CASE eventpair_tests [STARTED] [24.201] 01048.01051> create_test [RUNNING] [24.201] 01048.01051> [PASSED] (0 ms) [24.201] 01048.01051> signal_test [RUNNING] [24.201] 01048.01051> [PASSED] (0 ms) [24.201] 01048.01051> signal_peer_test [RUNNING] [24.201] 01048.01051> [PASSED] (0 ms) [24.201] 01048.01051> signal_peer_closed_test [RUNNING] [24.201] 01048.01051> [PASSED] (0 ms) [24.201] 01048.01051> CASE eventpair_tests [PASSED] CASE elf_tls_tests [STARTED] [24.201] 01048.01051> ExecutableTlsTest [RUNNING] [25.138] 01048.01051> [PASSED] (936 ms) [25.139] 01048.01051> CASE elf_tls_tests [PASSED] CASE channel_tests [STARTED] [25.139] 01048.01051> channel_test [RUNNING] [25.140] 01048.01051> [PASSED] (1 ms) [25.158] 01048.01051> channel_read_error_test [RUNNING] [25.158] 01048.01051> [PASSED] (0 ms) [25.158] 01048.01051> channel_close_test [RUNNING] [25.158] 01048.01051> [PASSED] (0 ms) [25.158] 01048.01051> channel_peer_closed_test [RUNNING] [25.158] 01048.01051> [PASSED] (0 ms) [25.158] 01048.01051> channel_non_transferable [RUNNING] [25.158] 01048.01051> [PASSED] (0 ms) [25.158] 01048.01051> channel_duplicate_handles [RUNNING] [25.158] 01048.01051> [PASSED] (0 ms) [25.158] 01048.01051> channel_multithread_read [RUNNING] [25.362] 01048.01051> [PASSED] (203 ms) [25.362] 01048.01051> channel_may_discard [RUNNING] [25.362] 01048.01051> [PASSED] (0 ms) [25.362] 01048.01051> channel_call [RUNNING] [25.379] 01048.01051> [PASSED] (17 ms) [25.379] 01048.01051> channel_call_consumes_handles [RUNNING] [25.379] 01048.01051> [PASSED] (0 ms) [25.379] 01048.01051> channel_call2 [RUNNING] [25.644] 01048.01051> [PASSED] (264 ms) [25.644] 01048.01051> bad_channel_call_finish [RUNNING] [25.644] 01048.01051> [PASSED] (0 ms) [25.644] 01048.01051> channel_nest [RUNNING] [25.659] 01048.01051> [PASSED] (15 ms) [25.659] 01048.01051> channel_disallow_write_to_self [RUNNING] [25.659] 01048.01051> [PASSED] (0 ms) [25.659] 01048.01051> channel_read_etc [RUNNING] [25.659] 01048.01051> [PASSED] (0 ms) [25.659] 01048.01051> channel_write_different_sizes [RUNNING] [25.763] 01048.01051> [PASSED] (103 ms) [25.764] 01048.01051> channel_write_takes_all_handles [RUNNING] [25.772] 01048.01051> [PASSED] (8 ms) [25.772] 01048.01051> CASE channel_tests [PASSED] CASE c11_thread_tests [STARTED] [25.772] 01048.01051> c11_thread_test [RUNNING] [26.578] 01048.01051> [PASSED] (805 ms) [26.578] 01048.01051> long_name_succeeds [RUNNING] [26.678] 01048.01051> [PASSED] (100 ms) [26.678] 01048.01051> detach_self_test [RUNNING] [27.442] 01048.01051> [PASSED] (763 ms) [27.442] 01048.01051> CASE c11_thread_tests [PASSED] CASE mtx_tests [STARTED] [27.442] 01048.01051> test_initializer [RUNNING] [27.442] 01048.01051> [PASSED] (0 ms) [27.442] 01048.01051> test_mutexes [RUNNING] [27.545] 01048.01051> [PASSED] (102 ms) [27.545] 01048.01051> test_try_mutexes [RUNNING] [27.637] 01048.01051> [PASSED] (91 ms) [27.637] 01048.01051> test_static_initializer [RUNNING] [27.637] 01048.01051> [PASSED] (0 ms) [27.637] 01048.01051> test_timeout_elapsed [RUNNING] [28.154] 01048.01051> [PASSED] (516 ms) [28.154] 01048.01051> CASE mtx_tests [PASSED] CASE cnd_tests [STARTED] [28.154] 01048.01051> cnd_test [RUNNING] [28.174] 01048.01051> [PASSED] (20 ms) [28.174] 01048.01051> cnd_timedwait_timeout_test [RUNNING] [28.175] 01048.01051> [PASSED] (1 ms) [28.175] 01048.01051> CASE cnd_tests [PASSED] CASE bad_syscall_tests [STARTED] [28.175] 01048.01051> bad_access_test [RUNNING] [28.175] 01048.01051> [PASSED] (0 ms) [28.175] 01048.01051> bad_syscall_num_test [RUNNING] [28.208] 01048.01051> [PASSED] (33 ms) [28.208] 01048.01051> CASE bad_syscall_tests [PASSED] [28.208] 01048.01051> ==================================================== [28.208] 01048.01051> Results: [28.208] 01048.01051> SUCCESS! All test cases passed! [28.208] 01048.01051> CASES: 34 SUCCESS: 34 FAILED: 0 [28.208] 01048.01051> ==================================================== [28.209] 01048.01051> Flags: [28.209] 01048.01051> --gtest_random_seed = 28 [28.209] 01048.01051> [==========] Running 240 tests from 12 test cases [28.209] 01048.01051> . [28.209] 01048.01051> [----------] Global test environment set-up. [28.209] 01048.01051> [----------] 1 test from ClockTest [28.209] 01048.01051> [ RUN ] ClockTest.ClockMonotonic [28.325] 01048.01051> [ OK ] ClockTest.ClockMonotonic (116 [28.325] 01048.01051> ms) [28.325] 01048.01051> [----------] 1 test from ClockTest (116 [28.325] 01048.01051> ms total) [28.325] 01048.01051> [----------] 9 tests from FifoTest [28.325] 01048.01051> [ RUN ] FifoTest.InvalidParametersReturnOutOfRange [28.325] 01048.01051> [ OK ] FifoTest.InvalidParametersReturnOutOfRange (0 [28.325] 01048.01051> ms) [28.325] 01048.01051> [ RUN ] FifoTest.EndpointsAreRelated [28.325] 01048.01051> [ OK ] FifoTest.EndpointsAreRelated (0 [28.325] 01048.01051> ms) [28.325] 01048.01051> [ RUN ] FifoTest.EmptyQueueReturnsErrShouldWait [28.325] 01048.01051> [ OK ] FifoTest.EmptyQueueReturnsErrShouldWait (0 [28.325] 01048.01051> ms) [28.325] 01048.01051> [ RUN ] FifoTest.ReadAndWriteValidatesSizeAndElementCount [28.325] 01048.01051> [ OK ] FifoTest.ReadAndWriteValidatesSizeAndElementCount (0 [28.325] 01048.01051> ms) [28.325] 01048.01051> [ RUN ] FifoTest.DequeueSignalsWriteable [28.325] 01048.01051> [ OK ] FifoTest.DequeueSignalsWriteable (0 [28.325] 01048.01051> ms) [28.325] 01048.01051> [ RUN ] FifoTest.FifoOrderIsPreserved [28.325] 01048.01051> [ OK ] FifoTest.FifoOrderIsPreserved (0 [28.325] 01048.01051> ms) [28.325] 01048.01051> [ RUN ] FifoTest.PartialWriteQueuesElementsThatFit [28.326] 01048.01051> [ OK ] FifoTest.PartialWriteQueuesElementsThatFit (0 [28.326] 01048.01051> ms) [28.326] 01048.01051> [ RUN ] FifoTest.IndividualReadsPreserveOrder [28.326] 01048.01051> [ OK ] FifoTest.IndividualReadsPreserveOrder (0 [28.326] 01048.01051> ms) [28.326] 01048.01051> [ RUN ] FifoTest.EndpointCloseSignalsPeerClosed [28.326] 01048.01051> [ OK ] FifoTest.EndpointCloseSignalsPeerClosed (0 [28.326] 01048.01051> ms) [28.326] 01048.01051> [----------] 9 tests from FifoTest (0 [28.326] 01048.01051> ms total) [28.326] 01048.01051> [----------] 15 tests from FutexTest [28.326] 01048.01051> [ RUN ] FutexTest.WaitValueMismatch [28.326] 01048.01051> [ OK ] FutexTest.WaitValueMismatch (0 [28.326] 01048.01051> ms) [28.326] 01048.01051> [ RUN ] FutexTest.WaitTimeout [28.326] 01048.01051> [ OK ] FutexTest.WaitTimeout (0 [28.326] 01048.01051> ms) [28.326] 01048.01051> [ RUN ] FutexTest.WaitTimeoutElapsed [28.827] 01048.01051> [ OK ] FutexTest.WaitTimeoutElapsed (501 [28.827] 01048.01051> ms) [28.827] 01048.01051> [ RUN ] FutexTest.WaitBadAddress [28.827] 01048.01051> [ OK ] FutexTest.WaitBadAddress (0 [28.827] 01048.01051> ms) [28.827] 01048.01051> [ RUN ] FutexTest.Wakeup [28.833] 01048.01051> [ OK ] FutexTest.Wakeup (6 [28.834] 01048.01051> ms) [28.834] 01048.01051> [ RUN ] FutexTest.WakeupLimit [29.463] 01048.01051> [ OK ] FutexTest.WakeupLimit (629 [29.463] 01048.01051> ms) [29.463] 01048.01051> [ RUN ] FutexTest.WakeupAddress [29.475] 01048.01051> [ OK ] FutexTest.WakeupAddress (11 [29.475] 01048.01051> ms) [29.475] 01048.01051> [ RUN ] FutexTest.RequeueValueMismatch [29.475] 01048.01051> [ OK ] FutexTest.RequeueValueMismatch (0 [29.475] 01048.01051> ms) [29.475] 01048.01051> [ RUN ] FutexTest.RequeueSameAddr [29.475] 01048.01051> [ OK ] FutexTest.RequeueSameAddr (0 [29.476] 01048.01051> ms) [29.476] 01048.01051> [ RUN ] FutexTest.Requeue [30.413] 01048.01051> [ OK ] FutexTest.Requeue (937 [30.413] 01048.01051> ms) [30.413] 01048.01051> [ RUN ] FutexTest.RequeueUnqueuedOnTimeout [30.715] 01048.01051> [ OK ] FutexTest.RequeueUnqueuedOnTimeout (301 [30.715] 01048.01051> ms) [30.715] 01048.01051> [ RUN ] FutexTest.ThreadKilled [30.715] 01048.01051> [ OK ] FutexTest.ThreadKilled (0 [30.715] 01048.01051> ms) [30.715] 01048.01051> [ RUN ] FutexTest.ThreadSuspended [31.017] 01048.01051> [ OK ] FutexTest.ThreadSuspended (301 [31.017] 01048.01051> ms) [31.017] 01048.01051> [ RUN ] FutexTest.MisalignedFutextAddr [31.017] 01048.01051> [ OK ] FutexTest.MisalignedFutextAddr (0 [31.017] 01048.01051> ms) [31.017] 01048.01051> [ RUN ] FutexTest.EventSignaling [31.017] 01048.01051> [00000031.17361226]: starting signal threads [31.017] 01048.106535> [00000031.17655094]: thread 1 waiting on event [31.018] 01048.106540> [00000031.17943644]: thread 2 waiting on event [31.053] 01048.106545> [00000031.52993664]: thread 3 waiting on event [31.053] 01048.01051> [00000031.53225854]: signaling event [31.053] 01048.01051> [00000031.53406541]: joining signal threads [31.053] 01048.106540> [00000031.53545999]: thread 2 done [31.053] 01048.106535> [00000031.53674283]: thread 1 done [31.054] 01048.01051> [00000031.54089055]: signal_thread 1 joined [31.054] 01048.01051> [00000031.54262032]: signal_thread 2 joined [31.054] 01048.106545> [00000031.54366928]: thread 3 done [31.054] 01048.01051> [00000031.54483262]: signal_thread 3 joined [31.054] 01048.01051> [ OK ] FutexTest.EventSignaling (37 [31.054] 01048.01051> ms) [31.054] 01048.01051> [----------] 15 tests from FutexTest (2728 [31.054] 01048.01051> ms total) [31.054] 01048.01051> [----------] 3 tests from HandleCloseTest [31.054] 01048.01051> [ RUN ] HandleCloseTest.Many [31.054] 01048.01051> [ OK ] HandleCloseTest.Many (0 [31.054] 01048.01051> ms) [31.054] 01048.01051> [ RUN ] HandleCloseTest.ManyInvalidHandlesShouldNotFail [31.054] 01048.01051> [ OK ] HandleCloseTest.ManyInvalidHandlesShouldNotFail (0 [31.054] 01048.01051> ms) [31.054] 01048.01051> [ RUN ] HandleCloseTest.ManyDuplicateTest [31.054] 01048.01051> [ OK ] HandleCloseTest.ManyDuplicateTest (0 [31.054] 01048.01051> ms) [31.054] 01048.01051> [----------] 3 tests from HandleCloseTest (0 [31.054] 01048.01051> ms total) [31.054] 01048.01051> [----------] 6 tests from HandleInfoTest [31.054] 01048.01051> [ RUN ] HandleInfoTest.ReplaceSuccessOrigInvalid [31.054] 01048.01051> [ OK ] HandleInfoTest.ReplaceSuccessOrigInvalid (0 [31.054] 01048.01051> ms) [31.054] 01048.01051> [ RUN ] HandleInfoTest.ReplaceFailureBothInvalid [31.054] 01048.01051> [ OK ] HandleInfoTest.ReplaceFailureBothInvalid (0 [31.054] 01048.01051> ms) [31.054] 01048.01051> [ RUN ] HandleInfoTest.DupAndInfoRights [31.054] 01048.01051> [ OK ] HandleInfoTest.DupAndInfoRights (0 [31.054] 01048.01051> ms) [31.054] 01048.01051> [ RUN ] HandleInfoTest.RelatedKoid [31.193] 01048.01051> [ OK ] HandleInfoTest.RelatedKoid (138 [31.193] 01048.01051> ms) [31.193] 01048.01051> [ RUN ] HandleInfoTest.DuplicateRights [31.193] 01048.01051> [ OK ] HandleInfoTest.DuplicateRights (0 [31.193] 01048.01051> ms) [31.193] 01048.01051> [ RUN ] HandleInfoTest.ReplaceRights [31.193] 01048.01051> [ OK ] HandleInfoTest.ReplaceRights (0 [31.193] 01048.01051> ms) [31.193] 01048.01051> [----------] 6 tests from HandleInfoTest (139 [31.193] 01048.01051> ms total) [31.193] 01048.01051> [----------] 4 tests from ObjectGetInfoTest [31.193] 01048.01051> [ RUN ] ObjectGetInfoTest.OpenValidHandleSuceeds [31.193] 01048.01051> [ OK ] ObjectGetInfoTest.OpenValidHandleSuceeds (0 [31.193] 01048.01051> ms) [31.193] 01048.01051> [ RUN ] ObjectGetInfoTest.ClosedValidHandleFails [31.193] 01048.01051> [ OK ] ObjectGetInfoTest.ClosedValidHandleFails (0 [31.193] 01048.01051> ms) [31.193] 01048.01051> [ RUN ] ObjectGetInfoTest.HandleCountCorrectness [31.193] 01048.01051> [ OK ] ObjectGetInfoTest.HandleCountCorrectness (0 [31.193] 01048.01051> ms) [31.193] 01048.01051> [ RUN ] ObjectGetInfoTest.InvalidHandleFails [31.193] 01048.01051> [ OK ] ObjectGetInfoTest.InvalidHandleFails (0 [31.193] 01048.01051> ms) [31.193] 01048.01051> [----------] 4 tests from ObjectGetInfoTest (0 [31.193] 01048.01051> ms total) [31.193] 01048.01051> [----------] 39 tests from JobGetInfoTest [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoJobProcessesGetChild [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoJobProcessesGetChild (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoJobChildJobsGetChild [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoJobChildJobsGetChild (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoJobProcessesOnSelfSuceeds [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoJobProcessesOnSelfSuceeds (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoJobProcessesInvalidHandleFails [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoJobProcessesInvalidHandleFails (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoJobProcessesNullAvailSucceeds [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoJobProcessesNullAvailSucceeds (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoJobProcessesNullActualSucceeds [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoJobProcessesNullActualSucceeds (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoJobProcessesNullActualAndAvailSucceeds [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoJobProcessesNullActualAndAvailSucceeds (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoJobProcessesInvalidBufferPointerFails [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoJobProcessesInvalidBufferPointerFails (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoJobProcessesBadActualgIsInvalidArg [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoJobProcessesBadActualgIsInvalidArg (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoJobProcessesBadAvailIsInvalidArg [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoJobProcessesBadAvailIsInvalidArg (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoJobProcessesZeroSizedBufferIsOk [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoJobProcessesZeroSizedBufferIsOk (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoJobProcessesSmallBufferIsOk [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoJobProcessesSmallBufferIsOk (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoJobProcessesPartiallyUnmappedBufferIsInvalidArgs [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoJobProcessesPartiallyUnmappedBufferIsInvalidArgs (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoJobProcessesRequiresEnumerateRights [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoJobProcessesRequiresEnumerateRights (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoJobProcessesProcessbHandleIsBadHandle [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoJobProcessesProcessbHandleIsBadHandle (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoJobProcessesThreadHandleIsBadHandle [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoJobProcessesThreadHandleIsBadHandle (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoJobChildrenOnSelfSuceeds [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoJobChildrenOnSelfSuceeds (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoJobChildrenInvalidHandleFails [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoJobChildrenInvalidHandleFails (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoJobChildrenNullAvailSucceeds [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoJobChildrenNullAvailSucceeds (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoJobChildrenNullActualSucceeds [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoJobChildrenNullActualSucceeds (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoJobChildrenNullActualAndAvailSucceeds [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoJobChildrenNullActualAndAvailSucceeds (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoJobChildrenInvalidBufferPointerFails [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoJobChildrenInvalidBufferPointerFails (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoJobChildrenBadActualgIsInvalidArg [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoJobChildrenBadActualgIsInvalidArg (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoJobChildrenBadAvailIsInvalidArg [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoJobChildrenBadAvailIsInvalidArg (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoJobChildrenZeroSizedBufferIsOk [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoJobChildrenZeroSizedBufferIsOk (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoJobChildrenSmallBufferIsOk [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoJobChildrenSmallBufferIsOk (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoJobChildrenPartiallyUnmappedBufferIsInvalidArgs [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoJobChildrenPartiallyUnmappedBufferIsInvalidArgs (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoJobChildrenRequiresEnumerateRights [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoJobChildrenRequiresEnumerateRights (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoJobChildrenJobHandleIsBadHandle [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoJobChildrenJobHandleIsBadHandle (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoJobChildrenThreadHandleIsBadHandle [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoJobChildrenThreadHandleIsBadHandle (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoHandleBasicOnSelfSuceeds [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoHandleBasicOnSelfSuceeds (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoHandleBasicInvalidHandleFails [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoHandleBasicInvalidHandleFails (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoHandleBasicNullAvailSucceeds [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoHandleBasicNullAvailSucceeds (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoHandleBasicNullActualSucceeds [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoHandleBasicNullActualSucceeds (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoHandleBasicNullActualAndAvailSucceeds [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoHandleBasicNullActualAndAvailSucceeds (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoHandleBasicInvalidBufferPointerFails [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoHandleBasicInvalidBufferPointerFails (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoHandleBasicBadActualgIsInvalidArg [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoHandleBasicBadActualgIsInvalidArg (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoHandleBasicBadAvailIsInvalidArg [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoHandleBasicBadAvailIsInvalidArg (0 [31.194] 01048.01051> ms) [31.194] 01048.01051> [ RUN ] JobGetInfoTest.InfoHandleBasicZeroSizedBufferFails [31.194] 01048.01051> [ OK ] JobGetInfoTest.InfoHandleBasicZeroSizedBufferFails (0 [31.194] 01048.01051> ms) [31.195] 01048.01051> [----------] 39 tests from JobGetInfoTest (1 [31.195] 01048.01051> ms total) [31.195] 01048.01051> [----------] 61 tests from ProcessGetInfoTest [31.195] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessMapsUnstartedSuceeds [31.195] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessMapsUnstartedSuceeds (0 [31.195] 01048.01051> ms) [31.195] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessMapsSmokeTest [31.195] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessMapsSmokeTest (0 [31.195] 01048.01051> ms) [31.195] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessHandleStats [31.195] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessHandleStats (0 [31.195] 01048.01051> ms) [31.195] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessMapsOnSelfFails [31.195] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessMapsOnSelfFails (0 [31.195] 01048.01051> ms) [31.195] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessMapsInvalidHandleFails [31.195] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessMapsInvalidHandleFails (0 [31.195] 01048.01051> ms) [31.195] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessMapsNullAvailSucceeds [31.195] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessMapsNullAvailSucceeds (0 [31.195] 01048.01051> ms) [31.195] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessMapsNullActualSucceeds [31.195] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessMapsNullActualSucceeds (0 [31.195] 01048.01051> ms) [31.195] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessMapsNullActualAndAvailSucceeds [31.195] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessMapsNullActualAndAvailSucceeds (0 [31.195] 01048.01051> ms) [31.195] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessMapsInvalidBufferPointerFails [31.195] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessMapsInvalidBufferPointerFails (0 [31.195] 01048.01051> ms) [31.195] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessMapsBadActualgIsInvalidArg [31.195] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessMapsBadActualgIsInvalidArg (0 [31.195] 01048.01051> ms) [31.195] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessMapsBadAvailIsInvalidArg [31.195] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessMapsBadAvailIsInvalidArg (0 [31.195] 01048.01051> ms) [31.195] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessMapsZeroSizedBufferIsOk [31.195] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessMapsZeroSizedBufferIsOk (0 [31.195] 01048.01051> ms) [31.195] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessMapsSmallBufferIsOk [31.195] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessMapsSmallBufferIsOk (0 [31.195] 01048.01051> ms) [31.195] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessMapsPartiallyUnmappedBufferIsInvalidArgs [31.195] 01048.01051> ZIRCON KERNEL PANIC [31.196] 01048.01051> Lock validation failed for thread 0xffffff800074e978 pid 1048 tid 1051 (bin/core-tests:bin/core-tests): [31.196] 01048.01051> Reason: Already Acquired [31.196] 01048.01051> Bad lock: name=lockdep::LockClass<VmAspace, Mutex, 199, lockdep::LockFlagsNone> order=0 [31.196] 01048.01051> Conflict: name=lockdep::LockClass<VmAspace, Mutex, 199, lockdep::LockFlagsNone> order=0 [31.196] 01048.01051> caller=0xffffffff001e5e5c frame=0xffffff98069a7bd0 [31.196] 01048.01051> BUILDID git-808b66d9daaaacd45b64fe427fa28a1682088ff2-dirty [31.196] 01048.01051> dso: id=3776c8a9518e2b61 base=0xffffffff00100000 name=zircon.elf [31.196] 01048.01051> bt#00: 0xffffffff001e5e5c [31.196] 01048.01051> bt#01: 0xffffffff001f6d82 [31.196] 01048.01051> bt#02: 0xffffffff001bd5fe [31.196] 01048.01051> bt#03: 0xffffffff001bd24b [31.196] 01048.01051> bt#04: 0xffffffff00138195 [31.196] 01048.01051> bt#05: 0xffffffff001e615c [31.196] 01048.01051> bt#06: 0xffffffff00136c1f [31.196] 01048.01051> bt#07: 0xffffffff0015ba68 [31.196] 01048.01051> bt#08: 0xffffffff0020dcac [31.196] 01048.01051> bt#09: 0xffffffff0021d048 [31.196] 01048.01051> bt#10: 0xffffffff001ce708 [31.196] 01048.01051> bt#11: end [31.196] 01048.01051> #0 0xffffffff001e5e5b in <kernel>+0xffffffff801e5e5b [31.196] 01048.01051> #1 0xffffffff001f6d81 in <kernel>+0xffffffff801f6d81 [31.196] 01048.01051> #2 0xffffffff001bd5fd in <kernel>+0xffffffff801bd5fd [31.196] 01048.01051> #3 0xffffffff001bd24a in <kernel>+0xffffffff801bd24a [31.196] 01048.01051> #4 0xffffffff00138194 in <kernel>+0xffffffff80138194 [31.196] 01048.01051> #5 0xffffffff001e615b in <kernel>+0xffffffff801e615b [31.196] 01048.01051> #6 0xffffffff00136c1e in <kernel>+0xffffffff80136c1e [31.196] 01048.01051> #7 0xffffffff0015ba67 in <kernel>+0xffffffff8015ba67 [31.196] 01048.01051> #8 0xffffffff0020dcab in <kernel>+0xffffffff8020dcab [31.196] 01048.01051> #9 0xffffffff0021d047 in <kernel>+0xffffffff8021d047 [31.196] 01048.01051> #10 0xffffffff001ce707 in <kernel>+0xffffffff801ce707 [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessMapsPartiallyUnmappedBufferIsInvalidArgs (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessMapsRequiresInspectRights [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessMapsRequiresInspectRights (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessMapsJobHandleIsBadHandle [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessMapsJobHandleIsBadHandle (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessMapsThreadHandleIsBadHandle [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessMapsThreadHandleIsBadHandle (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessVmosSmokeTest [31.196] 01048.01051> ZIRCON KERNEL PANIC [31.196] 01048.01051> Lock validation failed for thread 0xffffff800074e978 pid 1048 tid 1051 (bin/core-tests:bin/core-tests): [31.196] 01048.01051> Reason: Out Of Order [31.196] 01048.01051> Bad lock: name=lockdep::LockClass<ProcessDispatcher, BrwLock, 360, lockdep::LockFlagsNone> order=0 [31.196] 01048.01051> Conflict: name=lockdep::LockClass<SoloDispatcher<ProcessDispatcher, 447439, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [31.196] 01048.01051> caller=0xffffffff00136f06 frame=0xffffff98069a7de0 [31.196] 01048.01051> BUILDID git-808b66d9daaaacd45b64fe427fa28a1682088ff2-dirty [31.196] 01048.01051> dso: id=3776c8a9518e2b61 base=0xffffffff00100000 name=zircon.elf [31.196] 01048.01051> bt#00: 0xffffffff00136f06 [31.196] 01048.01051> bt#01: 0xffffffff0015bc2d [31.196] 01048.01051> bt#02: 0xffffffff0020df88 [31.196] 01048.01051> bt#03: 0xffffffff0021d048 [31.196] 01048.01051> bt#04: 0xffffffff001ce708 [31.196] 01048.01051> bt#05: end [31.196] 01048.01051> #0 0xffffffff00136f05 in <kernel>+0xffffffff80136f05 [31.196] 01048.01051> #1 0xffffffff0015bc2c in <kernel>+0xffffffff8015bc2c [31.196] 01048.01051> #2 0xffffffff0020df87 in <kernel>+0xffffffff8020df87 [31.196] 01048.01051> #3 0xffffffff0021d047 in <kernel>+0xffffffff8021d047 [31.196] 01048.01051> #4 0xffffffff001ce707 in <kernel>+0xffffffff801ce707 [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessVmosSmokeTest (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessVmosOnSelfFails [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessVmosOnSelfFails (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessVmosInvalidHandleFails [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessVmosInvalidHandleFails (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessVmosNullAvailSucceeds [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessVmosNullAvailSucceeds (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessVmosNullActualSucceeds [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessVmosNullActualSucceeds (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessVmosNullActualAndAvailSucceeds [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessVmosNullActualAndAvailSucceeds (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessVmosInvalidBufferPointerFails [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessVmosInvalidBufferPointerFails (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessVmosBadActualgIsInvalidArg [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessVmosBadActualgIsInvalidArg (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessVmosBadAvailIsInvalidArg [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessVmosBadAvailIsInvalidArg (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessVmosZeroSizedBufferIsOk [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessVmosZeroSizedBufferIsOk (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessVmosSmallBufferIsOk [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessVmosSmallBufferIsOk (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessVmosPartiallyUnmappedBufferIsInvalidArgs [31.196] 01048.01051> ZIRCON KERNEL PANIC [31.196] 01048.01051> Lock validation failed for thread 0xffffff800074e978 pid 1048 tid 1051 (bin/core-tests:bin/core-tests): [31.196] 01048.01051> Reason: Already Acquired [31.196] 01048.01051> Bad lock: name=lockdep::LockClass<VmAspace, Mutex, 199, lockdep::LockFlagsNone> order=0 [31.196] 01048.01051> Conflict: name=lockdep::LockClass<VmAspace, Mutex, 199, lockdep::LockFlagsNone> order=0 [31.196] 01048.01051> caller=0xffffffff001e5e5c frame=0xffffff98069a7b50 [31.196] 01048.01051> BUILDID git-808b66d9daaaacd45b64fe427fa28a1682088ff2-dirty [31.196] 01048.01051> dso: id=3776c8a9518e2b61 base=0xffffffff00100000 name=zircon.elf [31.196] 01048.01051> bt#00: 0xffffffff001e5e5c [31.196] 01048.01051> bt#01: 0xffffffff001f6d82 [31.196] 01048.01051> bt#02: 0xffffffff001bd5fe [31.196] 01048.01051> bt#03: 0xffffffff001bd24b [31.196] 01048.01051> bt#04: 0xffffffff001384ac [31.196] 01048.01051> bt#05: 0xffffffff001dffe8 [31.196] 01048.01051> bt#06: 0xffffffff001e6176 [31.196] 01048.01051> bt#07: 0xffffffff00136d67 [31.196] 01048.01051> bt#08: 0xffffffff0015bcb3 [31.196] 01048.01051> bt#09: 0xffffffff0020df88 [31.196] 01048.01051> bt#10: 0xffffffff0021d048 [31.196] 01048.01051> bt#11: 0xffffffff001ce708 [31.196] 01048.01051> bt#12: end [31.196] 01048.01051> #0 0xffffffff001e5e5b in <kernel>+0xffffffff801e5e5b [31.196] 01048.01051> #1 0xffffffff001f6d81 in <kernel>+0xffffffff801f6d81 [31.196] 01048.01051> #2 0xffffffff001bd5fd in <kernel>+0xffffffff801bd5fd [31.196] 01048.01051> #3 0xffffffff001bd24a in <kernel>+0xffffffff801bd24a [31.196] 01048.01051> #4 0xffffffff001384ab in <kernel>+0xffffffff801384ab [31.196] 01048.01051> #5 0xffffffff001dffe7 in <kernel>+0xffffffff801dffe7 [31.196] 01048.01051> #6 0xffffffff001e6175 in <kernel>+0xffffffff801e6175 [31.196] 01048.01051> #7 0xffffffff00136d66 in <kernel>+0xffffffff80136d66 [31.196] 01048.01051> #8 0xffffffff0015bcb2 in <kernel>+0xffffffff8015bcb2 [31.196] 01048.01051> #9 0xffffffff0020df87 in <kernel>+0xffffffff8020df87 [31.196] 01048.01051> #10 0xffffffff0021d047 in <kernel>+0xffffffff8021d047 [31.196] 01048.01051> #11 0xffffffff001ce707 in <kernel>+0xffffffff801ce707 [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessVmosPartiallyUnmappedBufferIsInvalidArgs (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessVmosRequiresInspectRights [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessVmosRequiresInspectRights (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessVmosJobHandleIsBadHandle [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessVmosJobHandleIsBadHandle (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessVmosThreadHandleIsBadHandle [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessVmosThreadHandleIsBadHandle (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoHandleBasicOnSelfSuceeds [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoHandleBasicOnSelfSuceeds (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoHandleBasicInvalidHandleFails [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoHandleBasicInvalidHandleFails (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoHandleBasicNullAvailSucceeds [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoHandleBasicNullAvailSucceeds (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoHandleBasicNullActualSucceeds [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoHandleBasicNullActualSucceeds (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoHandleBasicNullActualAndAvailSucceeds [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoHandleBasicNullActualAndAvailSucceeds (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoHandleBasicInvalidBufferPointerFails [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoHandleBasicInvalidBufferPointerFails (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoHandleBasicBadActualgIsInvalidArg [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoHandleBasicBadActualgIsInvalidArg (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoHandleBasicBadAvailIsInvalidArg [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoHandleBasicBadAvailIsInvalidArg (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoHandleBasicZeroSizedFails [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoHandleBasicZeroSizedFails (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessOnSelfSuceeds [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessOnSelfSuceeds (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessInvalidHandleFails [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessInvalidHandleFails (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessNullAvailSucceeds [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessNullAvailSucceeds (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessNullActualSucceeds [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessNullActualSucceeds (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessNullActualAndAvailSucceeds [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessNullActualAndAvailSucceeds (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessInvalidBufferPointerFails [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessInvalidBufferPointerFails (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessBadActualgIsInvalidArg [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessBadActualgIsInvalidArg (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessBadAvailIsInvalidArg [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessBadAvailIsInvalidArg (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessZeroSizedBufferFails [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessZeroSizedBufferFails (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessJobHandleIsBadHandle [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessJobHandleIsBadHandle (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessThreadHandleIsBadHandle [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessThreadHandleIsBadHandle (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessThreadsSelfSuceeds [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessThreadsSelfSuceeds (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessThreadsInvalidHandleFails [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessThreadsInvalidHandleFails (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessThreadsNullAvailSucceeds [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessThreadsNullAvailSucceeds (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessThreadsNullActualSucceeds [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessThreadsNullActualSucceeds (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessThreadsNullActualAndAvailSucceeds [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessThreadsNullActualAndAvailSucceeds (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessThreadsInvalidBufferPointerFails [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessThreadsInvalidBufferPointerFails (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessThreadsBadActualgIsInvalidArg [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessThreadsBadActualgIsInvalidArg (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessThreadsBadAvailIsInvalidArg [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessThreadsBadAvailIsInvalidArg (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] ProcessGetInfoTest.InfoProcessThreadsZeroSizedBufferSucceeds [31.196] 01048.01051> [ OK ] ProcessGetInfoTest.InfoProcessThreadsZeroSizedBufferSucceeds (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [----------] 61 tests from ProcessGetInfoTest (1 [31.196] 01048.01051> ms total) [31.196] 01048.01051> [----------] 12 tests from TaskGetInfoTest [31.196] 01048.01051> [ RUN ] TaskGetInfoTest.InfoStatsUnstartedSuceeds [31.196] 01048.01051> [ OK ] TaskGetInfoTest.InfoStatsUnstartedSuceeds (0 [31.196] 01048.01051> ms) [31.196] 01048.01051> [ RUN ] TaskGetInfoTest.InfoStatsSmokeTest [31.197] 01048.01051> [ OK ] TaskGetInfoTest.InfoStatsSmokeTest (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] TaskGetInfoTest.InfoTaskStatsInvalidHandleFails [31.197] 01048.01051> [ OK ] TaskGetInfoTest.InfoTaskStatsInvalidHandleFails (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] TaskGetInfoTest.InfoTaskStatsNullAvailSucceeds [31.197] 01048.01051> [ OK ] TaskGetInfoTest.InfoTaskStatsNullAvailSucceeds (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] TaskGetInfoTest.InfoTaskStatsNullActualSucceeds [31.197] 01048.01051> [ OK ] TaskGetInfoTest.InfoTaskStatsNullActualSucceeds (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] TaskGetInfoTest.InfoTaskStatsNullActualAndAvailSucceeds [31.197] 01048.01051> [ OK ] TaskGetInfoTest.InfoTaskStatsNullActualAndAvailSucceeds (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] TaskGetInfoTest.InfoTaskStatsInvalidBufferPointerFails [31.197] 01048.01051> [ OK ] TaskGetInfoTest.InfoTaskStatsInvalidBufferPointerFails (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] TaskGetInfoTest.InfoTaskStatsBadActualgIsInvalidArg [31.197] 01048.01051> [ OK ] TaskGetInfoTest.InfoTaskStatsBadActualgIsInvalidArg (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] TaskGetInfoTest.InfoTaskStatsBadAvailIsInvalidArg [31.197] 01048.01051> [ OK ] TaskGetInfoTest.InfoTaskStatsBadAvailIsInvalidArg (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] TaskGetInfoTest.InfoTaskStatsZeroSizedBufferIsTooSmall [31.197] 01048.01051> [ OK ] TaskGetInfoTest.InfoTaskStatsZeroSizedBufferIsTooSmall (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] TaskGetInfoTest.InfoTaskStatsJobHandleIsBadHandle [31.197] 01048.01051> [ OK ] TaskGetInfoTest.InfoTaskStatsJobHandleIsBadHandle (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] TaskGetInfoTest.InfoTaskStatsThreadHandleIsBadHandle [31.197] 01048.01051> [ OK ] TaskGetInfoTest.InfoTaskStatsThreadHandleIsBadHandle (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [----------] 12 tests from TaskGetInfoTest (0 [31.197] 01048.01051> ms total) [31.197] 01048.01051> [----------] 41 tests from ThreadGetInfoTest [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoHandleBasicOnSelfSuceeds [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoHandleBasicOnSelfSuceeds (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoHandleBasicInvalidHandleFails [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoHandleBasicInvalidHandleFails (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoHandleBasicNullAvailSucceeds [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoHandleBasicNullAvailSucceeds (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoHandleBasicNullActualSucceeds [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoHandleBasicNullActualSucceeds (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoHandleBasicNullActualAndAvailSucceeds [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoHandleBasicNullActualAndAvailSucceeds (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoHandleBasicInvalidBufferPointerFails [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoHandleBasicInvalidBufferPointerFails (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoHandleBasicBadActualgIsInvalidArg [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoHandleBasicBadActualgIsInvalidArg (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoHandleBasicBadAvailIsInvalidArg [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoHandleBasicBadAvailIsInvalidArg (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoHandleBasicZeroSizedBufferFails [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoHandleBasicZeroSizedBufferFails (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoHandleCountOnSelfSuceeds [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoHandleCountOnSelfSuceeds (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoHandleCountInvalidHandleFails [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoHandleCountInvalidHandleFails (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoHandleCountNullAvailSucceeds [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoHandleCountNullAvailSucceeds (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoHandleCountNullActualSucceeds [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoHandleCountNullActualSucceeds (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoHandleCountNullActualAndAvailSucceeds [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoHandleCountNullActualAndAvailSucceeds (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoHandleCountInvalidBufferPointerFails [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoHandleCountInvalidBufferPointerFails (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoHandleCountBadActualgIsInvalidArg [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoHandleCountBadActualgIsInvalidArg (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoHandleCountBadAvailIsInvalidArg [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoHandleCountBadAvailIsInvalidArg (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoHandleCountZeroSizedBufferFails [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoHandleCountZeroSizedBufferFails (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoThreadOnSelfSuceeds [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoThreadOnSelfSuceeds (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoThreadInvalidHandleFails [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoThreadInvalidHandleFails (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoThreadNullAvailSucceeds [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoThreadNullAvailSucceeds (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoThreadNullActualSucceeds [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoThreadNullActualSucceeds (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoThreadNullActualAndAvailSucceeds [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoThreadNullActualAndAvailSucceeds (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoThreadInvalidBufferPointerFails [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoThreadInvalidBufferPointerFails (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoThreadBadActualgIsInvalidArg [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoThreadBadActualgIsInvalidArg (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoThreadBadAvailIsInvalidArg [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoThreadBadAvailIsInvalidArg (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoThreadZeroSizedBufferFails [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoThreadZeroSizedBufferFails (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoThreadJobHandleIsBadHandle [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoThreadJobHandleIsBadHandle (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoThreadProcessHandleIsBadHandle [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoThreadProcessHandleIsBadHandle (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoThreadStatsOnSelfSuceeds [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoThreadStatsOnSelfSuceeds (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoThreadStatsInvalidHandleFails [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoThreadStatsInvalidHandleFails (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoThreadStatsNullAvailSucceeds [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoThreadStatsNullAvailSucceeds (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoThreadStatsNullActualSucceeds [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoThreadStatsNullActualSucceeds (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoThreadStatsNullActualAndAvailSucceeds [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoThreadStatsNullActualAndAvailSucceeds (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoThreadStatsInvalidBufferPointerFails [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoThreadStatsInvalidBufferPointerFails (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoThreadStatsBadActualgIsInvalidArg [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoThreadStatsBadActualgIsInvalidArg (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoThreadStatsBadAvailIsInvalidArg [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoThreadStatsBadAvailIsInvalidArg (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoThreadStatsZeroSizedBufferFails [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoThreadStatsZeroSizedBufferFails (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoThreadStatsJobHandleIsBadHandle [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoThreadStatsJobHandleIsBadHandle (0 [31.197] 01048.01051> ms) [31.197] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoThreadStatsProcessHandleIsBadHandle [31.197] 01048.01051> [ OK ] ThreadGetInfoTest.InfoThreadStatsProcessHandleIsBadHandle (0 [31.197] 01048.01051> ms) [31.198] 01048.01051> [ RUN ] ThreadGetInfoTest.InfoThreadExceptionReportInvalidHandleFails [31.198] 01048.01051> [ OK ] ThreadGetInfoTest.InfoThreadExceptionReportInvalidHandleFails (0 [31.198] 01048.01051> ms) [31.198] 01048.01051> [----------] 41 tests from ThreadGetInfoTest (0 [31.198] 01048.01051> ms total) [31.198] 01048.01051> [----------] 21 tests from VmarGetInfoTest [31.198] 01048.01051> [ RUN ] VmarGetInfoTest.InfoHandleBasicOnSelfSuceeds [31.198] 01048.01051> [ OK ] VmarGetInfoTest.InfoHandleBasicOnSelfSuceeds (0 [31.198] 01048.01051> ms) [31.198] 01048.01051> [ RUN ] VmarGetInfoTest.InfoHandleBasicInvalidHandleFails [31.198] 01048.01051> [ OK ] VmarGetInfoTest.InfoHandleBasicInvalidHandleFails (0 [31.198] 01048.01051> ms) [31.198] 01048.01051> [ RUN ] VmarGetInfoTest.InfoHandleBasicNullAvailSucceeds [31.198] 01048.01051> [ OK ] VmarGetInfoTest.InfoHandleBasicNullAvailSucceeds (0 [31.198] 01048.01051> ms) [31.198] 01048.01051> [ RUN ] VmarGetInfoTest.InfoHandleBasicNullActualSucceeds [31.198] 01048.01051> [ OK ] VmarGetInfoTest.InfoHandleBasicNullActualSucceeds (0 [31.198] 01048.01051> ms) [31.198] 01048.01051> [ RUN ] VmarGetInfoTest.InfoHandleBasicNullActualAndAvailSucceeds [31.198] 01048.01051> [ OK ] VmarGetInfoTest.InfoHandleBasicNullActualAndAvailSucceeds (0 [31.198] 01048.01051> ms) [31.198] 01048.01051> [ RUN ] VmarGetInfoTest.InfoHandleBasicInvalidBufferPointerFails [31.198] 01048.01051> [ OK ] VmarGetInfoTest.InfoHandleBasicInvalidBufferPointerFails (0 [31.198] 01048.01051> ms) [31.198] 01048.01051> [ RUN ] VmarGetInfoTest.InfoHandleBasicBadActualgIsInvalidArg [31.198] 01048.01051> [ OK ] VmarGetInfoTest.InfoHandleBasicBadActualgIsInvalidArg (0 [31.198] 01048.01051> ms) [31.198] 01048.01051> [ RUN ] VmarGetInfoTest.InfoHandleBasicBadAvailIsInvalidArg [31.198] 01048.01051> [ OK ] VmarGetInfoTest.InfoHandleBasicBadAvailIsInvalidArg (0 [31.198] 01048.01051> ms) [31.198] 01048.01051> [ RUN ] VmarGetInfoTest.InfoHandleBasicZeroSizedBufferFails [31.198] 01048.01051> [ OK ] VmarGetInfoTest.InfoHandleBasicZeroSizedBufferFails (0 [31.198] 01048.01051> ms) [31.198] 01048.01051> [ RUN ] VmarGetInfoTest.InfoVmarOnSelfFails [31.198] 01048.01051> [ OK ] VmarGetInfoTest.InfoVmarOnSelfFails (0 [31.198] 01048.01051> ms) [31.198] 01048.01051> [ RUN ] VmarGetInfoTest.InfoVmarInvalidHandleFails [31.198] 01048.01051> [ OK ] VmarGetInfoTest.InfoVmarInvalidHandleFails (0 [31.198] 01048.01051> ms) [31.198] 01048.01051> [ RUN ] VmarGetInfoTest.InfoVmarNullAvailSucceeds [31.198] 01048.01051> [ OK ] VmarGetInfoTest.InfoVmarNullAvailSucceeds (0 [31.198] 01048.01051> ms) [31.198] 01048.01051> [ RUN ] VmarGetInfoTest.InfoVmarNullActualSucceeds [31.198] 01048.01051> [ OK ] VmarGetInfoTest.InfoVmarNullActualSucceeds (0 [31.198] 01048.01051> ms) [31.198] 01048.01051> [ RUN ] VmarGetInfoTest.InfoVmarNullActualAndAvailSucceeds [31.198] 01048.01051> [ OK ] VmarGetInfoTest.InfoVmarNullActualAndAvailSucceeds (0 [31.198] 01048.01051> ms) [31.198] 01048.01051> [ RUN ] VmarGetInfoTest.InfoVmarInvalidBufferPointerFails [31.198] 01048.01051> [ OK ] VmarGetInfoTest.InfoVmarInvalidBufferPointerFails (0 [31.198] 01048.01051> ms) [31.198] 01048.01051> [ RUN ] VmarGetInfoTest.InfoVmarBadActualgIsInvalidArg [31.198] 01048.01051> [ OK ] VmarGetInfoTest.InfoVmarBadActualgIsInvalidArg (0 [31.198] 01048.01051> ms) [31.198] 01048.01051> [ RUN ] VmarGetInfoTest.InfoVmarBadAvailIsInvalidArg [31.198] 01048.01051> [ OK ] VmarGetInfoTest.InfoVmarBadAvailIsInvalidArg (0 [31.198] 01048.01051> ms) [31.198] 01048.01051> [ RUN ] VmarGetInfoTest.InfoVmarZeroSizedBufferFails [31.198] 01048.01051> [ OK ] VmarGetInfoTest.InfoVmarZeroSizedBufferFails (0 [31.198] 01048.01051> ms) [31.198] 01048.01051> [ RUN ] VmarGetInfoTest.InfoVmarJobHandleIsBadHandle [31.198] 01048.01051> [ OK ] VmarGetInfoTest.InfoVmarJobHandleIsBadHandle (0 [31.198] 01048.01051> ms) [31.198] 01048.01051> [ RUN ] VmarGetInfoTest.InfoVmarProcessHandleIsBadHandle [31.198] 01048.01051> [ OK ] VmarGetInfoTest.InfoVmarProcessHandleIsBadHandle (0 [31.198] 01048.01051> ms) [31.198] 01048.01051> [ RUN ] VmarGetInfoTest.InfoVmarThreadHandleIsBadHandle [31.198] 01048.01051> [ OK ] VmarGetInfoTest.InfoVmarThreadHandleIsBadHandle (0 [31.198] 01048.01051> ms) [31.198] 01048.01051> [----------] 21 tests from VmarGetInfoTest (0 [31.198] 01048.01051> ms total) [31.198] 01048.01051> [----------] 28 tests from SocketTest [31.198] 01048.01051> [ RUN ] SocketTest.EndpointsAreRelated [31.198] 01048.01051> [ OK ] SocketTest.EndpointsAreRelated (0 [31.198] 01048.01051> ms) [31.198] 01048.01051> [ RUN ] SocketTest.EmptySocketShouldWait [31.198] 01048.01051> [ OK ] SocketTest.EmptySocketShouldWait (0 [31.198] 01048.01051> ms) [31.198] 01048.01051> [ RUN ] SocketTest.WriteReadDataVerify [31.198] 01048.01051> [ OK ] SocketTest.WriteReadDataVerify (0 [31.198] 01048.01051> ms) [31.198] 01048.01051> [ RUN ] SocketTest.PeerClosedError [31.198] 01048.01051> [ OK ] SocketTest.PeerClosedError (0 [31.198] 01048.01051> ms) [31.198] 01048.01051> [ RUN ] SocketTest.PeekingLeavesData [31.198] 01048.01051> [ OK ] SocketTest.PeekingLeavesData (0 [31.198] 01048.01051> ms) [31.198] 01048.01051> [ RUN ] SocketTest.PeekingIntoEmpty [31.198] 01048.01051> [ OK ] SocketTest.PeekingIntoEmpty (0 [31.198] 01048.01051> ms) [31.198] 01048.01051> [ RUN ] SocketTest.Signals [31.198] 01048.01051> [ OK ] SocketTest.Signals (0 [31.198] 01048.01051> ms) [31.198] 01048.01051> [ RUN ] SocketTest.SetThreshholdsProp [31.198] 01048.01051> [ OK ] SocketTest.SetThreshholdsProp (0 [31.198] 01048.01051> ms) [31.198] 01048.01051> [ RUN ] SocketTest.SetThreshholdsAndCheckSignals [31.199] 01048.01051> [ OK ] SocketTest.SetThreshholdsAndCheckSignals (0 [31.199] 01048.01051> ms) [31.199] 01048.01051> [ RUN ] SocketTest.SignalClosedPeer [31.199] 01048.01051> [ OK ] SocketTest.SignalClosedPeer (0 [31.199] 01048.01051> ms) [31.199] 01048.01051> [ RUN ] SocketTest.PeerClosedSetProperty [31.199] 01048.01051> [ OK ] SocketTest.PeerClosedSetProperty (0 [31.199] 01048.01051> ms) [31.199] 01048.01051> [ RUN ] SocketTest.ShutdownWrite [31.199] 01048.01051> [ OK ] SocketTest.ShutdownWrite (0 [31.199] 01048.01051> ms) [31.199] 01048.01051> [ RUN ] SocketTest.ShutdownRead [31.199] 01048.01051> [ OK ] SocketTest.ShutdownRead (0 [31.199] 01048.01051> ms) [31.199] 01048.01051> [ RUN ] SocketTest.BytesOutstanding [31.199] 01048.01051> [ OK ] SocketTest.BytesOutstanding (0 [31.199] 01048.01051> ms) [31.199] 01048.01051> [ RUN ] SocketTest.ShutdownWriteBytesOutstanding [31.199] 01048.01051> [ OK ] SocketTest.ShutdownWriteBytesOutstanding (0 [31.199] 01048.01051> ms) [31.199] 01048.01051> [ RUN ] SocketTest.ShutdownReadBytesOutstanding [31.199] 01048.01051> [ OK ] SocketTest.ShutdownReadBytesOutstanding (0 [31.199] 01048.01051> ms) [31.199] 01048.01051> [ RUN ] SocketTest.ShortWrite [31.200] 01048.01051> [ OK ] SocketTest.ShortWrite (0 [31.200] 01048.01051> ms) [31.200] 01048.01051> [ RUN ] SocketTest.Datagram [31.200] 01048.01051> [ OK ] SocketTest.Datagram (0 [31.200] 01048.01051> ms) [31.200] 01048.01051> [ RUN ] SocketTest.DatagramPeek [31.200] 01048.01051> [ OK ] SocketTest.DatagramPeek (0 [31.200] 01048.01051> ms) [31.200] 01048.01051> [ RUN ] SocketTest.DatagramPeekEmpty [31.200] 01048.01051> [ OK ] SocketTest.DatagramPeekEmpty (0 [31.200] 01048.01051> ms) [31.200] 01048.01051> [ RUN ] SocketTest.DatagramNoShortWrite [31.200] 01048.01051> [ OK ] SocketTest.DatagramNoShortWrite (0 [31.200] 01048.01051> ms) [31.200] 01048.01051> [ RUN ] SocketTest.ControlPlaneAbsent [31.200] 01048.01051> [ OK ] SocketTest.ControlPlaneAbsent (0 [31.200] 01048.01051> ms) [31.200] 01048.01051> [ RUN ] SocketTest.ControlPlane [31.200] 01048.01051> [ OK ] SocketTest.ControlPlane (0 [31.200] 01048.01051> ms) [31.200] 01048.01051> [ RUN ] SocketTest.ControlPlanePeek [31.200] 01048.01051> [ OK ] SocketTest.ControlPlanePeek (0 [31.200] 01048.01051> ms) [31.200] 01048.01051> [ RUN ] SocketTest.ControlPlanePeekEmpty [31.200] 01048.01051> [ OK ] SocketTest.ControlPlanePeekEmpty (0 [31.200] 01048.01051> ms) [31.200] 01048.01051> [ RUN ] SocketTest.ControlPlaneShutdown [31.200] 01048.01051> [ OK ] SocketTest.ControlPlaneShutdown (0 [31.200] 01048.01051> ms) [31.200] 01048.01051> [ RUN ] SocketTest.Accept [31.200] 01048.01051> ZIRCON KERNEL PANIC [31.200] 01048.01051> Lock validation failed for thread 0xffffff800074e978 pid 1048 tid 1051 (bin/core-tests:bin/core-tests): [31.200] 01048.01051> Reason: Already Acquired [31.200] 01048.01051> Bad lock: name=lockdep::LockClass<PeerHolder<SocketDispatcher>, Mutex, 319, lockdep::LockFlagsNone> order=0 [31.200] 01048.01051> Conflict: name=lockdep::LockClass<PeerHolder<SocketDispatcher>, Mutex, 319, lockdep::LockFlagsNone> order=0 [31.200] 01048.01051> caller=0xffffffff0013a0fa frame=0xffffff98069a7e70 [31.200] 01048.01051> BUILDID git-808b66d9daaaacd45b64fe427fa28a1682088ff2-dirty [31.200] 01048.01051> dso: id=3776c8a9518e2b61 base=0xffffffff00100000 name=zircon.elf [31.200] 01048.01051> bt#00: 0xffffffff0013a0fa [31.200] 01048.01051> bt#01: 0xffffffff00146390 [31.200] 01048.01051> bt#02: 0xffffffff00161c12 [31.200] 01048.01051> bt#03: 0xffffffff00214a52 [31.200] 01048.01051> bt#04: 0xffffffff0021ea21 [31.200] 01048.01051> bt#05: 0xffffffff001ce805 [31.200] 01048.01051> bt#06: end [31.200] 01048.01051> #0 0xffffffff0013a0f9 in <kernel>+0xffffffff8013a0f9 [31.200] 01048.01051> #1 0xffffffff0014638f in <kernel>+0xffffffff8014638f [31.200] 01048.01051> #2 0xffffffff00161c11 in <kernel>+0xffffffff80161c11 [31.200] 01048.01051> #3 0xffffffff00214a51 in <kernel>+0xffffffff80214a51 [31.200] 01048.01051> #4 0xffffffff0021ea20 in <kernel>+0xffffffff8021ea20 [31.200] 01048.01051> #5 0xffffffff001ce804 in <kernel>+0xffffffff801ce804 [31.200] 01048.01051> ZIRCON KERNEL PANIC [31.200] 01048.01051> Lock validation failed for thread 0xffffff800074e978 pid 1048 tid 1051 (bin/core-tests:bin/core-tests): [31.200] 01048.01051> Reason: Already Acquired [31.200] 01048.01051> Bad lock: name=lockdep::LockClass<PeerHolder<SocketDispatcher>, Mutex, 319, lockdep::LockFlagsNone> order=0 [31.200] 01048.01051> Conflict: name=lockdep::LockClass<PeerHolder<SocketDispatcher>, Mutex, 319, lockdep::LockFlagsNone> order=0 [31.200] 01048.01051> caller=0xffffffff00162774 frame=0xffffff98069a7e90 [31.200] 01048.01051> BUILDID git-808b66d9daaaacd45b64fe427fa28a1682088ff2-dirty [31.200] 01048.01051> dso: id=3776c8a9518e2b61 base=0xffffffff00100000 name=zircon.elf [31.200] 01048.01051> bt#00: 0xffffffff00162774 [31.200] 01048.01051> bt#01: 0xffffffff00146448 [31.200] 01048.01051> bt#02: 0xffffffff00161c12 [31.200] 01048.01051> bt#03: 0xffffffff00214a52 [31.200] 01048.01051> bt#04: 0xffffffff0021ea21 [31.200] 01048.01051> bt#05: 0xffffffff001ce805 [31.200] 01048.01051> bt#06: end [31.200] 01048.01051> #0 0xffffffff00162773 in <kernel>+0xffffffff80162773 [31.200] 01048.01051> #1 0xffffffff00146447 in <kernel>+0xffffffff80146447 [31.200] 01048.01051> #2 0xffffffff00161c11 in <kernel>+0xffffffff80161c11 [31.200] 01048.01051> #3 0xffffffff00214a51 in <kernel>+0xffffffff80214a51 [31.200] 01048.01051> #4 0xffffffff0021ea20 in <kernel>+0xffffffff8021ea20 [31.200] 01048.01051> #5 0xffffffff001ce804 in <kernel>+0xffffffff801ce804 [31.200] 01048.01051> [ OK ] SocketTest.Accept (0 [31.200] 01048.01051> ms) [31.200] 01048.01051> [ RUN ] SocketTest.ZeroSize [31.200] 01048.01051> [ OK ] SocketTest.ZeroSize (0 [31.200] 01048.01051> ms) [31.200] 01048.01051> [----------] 28 tests from SocketTest (2 [31.200] 01048.01051> ms total) [31.200] 01048.01051> [----------] Global test environment tear-down. [31.200] 01048.01051> [==========] 240 tests from 12 test cases ran (2991 [31.200] 01048.01051> ms total). [31.200] 01048.01051> [ PASSED ] 240 tests [31.200] 01048.01051> core-tests succeeded RZMm59f7zOSs6aZUIXZR [31.291] 01034.01041> userboot: loader-service channel peer closed [31.291] 01034.01041> userboot: Waiting for bin/core-tests to exit... [31.517] 00000.00000> ZIRCON KERNEL OOPS [31.517] 00000.00000> Circular lock dependency detected: [31.517] 00000.00000> lockdep::LockClass<SoloDispatcher<ThreadDispatcher, 316111, 0>, Mutex, 282, lockdep::LockFlagsNone> [31.517] 00000.00000> lockdep::LockClass<ProcessDispatcher, BrwLock, 360, lockdep::LockFlagsNone> [31.517] 00000.00000> lockdep::LockClass<SoloDispatcher<ProcessDispatcher, 447439, 0>, Mutex, 282, lockdep::LockFlagsNone> [31.517] 00000.00000> lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> [33.205] 01034.01041> userboot: Process exited. Executing "poweroff". [33.517] 00000.00000> ZIRCON KERNEL OOPS [33.517] 00000.00000> Circular lock dependency detected: [33.517] 00000.00000> lockdep::LockClass<SoloDispatcher<ThreadDispatcher, 316111, 0>, Mutex, 282, lockdep::LockFlagsNone> [33.517] 00000.00000> lockdep::LockClass<ProcessDispatcher, BrwLock, 360, lockdep::LockFlagsNone> [33.517] 00000.00000> lockdep::LockClass<SoloDispatcher<ProcessDispatcher, 447439, 0>, Mutex, 282, lockdep::LockFlagsNone> [33.517] 00000.00000> lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> [34.494] 01034.01041> mp_unplug_cpu_mask_single_locked:362: WARNING: leaking thread for cpu 3 [34.500] 01034.01041> mp_unplug_cpu_mask_single_locked:362: WARNING: leaking thread for cpu 2 [34.506] 01034.01041> mp_unplug_cpu_mask_single_locked:362: WARNING: leaking thread for cpu 1 [34.506] 01034.01041> Shutting down debuglog 2019/04/20 02:13:58 successful QEMU exit status: 31