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

2019/04/19 15:35:38 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] [00000.000] 00000:00000> zbi: @ 0xffffff8000407000 (3161664 bytes) [00000.000] 00000:00000> UART: FIFO depth 16 [00000.000] 00000:00000> PMM: boot reserve add [0x100000, 0x333fff] [00000.000] 00000:00000> PMM: boot reserve add [0x407000, 0x70afff] [00000.000] 00000:00000> PMM: boot reserve marking WIRED [0x100000, 0x333fff] [00000.000] 00000:00000> PMM: boot reserve marking WIRED [0x407000, 0x70afff] [00000.000] 00000:00000> [00000.000] 00000:00000> welcome to Zircon [00000.000] 00000:00000> [00000.000] 00000:00000> KASLR: .text section at 0xffffffff00100000 [00000.000] 00000:00000> INIT: cpu 0, calling hook 0xffffffff00120510 (global_prng_seed) at level 0x30000, flags 0x1 [00000.000] 00000:00000> initializing vm pre-heap [00000.000] 00000:00000> VM: marking boot alloc used range [0x70b000, 0x70b080) [00000.000] 00000:00000> INIT: cpu 0, calling hook 0xffffffff00131640 (elf_build_id) at level 0x4fffe, flags 0x1 [00000.000] 00000:00000> INIT: cpu 0, calling hook 0xffffffff001317e0 (version) at level 0x4ffff, flags 0x1 [00000.000] 00000:00000> version: [00000.000] 00000:00000> arch: x86 [00000.000] 00000:00000> platform: XXX [00000.000] 00000:00000> target: XXX [00000.000] 00000:00000> project: x64 [00000.000] 00000:00000> buildid: git-1fd4ec2f02fc15f15eb764f03bcf7db389dc96bc-dirty [00000.000] 00000:00000> ELF build ID: 5a442b2e49e51a94 [00000.000] 00000:00000> initializing heap [00000.000] 00000:00000> INIT: cpu 0, calling hook 0xffffffff001020d0 (console) at level 0x50000, flags 0x1 [00000.000] 00000:00000> INIT: cpu 0, calling hook 0xffffffff0019e6a0 (x86_resource_init) at level 0x50000, flags 0x1 [00000.000] 00000:00000> initializing vm [00000.000] 00000:00000> VM: reserving kernel region [0xffffffff00100000, 0xffffffff0024d000) flags 0x28 name 'kernel_code' [00000.000] 00000:00000> VM: reserving kernel region [0xffffffff0024d000, 0xffffffff00297000) flags 0x8 name 'kernel_rodata' [00000.000] 00000:00000> VM: reserving kernel region [0xffffffff00297000, 0xffffffff0029e000) flags 0x18 name 'kernel_data' [00000.000] 00000:00000> VM: reserving kernel region [0xffffffff0029e000, 0xffffffff00334000) flags 0x18 name 'kernel_bss' [00000.000] 00000:00000> INIT: cpu 0, calling hook 0xffffffff0019f4c0 (display_memtype) at level 0x60001, flags 0x1 [00000.000] 00000:00000> INIT: cpu 0, calling hook 0xffffffff001a35a0 (acpi_tables) at level 0x60001, flags 0x1 [00000.000] 00000:00000> INIT: cpu 0, calling hook 0xffffffff0019cac0 (hpet) at level 0x60002, flags 0x1 [00000.000] 00000:00000> INIT: cpu 0, calling hook 0xffffffff0019d010 (apic) at level 0x60002, flags 0x1 [00000.000] 00000:00000> x2APIC enabled [00000.000] 00000:00000> INIT: cpu 0, calling hook 0xffffffff001cd330 (system_topology_init) at level 0x60002, flags 0x1 [00000.000] 00000:00000> WARNING: unable to find any cache levels. [00000.000] 00000:00000> WARNING: unable to find any cache levels. [00000.000] 00000:00000> Could not find SRAT table. ACPICA returned: 5 [00000.000] 00000:00000> INIT: cpu 0, calling hook 0xffffffff001a2450 (timer) at level 0x60003, flags 0x1 [00000.000] 00000:00000> pvclock: Clocksource is stable [00000.000] 00000:00000> HPET frequency: 100000 ticks/ms [00000.000] 00000:00000> pvclock: Fetching TSC frequency [00000.000] 00000:00000> TSC frequency: 2299808 ticks/ms [00000.434] 00000:00000> timer features: constant_tsc 1 invariant_tsc 1 tsc_deadline 1 [00000.434] 00000:00000> Using TSC as wallclock [00000.434] 00000:00000> INIT: cpu 0, calling hook 0xffffffff002340d0 (percpu_heap_init) at level 0x60003, flags 0x1 [00000.434] 00000:00000> initializing kernel [00000.434] 00000:00000> initializing mp [00000.434] 00000:00000> INIT: cpu 0, calling hook 0xffffffff00120850 (global_prng_thread_safe) at level 0x7ffff, flags 0x1 [00000.434] 00000:00000> creating bootstrap completion thread [00000.435] 00000:00000> top of bootstrap2() [00000.435] 00000:00000> INIT: cpu 0, calling hook 0xffffffff00144e30 (libobject) at level 0x80000, flags 0x1 [00000.435] 00000:00000> OOM: started thread [00000.435] 00000:00000> INIT: cpu 0, calling hook 0xffffffff001a8800 (lockdep) at level 0x80000, flags 0x1 [00000.435] 00000:00000> INIT: cpu 0, calling hook 0xffffffff002302a0 (dpc) at level 0x80000, flags 0x1 [00000.435] 00000:00000> Processor Model Info: type 0 family 0x6 model 0xf stepping 0 [00000.435] 00000:00000> display_family 0x6 display_model 0x3f [00000.435] 00000:00000> Vendor: Intel [00000.435] 00000:00000> Microarch: Haswell [00000.435] 00000:00000> F/M/S: 6/3f/0 [00000.435] 00000:00000> patch_level: 0 [00000.435] 00000:00000> Brand: Intel(R) Xeon(R) CPU @ 2.30GHz [00000.435] 00000:00000> Features: fpu sse sse2 sse3 ssse3 sse4.1 sse4.2 mmx avx avx2 fxsr pcid xsave aesni clflush [00000.435] 00000:00000> fsgsbase tsc_adj smep erms rdrand syscall nx rdtscp tsc_deadline x2apic hypervisor [00000.435] 00000:00000> Properties: meltdown l1tf pcid_good [00000.435] 00000:00000> initializing platform [00000.435] 00000:00000> INIT: cpu 0, calling hook 0xffffffff001c5e10 (x86_perfmon) at level 0x90000, flags 0x1 [00000.435] 00000:00000> x86_perfmon_init_once:454: perfmon: unexpected programmable counter width 0 in cpuid.0AH [00000.435] 00000:00000> UART: started IRQ driven RX [00000.435] 00000:00000> UART: started IRQ driven TX [00000.435] 00000:00000> cpu topology: [00000.435] 00000:00000> 0: apic id 0x0 BSP [00000.435] 00000:00000> 1: apic id 0x1 [00000.435] 00000:00000> 2: apic id 0x2 [00000.435] 00000:00000> 3: apic id 0x3 [00000.435] 00000:00000> Found 4 cpus [00000.436] 00000:00000> booting apic ids: 0x1 0x2 0x3 [00000.448] 00000:00000> entering scheduler on cpu 1 [00000.448] 00000:00000> entering scheduler on cpu 3 [00000.448] 00000:00000> entering scheduler on cpu 2 [00000.451] 00000:00000> smbios: manufacturer="QEMU" product="Standard PC (Q35 + ICH9, 2009)" [00000.451] 00000:00000> initializing target [00000.451] 00000:00000> INIT: cpu 0, calling hook 0xffffffff001a1470 (x86_pcie_init) at level 0xa0000, flags 0x1 [00000.452] 00000:00000> moving to last init level [00000.452] 00000:00000> INIT: cpu 0, calling hook 0xffffffff00130d00 (debuglog) at level 0xb0000, flags 0x1 [00000.452] 00000:00000> INIT: cpu 0, calling hook 0xffffffff0018c470 (kcounters) at level 0xb0000, flags 0x1 [00000.452] 00000:00000> INIT: cpu 0, calling hook 0xffffffff00102610 (kernel_shell) at level 0xc0000, flags 0x1 [00000.452] 00000:00000> INIT: cpu 0, calling hook 0xffffffff0022ca70 (ktrace) at level 0xc0000, flags 0x1 [00000.802] 00000:00000> ktrace: buffer at 0xffffff98ed9ff000 (33554432 bytes) [00000.803] 00000:00000> INIT: cpu 0, calling hook 0xffffffff00247770 (userboot) at level 0xc0000, flags 0x1 [00000.803] 00000:00000> userboot: ramdisk 0x304000 @ 0xffffff8000407000 [00000.818] 00000:00000> userboot: userboot rodata 0 @ [0x73943a2cc000,0x73943a2ce000) [00000.818] 00000:00000> userboot: userboot code 0x2000 @ [0x73943a2ce000,0x73943a2d2000) [00000.818] 00000:00000> userboot: vdso/full rodata 0 @ [0x73943a2d2000,0x73943a2d8000) [00000.818] 00000:00000> userboot: vdso/full code 0x6000 @ [0x73943a2d8000,0x73943a2d9000) [00000.818] 00000:00000> userboot: entry point @ 0x73943a2ceb10 [00000.841] 01034:01041> userboot: option "userboot=bin/core-tests" [00000.841] 01034:01041> userboot: option "userboot.shutdown=" [00000.841] 01034:01041> userboot: option "/b/s/w/ir/multiboot.bin=" [00000.841] 01034:01041> userboot: option "kernel.halt-on-panic=true" [00000.841] 01034:01041> userboot: option "devmgr.suspend-timeout-debug=true" [00000.841] 01034:01041> userboot: option "TERM=dumb" [00000.841] 01034:01041> userboot: option "kernel.serial=legacy" [00000.841] 01034:01041> userboot: option "multiboot.boot_loader_name=qemu" [00000.850] 01034:01041> userboot: searching bootfs for 'bin/core-tests' [00000.850] 01034:01041> userboot: 'bin/core-tests' has PT_INTERP "lib/ld.so.1" [00000.850] 01034:01041> userboot: searching bootfs for 'lib/ld.so.1' [00000.850] 01034:01041> userboot: userboot: loaded lib/ld.so.1 at 0x72a0758fa000, entry point 0x72a075944ef8 [00000.850] 01034:01041> userboot: userboot: loaded vDSO at 0x623e9ce5d000, entry point 0x623e9ce63842 [00000.850] 01034:01041> userboot: process bin/core-tests started. [00000.850] 01034:01041> userboot: waiting for loader-service requests... [00000.901] 01034:01041> userboot: searching bootfs for 'lib/libfdio.so' [00000.902] 01034:01041> userboot: searching bootfs for 'lib/libunittest.so' [00000.902] 01034:01041> userboot: searching bootfs for 'lib/libmini-process.so' [00000.902] 01034:01041> userboot: searching bootfs for 'lib/libinspector.so' [00000.918] 01034:01041> userboot: searching bootfs for 'lib/libbacktrace.so' [00000.919] 01048:01051> {{{reset}}} [00000.919] 01048:01051> {{{module:0:<application>:elf:679291a1def7c8c9}}} [00000.919] 01048:01051> {{{mmap:0x4d277085c000:0x50000:load:0:r:0}}} [00000.919] 01048:01051> {{{mmap:0x4d27708ac000:0x162000:load:0:rx:0x50000}}} [00000.919] 01048:01051> {{{mmap:0x4d2770a0e000:0x9000:load:0:rw:0x1b2000}}} [00000.919] 01048:01051> {{{mmap:0x4d2770a17000:0x2000:load:0:rw:0x1bb000}}} [00000.919] 01048:01051> dso: id=679291a1def7c8c9 base=0x00004d277085c000 name=<application> [00000.919] 01048:01051> {{{module:0x3:libfdio.so:elf:dbae9e2195a558f9}}} [00000.919] 01048:01051> {{{mmap:0x772108912000:0xc000:load:0x3:r:0}}} [00000.919] 01048:01051> {{{mmap:0x77210891e000:0x1c000:load:0x3:rx:0xc000}}} [00000.919] 01048:01051> {{{mmap:0x77210893a000:0x2000:load:0x3:rw:0x28000}}} [00000.919] 01048:01051> {{{mmap:0x77210893c000:0x2000:load:0x3:rw:0x2a000}}} [00000.919] 01048:01051> dso: id=dbae9e2195a558f9 base=0x0000772108912000 name=libfdio.so [00000.919] 01048:01051> {{{module:0x4:libunittest.so:elf:b460931e4869032b}}} [00000.919] 01048:01051> {{{mmap:0xc6a339ef000:0x4000:load:0x4:r:0}}} [00000.919] 01048:01051> {{{mmap:0xc6a339f3000:0x3000:load:0x4:rx:0x4000}}} [00000.919] 01048:01051> {{{mmap:0xc6a339f6000:0x1000:load:0x4:rw:0x7000}}} [00000.919] 01048:01051> {{{mmap:0xc6a339f7000:0x2000:load:0x4:rw:0x8000}}} [00000.919] 01048:01051> dso: id=b460931e4869032b base=0x00000c6a339ef000 name=libunittest.so [00000.919] 01048:01051> {{{module:0x2:<vDSO>:elf:1b7804b5b4b7d4e9}}} [00000.919] 01048:01051> {{{mmap:0x623e9ce5d000:0x6000:load:0x2:r:0}}} [00000.919] 01048:01051> {{{mmap:0x623e9ce63000:0x1000:load:0x2:rx:0x6000}}} [00000.919] 01048:01051> dso: id=1b7804b5b4b7d4e9 base=0x0000623e9ce5d000 name=<vDSO> [00000.919] 01048:01051> {{{module:0x5:libmini-process.so:elf:961c9ec3b6f98782}}} [00000.919] 01048:01051> {{{mmap:0x22afd952000:0x2000:load:0x5:r:0}}} [00000.919] 01048:01051> {{{mmap:0x22afd954000:0x2000:load:0x5:rx:0x2000}}} [00000.919] 01048:01051> {{{mmap:0x22afd956000:0x1000:load:0x5:rw:0x4000}}} [00000.919] 01048:01051> {{{mmap:0x22afd957000:0x1000:load:0x5:rw:0x5000}}} [00000.919] 01048:01051> dso: id=961c9ec3b6f98782 base=0x0000022afd952000 name=libmini-process.so [00000.919] 01048:01051> {{{module:0x6:libinspector.so:elf:d2d8950b17dc8a15}}} [00000.919] 01048:01051> {{{mmap:0x40483e92a000:0x6000:load:0x6:r:0}}} [00000.919] 01048:01051> {{{mmap:0x40483e930000:0x15000:load:0x6:rx:0x6000}}} [00000.919] 01048:01051> {{{mmap:0x40483e945000:0x1000:load:0x6:rw:0x1b000}}} [00000.919] 01048:01051> {{{mmap:0x40483e946000:0xf000:load:0x6:rw:0x1c000}}} [00000.919] 01048:01051> dso: id=d2d8950b17dc8a15 base=0x000040483e92a000 name=libinspector.so [00000.919] 01048:01051> {{{module:0x7:libbacktrace.so:elf:4b017048bddb2a3c}}} [00000.919] 01048:01051> {{{mmap:0x40d4cf0a2000:0x2000:load:0x7:r:0}}} [00001.067] 01048:01051> {{{mmap:0x40d4cf0a4000:0xb000:load:0x7:rx:0x2000}}} [00001.067] 01048:01051> {{{mmap:0x40d4cf0af000:0x1000:load:0x7:rw:0xd000}}} [00001.067] 01048:01051> dso: id=4b017048bddb2a3c base=0x000040d4cf0a2000 name=libbacktrace.so [00001.067] 01048:01051> {{{module:0x1:libc.so:elf:015cc27bccc48dd1}}} [00001.067] 01048:01051> {{{mmap:0x72a0758fa000:0x44000:load:0x1:r:0}}} [00001.067] 01048:01051> {{{mmap:0x72a07593e000:0x56000:load:0x1:rx:0x44000}}} [00001.067] 01048:01051> {{{mmap:0x72a075994000:0x2000:load:0x1:rw:0x9a000}}} [00001.067] 01048:01051> {{{mmap:0x72a075996000:0x3000:load:0x1:rw:0x9c000}}} [00001.067] 01048:01051> dso: id=015cc27bccc48dd1 base=0x000072a0758fa000 name=libc.so [00001.105] 01048:01051> TEST [00001.105] 01048:01051> CASE resource_tests [STARTED] [00001.105] 01048:01051> probe_address_space [RUNNING] [00001.105] 01048:01051> [PASSED] (0 ms) [00001.105] 01048:01051> TestBasicActions [RUNNING] [00001.105] 01048:01051> [PASSED] (0 ms) [00001.105] 01048:01051> TestExclusiveShared [RUNNING] [00001.105] 01048:01051> [PASSED] (0 ms) [00001.105] 01048:01051> TestSharedExclusive [RUNNING] [00001.105] 01048:01051> [PASSED] (0 ms) [00001.105] 01048:01051> TestInvalidArgs [RUNNING] [00001.105] 01048:01051> [PASSED] (0 ms) [00001.105] 01048:01051> TestVmoCreation [RUNNING] [00001.105] 01048:01051> [PASSED] (0 ms) [00001.105] 01048:01051> TestVmoCreationSmaller [RUNNING] [00001.105] 01048:01051> [PASSED] (0 ms) [00001.105] 01048:01051> TestVmoCreationUnaligned [RUNNING] [00001.105] 01048:01051> [PASSED] (0 ms) [00001.105] 01048:01051> TestVmoReplaceAsExecutable [RUNNING] [00001.105] 01048:01051> [PASSED] (0 ms) [00001.105] 01048:01051> test_ioports [RUNNING] [00001.177] 01048:01051> [PASSED] (72 ms) [00001.178] 01048:01051> CASE resource_tests [PASSED] [00001.178] 01048:01051> CASE profile_tests [STARTED] [00001.178] 01048:01051> make_profile_fails [RUNNING] [00001.178] 01048:01051> [PASSED] (0 ms) [00001.178] 01048:01051> change_priority_via_profile [RUNNING] [00001.178] 01048:01051> [PASSED] (0 ms) [00001.178] 01048:01051> CASE profile_tests [PASSED] [00001.178] 01048:01051> CASE interrupt_tests [STARTED] [00001.178] 01048:01051> interrupt_test [RUNNING] [00001.178] 01048:01051> [PASSED] (0 ms) [00001.178] 01048:01051> interrupt_port_bound_test [RUNNING] [00001.178] 01048:01051> [PASSED] (0 ms) [00001.178] 01048:01051> interrupt_port_non_bindable_test [RUNNING] [00001.178] 01048:01051> [PASSED] (0 ms) [00001.178] 01048:01051> interrupt_suspend_test [RUNNING] [00001.238] 01048:01051> [PASSED] (60 ms) [00001.238] 01048:01051> interrupt_bind_vcpu_test [RUNNING] [00001.238] 01048:01051> Guest creation not supported [00001.238] 01048:01051> [PASSED] (0 ms) [00001.238] 01048:01051> interrupt_bind_vcpu_not_supported_test [RUNNING] [00001.238] 01048:01051> Guest creation not supported [00001.238] 01048:01051> [PASSED] (0 ms) [00001.238] 01048:01051> interrupt_bind_vcpu_already_bound_test [RUNNING] [00001.238] 01048:01051> Guest creation not supported [00001.238] 01048:01051> [PASSED] (0 ms) [00001.238] 01048:01051> interrupt_bind_vcpu_multiple_guests_test [RUNNING] [00001.238] 01048:01051> Guest creation not supported [00001.238] 01048:01051> [PASSED] (0 ms) [00001.238] 01048:01051> CASE interrupt_tests [PASSED] [00001.238] 01048:01051> CASE vmo_signal_tests [STARTED] [00001.238] 01048:01051> vmo_signal_sanity_test [RUNNING] [00001.238] 01048:01051> [PASSED] (0 ms) [00001.238] 01048:01051> vmo_child_signal_clone_test [RUNNING] [00001.239] 01048:01051> [PASSED] (0 ms) [00001.239] 01048:01051> vmo_child_signal_map_test [RUNNING] [00001.307] 01048:01051> [PASSED] (68 ms) [00001.307] 01048:01051> CASE vmo_signal_tests [PASSED] [00001.307] 01048:01051> CASE vmar_tests [STARTED] [00001.307] 01048:01051> destroy_root_test [RUNNING] [00001.307] 01048:01051> [PASSED] (0 ms) [00001.307] 01048:01051> basic_allocate_test [RUNNING] [00001.307] 01048:01051> [PASSED] (0 ms) [00001.307] 01048:01051> allocate_oob_test [RUNNING] [00001.307] 01048:01051> [PASSED] (0 ms) [00001.307] 01048:01051> allocate_unsatisfiable_test [RUNNING] [00001.307] 01048:01051> [PASSED] (0 ms) [00001.307] 01048:01051> destroyed_vmar_test [RUNNING] [00001.307] 01048:01051> [PASSED] (0 ms) [00001.307] 01048:01051> map_over_destroyed_test [RUNNING] [00001.308] 01048:01051> [PASSED] (0 ms) [00001.308] 01048:01051> map_in_compact_test [RUNNING] [00001.308] 01048:01051> [PASSED] (0 ms) [00001.308] 01048:01051> overmapping_test [RUNNING] [00001.308] 01048:01051> [PASSED] (0 ms) [00001.308] 01048:01051> invalid_args_test [RUNNING] [00001.308] 01048:01051> [PASSED] (0 ms) [00001.308] 01048:01051> unaligned_len_test [RUNNING] [00001.308] 01048:01051> [PASSED] (0 ms) [00001.308] 01048:01051> unaligned_len_map_test [RUNNING] [00001.308] 01048:01051> [PASSED] (0 ms) [00001.308] 01048:01051> rights_drop_test [RUNNING] [00001.308] 01048:01051> [PASSED] (0 ms) [00001.308] 01048:01051> protect_test [RUNNING] [00001.308] 01048:01051> [PASSED] (0 ms) [00001.308] 01048:01051> nested_region_perms_test [RUNNING] [00001.308] 01048:01051> [PASSED] (0 ms) [00001.308] 01048:01051> object_info_test [RUNNING] [00001.308] 01048:01051> [PASSED] (0 ms) [00001.308] 01048:01051> unmap_split_test [RUNNING] [00001.308] 01048:01051> [PASSED] (0 ms) [00001.430] 01048:01051> unmap_multiple_test [RUNNING] [00001.431] 01048:01051> [PASSED] (0 ms) [00001.431] 01048:01051> unmap_base_not_mapped_test [RUNNING] [00001.431] 01048:01051> [PASSED] (0 ms) [00001.431] 01048:01051> map_specific_overwrite_test [RUNNING] [00001.431] 01048:01051> [PASSED] (0 ms) [00001.431] 01048:01051> protect_split_test [RUNNING] [00001.431] 01048:01051> [PASSED] (0 ms) [00001.431] 01048:01051> protect_multiple_test [RUNNING] [00001.431] 01048:01051> [PASSED] (0 ms) [00001.431] 01048:01051> protect_over_demand_paged_test [RUNNING] [00001.472] 01048:01051> [PASSED] (41 ms) [00001.472] 01048:01051> protect_large_uncommitted_test [RUNNING] [00001.483] 01048:01051> [PASSED] (10 ms) [00001.483] 01048:01051> unmap_large_uncommitted_test [RUNNING] [00001.491] 01048:01285> PageFault: 1029307 free pages [00001.491] 01048:01285> [00001.491] 01048:01285> ZIRCON KERNEL PANIC [00001.491] 01048:01285> Lock validation failed for thread 0xffffff8000759c88 pid 1048 tid 1285 (bin/core-tests:vmar_test_addr): [00001.491] 01048:01285> Reason: Already Acquired [00001.491] 01048:01285> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00001.491] 01048:01285> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00001.491] 01048:01285> caller=0xffffffff0014b49b frame=0xffffff98efa09d60 [00001.491] 01048:01285> BUILDID git-1fd4ec2f02fc15f15eb764f03bcf7db389dc96bc-dirty [00001.491] 01048:01285> [00001.491] 01048:01285> {{{module:0:kernel:elf:5a442b2e49e51a94}}} [00001.491] 01048:01285> {{{mmap:0xffffffff00100000:0x14c7c8:load:0:rx:0xffffffff80100000}}} [00001.491] 01048:01285> {{{mmap:0xffffffff0024d000:0x4a000:load:0:r:0xffffffff8024d000}}} [00001.492] 01048:01285> {{{mmap:0xffffffff00297000:0x6450:load:0:rw:0xffffffff80297000}}} [00001.492] 01048:01285> {{{mmap:0xffffffff0029e000:0x96000:load:0:rw:0xffffffff8029e000}}} [00001.492] 01048:01285> dso: id=5a442b2e49e51a94 base=0xffffffff00100000 name=zircon.elf [00001.492] 01048:01285> bt#00: 0xffffffff0014b49b [00001.492] 01048:01285> bt#01: 0xffffffff0014b8cf [00001.492] 01048:01285> bt#02: 0xffffffff0013716e [00001.492] 01048:01285> bt#03: 0xffffffff001f6944 [00001.492] 01048:01285> bt#04: 0xffffffff001bd43e [00001.492] 01048:01285> bt#05: 0xffffffff001bd08b [00001.492] 01048:01285> bt#06: end [00001.492] 01048:01285> {{{bt:0:0xffffffff0014b49b}}} [00001.492] 01048:01285> {{{bt:1:0xffffffff0014b8cf}}} [00001.492] 01048:01285> {{{bt:2:0xffffffff0013716e}}} [00001.492] 01048:01285> {{{bt:3:0xffffffff001f6944}}} [00001.492] 01048:01285> {{{bt:4:0xffffffff001bd43e}}} [00001.492] 01048:01285> {{{bt:5:0xffffffff001bd08b}}} [00001.492] 01048:01285> [00001.595] 01048:01288> PageFault: 1029307 free pages [00001.595] 01048:01288> [00001.595] 01048:01288> ZIRCON KERNEL PANIC [00001.595] 01048:01288> Lock validation failed for thread 0xffffff8000759c88 pid 1048 tid 1288 (bin/core-tests:vmar_test_addr): [00001.595] 01048:01288> Reason: Already Acquired [00001.595] 01048:01288> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00001.595] 01048:01288> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00001.595] 01048:01288> caller=0xffffffff0014b49b frame=0xffffff98efa09d60 [00001.595] 01048:01288> BUILDID git-1fd4ec2f02fc15f15eb764f03bcf7db389dc96bc-dirty [00001.595] 01048:01288> [00001.595] 01048:01288> {{{module:0:kernel:elf:5a442b2e49e51a94}}} [00001.595] 01048:01288> {{{mmap:0xffffffff00100000:0x14c7c8:load:0:rx:0xffffffff80100000}}} [00001.595] 01048:01288> {{{mmap:0xffffffff0024d000:0x4a000:load:0:r:0xffffffff8024d000}}} [00001.595] 01048:01288> {{{mmap:0xffffffff00297000:0x6450:load:0:rw:0xffffffff80297000}}} [00001.595] 01048:01288> {{{mmap:0xffffffff0029e000:0x96000:load:0:rw:0xffffffff8029e000}}} [00001.595] 01048:01288> dso: id=5a442b2e49e51a94 base=0xffffffff00100000 name=zircon.elf [00001.595] 01048:01288> bt#00: 0xffffffff0014b49b [00001.595] 01048:01288> bt#01: 0xffffffff0014b8cf [00001.595] 01048:01288> bt#02: 0xffffffff0013716e [00001.595] 01048:01288> bt#03: 0xffffffff001f6944 [00001.595] 01048:01288> bt#04: 0xffffffff001bd43e [00001.595] 01048:01288> bt#05: 0xffffffff001bd08b [00001.595] 01048:01288> bt#06: end [00001.595] 01048:01288> {{{bt:0:0xffffffff0014b49b}}} [00001.595] 01048:01288> {{{bt:1:0xffffffff0014b8cf}}} [00001.595] 01048:01288> {{{bt:2:0xffffffff0013716e}}} [00001.595] 01048:01288> {{{bt:3:0xffffffff001f6944}}} [00001.595] 01048:01288> {{{bt:4:0xffffffff001bd43e}}} [00001.595] 01048:01288> {{{bt:5:0xffffffff001bd08b}}} [00001.595] 01048:01288> [00001.707] 01048:01291> PageFault: 1029307 free pages [00001.707] 01048:01291> [00001.707] 01048:01291> ZIRCON KERNEL PANIC [00001.707] 01048:01291> Lock validation failed for thread 0xffffff8000759c88 pid 1048 tid 1291 (bin/core-tests:vmar_test_addr): [00001.707] 01048:01291> Reason: Already Acquired [00001.707] 01048:01291> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00001.707] 01048:01291> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00001.707] 01048:01291> caller=0xffffffff0014b49b frame=0xffffff98efa09d60 [00001.707] 01048:01291> BUILDID git-1fd4ec2f02fc15f15eb764f03bcf7db389dc96bc-dirty [00001.707] 01048:01291> [00001.707] 01048:01291> {{{module:0:kernel:elf:5a442b2e49e51a94}}} [00001.707] 01048:01291> {{{mmap:0xffffffff00100000:0x14c7c8:load:0:rx:0xffffffff80100000}}} [00001.707] 01048:01291> {{{mmap:0xffffffff0024d000:0x4a000:load:0:r:0xffffffff8024d000}}} [00001.707] 01048:01291> {{{mmap:0xffffffff00297000:0x6450:load:0:rw:0xffffffff80297000}}} [00001.707] 01048:01291> {{{mmap:0xffffffff0029e000:0x96000:load:0:rw:0xffffffff8029e000}}} [00001.707] 01048:01291> dso: id=5a442b2e49e51a94 base=0xffffffff00100000 name=zircon.elf [00001.707] 01048:01291> bt#00: 0xffffffff0014b49b [00001.707] 01048:01291> bt#01: 0xffffffff0014b8cf [00001.707] 01048:01291> bt#02: 0xffffffff0013716e [00001.707] 01048:01291> bt#03: 0xffffffff001f6944 [00001.707] 01048:01291> bt#04: 0xffffffff001bd43e [00001.707] 01048:01291> bt#05: 0xffffffff001bd08b [00001.707] 01048:01291> bt#06: end [00001.707] 01048:01291> {{{bt:0:0xffffffff0014b49b}}} [00001.707] 01048:01291> {{{bt:1:0xffffffff0014b8cf}}} [00001.707] 01048:01291> {{{bt:2:0xffffffff0013716e}}} [00001.707] 01048:01291> {{{bt:3:0xffffffff001f6944}}} [00001.707] 01048:01291> {{{bt:4:0xffffffff001bd43e}}} [00001.707] 01048:01291> {{{bt:5:0xffffffff001bd08b}}} [00001.707] 01048:01291> [00001.809] 01048:01051> [PASSED] (325 ms) [00001.809] 01048:01051> partial_unmap_and_read [RUNNING] [00001.809] 01048:01051> [PASSED] (0 ms) [00001.809] 01048:01051> partial_unmap_and_write [RUNNING] [00001.809] 01048:01051> [PASSED] (0 ms) [00001.809] 01048:01051> partial_unmap_with_vmar_offset [RUNNING] [00001.809] 01048:01051> [PASSED] (0 ms) [00001.809] 01048:01051> CASE vmar_tests [PASSED] [00001.809] 01048:01051> CASE ticks_tests [STARTED] [00001.809] 01048:01051> elapsed_time_using_ticks [RUNNING] [00001.809] 01048:01051> [PASSED] (0 ms) [00001.809] 01048:01051> CASE ticks_tests [PASSED] [00001.809] 01048:01051> CASE threads_tests [STARTED] [00001.809] 01048:01051> TestBasics [RUNNING] [00001.910] 01048:01051> [PASSED] (100 ms) [00001.910] 01048:01051> TestInvalidRights [RUNNING] [00001.910] 01048:01051> [PASSED] (0 ms) [00001.910] 01048:01051> TestDetach [RUNNING] [00001.910] 01048:01051> [PASSED] (0 ms) [00001.910] 01048:01051> TestLongNameSucceeds [RUNNING] [00001.931] 01048:01051> [PASSED] (20 ms) [00001.931] 01048:01051> TestThreadStartOnInitialThread [RUNNING] [00001.938] 01048:01051> [PASSED] (7 ms) [00001.938] 01048:01051> TestThreadStartWithZeroInstructionPointer [RUNNING] [00001.939] 01048:01051> [00001.939] 01048:01051> ZIRCON KERNEL PANIC [00001.939] 01048:01051> Lock validation failed for thread 0xffffff800074e978 pid 1048 tid 1051 (bin/core-tests:bin/core-tests): [00001.939] 01048:01051> Reason: Out Of Order [00001.939] 01048:01051> Bad lock: name=lockdep::LockClass<SoloDispatcher<ThreadDispatcher, 316111, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00001.939] 01048:01051> Conflict: name=lockdep::LockClass<SoloDispatcher<ProcessDispatcher, 447439, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00001.939] 01048:01051> caller=0xffffffff001647cb frame=0xffffff98ec9e4ec0 [00001.939] 01048:01051> BUILDID git-1fd4ec2f02fc15f15eb764f03bcf7db389dc96bc-dirty [00001.939] 01048:01051> [00001.939] 01048:01051> {{{module:0:kernel:elf:5a442b2e49e51a94}}} [00001.939] 01048:01051> {{{mmap:0xffffffff00100000:0x14c7c8:load:0:rx:0xffffffff80100000}}} [00001.939] 01048:01051> {{{mmap:0xffffffff0024d000:0x4a000:load:0:r:0xffffffff8024d000}}} [00001.939] 01048:01051> {{{mmap:0xffffffff00297000:0x6450:load:0:rw:0xffffffff80297000}}} [00001.939] 01048:01051> {{{mmap:0xffffffff0029e000:0x96000:load:0:rw:0xffffffff8029e000}}} [00001.939] 01048:01051> dso: id=5a442b2e49e51a94 base=0xffffffff00100000 name=zircon.elf [00001.939] 01048:01051> bt#00: 0xffffffff001647cb [00001.939] 01048:01051> bt#01: 0xffffffff00159a9c [00001.939] 01048:01051> bt#02: 0xffffffff0015a2cd [00001.939] 01048:01051> bt#03: 0xffffffff00217616 [00001.939] 01048:01051> bt#04: 0xffffffff00220a8a [00001.939] 01048:01051> bt#05: 0xffffffff001ce7ce [00001.939] 01048:01051> bt#06: end [00001.939] 01048:01051> {{{bt:0:0xffffffff001647cb}}} [00001.939] 01048:01051> {{{bt:1:0xffffffff00159a9c}}} [00001.939] 01048:01051> {{{bt:2:0xffffffff0015a2cd}}} [00001.939] 01048:01051> {{{bt:3:0xffffffff00217616}}} [00001.939] 01048:01051> {{{bt:4:0xffffffff00220a8a}}} [00001.939] 01048:01051> {{{bt:5:0xffffffff001ce7ce}}} [00001.939] 01048:01051> [00001.939] 01048:01051> [PASSED] (1 ms) [00001.939] 01048:01051> TestKillBusyThread [RUNNING] [00002.152] 01048:01051> [PASSED] (212 ms) [00002.152] 01048:01051> TestKillSleepThread [RUNNING] [00002.253] 01048:01051> [PASSED] (100 ms) [00002.253] 01048:01051> TestKillWaitThread [RUNNING] [00002.354] 01048:01051> [PASSED] (100 ms) [00002.354] 01048:01051> TestNonstartedThread [RUNNING] [00002.356] 01048:01051> [PASSED] (2 ms) [00002.356] 01048:01051> TestThreadKillsItself [RUNNING] [00002.368] 01048:01051> [PASSED] (11 ms) [00002.368] 01048:01051> TestInfoTaskStatsFails [RUNNING] [00002.469] 01048:01051> [PASSED] (101 ms) [00002.469] 01048:01051> TestResumeSuspended [RUNNING] [00002.578] 01048:01051> [PASSED] (108 ms) [00002.578] 01048:01051> TestSuspendSleeping [RUNNING] [00002.679] 01048:01051> [PASSED] (100 ms) [00002.679] 01048:01051> TestSuspendChannelCall [RUNNING] [00002.686] 01048:01051> [PASSED] (7 ms) [00002.686] 01048:01051> TestSuspendPortCall [RUNNING] [00002.888] 01048:01051> [PASSED] (201 ms) [00002.888] 01048:01051> TestSuspendStopsThread [RUNNING] [00002.939] 01048:01051> [PASSED] (50 ms) [00002.939] 01048:01051> TestSuspendMultiple [RUNNING] [00002.940] 01048:01051> [PASSED] (0 ms) [00002.940] 01048:01051> TestSuspendSelf [RUNNING] [00002.940] 01048:01051> [PASSED] (0 ms) [00002.940] 01048:01051> TestKillSuspendedThread [RUNNING] [00002.940] 01048:01051> [PASSED] (0 ms) [00002.940] 01048:01051> TestStartSuspendedThread [RUNNING] [00002.971] 01048:01051> [PASSED] (30 ms) [00002.971] 01048:01051> TestStartSuspendedAndResumedThread [RUNNING] [00002.971] 01048:01051> [PASSED] (0 ms) [00002.971] 01048:01051> TestSuspendSingleWaitAsyncSignalDelivery [RUNNING] [00002.987] 01048:01051> [PASSED] (16 ms) [00002.987] 01048:01051> TestSuspendRepeatingWaitAsyncSignalDelivery [RUNNING] [00002.995] 01048:01051> [PASSED] (7 ms) [00002.995] 01048:01051> TestReadingGeneralRegisterState [RUNNING] [00003.096] 01048:01051> [PASSED] (100 ms) [00003.096] 01048:01051> TestWritingGeneralRegisterState [RUNNING] [00003.197] 01048:01051> [PASSED] (100 ms) [00003.197] 01048:01051> TestThreadLocalRegisterState [RUNNING] [00003.297] 01048:01051> [PASSED] (100 ms) [00003.297] 01048:01051> TestNoncanonicalRipAddress [RUNNING] [00003.399] 01048:01051> [PASSED] (101 ms) [00003.399] 01048:01051> TestWritingArmFlagsRegister [RUNNING] [00003.399] 01048:01051> [PASSED] (0 ms) [00003.399] 01048:01051> CASE threads_tests [PASSED] [00003.399] 01048:01051> CASE sync_mutex_tests [STARTED] [00003.399] 01048:01051> test_mutexes [RUNNING] [00003.506] 01048:01051> [PASSED] (106 ms) [00003.506] 01048:01051> test_try_mutexes [RUNNING] [00003.586] 01048:01051> [PASSED] (79 ms) [00003.586] 01048:01051> test_timeout_elapsed [RUNNING] [00004.088] 01048:01051> [PASSED] (502 ms) [00004.088] 01048:01051> CASE sync_mutex_tests [PASSED] [00004.088] 01048:01051> CASE sync_condition_tests [STARTED] [00004.088] 01048:01051> Condition::condition_test [RUNNING] [00004.109] 01048:01051> [PASSED] (20 ms) [00004.109] 01048:01051> Condition::condition_timeout_test [RUNNING] [00004.111] 01048:01051> [PASSED] (2 ms) [00004.111] 01048:01051> CASE sync_condition_tests [PASSED] [00004.111] 01048:01051> CASE sync_completion_tests [STARTED] [00004.111] 01048:01051> test_initializer [RUNNING] [00004.111] 01048:01051> [PASSED] (0 ms) [00004.111] 01048:01051> test_completions [RUNNING] [00004.128] 01048:01051> [PASSED] (16 ms) [00004.128] 01048:01051> test_timeout [RUNNING] [00004.129] 01048:01051> [PASSED] (0 ms) [00004.129] 01048:01051> CASE sync_completion_tests [PASSED] [00004.129] 01048:01051> CASE sync_completion_tests [STARTED] [00004.129] 01048:01051> test_initializer [RUNNING] [00004.129] 01048:01051> [PASSED] (0 ms) [00004.129] 01048:01051> test_completions [RUNNING] [00004.190] 01048:01051> [PASSED] (60 ms) [00004.190] 01048:01051> test_timeout [RUNNING] [00005.335] 01048:01051> [PASSED] (1144 ms) [00005.335] 01048:01051> test_signal_requeue [RUNNING] [00005.355] 01048:01051> [PASSED] (20 ms) [00005.355] 01048:01051> test_completion_signaled [RUNNING] [00005.355] 01048:01051> [PASSED] (0 ms) [00005.355] 01048:01051> CASE sync_completion_tests [PASSED] [00005.355] 01048:01051> CASE stack_tests [STARTED] [00005.355] 01048:01051> main_thread_stack_tests [RUNNING] [00005.355] 01048:01051> [PASSED] (0 ms) [00005.355] 01048:01051> other_thread_stack_tests [RUNNING] [00005.389] 01048:01051> [PASSED] (33 ms) [00005.389] 01048:01051> CASE stack_tests [PASSED] [00005.389] 01048:01051> CASE tls_tests [STARTED] [00005.389] 01048:01051> tls_test [RUNNING] [00007.425] 01048:01051> [PASSED] (2035 ms) [00007.425] 01048:01051> CASE tls_tests [PASSED] [00007.425] 01048:01051> CASE pthread_barrier_tests [STARTED] [00007.425] 01048:01051> test_barrier [RUNNING] [00007.575] 01048:01051> [PASSED] (150 ms) [00007.575] 01048:01051> CASE pthread_barrier_tests [PASSED] [00007.575] 01048:01051> CASE pthread_tests [STARTED] [00007.575] 01048:01051> pthread_test [RUNNING] [00010.703] 01048:01051> [PASSED] (3127 ms) [00010.703] 01048:01051> pthread_self_main_thread_test [RUNNING] [00010.703] 01048:01051> [PASSED] (0 ms) [00010.703] 01048:01051> pthread_big_stack_size [RUNNING] [00010.724] 01048:01051> [PASSED] (20 ms) [00010.724] 01048:01051> pthread_getstack_main_thread [RUNNING] [00010.724] 01048:01051> [PASSED] (0 ms) [00010.724] 01048:01051> pthread_getstack_other_thread [RUNNING] [00010.724] 01048:01051> [PASSED] (0 ms) [00010.724] 01048:01051> CASE pthread_tests [PASSED] [00010.724] 01048:01051> CASE process_tests [STARTED] [00010.724] 01048:01051> mini_process_sanity [RUNNING] [00010.762] 01048:01051> [PASSED] (37 ms) [00010.762] 01048:01051> process_start_fail [RUNNING] [00010.770] 01048:01051> [PASSED] (8 ms) [00010.770] 01048:01051> process_start_no_handle [RUNNING] [00010.771] 01048:01051> [PASSED] (0 ms) [00010.771] 01048:01051> process_not_killed_via_thread_close [RUNNING] [00010.788] 01048:01051> [PASSED] (16 ms) [00010.788] 01048:01051> process_not_killed_via_process_close [RUNNING] [00010.796] 01048:01051> [PASSED] (8 ms) [00010.796] 01048:01051> kill_process_via_thread_kill [RUNNING] [00010.805] 01048:01051> [PASSED] (8 ms) [00010.805] 01048:01051> kill_process_via_vmar_destroy [RUNNING] [00010.805] 02077:02079> PageFault: 1029101 free pages [00010.805] 02077:02079> [00010.805] 02077:02079> ZIRCON KERNEL PANIC [00010.805] 02077:02079> Lock validation failed for thread 0xffffff8000771510 pid 2077 tid 2079 (ttp:th): [00010.805] 02077:02079> Reason: Already Acquired [00010.805] 02077:02079> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00010.805] 02077:02079> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00010.805] 02077:02079> caller=0xffffffff0014b49b frame=0xffffff98efa71d60 [00010.805] 02077:02079> BUILDID git-1fd4ec2f02fc15f15eb764f03bcf7db389dc96bc-dirty [00010.805] 02077:02079> [00010.805] 02077:02079> {{{module:0:kernel:elf:5a442b2e49e51a94}}} [00010.805] 02077:02079> {{{mmap:0xffffffff00100000:0x14c7c8:load:0:rx:0xffffffff80100000}}} [00010.805] 02077:02079> {{{mmap:0xffffffff0024d000:0x4a000:load:0:r:0xffffffff8024d000}}} [00010.805] 02077:02079> {{{mmap:0xffffffff00297000:0x6450:load:0:rw:0xffffffff80297000}}} [00010.805] 02077:02079> {{{mmap:0xffffffff0029e000:0x96000:load:0:rw:0xffffffff8029e000}}} [00010.805] 02077:02079> dso: id=5a442b2e49e51a94 base=0xffffffff00100000 name=zircon.elf [00010.805] 02077:02079> bt#00: 0xffffffff0014b49b [00010.805] 02077:02079> bt#01: 0xffffffff0014b8cf [00010.805] 02077:02079> bt#02: 0xffffffff0013716e [00010.805] 02077:02079> bt#03: 0xffffffff001f6944 [00010.805] 02077:02079> bt#04: 0xffffffff001bd43e [00010.805] 02077:02079> bt#05: 0xffffffff001bd08b [00010.805] 02077:02079> bt#06: end [00010.805] 02077:02079> {{{bt:0:0xffffffff0014b49b}}} [00010.805] 02077:02079> {{{bt:1:0xffffffff0014b8cf}}} [00010.805] 02077:02079> {{{bt:2:0xffffffff0013716e}}} [00010.805] 02077:02079> {{{bt:3:0xffffffff001f6944}}} [00010.805] 02077:02079> {{{bt:4:0xffffffff001bd43e}}} [00010.805] 02077:02079> {{{bt:5:0xffffffff001bd08b}}} [00010.805] 02077:02079> [00010.813] 01048:01051> [PASSED] (8 ms) [00010.813] 01048:01051> kill_channel_handle_cycle [RUNNING] [00011.571] 01048:01051> [PASSED] (757 ms) [00011.571] 01048:01051> info_reflects_process_state [RUNNING] [00011.822] 01048:01051> [PASSED] (251 ms) [00011.822] 01048:01051> suspend [RUNNING] [00011.823] 01048:01051> [PASSED] (0 ms) [00011.823] 01048:01051> suspend_self [RUNNING] [00011.823] 01048:01051> [PASSED] (0 ms) [00011.823] 01048:01051> suspend_multiple_threads [RUNNING] [00011.847] 01048:01051> [PASSED] (23 ms) [00011.847] 01048:01051> suspend_before_creating_threads [RUNNING] [00011.847] 01048:01051> [PASSED] (0 ms) [00011.847] 01048:01051> suspend_before_starting_threads [RUNNING] [00011.848] 01048:01051> [PASSED] (0 ms) [00011.848] 01048:01051> suspend_process_then_thread [RUNNING] [00012.099] 01048:01051> [PASSED] (250 ms) [00012.099] 01048:01051> suspend_thread_then_process [RUNNING] [00012.359] 01048:01051> [PASSED] (259 ms) [00012.359] 01048:01051> suspend_thread_and_process_before_starting_process [RUNNING] [00012.619] 01048:01051> [PASSED] (260 ms) [00012.619] 01048:01051> suspend_twice [RUNNING] [00012.879] 01048:01051> [PASSED] (259 ms) [00012.879] 01048:01051> suspend_twice_before_creating_threads [RUNNING] [00013.131] 01048:01051> [PASSED] (251 ms) [00013.131] 01048:01051> suspend_with_dying_thread [RUNNING] [00013.141] 01048:01051> [PASSED] (9 ms) [00013.141] 01048:01051> create_and_kill_job_race_stress [RUNNING] [00014.153] 01048:01051> [PASSED] (1011 ms) [00014.153] 01048:01051> CASE process_tests [PASSED] [00014.153] 01048:01051> CASE port_tests [STARTED] [00014.153] 01048:01051> basic_test [RUNNING] [00014.153] 01048:01051> [PASSED] (0 ms) [00014.153] 01048:01051> queue_and_close_test [RUNNING] [00014.153] 01048:01051> [PASSED] (0 ms) [00014.153] 01048:01051> queue_too_many [RUNNING] [00014.154] 01048:01051> [PASSED] (1 ms) [00014.155] 01048:01051> async_wait_channel_test [RUNNING] [00014.157] 01048:01051> [PASSED] (2 ms) [00014.157] 01048:01051> async_wait_event_test_single [RUNNING] [00014.157] 01048:01051> [PASSED] (0 ms) [00014.157] 01048:01051> async_wait_event_test_repeat [RUNNING] [00014.157] 01048:01051> [PASSED] (0 ms) [00014.157] 01048:01051> async_wait_event_all_processed [RUNNING] [00014.212] 01048:01051> [PASSED] (55 ms) [00014.212] 01048:01051> async_wait_invalid_option [RUNNING] [00014.212] 01048:01051> [PASSED] (0 ms) [00014.212] 01048:01051> async_wait_close_order_1 [RUNNING] [00014.212] 01048:01051> [PASSED] (0 ms) [00014.212] 01048:01051> async_wait_close_order_2 [RUNNING] [00014.212] 01048:01051> [PASSED] (0 ms) [00014.212] 01048:01051> async_wait_close_order_3 [RUNNING] [00014.212] 01048:01051> [PASSED] (0 ms) [00014.212] 01048:01051> async_wait_close_order_4 [RUNNING] [00014.212] 01048:01051> [PASSED] (0 ms) [00014.212] 01048:01051> async_wait_close_order_5 [RUNNING] [00014.212] 01048:01051> [PASSED] (0 ms) [00014.212] 01048:01051> async_wait_close_order_6 [RUNNING] [00014.212] 01048:01051> [PASSED] (0 ms) [00014.212] 01048:01051> channel_pre_writes_test [RUNNING] [00014.212] 01048:01051> [PASSED] (0 ms) [00014.212] 01048:01051> cancel_event_key [RUNNING] [00014.212] 01048:01051> [PASSED] (0 ms) [00014.212] 01048:01051> cancel_event_key_after [RUNNING] [00014.212] 01048:01051> [PASSED] (0 ms) [00014.212] 01048:01051> threads_event [RUNNING] [00014.296] 01048:01051> [PASSED] (83 ms) [00014.296] 01048:01051> cancel_stress [RUNNING] [00019.600] 01048:01051> [PASSED] (5303 ms) [00019.600] 01048:01051> signal_close_stress [RUNNING] [00020.640] 01048:01051> [PASSED] (1040 ms) [00020.640] 01048:01051> port_close_wait_race_stress [RUNNING] [00021.655] 01048:01051> [PASSED] (1014 ms) [00021.655] 01048:01051> CASE port_tests [PASSED] [00021.655] 01048:01051> CASE api_violations [STARTED] [00021.655] 01048:01051> invalid_pager_create [RUNNING] [00021.655] 01048:01051> [PASSED] (0 ms) [00021.655] 01048:01051> invalid_pager_create_vmo [RUNNING] [00021.655] 01048:01051> [PASSED] (0 ms) [00021.655] 01048:01051> invalid_pager_detach_vmo [RUNNING] [00021.655] 01048:01051> [PASSED] (0 ms) [00021.655] 01048:01051> invalid_pager_supply_pages [RUNNING] [00021.655] 01048:01051> [PASSED] (0 ms) [00021.655] 01048:01051> resize_nonresizable_vmo [RUNNING] [00021.655] 01048:01051> [PASSED] (0 ms) [00021.655] 01048:01051> CASE api_violations [PASSED] [00021.655] 01048:01051> CASE commit_tests [STARTED] [00021.655] 01048:01051> simple_commit_test [RUNNING] [00021.662] 01048:01051> [PASSED] (6 ms) [00021.662] 01048:01051> split_commit_test [RUNNING] [00021.667] 01048:01051> [PASSED] (4 ms) [00021.667] 01048:01051> overlap_commit_test [RUNNING] [00021.672] 01048:01051> [PASSED] (4 ms) [00021.672] 01048:01051> overlap_commit_supply_test [RUNNING] [00021.741] 01048:01051> [PASSED] (69 ms) [00021.741] 01048:01051> multisupply_commit_test [RUNNING] [00021.750] 01048:01051> [PASSED] (9 ms) [00021.750] 01048:01051> multicommit_supply_test [RUNNING] [00021.762] 01048:01051> [PASSED] (11 ms) [00021.762] 01048:01051> commit_redundant_supply_test [RUNNING] [00021.771] 01048:01051> [PASSED] (8 ms) [00021.771] 01048:01051> supply_decommit_test [RUNNING] [00021.779] 01048:01051> [PASSED] (8 ms) [00021.779] 01048:01051> resize_commit_test [RUNNING] [00021.788] 01048:01051> [PASSED] (8 ms) [00021.788] 01048:01051> suspend_commit_test [RUNNING] [00021.796] 01048:01051> [PASSED] (7 ms) [00021.796] 01048:01051> CASE commit_tests [PASSED] [00021.796] 01048:01051> CASE clone_tests [STARTED] [00021.796] 01048:01051> clone_read_from_clone_test_vmar [RUNNING] [00021.803] 01048:01051> [PASSED] (6 ms) [00021.803] 01048:01051> clone_read_from_clone_test_vmo [RUNNING] [00021.812] 01048:01051> [PASSED] (9 ms) [00021.812] 01048:01051> clone_read_from_parent_test_vmar [RUNNING] [00021.826] 01048:01051> [PASSED] (13 ms) [00021.826] 01048:01051> clone_read_from_parent_test_vmo [RUNNING] [00021.834] 01048:01051> [PASSED] (7 ms) [00021.834] 01048:01051> clone_simultaneous_read_test_vmar [RUNNING] [00021.843] 01048:01051> [PASSED] (9 ms) [00021.843] 01048:01051> clone_simultaneous_read_test_vmo [RUNNING] [00021.852] 01048:01051> [PASSED] (9 ms) [00021.852] 01048:01051> clone_simultaneous_child_read_test_vmar [RUNNING] [00021.859] 01048:01051> [PASSED] (7 ms) [00021.859] 01048:01051> clone_simultaneous_child_read_test_vmo [RUNNING] [00021.868] 01048:01051> [PASSED] (8 ms) [00021.868] 01048:01051> clone_write_to_clone_test [RUNNING] [00021.875] 01048:01051> [PASSED] (7 ms) [00021.875] 01048:01051> clone_detach_test [RUNNING] [00021.882] 01048:01051> [PASSED] (6 ms) [00021.882] 01048:01051> clone_commit_test [RUNNING] [00021.889] 01048:01051> [PASSED] (7 ms) [00021.889] 01048:01051> clone_split_commit_test [RUNNING] [00021.897] 01048:01051> [PASSED] (7 ms) [00021.897] 01048:01051> clone_decommit_test [RUNNING] [00021.897] 01048:01051> [PASSED] (0 ms) [00021.897] 01048:01051> CASE clone_tests [PASSED] [00021.897] 01048:01051> CASE lifecycle_tests [STARTED] [00021.897] 01048:01051> vmo_info_pager_test [RUNNING] [00021.897] 01048:01051> [PASSED] (0 ms) [00021.897] 01048:01051> detach_page_complete_test [RUNNING] [00021.897] 01048:01051> [PASSED] (0 ms) [00021.897] 01048:01051> close_page_complete_test [RUNNING] [00021.897] 01048:01051> [PASSED] (0 ms) [00021.897] 01048:01051> read_detach_interrupt_late_test_vmar [RUNNING] [00021.897] 01048:25703> PageFault: 1027417 free pages [00021.897] 01048:25703> [00021.897] 01048:25703> ZIRCON KERNEL PANIC [00021.897] 01048:25703> Lock validation failed for thread 0xffffff800317d9e0 pid 1048 tid 25703 (bin/core-tests:test_thread): [00021.897] 01048:25703> Reason: Already Acquired [00021.897] 01048:25703> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00021.897] 01048:25703> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00021.897] 01048:25703> caller=0xffffffff0014b49b frame=0xffffff98efa71d60 [00021.897] 01048:25703> BUILDID git-1fd4ec2f02fc15f15eb764f03bcf7db389dc96bc-dirty [00021.897] 01048:25703> [00021.897] 01048:25703> {{{module:0:kernel:elf:5a442b2e49e51a94}}} [00021.897] 01048:25703> {{{mmap:0xffffffff00100000:0x14c7c8:load:0:rx:0xffffffff80100000}}} [00021.897] 01048:25703> {{{mmap:0xffffffff0024d000:0x4a000:load:0:r:0xffffffff8024d000}}} [00021.897] 01048:25703> {{{mmap:0xffffffff00297000:0x6450:load:0:rw:0xffffffff80297000}}} [00021.897] 01048:25703> {{{mmap:0xffffffff0029e000:0x96000:load:0:rw:0xffffffff8029e000}}} [00021.897] 01048:25703> dso: id=5a442b2e49e51a94 base=0xffffffff00100000 name=zircon.elf [00021.897] 01048:25703> bt#00: 0xffffffff0014b49b [00021.897] 01048:25703> bt#01: 0xffffffff0014b8cf [00021.897] 01048:25703> bt#02: 0xffffffff0013716e [00021.897] 01048:25703> bt#03: 0xffffffff001f6944 [00021.897] 01048:25703> bt#04: 0xffffffff001bd43e [00021.897] 01048:25703> bt#05: 0xffffffff001bd08b [00021.897] 01048:25703> bt#06: end [00021.897] 01048:25703> {{{bt:0:0xffffffff0014b49b}}} [00021.897] 01048:25703> {{{bt:1:0xffffffff0014b8cf}}} [00021.897] 01048:25703> {{{bt:2:0xffffffff0013716e}}} [00021.897] 01048:25703> {{{bt:3:0xffffffff001f6944}}} [00021.897] 01048:25703> {{{bt:4:0xffffffff001bd43e}}} [00021.897] 01048:25703> {{{bt:5:0xffffffff001bd08b}}} [00021.897] 01048:25703> [00021.897] 01048:25703> [00021.897] 01048:25703> ZIRCON KERNEL PANIC [00021.897] 01048:25703> Lock validation failed for thread 0xffffff800317d9e0 pid 1048 tid 25703 (bin/core-tests:test_thread): [00021.897] 01048:25703> Reason: Already Acquired [00021.897] 01048:25703> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00021.897] 01048:25703> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00021.897] 01048:25703> caller=0xffffffff0014b49b frame=0xffffff98efa71cd0 [00021.897] 01048:25703> BUILDID git-1fd4ec2f02fc15f15eb764f03bcf7db389dc96bc-dirty [00021.897] 01048:25703> [00021.897] 01048:25703> {{{module:0:kernel:elf:5a442b2e49e51a94}}} [00021.897] 01048:25703> {{{mmap:0xffffffff00100000:0x14c7c8:load:0:rx:0xffffffff80100000}}} [00021.897] 01048:25703> {{{mmap:0xffffffff0024d000:0x4a000:load:0:r:0xffffffff8024d000}}} [00021.897] 01048:25703> {{{mmap:0xffffffff00297000:0x6450:load:0:rw:0xffffffff80297000}}} [00021.897] 01048:25703> {{{mmap:0xffffffff0029e000:0x96000:load:0:rw:0xffffffff8029e000}}} [00021.897] 01048:25703> dso: id=5a442b2e49e51a94 base=0xffffffff00100000 name=zircon.elf [00021.897] 01048:25703> bt#00: 0xffffffff0014b49b [00021.897] 01048:25703> bt#01: 0xffffffff0014b8cf [00021.897] 01048:25703> bt#02: 0xffffffff0014b8cf [00021.897] 01048:25703> bt#03: 0xffffffff0013716e [00021.897] 01048:25703> bt#04: 0xffffffff001f6944 [00021.897] 01048:25703> bt#05: 0xffffffff001bd43e [00021.897] 01048:25703> bt#06: 0xffffffff001bd08b [00021.897] 01048:25703> bt#07: end [00021.897] 01048:25703> {{{bt:0:0xffffffff0014b49b}}} [00021.897] 01048:25703> {{{bt:1:0xffffffff0014b8cf}}} [00021.897] 01048:25703> {{{bt:2:0xffffffff0014b8cf}}} [00021.897] 01048:25703> {{{bt:3:0xffffffff0013716e}}} [00021.897] 01048:25703> {{{bt:4:0xffffffff001f6944}}} [00021.897] 01048:25703> {{{bt:5:0xffffffff001bd43e}}} [00021.897] 01048:25703> {{{bt:6:0xffffffff001bd08b}}} [00021.897] 01048:25703> [00021.897] 01048:25703> [00021.897] 01048:25703> ZIRCON KERNEL PANIC [00021.897] 01048:25703> Lock validation failed for thread 0xffffff800317d9e0 pid 1048 tid 25703 (bin/core-tests:test_thread): [00021.897] 01048:25703> Reason: Already Acquired [00021.897] 01048:25703> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00021.897] 01048:25703> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00021.897] 01048:25703> caller=0xffffffff0014b49b frame=0xffffff98efa71cd0 [00021.897] 01048:25703> BUILDID git-1fd4ec2f02fc15f15eb764f03bcf7db389dc96bc-dirty [00021.897] 01048:25703> [00021.897] 01048:25703> {{{module:0:kernel:elf:5a442b2e49e51a94}}} [00021.897] 01048:25703> {{{mmap:0xffffffff00100000:0x14c7c8:load:0:rx:0xffffffff80100000}}} [00021.897] 01048:25703> {{{mmap:0xffffffff0024d000:0x4a000:load:0:r:0xffffffff8024d000}}} [00021.897] 01048:25703> {{{mmap:0xffffffff00297000:0x6450:load:0:rw:0xffffffff80297000}}} [00021.897] 01048:25703> {{{mmap:0xffffffff0029e000:0x96000:load:0:rw:0xffffffff8029e000}}} [00021.897] 01048:25703> dso: id=5a442b2e49e51a94 base=0xffffffff00100000 name=zircon.elf [00021.897] 01048:25703> bt#00: 0xffffffff0014b49b [00021.897] 01048:25703> bt#01: 0xffffffff0014b8cf [00021.897] 01048:25703> bt#02: 0xffffffff0014b8cf [00021.897] 01048:25703> bt#03: 0xffffffff0013716e [00021.897] 01048:25703> bt#04: 0xffffffff001f6944 [00021.897] 01048:25703> bt#05: 0xffffffff001bd43e [00021.897] 01048:25703> bt#06: 0xffffffff001bd08b [00021.897] 01048:25703> bt#07: end [00021.897] 01048:25703> {{{bt:0:0xffffffff0014b49b}}} [00021.897] 01048:25703> {{{bt:1:0xffffffff0014b8cf}}} [00021.897] 01048:25703> {{{bt:2:0xffffffff0014b8cf}}} [00021.897] 01048:25703> {{{bt:3:0xffffffff0013716e}}} [00021.897] 01048:25703> {{{bt:4:0xffffffff001f6944}}} [00021.897] 01048:25703> {{{bt:5:0xffffffff001bd43e}}} [00021.897] 01048:25703> {{{bt:6:0xffffffff001bd08b}}} [00021.897] 01048:25703> [00021.904] 01048:01051> [PASSED] (7 ms) [00021.904] 01048:01051> read_detach_interrupt_late_test_vmo [RUNNING] [00021.911] 01048:01051> [PASSED] (6 ms) [00021.911] 01048:01051> read_close_interrupt_late_test_vmar [RUNNING] [00021.911] 01048:25723> PageFault: 1027418 free pages [00021.911] 01048:25723> [00021.911] 01048:25723> ZIRCON KERNEL PANIC [00021.911] 01048:25723> Lock validation failed for thread 0xffffff800317d9e0 pid 1048 tid 25723 (bin/core-tests:test_thread): [00021.911] 01048:25723> Reason: Already Acquired [00021.911] 01048:25723> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00021.911] 01048:25723> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00021.911] 01048:25723> caller=0xffffffff0014b49b frame=0xffffff98efa71d60 [00021.911] 01048:25723> BUILDID git-1fd4ec2f02fc15f15eb764f03bcf7db389dc96bc-dirty [00021.911] 01048:25723> [00021.911] 01048:25723> {{{module:0:kernel:elf:5a442b2e49e51a94}}} [00021.911] 01048:25723> {{{mmap:0xffffffff00100000:0x14c7c8:load:0:rx:0xffffffff80100000}}} [00021.911] 01048:25723> {{{mmap:0xffffffff0024d000:0x4a000:load:0:r:0xffffffff8024d000}}} [00021.911] 01048:25723> {{{mmap:0xffffffff00297000:0x6450:load:0:rw:0xffffffff80297000}}} [00021.911] 01048:25723> {{{mmap:0xffffffff0029e000:0x96000:load:0:rw:0xffffffff8029e000}}} [00021.911] 01048:25723> dso: id=5a442b2e49e51a94 base=0xffffffff00100000 name=zircon.elf [00021.911] 01048:25723> bt#00: 0xffffffff0014b49b [00021.911] 01048:25723> bt#01: 0xffffffff0014b8cf [00021.911] 01048:25723> bt#02: 0xffffffff0013716e [00021.911] 01048:25723> bt#03: 0xffffffff001f6944 [00021.911] 01048:25723> bt#04: 0xffffffff001bd43e [00021.911] 01048:25723> bt#05: 0xffffffff001bd08b [00021.911] 01048:25723> bt#06: end [00021.911] 01048:25723> {{{bt:0:0xffffffff0014b49b}}} [00021.911] 01048:25723> {{{bt:1:0xffffffff0014b8cf}}} [00021.911] 01048:25723> {{{bt:2:0xffffffff0013716e}}} [00021.911] 01048:25723> {{{bt:3:0xffffffff001f6944}}} [00021.911] 01048:25723> {{{bt:4:0xffffffff001bd43e}}} [00021.911] 01048:25723> {{{bt:5:0xffffffff001bd08b}}} [00021.911] 01048:25723> [00021.911] 01048:25723> [00021.911] 01048:25723> ZIRCON KERNEL PANIC [00021.911] 01048:25723> Lock validation failed for thread 0xffffff800317d9e0 pid 1048 tid 25723 (bin/core-tests:test_thread): [00021.911] 01048:25723> Reason: Already Acquired [00021.911] 01048:25723> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00021.911] 01048:25723> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00021.911] 01048:25723> caller=0xffffffff0014b49b frame=0xffffff98efa71cd0 [00021.911] 01048:25723> BUILDID git-1fd4ec2f02fc15f15eb764f03bcf7db389dc96bc-dirty [00021.911] 01048:25723> [00021.911] 01048:25723> {{{module:0:kernel:elf:5a442b2e49e51a94}}} [00021.911] 01048:25723> {{{mmap:0xffffffff00100000:0x14c7c8:load:0:rx:0xffffffff80100000}}} [00021.911] 01048:25723> {{{mmap:0xffffffff0024d000:0x4a000:load:0:r:0xffffffff8024d000}}} [00021.911] 01048:25723> {{{mmap:0xffffffff00297000:0x6450:load:0:rw:0xffffffff80297000}}} [00021.911] 01048:25723> {{{mmap:0xffffffff0029e000:0x96000:load:0:rw:0xffffffff8029e000}}} [00021.911] 01048:25723> dso: id=5a442b2e49e51a94 base=0xffffffff00100000 name=zircon.elf [00021.911] 01048:25723> bt#00: 0xffffffff0014b49b [00021.911] 01048:25723> bt#01: 0xffffffff0014b8cf [00021.911] 01048:25723> bt#02: 0xffffffff0014b8cf [00021.911] 01048:25723> bt#03: 0xffffffff0013716e [00021.911] 01048:25723> bt#04: 0xffffffff001f6944 [00021.911] 01048:25723> bt#05: 0xffffffff001bd43e [00021.911] 01048:25723> bt#06: 0xffffffff001bd08b [00021.911] 01048:25723> bt#07: end [00021.911] 01048:25723> {{{bt:0:0xffffffff0014b49b}}} [00021.911] 01048:25723> {{{bt:1:0xffffffff0014b8cf}}} [00021.911] 01048:25723> {{{bt:2:0xffffffff0014b8cf}}} [00021.911] 01048:25723> {{{bt:3:0xffffffff0013716e}}} [00021.911] 01048:25723> {{{bt:4:0xffffffff001f6944}}} [00021.911] 01048:25723> {{{bt:5:0xffffffff001bd43e}}} [00021.911] 01048:25723> {{{bt:6:0xffffffff001bd08b}}} [00021.911] 01048:25723> [00021.911] 01048:25723> [00021.911] 01048:25723> ZIRCON KERNEL PANIC [00021.911] 01048:25723> Lock validation failed for thread 0xffffff800317d9e0 pid 1048 tid 25723 (bin/core-tests:test_thread): [00021.911] 01048:25723> Reason: Already Acquired [00021.911] 01048:25723> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00021.911] 01048:25723> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00021.911] 01048:25723> caller=0xffffffff0014b49b frame=0xffffff98efa71cd0 [00021.911] 01048:25723> BUILDID git-1fd4ec2f02fc15f15eb764f03bcf7db389dc96bc-dirty [00021.911] 01048:25723> [00021.911] 01048:25723> {{{module:0:kernel:elf:5a442b2e49e51a94}}} [00021.911] 01048:25723> {{{mmap:0xffffffff00100000:0x14c7c8:load:0:rx:0xffffffff80100000}}} [00021.911] 01048:25723> {{{mmap:0xffffffff0024d000:0x4a000:load:0:r:0xffffffff8024d000}}} [00021.911] 01048:25723> {{{mmap:0xffffffff00297000:0x6450:load:0:rw:0xffffffff80297000}}} [00021.911] 01048:25723> {{{mmap:0xffffffff0029e000:0x96000:load:0:rw:0xffffffff8029e000}}} [00021.911] 01048:25723> dso: id=5a442b2e49e51a94 base=0xffffffff00100000 name=zircon.elf [00021.911] 01048:25723> bt#00: 0xffffffff0014b49b [00021.911] 01048:25723> bt#01: 0xffffffff0014b8cf [00021.911] 01048:25723> bt#02: 0xffffffff0014b8cf [00021.911] 01048:25723> bt#03: 0xffffffff0013716e [00021.911] 01048:25723> bt#04: 0xffffffff001f6944 [00021.911] 01048:25723> bt#05: 0xffffffff001bd43e [00021.911] 01048:25723> bt#06: 0xffffffff001bd08b [00021.911] 01048:25723> bt#07: end [00021.911] 01048:25723> {{{bt:0:0xffffffff0014b49b}}} [00021.911] 01048:25723> {{{bt:1:0xffffffff0014b8cf}}} [00021.911] 01048:25723> {{{bt:2:0xffffffff0014b8cf}}} [00021.911] 01048:25723> {{{bt:3:0xffffffff0013716e}}} [00021.911] 01048:25723> {{{bt:4:0xffffffff001f6944}}} [00021.911] 01048:25723> {{{bt:5:0xffffffff001bd43e}}} [00021.911] 01048:25723> {{{bt:6:0xffffffff001bd08b}}} [00021.911] 01048:25723> [00021.920] 01048:01051> [PASSED] (9 ms) [00021.920] 01048:01051> read_close_interrupt_late_test_vmo [RUNNING] [00021.927] 01048:01051> [PASSED] (7 ms) [00021.927] 01048:01051> read_detach_interrupt_early_test_vmar [RUNNING] [00021.928] 01048:25743> PageFault: 1027418 free pages [00021.928] 01048:25743> [00021.928] 01048:25743> ZIRCON KERNEL PANIC [00021.928] 01048:25743> Lock validation failed for thread 0xffffff800317d9e0 pid 1048 tid 25743 (bin/core-tests:test_thread): [00021.928] 01048:25743> Reason: Already Acquired [00021.928] 01048:25743> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00021.928] 01048:25743> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00021.928] 01048:25743> caller=0xffffffff0014b49b frame=0xffffff98efa71d60 [00021.928] 01048:25743> BUILDID git-1fd4ec2f02fc15f15eb764f03bcf7db389dc96bc-dirty [00021.928] 01048:25743> [00021.928] 01048:25743> {{{module:0:kernel:elf:5a442b2e49e51a94}}} [00021.928] 01048:25743> {{{mmap:0xffffffff00100000:0x14c7c8:load:0:rx:0xffffffff80100000}}} [00021.928] 01048:25743> {{{mmap:0xffffffff0024d000:0x4a000:load:0:r:0xffffffff8024d000}}} [00021.928] 01048:25743> {{{mmap:0xffffffff00297000:0x6450:load:0:rw:0xffffffff80297000}}} [00021.928] 01048:25743> {{{mmap:0xffffffff0029e000:0x96000:load:0:rw:0xffffffff8029e000}}} [00021.928] 01048:25743> dso: id=5a442b2e49e51a94 base=0xffffffff00100000 name=zircon.elf [00021.928] 01048:25743> bt#00: 0xffffffff0014b49b [00021.928] 01048:25743> bt#01: 0xffffffff0014b8cf [00021.928] 01048:25743> bt#02: 0xffffffff0013716e [00021.928] 01048:25743> bt#03: 0xffffffff001f6944 [00021.928] 01048:25743> bt#04: 0xffffffff001bd43e [00021.928] 01048:25743> bt#05: 0xffffffff001bd08b [00021.928] 01048:25743> bt#06: end [00021.928] 01048:25743> {{{bt:0:0xffffffff0014b49b}}} [00021.928] 01048:25743> {{{bt:1:0xffffffff0014b8cf}}} [00021.928] 01048:25743> {{{bt:2:0xffffffff0013716e}}} [00021.928] 01048:25743> {{{bt:3:0xffffffff001f6944}}} [00021.928] 01048:25743> {{{bt:4:0xffffffff001bd43e}}} [00021.928] 01048:25743> {{{bt:5:0xffffffff001bd08b}}} [00021.928] 01048:25743> [00021.928] 01048:25743> [00021.928] 01048:25743> ZIRCON KERNEL PANIC [00021.928] 01048:25743> Lock validation failed for thread 0xffffff800317d9e0 pid 1048 tid 25743 (bin/core-tests:test_thread): [00021.928] 01048:25743> Reason: Already Acquired [00021.928] 01048:25743> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00021.928] 01048:25743> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00021.928] 01048:25743> caller=0xffffffff0014b49b frame=0xffffff98efa71cd0 [00021.928] 01048:25743> BUILDID git-1fd4ec2f02fc15f15eb764f03bcf7db389dc96bc-dirty [00021.928] 01048:25743> [00021.928] 01048:25743> {{{module:0:kernel:elf:5a442b2e49e51a94}}} [00021.928] 01048:25743> {{{mmap:0xffffffff00100000:0x14c7c8:load:0:rx:0xffffffff80100000}}} [00021.928] 01048:25743> {{{mmap:0xffffffff0024d000:0x4a000:load:0:r:0xffffffff8024d000}}} [00021.928] 01048:25743> {{{mmap:0xffffffff00297000:0x6450:load:0:rw:0xffffffff80297000}}} [00021.928] 01048:25743> {{{mmap:0xffffffff0029e000:0x96000:load:0:rw:0xffffffff8029e000}}} [00021.928] 01048:25743> dso: id=5a442b2e49e51a94 base=0xffffffff00100000 name=zircon.elf [00021.928] 01048:25743> bt#00: 0xffffffff0014b49b [00021.928] 01048:25743> bt#01: 0xffffffff0014b8cf [00021.928] 01048:25743> bt#02: 0xffffffff0014b8cf [00021.928] 01048:25743> bt#03: 0xffffffff0013716e [00021.928] 01048:25743> bt#04: 0xffffffff001f6944 [00021.928] 01048:25743> bt#05: 0xffffffff001bd43e [00021.928] 01048:25743> bt#06: 0xffffffff001bd08b [00021.928] 01048:25743> bt#07: end [00021.928] 01048:25743> {{{bt:0:0xffffffff0014b49b}}} [00021.928] 01048:25743> {{{bt:1:0xffffffff0014b8cf}}} [00021.928] 01048:25743> {{{bt:2:0xffffffff0014b8cf}}} [00021.928] 01048:25743> {{{bt:3:0xffffffff0013716e}}} [00021.928] 01048:25743> {{{bt:4:0xffffffff001f6944}}} [00021.928] 01048:25743> {{{bt:5:0xffffffff001bd43e}}} [00021.928] 01048:25743> {{{bt:6:0xffffffff001bd08b}}} [00021.928] 01048:25743> [00021.928] 01048:25743> [00021.928] 01048:25743> ZIRCON KERNEL PANIC [00021.928] 01048:25743> Lock validation failed for thread 0xffffff800317d9e0 pid 1048 tid 25743 (bin/core-tests:test_thread): [00021.928] 01048:25743> Reason: Already Acquired [00021.928] 01048:25743> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00021.928] 01048:25743> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00021.928] 01048:25743> caller=0xffffffff0014b49b frame=0xffffff98efa71cd0 [00021.928] 01048:25743> BUILDID git-1fd4ec2f02fc15f15eb764f03bcf7db389dc96bc-dirty [00021.928] 01048:25743> [00021.928] 01048:25743> {{{module:0:kernel:elf:5a442b2e49e51a94}}} [00021.928] 01048:25743> {{{mmap:0xffffffff00100000:0x14c7c8:load:0:rx:0xffffffff80100000}}} [00021.928] 01048:25743> {{{mmap:0xffffffff0024d000:0x4a000:load:0:r:0xffffffff8024d000}}} [00021.928] 01048:25743> {{{mmap:0xffffffff00297000:0x6450:load:0:rw:0xffffffff80297000}}} [00021.928] 01048:25743> {{{mmap:0xffffffff0029e000:0x96000:load:0:rw:0xffffffff8029e000}}} [00021.928] 01048:25743> dso: id=5a442b2e49e51a94 base=0xffffffff00100000 name=zircon.elf [00021.928] 01048:25743> bt#00: 0xffffffff0014b49b [00021.928] 01048:25743> bt#01: 0xffffffff0014b8cf [00021.928] 01048:25743> bt#02: 0xffffffff0014b8cf [00021.928] 01048:25743> bt#03: 0xffffffff0013716e [00021.928] 01048:25743> bt#04: 0xffffffff001f6944 [00021.928] 01048:25743> bt#05: 0xffffffff001bd43e [00021.928] 01048:25743> bt#06: 0xffffffff001bd08b [00021.928] 01048:25743> bt#07: end [00021.928] 01048:25743> {{{bt:0:0xffffffff0014b49b}}} [00021.928] 01048:25743> {{{bt:1:0xffffffff0014b8cf}}} [00021.928] 01048:25743> {{{bt:2:0xffffffff0014b8cf}}} [00021.928] 01048:25743> {{{bt:3:0xffffffff0013716e}}} [00021.928] 01048:25743> {{{bt:4:0xffffffff001f6944}}} [00021.928] 01048:25743> {{{bt:5:0xffffffff001bd43e}}} [00021.928] 01048:25743> {{{bt:6:0xffffffff001bd08b}}} [00021.928] 01048:25743> [00021.934] 01048:01051> [PASSED] (7 ms) [00021.934] 01048:01051> read_detach_interrupt_early_test_vmo [RUNNING] [00021.941] 01048:01051> [PASSED] (6 ms) [00021.941] 01048:01051> read_close_interrupt_early_test_vmar [RUNNING] [00021.942] 01048:25763> PageFault: 1027419 free pages [00021.942] 01048:25763> [00021.942] 01048:25763> ZIRCON KERNEL PANIC [00021.942] 01048:25763> Lock validation failed for thread 0xffffff800317d9e0 pid 1048 tid 25763 (bin/core-tests:test_thread): [00021.942] 01048:25763> Reason: Already Acquired [00021.942] 01048:25763> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00021.942] 01048:25763> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00021.942] 01048:25763> caller=0xffffffff0014b49b frame=0xffffff98efa71d60 [00021.942] 01048:25763> BUILDID git-1fd4ec2f02fc15f15eb764f03bcf7db389dc96bc-dirty [00021.942] 01048:25763> [00021.942] 01048:25763> {{{module:0:kernel:elf:5a442b2e49e51a94}}} [00021.942] 01048:25763> {{{mmap:0xffffffff00100000:0x14c7c8:load:0:rx:0xffffffff80100000}}} [00021.942] 01048:25763> {{{mmap:0xffffffff0024d000:0x4a000:load:0:r:0xffffffff8024d000}}} [00021.942] 01048:25763> {{{mmap:0xffffffff00297000:0x6450:load:0:rw:0xffffffff80297000}}} [00021.942] 01048:25763> {{{mmap:0xffffffff0029e000:0x96000:load:0:rw:0xffffffff8029e000}}} [00021.942] 01048:25763> dso: id=5a442b2e49e51a94 base=0xffffffff00100000 name=zircon.elf [00021.942] 01048:25763> bt#00: 0xffffffff0014b49b [00021.942] 01048:25763> bt#01: 0xffffffff0014b8cf [00021.942] 01048:25763> bt#02: 0xffffffff0013716e [00021.942] 01048:25763> bt#03: 0xffffffff001f6944 [00021.942] 01048:25763> bt#04: 0xffffffff001bd43e [00021.942] 01048:25763> bt#05: 0xffffffff001bd08b [00021.942] 01048:25763> bt#06: end [00021.942] 01048:25763> {{{bt:0:0xffffffff0014b49b}}} [00021.942] 01048:25763> {{{bt:1:0xffffffff0014b8cf}}} [00021.942] 01048:25763> {{{bt:2:0xffffffff0013716e}}} [00021.942] 01048:25763> {{{bt:3:0xffffffff001f6944}}} [00021.942] 01048:25763> {{{bt:4:0xffffffff001bd43e}}} [00021.942] 01048:25763> {{{bt:5:0xffffffff001bd08b}}} [00021.942] 01048:25763> [00021.942] 01048:25763> [00021.942] 01048:25763> ZIRCON KERNEL PANIC [00021.942] 01048:25763> Lock validation failed for thread 0xffffff800317d9e0 pid 1048 tid 25763 (bin/core-tests:test_thread): [00021.942] 01048:25763> Reason: Already Acquired [00021.942] 01048:25763> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00021.942] 01048:25763> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00021.942] 01048:25763> caller=0xffffffff0014b49b frame=0xffffff98efa71cd0 [00021.942] 01048:25763> BUILDID git-1fd4ec2f02fc15f15eb764f03bcf7db389dc96bc-dirty [00021.942] 01048:25763> [00021.942] 01048:25763> {{{module:0:kernel:elf:5a442b2e49e51a94}}} [00021.942] 01048:25763> {{{mmap:0xffffffff00100000:0x14c7c8:load:0:rx:0xffffffff80100000}}} [00021.942] 01048:25763> {{{mmap:0xffffffff0024d000:0x4a000:load:0:r:0xffffffff8024d000}}} [00021.942] 01048:25763> {{{mmap:0xffffffff00297000:0x6450:load:0:rw:0xffffffff80297000}}} [00021.942] 01048:25763> {{{mmap:0xffffffff0029e000:0x96000:load:0:rw:0xffffffff8029e000}}} [00021.942] 01048:25763> dso: id=5a442b2e49e51a94 base=0xffffffff00100000 name=zircon.elf [00021.942] 01048:25763> bt#00: 0xffffffff0014b49b [00021.942] 01048:25763> bt#01: 0xffffffff0014b8cf [00021.942] 01048:25763> bt#02: 0xffffffff0014b8cf [00021.942] 01048:25763> bt#03: 0xffffffff0013716e [00021.942] 01048:25763> bt#04: 0xffffffff001f6944 [00021.942] 01048:25763> bt#05: 0xffffffff001bd43e [00021.942] 01048:25763> bt#06: 0xffffffff001bd08b [00021.942] 01048:25763> bt#07: end [00021.942] 01048:25763> {{{bt:0:0xffffffff0014b49b}}} [00021.942] 01048:25763> {{{bt:1:0xffffffff0014b8cf}}} [00021.942] 01048:25763> {{{bt:2:0xffffffff0014b8cf}}} [00021.942] 01048:25763> {{{bt:3:0xffffffff0013716e}}} [00021.942] 01048:25763> {{{bt:4:0xffffffff001f6944}}} [00021.942] 01048:25763> {{{bt:5:0xffffffff001bd43e}}} [00021.942] 01048:25763> {{{bt:6:0xffffffff001bd08b}}} [00021.942] 01048:25763> [00021.942] 01048:25763> [00021.942] 01048:25763> ZIRCON KERNEL PANIC [00021.942] 01048:25763> Lock validation failed for thread 0xffffff800317d9e0 pid 1048 tid 25763 (bin/core-tests:test_thread): [00021.942] 01048:25763> Reason: Already Acquired [00021.942] 01048:25763> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00021.942] 01048:25763> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00021.942] 01048:25763> caller=0xffffffff0014b49b frame=0xffffff98efa71cd0 [00021.942] 01048:25763> BUILDID git-1fd4ec2f02fc15f15eb764f03bcf7db389dc96bc-dirty [00021.942] 01048:25763> [00021.942] 01048:25763> {{{module:0:kernel:elf:5a442b2e49e51a94}}} [00021.942] 01048:25763> {{{mmap:0xffffffff00100000:0x14c7c8:load:0:rx:0xffffffff80100000}}} [00021.942] 01048:25763> {{{mmap:0xffffffff0024d000:0x4a000:load:0:r:0xffffffff8024d000}}} [00021.942] 01048:25763> {{{mmap:0xffffffff00297000:0x6450:load:0:rw:0xffffffff80297000}}} [00021.942] 01048:25763> {{{mmap:0xffffffff0029e000:0x96000:load:0:rw:0xffffffff8029e000}}} [00021.942] 01048:25763> dso: id=5a442b2e49e51a94 base=0xffffffff00100000 name=zircon.elf [00021.942] 01048:25763> bt#00: 0xffffffff0014b49b [00021.942] 01048:25763> bt#01: 0xffffffff0014b8cf [00021.942] 01048:25763> bt#02: 0xffffffff0014b8cf [00021.942] 01048:25763> bt#03: 0xffffffff0013716e [00021.942] 01048:25763> bt#04: 0xffffffff001f6944 [00021.942] 01048:25763> bt#05: 0xffffffff001bd43e [00021.942] 01048:25763> bt#06: 0xffffffff001bd08b [00021.942] 01048:25763> bt#07: end [00021.942] 01048:25763> {{{bt:0:0xffffffff0014b49b}}} [00021.942] 01048:25763> {{{bt:1:0xffffffff0014b8cf}}} [00021.942] 01048:25763> {{{bt:2:0xffffffff0014b8cf}}} [00021.942] 01048:25763> {{{bt:3:0xffffffff0013716e}}} [00021.942] 01048:25763> {{{bt:4:0xffffffff001f6944}}} [00021.942] 01048:25763> {{{bt:5:0xffffffff001bd43e}}} [00021.942] 01048:25763> {{{bt:6:0xffffffff001bd08b}}} [00021.942] 01048:25763> [00021.946] 01048:01051> [PASSED] (4 ms) [00021.946] 01048:01051> read_close_interrupt_early_test_vmo [RUNNING] [00021.955] 01048:01051> [PASSED] (9 ms) [00021.955] 01048:01051> thread_kill_test_vmar [RUNNING] [00021.965] 01048:01051> [PASSED] (9 ms) [00021.965] 01048:01051> thread_kill_test_vmo [RUNNING] [00021.976] 01048:01051> [PASSED] (11 ms) [00021.976] 01048:01051> thread_kill_overlap_test_vmar [RUNNING] [00021.982] 01048:01051> [PASSED] (5 ms) [00021.982] 01048:01051> thread_kill_overlap_test_vmo [RUNNING] [00021.991] 01048:01051> [PASSED] (8 ms) [00021.991] 01048:01051> close_pager_test [RUNNING] [00021.991] 01048:25854> PageFault: 1027346 free pages [00021.991] 01048:25854> [00021.991] 01048:25854> ZIRCON KERNEL PANIC [00021.991] 01048:25854> Lock validation failed for thread 0xffffff8002fe5390 pid 1048 tid 25854 (bin/core-tests:test_thread): [00021.991] 01048:25854> Reason: Already Acquired [00021.991] 01048:25854> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00021.991] 01048:25854> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00021.991] 01048:25854> caller=0xffffffff0014b49b frame=0xffffff98efa91d60 [00021.991] 01048:25854> BUILDID git-1fd4ec2f02fc15f15eb764f03bcf7db389dc96bc-dirty [00021.991] 01048:25854> [00021.991] 01048:25854> {{{module:0:kernel:elf:5a442b2e49e51a94}}} [00021.991] 01048:25854> {{{mmap:0xffffffff00100000:0x14c7c8:load:0:rx:0xffffffff80100000}}} [00021.991] 01048:25854> {{{mmap:0xffffffff0024d000:0x4a000:load:0:r:0xffffffff8024d000}}} [00021.991] 01048:25854> {{{mmap:0xffffffff00297000:0x6450:load:0:rw:0xffffffff80297000}}} [00021.991] 01048:25854> {{{mmap:0xffffffff0029e000:0x96000:load:0:rw:0xffffffff8029e000}}} [00021.991] 01048:25854> dso: id=5a442b2e49e51a94 base=0xffffffff00100000 name=zircon.elf [00021.991] 01048:25854> bt#00: 0xffffffff0014b49b [00021.991] 01048:25854> bt#01: 0xffffffff0014b8cf [00021.991] 01048:25854> bt#02: 0xffffffff0013716e [00021.991] 01048:25854> bt#03: 0xffffffff001f6944 [00021.991] 01048:25854> bt#04: 0xffffffff001bd43e [00021.991] 01048:25854> bt#05: 0xffffffff001bd08b [00021.991] 01048:25854> bt#06: end [00021.991] 01048:25854> {{{bt:0:0xffffffff0014b49b}}} [00021.991] 01048:25854> {{{bt:1:0xffffffff0014b8cf}}} [00021.991] 01048:25854> {{{bt:2:0xffffffff0013716e}}} [00021.991] 01048:25854> {{{bt:3:0xffffffff001f6944}}} [00021.991] 01048:25854> {{{bt:4:0xffffffff001bd43e}}} [00021.991] 01048:25854> {{{bt:5:0xffffffff001bd08b}}} [00021.991] 01048:25854> [00021.991] 01048:25854> [00021.991] 01048:25854> ZIRCON KERNEL PANIC [00021.991] 01048:25854> Lock validation failed for thread 0xffffff8002fe5390 pid 1048 tid 25854 (bin/core-tests:test_thread): [00021.991] 01048:25854> Reason: Already Acquired [00021.991] 01048:25854> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00021.991] 01048:25854> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00021.991] 01048:25854> caller=0xffffffff0014b49b frame=0xffffff98efa91cd0 [00021.991] 01048:25854> BUILDID git-1fd4ec2f02fc15f15eb764f03bcf7db389dc96bc-dirty [00021.991] 01048:25854> [00021.991] 01048:25854> {{{module:0:kernel:elf:5a442b2e49e51a94}}} [00021.991] 01048:25854> {{{mmap:0xffffffff00100000:0x14c7c8:load:0:rx:0xffffffff80100000}}} [00021.991] 01048:25854> {{{mmap:0xffffffff0024d000:0x4a000:load:0:r:0xffffffff8024d000}}} [00021.991] 01048:25854> {{{mmap:0xffffffff00297000:0x6450:load:0:rw:0xffffffff80297000}}} [00021.991] 01048:25854> {{{mmap:0xffffffff0029e000:0x96000:load:0:rw:0xffffffff8029e000}}} [00021.991] 01048:25854> dso: id=5a442b2e49e51a94 base=0xffffffff00100000 name=zircon.elf [00021.991] 01048:25854> bt#00: 0xffffffff0014b49b [00021.991] 01048:25854> bt#01: 0xffffffff0014b8cf [00021.991] 01048:25854> bt#02: 0xffffffff0014b8cf [00021.991] 01048:25854> bt#03: 0xffffffff0013716e [00021.991] 01048:25854> bt#04: 0xffffffff001f6944 [00021.991] 01048:25854> bt#05: 0xffffffff001bd43e [00021.991] 01048:25854> bt#06: 0xffffffff001bd08b [00021.991] 01048:25854> bt#07: end [00021.991] 01048:25854> {{{bt:0:0xffffffff0014b49b}}} [00021.991] 01048:25854> {{{bt:1:0xffffffff0014b8cf}}} [00021.991] 01048:25854> {{{bt:2:0xffffffff0014b8cf}}} [00021.991] 01048:25854> {{{bt:3:0xffffffff0013716e}}} [00021.991] 01048:25854> {{{bt:4:0xffffffff001f6944}}} [00021.991] 01048:25854> {{{bt:5:0xffffffff001bd43e}}} [00021.991] 01048:25854> {{{bt:6:0xffffffff001bd08b}}} [00021.991] 01048:25854> [00021.991] 01048:25854> [00021.991] 01048:25854> ZIRCON KERNEL PANIC [00021.991] 01048:25854> Lock validation failed for thread 0xffffff8002fe5390 pid 1048 tid 25854 (bin/core-tests:test_thread): [00021.991] 01048:25854> Reason: Already Acquired [00021.991] 01048:25854> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00021.991] 01048:25854> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00021.991] 01048:25854> caller=0xffffffff0014b49b frame=0xffffff98efa91cd0 [00021.991] 01048:25854> BUILDID git-1fd4ec2f02fc15f15eb764f03bcf7db389dc96bc-dirty [00021.991] 01048:25854> [00021.991] 01048:25854> {{{module:0:kernel:elf:5a442b2e49e51a94}}} [00021.991] 01048:25854> {{{mmap:0xffffffff00100000:0x14c7c8:load:0:rx:0xffffffff80100000}}} [00021.991] 01048:25854> {{{mmap:0xffffffff0024d000:0x4a000:load:0:r:0xffffffff8024d000}}} [00021.991] 01048:25854> {{{mmap:0xffffffff00297000:0x6450:load:0:rw:0xffffffff80297000}}} [00021.991] 01048:25854> {{{mmap:0xffffffff0029e000:0x96000:load:0:rw:0xffffffff8029e000}}} [00021.991] 01048:25854> dso: id=5a442b2e49e51a94 base=0xffffffff00100000 name=zircon.elf [00021.991] 01048:25854> bt#00: 0xffffffff0014b49b [00021.991] 01048:25854> bt#01: 0xffffffff0014b8cf [00021.991] 01048:25854> bt#02: 0xffffffff0014b8cf [00021.991] 01048:25854> bt#03: 0xffffffff0013716e [00021.991] 01048:25854> bt#04: 0xffffffff001f6944 [00021.991] 01048:25854> bt#05: 0xffffffff001bd43e [00021.991] 01048:25854> bt#06: 0xffffffff001bd08b [00021.991] 01048:25854> bt#07: end [00021.991] 01048:25854> {{{bt:0:0xffffffff0014b49b}}} [00021.991] 01048:25854> {{{bt:1:0xffffffff0014b8cf}}} [00021.991] 01048:25854> {{{bt:2:0xffffffff0014b8cf}}} [00021.991] 01048:25854> {{{bt:3:0xffffffff0013716e}}} [00021.991] 01048:25854> {{{bt:4:0xffffffff001f6944}}} [00021.991] 01048:25854> {{{bt:5:0xffffffff001bd43e}}} [00021.991] 01048:25854> {{{bt:6:0xffffffff001bd08b}}} [00021.991] 01048:25854> [00022.000] 01048:01051> [PASSED] (9 ms) [00022.000] 01048:01051> detach_close_pager_test [RUNNING] [00022.000] 01048:01051> [PASSED] (0 ms) [00022.000] 01048:01051> close_port_test [RUNNING] [00022.001] 01048:25867> PageFault: 1027344 free pages [00022.001] 01048:25867> [00022.001] 01048:25867> ZIRCON KERNEL PANIC [00022.001] 01048:25867> Lock validation failed for thread 0xffffff8002fe52d0 pid 1048 tid 25867 (bin/core-tests:test_thread): [00022.001] 01048:25867> Reason: Already Acquired [00022.001] 01048:25867> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00022.001] 01048:25867> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00022.001] 01048:25867> caller=0xffffffff0014b49b frame=0xffffff98efa91d60 [00022.001] 01048:25867> BUILDID git-1fd4ec2f02fc15f15eb764f03bcf7db389dc96bc-dirty [00022.001] 01048:25867> [00022.001] 01048:25867> {{{module:0:kernel:elf:5a442b2e49e51a94}}} [00022.001] 01048:25867> {{{mmap:0xffffffff00100000:0x14c7c8:load:0:rx:0xffffffff80100000}}} [00022.001] 01048:25867> {{{mmap:0xffffffff0024d000:0x4a000:load:0:r:0xffffffff8024d000}}} [00022.001] 01048:25867> {{{mmap:0xffffffff00297000:0x6450:load:0:rw:0xffffffff80297000}}} [00022.001] 01048:25867> {{{mmap:0xffffffff0029e000:0x96000:load:0:rw:0xffffffff8029e000}}} [00022.001] 01048:25867> dso: id=5a442b2e49e51a94 base=0xffffffff00100000 name=zircon.elf [00022.001] 01048:25867> bt#00: 0xffffffff0014b49b [00022.001] 01048:25867> bt#01: 0xffffffff0014b8cf [00022.001] 01048:25867> bt#02: 0xffffffff0013716e [00022.001] 01048:25867> bt#03: 0xffffffff001f6944 [00022.001] 01048:25867> bt#04: 0xffffffff001bd43e [00022.001] 01048:25867> bt#05: 0xffffffff001bd08b [00022.001] 01048:25867> bt#06: end [00022.001] 01048:25867> {{{bt:0:0xffffffff0014b49b}}} [00022.001] 01048:25867> {{{bt:1:0xffffffff0014b8cf}}} [00022.001] 01048:25867> {{{bt:2:0xffffffff0013716e}}} [00022.001] 01048:25867> {{{bt:3:0xffffffff001f6944}}} [00022.001] 01048:25867> {{{bt:4:0xffffffff001bd43e}}} [00022.001] 01048:25867> {{{bt:5:0xffffffff001bd08b}}} [00022.001] 01048:25867> [00022.001] 01048:25867> [00022.001] 01048:25867> ZIRCON KERNEL PANIC [00022.001] 01048:25867> Lock validation failed for thread 0xffffff8002fe52d0 pid 1048 tid 25867 (bin/core-tests:test_thread): [00022.001] 01048:25867> Reason: Already Acquired [00022.001] 01048:25867> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00022.001] 01048:25867> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00022.001] 01048:25867> caller=0xffffffff0014b49b frame=0xffffff98efa91cd0 [00022.001] 01048:25867> BUILDID git-1fd4ec2f02fc15f15eb764f03bcf7db389dc96bc-dirty [00022.001] 01048:25867> [00022.001] 01048:25867> {{{module:0:kernel:elf:5a442b2e49e51a94}}} [00022.001] 01048:25867> {{{mmap:0xffffffff00100000:0x14c7c8:load:0:rx:0xffffffff80100000}}} [00022.001] 01048:25867> {{{mmap:0xffffffff0024d000:0x4a000:load:0:r:0xffffffff8024d000}}} [00022.001] 01048:25867> {{{mmap:0xffffffff00297000:0x6450:load:0:rw:0xffffffff80297000}}} [00022.001] 01048:25867> {{{mmap:0xffffffff0029e000:0x96000:load:0:rw:0xffffffff8029e000}}} [00022.001] 01048:25867> dso: id=5a442b2e49e51a94 base=0xffffffff00100000 name=zircon.elf [00022.001] 01048:25867> bt#00: 0xffffffff0014b49b [00022.001] 01048:25867> bt#01: 0xffffffff0014b8cf [00022.001] 01048:25867> bt#02: 0xffffffff0014b8cf [00022.001] 01048:25867> bt#03: 0xffffffff0013716e [00022.001] 01048:25867> bt#04: 0xffffffff001f6944 [00022.001] 01048:25867> bt#05: 0xffffffff001bd43e [00022.001] 01048:25867> bt#06: 0xffffffff001bd08b [00022.001] 01048:25867> bt#07: end [00022.001] 01048:25867> {{{bt:0:0xffffffff0014b49b}}} [00022.001] 01048:25867> {{{bt:1:0xffffffff0014b8cf}}} [00022.001] 01048:25867> {{{bt:2:0xffffffff0014b8cf}}} [00022.001] 01048:25867> {{{bt:3:0xffffffff0013716e}}} [00022.001] 01048:25867> {{{bt:4:0xffffffff001f6944}}} [00022.001] 01048:25867> {{{bt:5:0xffffffff001bd43e}}} [00022.001] 01048:25867> {{{bt:6:0xffffffff001bd08b}}} [00022.001] 01048:25867> [00022.001] 01048:25867> [00022.001] 01048:25867> ZIRCON KERNEL PANIC [00022.001] 01048:25867> Lock validation failed for thread 0xffffff8002fe52d0 pid 1048 tid 25867 (bin/core-tests:test_thread): [00022.001] 01048:25867> Reason: Already Acquired [00022.001] 01048:25867> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00022.001] 01048:25867> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00022.001] 01048:25867> caller=0xffffffff0014b49b frame=0xffffff98efa91cd0 [00022.001] 01048:25867> BUILDID git-1fd4ec2f02fc15f15eb764f03bcf7db389dc96bc-dirty [00022.001] 01048:25867> [00022.001] 01048:25867> {{{module:0:kernel:elf:5a442b2e49e51a94}}} [00022.001] 01048:25867> {{{mmap:0xffffffff00100000:0x14c7c8:load:0:rx:0xffffffff80100000}}} [00022.001] 01048:25867> {{{mmap:0xffffffff0024d000:0x4a000:load:0:r:0xffffffff8024d000}}} [00022.001] 01048:25867> {{{mmap:0xffffffff00297000:0x6450:load:0:rw:0xffffffff80297000}}} [00022.001] 01048:25867> {{{mmap:0xffffffff0029e000:0x96000:load:0:rw:0xffffffff8029e000}}} [00022.001] 01048:25867> dso: id=5a442b2e49e51a94 base=0xffffffff00100000 name=zircon.elf [00022.001] 01048:25867> bt#00: 0xffffffff0014b49b [00022.001] 01048:25867> bt#01: 0xffffffff0014b8cf [00022.001] 01048:25867> bt#02: 0xffffffff0014b8cf [00022.001] 01048:25867> bt#03: 0xffffffff0013716e [00022.001] 01048:25867> bt#04: 0xffffffff001f6944 [00022.001] 01048:25867> bt#05: 0xffffffff001bd43e [00022.001] 01048:25867> bt#06: 0xffffffff001bd08b [00022.001] 01048:25867> bt#07: end [00022.001] 01048:25867> {{{bt:0:0xffffffff0014b49b}}} [00022.001] 01048:25867> {{{bt:1:0xffffffff0014b8cf}}} [00022.001] 01048:25867> {{{bt:2:0xffffffff0014b8cf}}} [00022.001] 01048:25867> {{{bt:3:0xffffffff0013716e}}} [00022.001] 01048:25867> {{{bt:4:0xffffffff001f6944}}} [00022.001] 01048:25867> {{{bt:5:0xffffffff001bd43e}}} [00022.001] 01048:25867> {{{bt:6:0xffffffff001bd08b}}} [00022.001] 01048:25867> [00022.009] 01048:01051> [PASSED] (9 ms) [00022.009] 01048:01051> CASE lifecycle_tests [PASSED] [00022.009] 01048:01051> CASE pager_read_tests [STARTED] [00022.009] 01048:01051> single_page_test_vmar [RUNNING] [00022.015] 01048:01051> [PASSED] (5 ms) [00022.015] 01048:01051> single_page_test_vmo [RUNNING] [00022.020] 01048:01051> [PASSED] (5 ms) [00022.020] 01048:01051> presupply_test_vmar [RUNNING] [00022.026] 01048:01051> [PASSED] (5 ms) [00022.026] 01048:01051> presupply_test_vmo [RUNNING] [00022.031] 01048:01051> [PASSED] (5 ms) [00022.031] 01048:01051> early_supply_test_vmar [RUNNING] [00022.040] 01048:01051> [PASSED] (9 ms) [00022.040] 01048:01051> early_supply_test_vmo [RUNNING] [00022.048] 01048:01051> [PASSED] (7 ms) [00022.048] 01048:01051> sequential_multipage_test_vmar [RUNNING] [00022.059] 01048:01051> [PASSED] (11 ms) [00022.059] 01048:01051> sequential_multipage_test_vmo [RUNNING] [00022.077] 01048:01051> [PASSED] (17 ms) [00022.077] 01048:01051> concurrent_multipage_access_test_vmar [RUNNING] [00022.091] 01048:01051> [PASSED] (13 ms) [00022.091] 01048:01051> concurrent_multipage_access_test_vmo [RUNNING] [00022.105] 01048:01051> [PASSED] (14 ms) [00022.105] 01048:01051> concurrent_overlapping_access_test_vmar [RUNNING] [00024.104] 01048:01051> [PASSED] (1998 ms) [00024.104] 01048:01051> concurrent_overlapping_access_test_vmo [RUNNING] [00024.137] 01048:01051> [PASSED] (33 ms) [00024.137] 01048:01051> bulk_single_supply_test_vmar [RUNNING] [00024.151] 01048:01051> [PASSED] (13 ms) [00024.151] 01048:01051> bulk_single_supply_test_vmo [RUNNING] [00024.165] 01048:01051> [PASSED] (13 ms) [00024.165] 01048:01051> bulk_odd_length_supply_test_vmar [RUNNING] [00024.244] 01048:01051> [PASSED] (79 ms) [00024.244] 01048:01051> bulk_odd_length_supply_test_vmo [RUNNING] [00024.320] 01048:01051> [PASSED] (76 ms) [00024.320] 01048:01051> bulk_odd_offset_supply_test_vmar [RUNNING] [00024.404] 01048:01051> [PASSED] (83 ms) [00024.404] 01048:01051> bulk_odd_offset_supply_test_vmo [RUNNING] [00024.488] 01048:01051> [PASSED] (84 ms) [00024.488] 01048:01051> overlap_supply_test_vmar [RUNNING] [00024.496] 01048:01051> [PASSED] (8 ms) [00024.496] 01048:01051> overlap_supply_test_vmo [RUNNING] [00024.504] 01048:01051> [PASSED] (7 ms) [00024.504] 01048:01051> many_request_test_vmar [RUNNING] [00024.683] 01048:01051> [PASSED] (179 ms) [00024.683] 01048:01051> many_request_test_vmo [RUNNING] [00024.853] 01048:01051> [PASSED] (169 ms) [00024.853] 01048:01051> successive_vmo_test [RUNNING] [00024.892] 01048:01051> [PASSED] (38 ms) [00024.892] 01048:01051> multiple_concurrent_vmo_test [RUNNING] [00024.903] 01048:01051> [PASSED] (10 ms) [00024.903] 01048:01051> vmar_unmap_test [RUNNING] [00024.904] 01048:33588> PageFault: 1027342 free pages [00024.904] 01048:33588> [00024.904] 01048:33588> ZIRCON KERNEL PANIC [00024.904] 01048:33588> Lock validation failed for thread 0xffffff8002fe5580 pid 1048 tid 33588 (bin/core-tests:test_thread): [00024.904] 01048:33588> Reason: Already Acquired [00024.904] 01048:33588> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00024.904] 01048:33588> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00024.904] 01048:33588> caller=0xffffffff0014b49b frame=0xffffff98efa91d60 [00024.904] 01048:33588> BUILDID git-1fd4ec2f02fc15f15eb764f03bcf7db389dc96bc-dirty [00024.904] 01048:33588> [00024.904] 01048:33588> {{{module:0:kernel:elf:5a442b2e49e51a94}}} [00024.904] 01048:33588> {{{mmap:0xffffffff00100000:0x14c7c8:load:0:rx:0xffffffff80100000}}} [00024.904] 01048:33588> {{{mmap:0xffffffff0024d000:0x4a000:load:0:r:0xffffffff8024d000}}} [00024.904] 01048:33588> {{{mmap:0xffffffff00297000:0x6450:load:0:rw:0xffffffff80297000}}} [00024.904] 01048:33588> {{{mmap:0xffffffff0029e000:0x96000:load:0:rw:0xffffffff8029e000}}} [00024.904] 01048:33588> dso: id=5a442b2e49e51a94 base=0xffffffff00100000 name=zircon.elf [00024.904] 01048:33588> bt#00: 0xffffffff0014b49b [00024.904] 01048:33588> bt#01: 0xffffffff0014b8cf [00024.904] 01048:33588> bt#02: 0xffffffff0013716e [00024.904] 01048:33588> bt#03: 0xffffffff001f6944 [00024.904] 01048:33588> bt#04: 0xffffffff001bd43e [00024.904] 01048:33588> bt#05: 0xffffffff001bd08b [00024.904] 01048:33588> bt#06: end [00024.904] 01048:33588> {{{bt:0:0xffffffff0014b49b}}} [00024.904] 01048:33588> {{{bt:1:0xffffffff0014b8cf}}} [00024.904] 01048:33588> {{{bt:2:0xffffffff0013716e}}} [00024.904] 01048:33588> {{{bt:3:0xffffffff001f6944}}} [00024.904] 01048:33588> {{{bt:4:0xffffffff001bd43e}}} [00024.904] 01048:33588> {{{bt:5:0xffffffff001bd08b}}} [00024.904] 01048:33588> [00024.904] 01048:33588> [00024.904] 01048:33588> ZIRCON KERNEL PANIC [00024.904] 01048:33588> Lock validation failed for thread 0xffffff8002fe5580 pid 1048 tid 33588 (bin/core-tests:test_thread): [00024.904] 01048:33588> Reason: Already Acquired [00024.904] 01048:33588> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00024.904] 01048:33588> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00024.904] 01048:33588> caller=0xffffffff0014b49b frame=0xffffff98efa91cd0 [00024.904] 01048:33588> BUILDID git-1fd4ec2f02fc15f15eb764f03bcf7db389dc96bc-dirty [00024.904] 01048:33588> [00024.904] 01048:33588> {{{module:0:kernel:elf:5a442b2e49e51a94}}} [00024.904] 01048:33588> {{{mmap:0xffffffff00100000:0x14c7c8:load:0:rx:0xffffffff80100000}}} [00024.904] 01048:33588> {{{mmap:0xffffffff0024d000:0x4a000:load:0:r:0xffffffff8024d000}}} [00024.904] 01048:33588> {{{mmap:0xffffffff00297000:0x6450:load:0:rw:0xffffffff80297000}}} [00024.904] 01048:33588> {{{mmap:0xffffffff0029e000:0x96000:load:0:rw:0xffffffff8029e000}}} [00024.904] 01048:33588> dso: id=5a442b2e49e51a94 base=0xffffffff00100000 name=zircon.elf [00024.904] 01048:33588> bt#00: 0xffffffff0014b49b [00024.904] 01048:33588> bt#01: 0xffffffff0014b8cf [00024.904] 01048:33588> bt#02: 0xffffffff0014b8cf [00024.904] 01048:33588> bt#03: 0xffffffff0013716e [00024.904] 01048:33588> bt#04: 0xffffffff001f6944 [00024.904] 01048:33588> bt#05: 0xffffffff001bd43e [00024.904] 01048:33588> bt#06: 0xffffffff001bd08b [00024.904] 01048:33588> bt#07: end [00024.904] 01048:33588> {{{bt:0:0xffffffff0014b49b}}} [00024.904] 01048:33588> {{{bt:1:0xffffffff0014b8cf}}} [00024.904] 01048:33588> {{{bt:2:0xffffffff0014b8cf}}} [00024.904] 01048:33588> {{{bt:3:0xffffffff0013716e}}} [00024.904] 01048:33588> {{{bt:4:0xffffffff001f6944}}} [00024.904] 01048:33588> {{{bt:5:0xffffffff001bd43e}}} [00024.904] 01048:33588> {{{bt:6:0xffffffff001bd08b}}} [00024.904] 01048:33588> [00024.904] 01048:33588> [00024.904] 01048:33588> ZIRCON KERNEL PANIC [00024.904] 01048:33588> Lock validation failed for thread 0xffffff8002fe5580 pid 1048 tid 33588 (bin/core-tests:test_thread): [00024.904] 01048:33588> Reason: Already Acquired [00024.904] 01048:33588> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00024.904] 01048:33588> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00024.904] 01048:33588> caller=0xffffffff0014b49b frame=0xffffff98efa91cd0 [00024.904] 01048:33588> BUILDID git-1fd4ec2f02fc15f15eb764f03bcf7db389dc96bc-dirty [00024.904] 01048:33588> [00024.904] 01048:33588> {{{module:0:kernel:elf:5a442b2e49e51a94}}} [00024.904] 01048:33588> {{{mmap:0xffffffff00100000:0x14c7c8:load:0:rx:0xffffffff80100000}}} [00024.904] 01048:33588> {{{mmap:0xffffffff0024d000:0x4a000:load:0:r:0xffffffff8024d000}}} [00024.904] 01048:33588> {{{mmap:0xffffffff00297000:0x6450:load:0:rw:0xffffffff80297000}}} [00024.904] 01048:33588> {{{mmap:0xffffffff0029e000:0x96000:load:0:rw:0xffffffff8029e000}}} [00024.904] 01048:33588> dso: id=5a442b2e49e51a94 base=0xffffffff00100000 name=zircon.elf [00024.904] 01048:33588> bt#00: 0xffffffff0014b49b [00024.904] 01048:33588> bt#01: 0xffffffff0014b8cf [00024.904] 01048:33588> bt#02: 0xffffffff0014b8cf [00024.904] 01048:33588> bt#03: 0xffffffff0013716e [00024.904] 01048:33588> bt#04: 0xffffffff001f6944 [00024.904] 01048:33588> bt#05: 0xffffffff001bd43e [00024.904] 01048:33588> bt#06: 0xffffffff001bd08b [00024.904] 01048:33588> bt#07: end [00024.904] 01048:33588> {{{bt:0:0xffffffff0014b49b}}} [00024.904] 01048:33588> {{{bt:1:0xffffffff0014b8cf}}} [00024.904] 01048:33588> {{{bt:2:0xffffffff0014b8cf}}} [00024.904] 01048:33588> {{{bt:3:0xffffffff0013716e}}} [00024.904] 01048:33588> {{{bt:4:0xffffffff001f6944}}} [00024.904] 01048:33588> {{{bt:5:0xffffffff001bd43e}}} [00024.904] 01048:33588> {{{bt:6:0xffffffff001bd08b}}} [00024.904] 01048:33588> [00024.911] 01048:01051> [PASSED] (7 ms) [00024.911] 01048:01051> vmar_remap_test [RUNNING] [00025.249] 01048:01051> [PASSED] (338 ms) [00025.249] 01048:01051> vmar_map_range_test [RUNNING] [00025.257] 01048:01051> [PASSED] (7 ms) [00025.257] 01048:01051> read_resize_test_vmar [RUNNING] [00025.264] 01048:01051> [PASSED] (7 ms) [00025.264] 01048:01051> read_resize_test_vmo [RUNNING] [00025.271] 01048:01051> [PASSED] (7 ms) [00025.271] 01048:01051> suspend_read_test_vmar [RUNNING] [00025.278] 01048:01051> [PASSED] (7 ms) [00025.278] 01048:01051> suspend_read_test_vmo [RUNNING] [00025.286] 01048:01051> [PASSED] (7 ms) [00025.286] 01048:01051> CASE pager_read_tests [PASSED] [00025.286] 01048:01051> CASE object_get_child_tests [STARTED] [00025.286] 01048:01051> handle_invalid [RUNNING] [00025.286] 01048:01051> [PASSED] (0 ms) [00025.286] 01048:01051> CASE object_get_child_tests [PASSED] [00025.286] 01048:01051> CASE memory_mapping_tests [STARTED] [00025.286] 01048:01051> address_space_limits_test [RUNNING] [00025.286] 01048:01051> [PASSED] (0 ms) [00025.286] 01048:01051> mmap_zerofilled_test [RUNNING] [00025.286] 01048:01051> [PASSED] (0 ms) [00025.286] 01048:01051> mmap_len_test [RUNNING] [00025.286] 01048:01051> [PASSED] (0 ms) [00025.286] 01048:01051> mmap_PROT_EXEC_test [RUNNING] [00025.286] 01048:01051> [PASSED] (0 ms) [00025.286] 01048:01051> mmap_offset_test [RUNNING] [00025.286] 01048:01051> [PASSED] (0 ms) [00025.286] 01048:01051> mmap_prot_test [RUNNING] [00025.286] 01048:01051> [PASSED] (0 ms) [00025.286] 01048:01051> mmap_flags_test [RUNNING] [00025.286] 01048:01051> [PASSED] (0 ms) [00025.286] 01048:01051> mprotect_test [RUNNING] [00025.287] 01048:01051> [00025.287] 01048:01051> ZIRCON KERNEL PANIC [00025.287] 01048:01051> Lock validation failed for thread 0xffffff800074e978 pid 1048 tid 1051 (bin/core-tests:bin/core-tests): [00025.287] 01048:01051> Reason: Already Acquired [00025.287] 01048:01051> Bad lock: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00025.287] 01048:01051> Conflict: name=lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00025.287] 01048:01051> caller=0xffffffff0014b49b frame=0xffffff98ec9e4d50 [00025.287] 01048:01051> BUILDID git-1fd4ec2f02fc15f15eb764f03bcf7db389dc96bc-dirty [00025.287] 01048:01051> [00025.287] 01048:01051> {{{module:0:kernel:elf:5a442b2e49e51a94}}} [00025.287] 01048:01051> {{{mmap:0xffffffff00100000:0x14c7c8:load:0:rx:0xffffffff80100000}}} [00025.287] 01048:01051> {{{mmap:0xffffffff0024d000:0x4a000:load:0:r:0xffffffff8024d000}}} [00025.287] 01048:01051> {{{mmap:0xffffffff00297000:0x6450:load:0:rw:0xffffffff80297000}}} [00025.287] 01048:01051> {{{mmap:0xffffffff0029e000:0x96000:load:0:rw:0xffffffff8029e000}}} [00025.287] 01048:01051> dso: id=5a442b2e49e51a94 base=0xffffffff00100000 name=zircon.elf [00025.287] 01048:01051> bt#00: 0xffffffff0014b49b [00025.287] 01048:01051> bt#01: 0xffffffff0014b8cf [00025.287] 01048:01051> bt#02: 0xffffffff0014cad2 [00025.287] 01048:01051> bt#03: 0xffffffff0013ffd6 [00025.287] 01048:01051> bt#04: 0xffffffff001565ab [00025.287] 01048:01051> bt#05: 0xffffffff00146288 [00025.287] 01048:01051> bt#06: 0xffffffff0020bede [00025.287] 01048:01051> bt#07: 0xffffffff0021b91a [00025.287] 01048:01051> bt#08: 0xffffffff001ce47b [00025.287] 01048:01051> bt#09: end [00025.287] 01048:01051> {{{bt:0:0xffffffff0014b49b}}} [00025.287] 01048:01051> {{{bt:1:0xffffffff0014b8cf}}} [00025.287] 01048:01051> {{{bt:2:0xffffffff0014cad2}}} [00025.287] 01048:01051> {{{bt:3:0xffffffff0013ffd6}}} [00025.287] 01048:01051> {{{bt:4:0xffffffff001565ab}}} [00025.287] 01048:01051> {{{bt:5:0xffffffff00146288}}} [00025.287] 01048:01051> {{{bt:6:0xffffffff0020bede}}} [00025.287] 01048:01051> {{{bt:7:0xffffffff0021b91a}}} [00025.287] 01048:01051> {{{bt:8:0xffffffff001ce47b}}} [00025.287] 01048:01051> [00025.287] 01048:01051> [PASSED] (0 ms) [00025.287] 01048:01051> CASE memory_mapping_tests [PASSED] [00025.287] 01048:01051> CASE job_tests [STARTED] [00025.287] 01048:01051> basic_test [RUNNING] [00025.287] 01048:01051> [PASSED] (0 ms) [00025.287] 01048:01051> create_missing_rights_test [RUNNING] [00025.287] 01048:01051> [PASSED] (0 ms) [00025.287] 01048:01051> policy_invalid_topic_test [RUNNING] [00025.287] 01048:01051> [PASSED] (0 ms) [00025.287] 01048:01051> policy_basic_test [RUNNING] [00025.287] 01048:01051> [PASSED] (0 ms) [00025.287] 01048:01051> policy_timer_slack_invalid_options_test [RUNNING] [00025.287] 01048:01051> [PASSED] (0 ms) [00025.287] 01048:01051> policy_timer_slack_invalid_count_test [RUNNING] [00025.287] 01048:01051> [PASSED] (0 ms) [00025.287] 01048:01051> policy_timer_slack_invalid_policy_test [RUNNING] [00025.287] 01048:01051> [PASSED] (0 ms) [00025.287] 01048:01051> policy_timer_slack_non_empty_test [RUNNING] [00025.287] 01048:01051> [PASSED] (0 ms) [00025.287] 01048:01051> policy_timer_slack_valid [RUNNING] [00025.287] 01048:01051> [PASSED] (0 ms) [00025.287] 01048:01051> create_test [RUNNING] [00025.287] 01048:01051> [PASSED] (0 ms) [00025.287] 01048:01051> kill_test [RUNNING] [00025.302] 01048:01051> [PASSED] (15 ms) [00025.302] 01048:01051> kill_job_no_child_test [RUNNING] [00025.303] 01048:01051> [PASSED] (0 ms) [00025.303] 01048:01051> set_job_oom_kill_bit [RUNNING] [00025.303] 01048:01051> [PASSED] (0 ms) [00025.303] 01048:01051> wait_test [RUNNING] [00025.314] 01048:01051> [PASSED] (11 ms) [00025.314] 01048:01051> info_task_stats_fails [RUNNING] [00025.314] 01048:01051> [PASSED] (0 ms) [00025.314] 01048:01051> max_height_smoke [RUNNING] [00025.315] 01048:01051> [PASSED] (0 ms) [00025.315] 01048:01051> CASE job_tests [PASSED] [00025.315] 01048:01051> CASE handle_wait_tests [STARTED] [00025.315] 01048:01051> handle_wait_test [RUNNING] [00025.317] 01048:01051> [PASSED] (2 ms) [00025.317] 01048:01051> CASE handle_wait_tests [PASSED] [00025.317] 01048:01051> CASE handle_transfer_tests [STARTED] [00025.317] 01048:01051> handle_transfer_test [RUNNING] [00025.317] 01048:01051> [PASSED] (0 ms) [00025.317] 01048:01051> handle_transfer_cancel_wait_test [RUNNING] [00025.340] 01048:01051> [PASSED] (22 ms) [00025.340] 01048:01051> CASE handle_transfer_tests [PASSED] [00025.340] 01048:01051> CASE eventpair_tests [STARTED] [00025.340] 01048:01051> create_test [RUNNING] [00025.340] 01048:01051> [PASSED] (0 ms) [00025.340] 01048:01051> signal_test [RUNNING] [00025.340] 01048:01051> [PASSED] (0 ms) [00025.340] 01048:01051> signal_peer_test [RUNNING] [00025.340] 01048:01051> [PASSED] (0 ms) [00025.340] 01048:01051> signal_peer_closed_test [RUNNING] [00025.340] 01048:01051> [PASSED] (0 ms) [00025.340] 01048:01051> CASE eventpair_tests [PASSED] [00025.340] 01048:01051> CASE elf_tls_tests [STARTED] [00025.340] 01048:01051> ExecutableTlsTest [RUNNING] [00026.558] 01048:01051> [PASSED] (1217 ms) [00026.558] 01048:01051> CASE elf_tls_tests [PASSED] [00026.558] 01048:01051> CASE channel_tests [STARTED] [00026.558] 01048:01051> channel_test [RUNNING] [00026.559] 01048:01051> [PASSED] (1 ms) [00026.559] 01048:01051> channel_read_error_test [RUNNING] [00026.559] 01048:01051> [PASSED] (0 ms) [00026.559] 01048:01051> channel_close_test [RUNNING] [00026.559] 01048:01051> [PASSED] (0 ms) [00026.559] 01048:01051> channel_peer_closed_test [RUNNING] [00026.559] 01048:01051> [PASSED] (0 ms) [00026.559] 01048:01051> channel_non_transferable [RUNNING] [00026.559] 01048:01051> [PASSED] (0 ms) [00026.559] 01048:01051> channel_duplicate_handles [RUNNING] [00026.559] 01048:01051> [PASSED] (0 ms) [00026.559] 01048:01051> channel_multithread_read [RUNNING] [00026.770] 01048:01051> [PASSED] (211 ms) [00026.770] 01048:01051> channel_may_discard [RUNNING] [00026.770] 01048:01051> [PASSED] (0 ms) [00026.770] 01048:01051> channel_call [RUNNING] [00026.790] 01048:01051> [PASSED] (19 ms) [00026.790] 01048:01051> channel_call_consumes_handles [RUNNING] [00026.790] 01048:01051> [PASSED] (0 ms) [00026.790] 01048:01051> channel_call2 [RUNNING] [00027.041] 01048:01051> [PASSED] (250 ms) [00027.041] 01048:01051> bad_channel_call_finish [RUNNING] [00027.041] 01048:01051> [PASSED] (0 ms) [00027.041] 01048:01051> channel_nest [RUNNING] [00027.042] 01048:01051> [PASSED] (0 ms) [00027.042] 01048:01051> channel_disallow_write_to_self [RUNNING] [00027.042] 01048:01051> [PASSED] (0 ms) [00027.042] 01048:01051> channel_read_etc [RUNNING] [00027.042] 01048:01051> [PASSED] (0 ms) [00027.042] 01048:01051> channel_write_different_sizes [RUNNING] [00027.127] 01048:01051> [PASSED] (85 ms) [00027.127] 01048:01051> channel_write_takes_all_handles [RUNNING] [00027.129] 01048:01051> [PASSED] (1 ms) [00027.129] 01048:01051> CASE channel_tests [PASSED] [00027.129] 01048:01051> CASE c11_thread_tests [STARTED] [00027.129] 01048:01051> c11_thread_test [RUNNING] [00027.856] 01048:01051> [PASSED] (727 ms) [00027.856] 01048:01051> long_name_succeeds [RUNNING] [00027.957] 01048:01051> [PASSED] (100 ms) [00027.957] 01048:01051> detach_self_test [RUNNING] [00028.850] 01048:01051> [PASSED] (892 ms) [00028.850] 01048:01051> CASE c11_thread_tests [PASSED] [00028.850] 01048:01051> CASE mtx_tests [STARTED] [00028.850] 01048:01051> test_initializer [RUNNING] [00028.850] 01048:01051> [PASSED] (0 ms) [00028.850] 01048:01051> test_mutexes [RUNNING] [00028.945] 01048:01051> [PASSED] (95 ms) [00028.946] 01048:01051> test_try_mutexes [RUNNING] [00029.018] 01048:01051> [PASSED] (72 ms) [00029.018] 01048:01051> test_static_initializer [RUNNING] [00029.018] 01048:01051> [PASSED] (0 ms) [00029.018] 01048:01051> test_timeout_elapsed [RUNNING] [00029.535] 01048:01051> [PASSED] (516 ms) [00029.535] 01048:01051> CASE mtx_tests [PASSED] [00029.535] 01048:01051> CASE cnd_tests [STARTED] [00029.535] 01048:01051> cnd_test [RUNNING] [00029.545] 01048:01051> [PASSED] (9 ms) [00029.545] 01048:01051> cnd_timedwait_timeout_test [RUNNING] [00029.546] 01048:01051> [PASSED] (1 ms) [00029.546] 01048:01051> CASE cnd_tests [PASSED] [00029.546] 01048:01051> CASE bad_syscall_tests [STARTED] [00029.546] 01048:01051> bad_access_test [RUNNING] [00029.546] 01048:01051> [PASSED] (0 ms) [00029.546] 01048:01051> bad_syscall_num_test [RUNNING] [00029.567] 01048:01051> [PASSED] (20 ms) [00029.567] 01048:01051> CASE bad_syscall_tests [PASSED] [00029.567] 01048:01051> ==================================================== [00029.567] 01048:01051> Results: [00029.567] 01048:01051> SUCCESS! All test cases passed! [00029.567] 01048:01051> CASES: 34 SUCCESS: 34 FAILED: 0 [00029.567] 01048:01051> ==================================================== [00029.568] 01048:01051> Flags: [00029.568] 01048:01051> --gtest_random_seed = 29 [00029.568] 01048:01051> [00029.568] 01048:01051> [00029.568] 01048:01051> [==========] Running 240 tests from 12 test cases [00029.568] 01048:01051> . [00029.568] 01048:01051> [----------] Global test environment set-up. [00029.568] 01048:01051> [----------] 1 test from ClockTest [00029.568] 01048:01051> [00029.568] 01048:01051> [ RUN ] ClockTest.ClockMonotonic [00029.568] 01048:01051> [00029.688] 01048:01051> [ OK ] ClockTest.ClockMonotonic (119 [00029.688] 01048:01051> ms) [00029.688] 01048:01051> [----------] 1 test from ClockTest (119 [00029.688] 01048:01051> ms total) [00029.688] 01048:01051> [----------] 9 tests from FifoTest [00029.688] 01048:01051> [00029.688] 01048:01051> [ RUN ] FifoTest.InvalidParametersReturnOutOfRange [00029.688] 01048:01051> [00029.688] 01048:01051> [ OK ] FifoTest.InvalidParametersReturnOutOfRange (0 [00029.688] 01048:01051> ms) [00029.688] 01048:01051> [ RUN ] FifoTest.EndpointsAreRelated [00029.688] 01048:01051> [00029.688] 01048:01051> [ OK ] FifoTest.EndpointsAreRelated (0 [00029.688] 01048:01051> ms) [00029.688] 01048:01051> [ RUN ] FifoTest.EmptyQueueReturnsErrShouldWait [00029.688] 01048:01051> [00029.688] 01048:01051> [ OK ] FifoTest.EmptyQueueReturnsErrShouldWait (0 [00029.688] 01048:01051> ms) [00029.688] 01048:01051> [ RUN ] FifoTest.ReadAndWriteValidatesSizeAndElementCount [00029.689] 01048:01051> [00029.689] 01048:01051> [ OK ] FifoTest.ReadAndWriteValidatesSizeAndElementCount (0 [00029.689] 01048:01051> ms) [00029.689] 01048:01051> [ RUN ] FifoTest.DequeueSignalsWriteable [00029.689] 01048:01051> [00029.689] 01048:01051> [ OK ] FifoTest.DequeueSignalsWriteable (0 [00029.689] 01048:01051> ms) [00029.689] 01048:01051> [ RUN ] FifoTest.FifoOrderIsPreserved [00029.689] 01048:01051> [00029.689] 01048:01051> [ OK ] FifoTest.FifoOrderIsPreserved (0 [00029.689] 01048:01051> ms) [00029.689] 01048:01051> [ RUN ] FifoTest.PartialWriteQueuesElementsThatFit [00029.689] 01048:01051> [00029.689] 01048:01051> [ OK ] FifoTest.PartialWriteQueuesElementsThatFit (0 [00029.689] 01048:01051> ms) [00029.689] 01048:01051> [ RUN ] FifoTest.IndividualReadsPreserveOrder [00029.689] 01048:01051> [00029.689] 01048:01051> [ OK ] FifoTest.IndividualReadsPreserveOrder (0 [00029.689] 01048:01051> ms) [00029.689] 01048:01051> [ RUN ] FifoTest.EndpointCloseSignalsPeerClosed [00029.689] 01048:01051> [00029.689] 01048:01051> [ OK ] FifoTest.EndpointCloseSignalsPeerClosed (0 [00029.689] 01048:01051> ms) [00029.689] 01048:01051> [----------] 9 tests from FifoTest (0 [00029.689] 01048:01051> ms total) [00029.689] 01048:01051> [----------] 15 tests from FutexTest [00029.689] 01048:01051> [00029.689] 01048:01051> [ RUN ] FutexTest.WaitValueMismatch [00029.689] 01048:01051> [00029.689] 01048:01051> [ OK ] FutexTest.WaitValueMismatch (0 [00029.689] 01048:01051> ms) [00029.689] 01048:01051> [ RUN ] FutexTest.WaitTimeout [00029.689] 01048:01051> [00029.689] 01048:01051> [ OK ] FutexTest.WaitTimeout (0 [00029.689] 01048:01051> ms) [00029.689] 01048:01051> [ RUN ] FutexTest.WaitTimeoutElapsed [00029.689] 01048:01051> [00030.191] 01048:01051> [ OK ] FutexTest.WaitTimeoutElapsed (501 [00030.191] 01048:01051> ms) [00030.191] 01048:01051> [ RUN ] FutexTest.WaitBadAddress [00030.191] 01048:01051> [00030.191] 01048:01051> [ OK ] FutexTest.WaitBadAddress (0 [00030.191] 01048:01051> ms) [00030.191] 01048:01051> [ RUN ] FutexTest.Wakeup [00030.191] 01048:01051> [00030.197] 01048:01051> [ OK ] FutexTest.Wakeup (6 [00030.197] 01048:01051> ms) [00030.197] 01048:01051> [ RUN ] FutexTest.WakeupLimit [00030.197] 01048:01051> [00030.830] 01048:01051> [ OK ] FutexTest.WakeupLimit (632 [00030.830] 01048:01051> ms) [00030.830] 01048:01051> [ RUN ] FutexTest.WakeupAddress [00030.830] 01048:01051> [00030.843] 01048:01051> [ OK ] FutexTest.WakeupAddress (12 [00030.843] 01048:01051> ms) [00030.843] 01048:01051> [ RUN ] FutexTest.RequeueValueMismatch [00030.843] 01048:01051> [00030.843] 01048:01051> [ OK ] FutexTest.RequeueValueMismatch (0 [00030.843] 01048:01051> ms) [00030.843] 01048:01051> [ RUN ] FutexTest.RequeueSameAddr [00030.843] 01048:01051> [00030.843] 01048:01051> [ OK ] FutexTest.RequeueSameAddr (0 [00030.843] 01048:01051> ms) [00030.843] 01048:01051> [ RUN ] FutexTest.Requeue [00030.843] 01048:01051> [00031.780] 01048:01051> [ OK ] FutexTest.Requeue (936 [00031.780] 01048:01051> ms) [00031.780] 01048:01051> [ RUN ] FutexTest.RequeueUnqueuedOnTimeout [00031.780] 01048:01051> [00032.081] 01048:01051> [ OK ] FutexTest.RequeueUnqueuedOnTimeout (301 [00032.081] 01048:01051> ms) [00032.081] 01048:01051> [ RUN ] FutexTest.ThreadKilled [00032.081] 01048:01051> [00032.082] 01048:01051> [ OK ] FutexTest.ThreadKilled (0 [00032.082] 01048:01051> ms) [00032.082] 01048:01051> [ RUN ] FutexTest.ThreadSuspended [00032.082] 01048:01051> [00032.405] 01048:01051> [ OK ] FutexTest.ThreadSuspended (323 [00032.405] 01048:01051> ms) [00032.405] 01048:01051> [ RUN ] FutexTest.MisalignedFutextAddr [00032.405] 01048:01051> [00032.405] 01048:01051> [ OK ] FutexTest.MisalignedFutextAddr (0 [00032.405] 01048:01051> ms) [00032.405] 01048:01051> [ RUN ] FutexTest.EventSignaling [00032.405] 01048:01051> [00032.405] 01048:01051> [00000032.405762744]: starting signal threads [00032.406] 01048:106130> [00000032.406190889]: thread 2 waiting on event [00032.406] 01048:106125> [00000032.406175763]: thread 1 waiting on event [00032.406] 01048:106135> [00000032.406226089]: thread 3 waiting on event [00032.406] 01048:01051> [00000032.406684081]: signaling event [00032.406] 01048:01051> [00000032.406821929]: joining signal threads [00032.406] 01048:106130> [00000032.406867232]: thread 2 done [00032.406] 01048:106125> [00000032.406872015]: thread 1 done [00032.407] 01048:106135> [00000032.407165124]: thread 3 done [00032.410] 01048:01051> [00000032.410044791]: signal_thread 1 joined [00032.410] 01048:01051> [00000032.410056838]: signal_thread 2 joined [00032.410] 01048:01051> [00000032.410066723]: signal_thread 3 joined [00032.410] 01048:01051> [ OK ] FutexTest.EventSignaling (4 [00032.410] 01048:01051> ms) [00032.410] 01048:01051> [----------] 15 tests from FutexTest (2720 [00032.410] 01048:01051> ms total) [00032.410] 01048:01051> [----------] 3 tests from HandleCloseTest [00032.410] 01048:01051> [00032.410] 01048:01051> [ RUN ] HandleCloseTest.Many [00032.410] 01048:01051> [00032.410] 01048:01051> [ OK ] HandleCloseTest.Many (0 [00032.410] 01048:01051> ms) [00032.410] 01048:01051> [ RUN ] HandleCloseTest.ManyInvalidHandlesShouldNotFail [00032.410] 01048:01051> [00032.410] 01048:01051> [ OK ] HandleCloseTest.ManyInvalidHandlesShouldNotFail (0 [00032.410] 01048:01051> ms) [00032.410] 01048:01051> [ RUN ] HandleCloseTest.ManyDuplicateTest [00032.410] 01048:01051> [00032.410] 01048:01051> [ OK ] HandleCloseTest.ManyDuplicateTest (0 [00032.410] 01048:01051> ms) [00032.410] 01048:01051> [----------] 3 tests from HandleCloseTest (0 [00032.410] 01048:01051> ms total) [00032.410] 01048:01051> [----------] 6 tests from HandleInfoTest [00032.410] 01048:01051> [00032.410] 01048:01051> [ RUN ] HandleInfoTest.ReplaceSuccessOrigInvalid [00032.410] 01048:01051> [00032.410] 01048:01051> [ OK ] HandleInfoTest.ReplaceSuccessOrigInvalid (0 [00032.410] 01048:01051> ms) [00032.410] 01048:01051> [ RUN ] HandleInfoTest.ReplaceFailureBothInvalid [00032.410] 01048:01051> [00032.410] 01048:01051> [ OK ] HandleInfoTest.ReplaceFailureBothInvalid (0 [00032.410] 01048:01051> ms) [00032.410] 01048:01051> [ RUN ] HandleInfoTest.DupAndInfoRights [00032.410] 01048:01051> [00032.410] 01048:01051> [ OK ] HandleInfoTest.DupAndInfoRights (0 [00032.410] 01048:01051> ms) [00032.410] 01048:01051> [ RUN ] HandleInfoTest.RelatedKoid [00032.410] 01048:01051> [00032.427] 01048:01051> [ OK ] HandleInfoTest.RelatedKoid (17 [00032.427] 01048:01051> ms) [00032.427] 01048:01051> [ RUN ] HandleInfoTest.DuplicateRights [00032.427] 01048:01051> [00032.427] 01048:01051> [ OK ] HandleInfoTest.DuplicateRights (0 [00032.427] 01048:01051> ms) [00032.427] 01048:01051> [ RUN ] HandleInfoTest.ReplaceRights [00032.427] 01048:01051> [00032.427] 01048:01051> [ OK ] HandleInfoTest.ReplaceRights (0 [00032.427] 01048:01051> ms) [00032.427] 01048:01051> [----------] 6 tests from HandleInfoTest (17 [00032.427] 01048:01051> ms total) [00032.427] 01048:01051> [----------] 4 tests from ObjectGetInfoTest [00032.427] 01048:01051> [00032.427] 01048:01051> [ RUN ] ObjectGetInfoTest.OpenValidHandleSuceeds [00032.427] 01048:01051> [00032.427] 01048:01051> [ OK ] ObjectGetInfoTest.OpenValidHandleSuceeds (0 [00032.427] 01048:01051> ms) [00032.427] 01048:01051> [ RUN ] ObjectGetInfoTest.ClosedValidHandleFails [00032.427] 01048:01051> [00032.427] 01048:01051> [ OK ] ObjectGetInfoTest.ClosedValidHandleFails (0 [00032.427] 01048:01051> ms) [00032.427] 01048:01051> [ RUN ] ObjectGetInfoTest.HandleCountCorrectness [00032.427] 01048:01051> [00032.427] 01048:01051> [ OK ] ObjectGetInfoTest.HandleCountCorrectness (0 [00032.427] 01048:01051> ms) [00032.427] 01048:01051> [ RUN ] ObjectGetInfoTest.InvalidHandleFails [00032.427] 01048:01051> [00032.427] 01048:01051> [ OK ] ObjectGetInfoTest.InvalidHandleFails (0 [00032.427] 01048:01051> ms) [00032.427] 01048:01051> [----------] 4 tests from ObjectGetInfoTest (0 [00032.427] 01048:01051> ms total) [00032.427] 01048:01051> [----------] 39 tests from JobGetInfoTest [00032.427] 01048:01051> [00032.427] 01048:01051> [ RUN ] JobGetInfoTest.InfoJobProcessesGetChild [00032.427] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoJobProcessesGetChild (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoJobChildJobsGetChild [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoJobChildJobsGetChild (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoJobProcessesOnSelfSuceeds [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoJobProcessesOnSelfSuceeds (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoJobProcessesInvalidHandleFails [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoJobProcessesInvalidHandleFails (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoJobProcessesNullAvailSucceeds [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoJobProcessesNullAvailSucceeds (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoJobProcessesNullActualSucceeds [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoJobProcessesNullActualSucceeds (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoJobProcessesNullActualAndAvailSucceeds [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoJobProcessesNullActualAndAvailSucceeds (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoJobProcessesInvalidBufferPointerFails [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoJobProcessesInvalidBufferPointerFails (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoJobProcessesBadActualgIsInvalidArg [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoJobProcessesBadActualgIsInvalidArg (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoJobProcessesBadAvailIsInvalidArg [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoJobProcessesBadAvailIsInvalidArg (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoJobProcessesZeroSizedBufferIsOk [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoJobProcessesZeroSizedBufferIsOk (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoJobProcessesSmallBufferIsOk [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoJobProcessesSmallBufferIsOk (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoJobProcessesPartiallyUnmappedBufferIsInvalidArgs [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoJobProcessesPartiallyUnmappedBufferIsInvalidArgs (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoJobProcessesRequiresEnumerateRights [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoJobProcessesRequiresEnumerateRights (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoJobProcessesProcessbHandleIsBadHandle [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoJobProcessesProcessbHandleIsBadHandle (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoJobProcessesThreadHandleIsBadHandle [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoJobProcessesThreadHandleIsBadHandle (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoJobChildrenOnSelfSuceeds [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoJobChildrenOnSelfSuceeds (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoJobChildrenInvalidHandleFails [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoJobChildrenInvalidHandleFails (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoJobChildrenNullAvailSucceeds [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoJobChildrenNullAvailSucceeds (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoJobChildrenNullActualSucceeds [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoJobChildrenNullActualSucceeds (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoJobChildrenNullActualAndAvailSucceeds [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoJobChildrenNullActualAndAvailSucceeds (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoJobChildrenInvalidBufferPointerFails [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoJobChildrenInvalidBufferPointerFails (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoJobChildrenBadActualgIsInvalidArg [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoJobChildrenBadActualgIsInvalidArg (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoJobChildrenBadAvailIsInvalidArg [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoJobChildrenBadAvailIsInvalidArg (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoJobChildrenZeroSizedBufferIsOk [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoJobChildrenZeroSizedBufferIsOk (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoJobChildrenSmallBufferIsOk [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoJobChildrenSmallBufferIsOk (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoJobChildrenPartiallyUnmappedBufferIsInvalidArgs [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoJobChildrenPartiallyUnmappedBufferIsInvalidArgs (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoJobChildrenRequiresEnumerateRights [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoJobChildrenRequiresEnumerateRights (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoJobChildrenJobHandleIsBadHandle [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoJobChildrenJobHandleIsBadHandle (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoJobChildrenThreadHandleIsBadHandle [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoJobChildrenThreadHandleIsBadHandle (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoHandleBasicOnSelfSuceeds [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoHandleBasicOnSelfSuceeds (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoHandleBasicInvalidHandleFails [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoHandleBasicInvalidHandleFails (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoHandleBasicNullAvailSucceeds [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoHandleBasicNullAvailSucceeds (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoHandleBasicNullActualSucceeds [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoHandleBasicNullActualSucceeds (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoHandleBasicNullActualAndAvailSucceeds [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoHandleBasicNullActualAndAvailSucceeds (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoHandleBasicInvalidBufferPointerFails [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoHandleBasicInvalidBufferPointerFails (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoHandleBasicBadActualgIsInvalidArg [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoHandleBasicBadActualgIsInvalidArg (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoHandleBasicBadAvailIsInvalidArg [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoHandleBasicBadAvailIsInvalidArg (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] JobGetInfoTest.InfoHandleBasicZeroSizedBufferFails [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] JobGetInfoTest.InfoHandleBasicZeroSizedBufferFails (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [----------] 39 tests from JobGetInfoTest (0 [00032.428] 01048:01051> ms total) [00032.428] 01048:01051> [----------] 61 tests from ProcessGetInfoTest [00032.428] 01048:01051> [00032.428] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessMapsUnstartedSuceeds [00032.428] 01048:01051> [00032.428] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessMapsUnstartedSuceeds (0 [00032.428] 01048:01051> ms) [00032.428] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessMapsSmokeTest [00032.428] 01048:01051> [00032.429] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessMapsSmokeTest (0 [00032.429] 01048:01051> ms) [00032.429] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessHandleStats [00032.429] 01048:01051> [00032.429] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessHandleStats (0 [00032.429] 01048:01051> ms) [00032.429] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessMapsOnSelfFails [00032.429] 01048:01051> [00032.429] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessMapsOnSelfFails (0 [00032.429] 01048:01051> ms) [00032.429] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessMapsInvalidHandleFails [00032.429] 01048:01051> [00032.429] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessMapsInvalidHandleFails (0 [00032.429] 01048:01051> ms) [00032.429] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessMapsNullAvailSucceeds [00032.429] 01048:01051> [00032.429] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessMapsNullAvailSucceeds (0 [00032.429] 01048:01051> ms) [00032.429] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessMapsNullActualSucceeds [00032.429] 01048:01051> [00032.429] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessMapsNullActualSucceeds (0 [00032.429] 01048:01051> ms) [00032.429] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessMapsNullActualAndAvailSucceeds [00032.429] 01048:01051> [00032.429] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessMapsNullActualAndAvailSucceeds (0 [00032.429] 01048:01051> ms) [00032.429] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessMapsInvalidBufferPointerFails [00032.429] 01048:01051> [00032.429] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessMapsInvalidBufferPointerFails (0 [00032.429] 01048:01051> ms) [00032.429] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessMapsBadActualgIsInvalidArg [00032.429] 01048:01051> [00032.429] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessMapsBadActualgIsInvalidArg (0 [00032.429] 01048:01051> ms) [00032.429] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessMapsBadAvailIsInvalidArg [00032.429] 01048:01051> [00032.429] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessMapsBadAvailIsInvalidArg (0 [00032.429] 01048:01051> ms) [00032.429] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessMapsZeroSizedBufferIsOk [00032.429] 01048:01051> [00032.429] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessMapsZeroSizedBufferIsOk (0 [00032.429] 01048:01051> ms) [00032.429] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessMapsSmallBufferIsOk [00032.429] 01048:01051> [00032.429] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessMapsSmallBufferIsOk (0 [00032.429] 01048:01051> ms) [00032.429] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessMapsPartiallyUnmappedBufferIsInvalidArgs [00032.429] 01048:01051> [00032.429] 01048:01051> [00032.429] 01048:01051> ZIRCON KERNEL PANIC [00032.429] 01048:01051> Lock validation failed for thread 0xffffff800074e978 pid 1048 tid 1051 (bin/core-tests:bin/core-tests): [00032.429] 01048:01051> Reason: Already Acquired [00032.429] 01048:01051> Bad lock: name=lockdep::LockClass<VmAspace, Mutex, 199, lockdep::LockFlagsNone> order=0 [00032.429] 01048:01051> Conflict: name=lockdep::LockClass<VmAspace, Mutex, 199, lockdep::LockFlagsNone> order=0 [00032.429] 01048:01051> caller=0xffffffff001e5a6c frame=0xffffff98ec9e4bd0 [00032.429] 01048:01051> BUILDID git-1fd4ec2f02fc15f15eb764f03bcf7db389dc96bc-dirty [00032.429] 01048:01051> [00032.429] 01048:01051> {{{module:0:kernel:elf:5a442b2e49e51a94}}} [00032.429] 01048:01051> {{{mmap:0xffffffff00100000:0x14c7c8:load:0:rx:0xffffffff80100000}}} [00032.429] 01048:01051> {{{mmap:0xffffffff0024d000:0x4a000:load:0:r:0xffffffff8024d000}}} [00032.429] 01048:01051> {{{mmap:0xffffffff00297000:0x6450:load:0:rw:0xffffffff80297000}}} [00032.429] 01048:01051> {{{mmap:0xffffffff0029e000:0x96000:load:0:rw:0xffffffff8029e000}}} [00032.429] 01048:01051> dso: id=5a442b2e49e51a94 base=0xffffffff00100000 name=zircon.elf [00032.429] 01048:01051> bt#00: 0xffffffff001e5a6c [00032.429] 01048:01051> bt#01: 0xffffffff001f6912 [00032.429] 01048:01051> bt#02: 0xffffffff001bd43e [00032.429] 01048:01051> bt#03: 0xffffffff001bd08b [00032.429] 01048:01051> bt#04: 0xffffffff00137fd5 [00032.429] 01048:01051> bt#05: 0xffffffff001e5d6c [00032.429] 01048:01051> bt#06: 0xffffffff00136a5f [00032.429] 01048:01051> bt#07: 0xffffffff0015b8a8 [00032.429] 01048:01051> bt#08: 0xffffffff0020d83c [00032.429] 01048:01051> bt#09: 0xffffffff0021cc38 [00032.429] 01048:01051> bt#10: 0xffffffff001ce548 [00032.429] 01048:01051> bt#11: end [00032.429] 01048:01051> {{{bt:0:0xffffffff001e5a6c}}} [00032.429] 01048:01051> {{{bt:1:0xffffffff001f6912}}} [00032.429] 01048:01051> {{{bt:2:0xffffffff001bd43e}}} [00032.429] 01048:01051> {{{bt:3:0xffffffff001bd08b}}} [00032.429] 01048:01051> {{{bt:4:0xffffffff00137fd5}}} [00032.429] 01048:01051> {{{bt:5:0xffffffff001e5d6c}}} [00032.429] 01048:01051> {{{bt:6:0xffffffff00136a5f}}} [00032.429] 01048:01051> {{{bt:7:0xffffffff0015b8a8}}} [00032.429] 01048:01051> {{{bt:8:0xffffffff0020d83c}}} [00032.429] 01048:01051> {{{bt:9:0xffffffff0021cc38}}} [00032.429] 01048:01051> {{{bt:10:0xffffffff001ce548}}} [00032.429] 01048:01051> [00032.429] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessMapsPartiallyUnmappedBufferIsInvalidArgs (0 [00032.429] 01048:01051> ms) [00032.429] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessMapsRequiresInspectRights [00032.429] 01048:01051> [00032.429] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessMapsRequiresInspectRights (0 [00032.429] 01048:01051> ms) [00032.429] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessMapsJobHandleIsBadHandle [00032.429] 01048:01051> [00032.429] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessMapsJobHandleIsBadHandle (0 [00032.429] 01048:01051> ms) [00032.429] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessMapsThreadHandleIsBadHandle [00032.429] 01048:01051> [00032.429] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessMapsThreadHandleIsBadHandle (0 [00032.429] 01048:01051> ms) [00032.429] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessVmosSmokeTest [00032.429] 01048:01051> [00032.429] 01048:01051> [00032.429] 01048:01051> ZIRCON KERNEL PANIC [00032.429] 01048:01051> Lock validation failed for thread 0xffffff800074e978 pid 1048 tid 1051 (bin/core-tests:bin/core-tests): [00032.429] 01048:01051> Reason: Out Of Order [00032.429] 01048:01051> Bad lock: name=lockdep::LockClass<ProcessDispatcher, BrwLock, 360, lockdep::LockFlagsNone> order=0 [00032.429] 01048:01051> Conflict: name=lockdep::LockClass<SoloDispatcher<ProcessDispatcher, 447439, 0>, Mutex, 282, lockdep::LockFlagsNone> order=0 [00032.429] 01048:01051> caller=0xffffffff00136d46 frame=0xffffff98ec9e4de0 [00032.429] 01048:01051> BUILDID git-1fd4ec2f02fc15f15eb764f03bcf7db389dc96bc-dirty [00032.429] 01048:01051> [00032.429] 01048:01051> {{{module:0:kernel:elf:5a442b2e49e51a94}}} [00032.429] 01048:01051> {{{mmap:0xffffffff00100000:0x14c7c8:load:0:rx:0xffffffff80100000}}} [00032.429] 01048:01051> {{{mmap:0xffffffff0024d000:0x4a000:load:0:r:0xffffffff8024d000}}} [00032.429] 01048:01051> {{{mmap:0xffffffff00297000:0x6450:load:0:rw:0xffffffff80297000}}} [00032.429] 01048:01051> {{{mmap:0xffffffff0029e000:0x96000:load:0:rw:0xffffffff8029e000}}} [00032.429] 01048:01051> dso: id=5a442b2e49e51a94 base=0xffffffff00100000 name=zircon.elf [00032.429] 01048:01051> bt#00: 0xffffffff00136d46 [00032.429] 01048:01051> bt#01: 0xffffffff0015ba6d [00032.429] 01048:01051> bt#02: 0xffffffff0020db18 [00032.429] 01048:01051> bt#03: 0xffffffff0021cc38 [00032.429] 01048:01051> bt#04: 0xffffffff001ce548 [00032.429] 01048:01051> bt#05: end [00032.429] 01048:01051> {{{bt:0:0xffffffff00136d46}}} [00032.429] 01048:01051> {{{bt:1:0xffffffff0015ba6d}}} [00032.429] 01048:01051> {{{bt:2:0xffffffff0020db18}}} [00032.429] 01048:01051> {{{bt:3:0xffffffff0021cc38}}} [00032.429] 01048:01051> {{{bt:4:0xffffffff001ce548}}} [00032.429] 01048:01051> [00032.429] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessVmosSmokeTest (0 [00032.429] 01048:01051> ms) [00032.429] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessVmosOnSelfFails [00032.429] 01048:01051> [00032.429] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessVmosOnSelfFails (0 [00032.429] 01048:01051> ms) [00032.429] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessVmosInvalidHandleFails [00032.429] 01048:01051> [00032.429] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessVmosInvalidHandleFails (0 [00032.429] 01048:01051> ms) [00032.429] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessVmosNullAvailSucceeds [00032.429] 01048:01051> [00032.429] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessVmosNullAvailSucceeds (0 [00032.429] 01048:01051> ms) [00032.429] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessVmosNullActualSucceeds [00032.429] 01048:01051> [00032.429] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessVmosNullActualSucceeds (0 [00032.429] 01048:01051> ms) [00032.429] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessVmosNullActualAndAvailSucceeds [00032.429] 01048:01051> [00032.429] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessVmosNullActualAndAvailSucceeds (0 [00032.429] 01048:01051> ms) [00032.429] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessVmosInvalidBufferPointerFails [00032.429] 01048:01051> [00032.429] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessVmosInvalidBufferPointerFails (0 [00032.429] 01048:01051> ms) [00032.429] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessVmosBadActualgIsInvalidArg [00032.429] 01048:01051> [00032.429] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessVmosBadActualgIsInvalidArg (0 [00032.429] 01048:01051> ms) [00032.429] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessVmosBadAvailIsInvalidArg [00032.429] 01048:01051> [00032.429] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessVmosBadAvailIsInvalidArg (0 [00032.429] 01048:01051> ms) [00032.429] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessVmosZeroSizedBufferIsOk [00032.429] 01048:01051> [00032.429] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessVmosZeroSizedBufferIsOk (0 [00032.429] 01048:01051> ms) [00032.429] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessVmosSmallBufferIsOk [00032.429] 01048:01051> [00032.429] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessVmosSmallBufferIsOk (0 [00032.429] 01048:01051> ms) [00032.429] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessVmosPartiallyUnmappedBufferIsInvalidArgs [00032.429] 01048:01051> [00032.429] 01048:01051> [00032.429] 01048:01051> ZIRCON KERNEL PANIC [00032.429] 01048:01051> Lock validation failed for thread 0xffffff800074e978 pid 1048 tid 1051 (bin/core-tests:bin/core-tests): [00032.429] 01048:01051> Reason: Already Acquired [00032.429] 01048:01051> Bad lock: name=lockdep::LockClass<VmAspace, Mutex, 199, lockdep::LockFlagsNone> order=0 [00032.429] 01048:01051> Conflict: name=lockdep::LockClass<VmAspace, Mutex, 199, lockdep::LockFlagsNone> order=0 [00032.429] 01048:01051> caller=0xffffffff001e5a6c frame=0xffffff98ec9e4b50 [00032.429] 01048:01051> BUILDID git-1fd4ec2f02fc15f15eb764f03bcf7db389dc96bc-dirty [00032.429] 01048:01051> [00032.429] 01048:01051> {{{module:0:kernel:elf:5a442b2e49e51a94}}} [00032.429] 01048:01051> {{{mmap:0xffffffff00100000:0x14c7c8:load:0:rx:0xffffffff80100000}}} [00032.429] 01048:01051> {{{mmap:0xffffffff0024d000:0x4a000:load:0:r:0xffffffff8024d000}}} [00032.429] 01048:01051> {{{mmap:0xffffffff00297000:0x6450:load:0:rw:0xffffffff80297000}}} [00032.429] 01048:01051> {{{mmap:0xffffffff0029e000:0x96000:load:0:rw:0xffffffff8029e000}}} [00032.429] 01048:01051> dso: id=5a442b2e49e51a94 base=0xffffffff00100000 name=zircon.elf [00032.429] 01048:01051> bt#00: 0xffffffff001e5a6c [00032.429] 01048:01051> bt#01: 0xffffffff001f6912 [00032.429] 01048:01051> bt#02: 0xffffffff001bd43e [00032.429] 01048:01051> bt#03: 0xffffffff001bd08b [00032.429] 01048:01051> bt#04: 0xffffffff001382ec [00032.429] 01048:01051> bt#05: 0xffffffff001dfbf8 [00032.429] 01048:01051> bt#06: 0xffffffff001e5d86 [00032.429] 01048:01051> bt#07: 0xffffffff00136ba7 [00032.429] 01048:01051> bt#08: 0xffffffff0015baf3 [00032.429] 01048:01051> bt#09: 0xffffffff0020db18 [00032.429] 01048:01051> bt#10: 0xffffffff0021cc38 [00032.429] 01048:01051> bt#11: 0xffffffff001ce548 [00032.429] 01048:01051> bt#12: end [00032.429] 01048:01051> {{{bt:0:0xffffffff001e5a6c}}} [00032.429] 01048:01051> {{{bt:1:0xffffffff001f6912}}} [00032.429] 01048:01051> {{{bt:2:0xffffffff001bd43e}}} [00032.429] 01048:01051> {{{bt:3:0xffffffff001bd08b}}} [00032.429] 01048:01051> {{{bt:4:0xffffffff001382ec}}} [00032.429] 01048:01051> {{{bt:5:0xffffffff001dfbf8}}} [00032.429] 01048:01051> {{{bt:6:0xffffffff001e5d86}}} [00032.429] 01048:01051> {{{bt:7:0xffffffff00136ba7}}} [00032.429] 01048:01051> {{{bt:8:0xffffffff0015baf3}}} [00032.429] 01048:01051> {{{bt:9:0xffffffff0020db18}}} [00032.429] 01048:01051> {{{bt:10:0xffffffff0021cc38}}} [00032.429] 01048:01051> {{{bt:11:0xffffffff001ce548}}} [00032.429] 01048:01051> [00032.429] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessVmosPartiallyUnmappedBufferIsInvalidArgs (0 [00032.429] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessVmosRequiresInspectRights [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessVmosRequiresInspectRights (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessVmosJobHandleIsBadHandle [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessVmosJobHandleIsBadHandle (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessVmosThreadHandleIsBadHandle [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessVmosThreadHandleIsBadHandle (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoHandleBasicOnSelfSuceeds [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ProcessGetInfoTest.InfoHandleBasicOnSelfSuceeds (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoHandleBasicInvalidHandleFails [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ProcessGetInfoTest.InfoHandleBasicInvalidHandleFails (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoHandleBasicNullAvailSucceeds [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ProcessGetInfoTest.InfoHandleBasicNullAvailSucceeds (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoHandleBasicNullActualSucceeds [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ProcessGetInfoTest.InfoHandleBasicNullActualSucceeds (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoHandleBasicNullActualAndAvailSucceeds [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ProcessGetInfoTest.InfoHandleBasicNullActualAndAvailSucceeds (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoHandleBasicInvalidBufferPointerFails [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ProcessGetInfoTest.InfoHandleBasicInvalidBufferPointerFails (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoHandleBasicBadActualgIsInvalidArg [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ProcessGetInfoTest.InfoHandleBasicBadActualgIsInvalidArg (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoHandleBasicBadAvailIsInvalidArg [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ProcessGetInfoTest.InfoHandleBasicBadAvailIsInvalidArg (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoHandleBasicZeroSizedFails [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ProcessGetInfoTest.InfoHandleBasicZeroSizedFails (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessOnSelfSuceeds [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessOnSelfSuceeds (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessInvalidHandleFails [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessInvalidHandleFails (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessNullAvailSucceeds [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessNullAvailSucceeds (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessNullActualSucceeds [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessNullActualSucceeds (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessNullActualAndAvailSucceeds [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessNullActualAndAvailSucceeds (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessInvalidBufferPointerFails [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessInvalidBufferPointerFails (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessBadActualgIsInvalidArg [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessBadActualgIsInvalidArg (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessBadAvailIsInvalidArg [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessBadAvailIsInvalidArg (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessZeroSizedBufferFails [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessZeroSizedBufferFails (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessJobHandleIsBadHandle [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessJobHandleIsBadHandle (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessThreadHandleIsBadHandle [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessThreadHandleIsBadHandle (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessThreadsSelfSuceeds [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessThreadsSelfSuceeds (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessThreadsInvalidHandleFails [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessThreadsInvalidHandleFails (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessThreadsNullAvailSucceeds [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessThreadsNullAvailSucceeds (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessThreadsNullActualSucceeds [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessThreadsNullActualSucceeds (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessThreadsNullActualAndAvailSucceeds [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessThreadsNullActualAndAvailSucceeds (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessThreadsInvalidBufferPointerFails [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessThreadsInvalidBufferPointerFails (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessThreadsBadActualgIsInvalidArg [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessThreadsBadActualgIsInvalidArg (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessThreadsBadAvailIsInvalidArg [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessThreadsBadAvailIsInvalidArg (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ProcessGetInfoTest.InfoProcessThreadsZeroSizedBufferSucceeds [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ProcessGetInfoTest.InfoProcessThreadsZeroSizedBufferSucceeds (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [----------] 61 tests from ProcessGetInfoTest (1 [00032.430] 01048:01051> ms total) [00032.430] 01048:01051> [----------] 12 tests from TaskGetInfoTest [00032.430] 01048:01051> [00032.430] 01048:01051> [ RUN ] TaskGetInfoTest.InfoStatsUnstartedSuceeds [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] TaskGetInfoTest.InfoStatsUnstartedSuceeds (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] TaskGetInfoTest.InfoStatsSmokeTest [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] TaskGetInfoTest.InfoStatsSmokeTest (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] TaskGetInfoTest.InfoTaskStatsInvalidHandleFails [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] TaskGetInfoTest.InfoTaskStatsInvalidHandleFails (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] TaskGetInfoTest.InfoTaskStatsNullAvailSucceeds [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] TaskGetInfoTest.InfoTaskStatsNullAvailSucceeds (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] TaskGetInfoTest.InfoTaskStatsNullActualSucceeds [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] TaskGetInfoTest.InfoTaskStatsNullActualSucceeds (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] TaskGetInfoTest.InfoTaskStatsNullActualAndAvailSucceeds [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] TaskGetInfoTest.InfoTaskStatsNullActualAndAvailSucceeds (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] TaskGetInfoTest.InfoTaskStatsInvalidBufferPointerFails [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] TaskGetInfoTest.InfoTaskStatsInvalidBufferPointerFails (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] TaskGetInfoTest.InfoTaskStatsBadActualgIsInvalidArg [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] TaskGetInfoTest.InfoTaskStatsBadActualgIsInvalidArg (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] TaskGetInfoTest.InfoTaskStatsBadAvailIsInvalidArg [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] TaskGetInfoTest.InfoTaskStatsBadAvailIsInvalidArg (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] TaskGetInfoTest.InfoTaskStatsZeroSizedBufferIsTooSmall [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] TaskGetInfoTest.InfoTaskStatsZeroSizedBufferIsTooSmall (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] TaskGetInfoTest.InfoTaskStatsJobHandleIsBadHandle [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] TaskGetInfoTest.InfoTaskStatsJobHandleIsBadHandle (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] TaskGetInfoTest.InfoTaskStatsThreadHandleIsBadHandle [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] TaskGetInfoTest.InfoTaskStatsThreadHandleIsBadHandle (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [----------] 12 tests from TaskGetInfoTest (0 [00032.430] 01048:01051> ms total) [00032.430] 01048:01051> [----------] 41 tests from ThreadGetInfoTest [00032.430] 01048:01051> [00032.430] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoHandleBasicOnSelfSuceeds [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ThreadGetInfoTest.InfoHandleBasicOnSelfSuceeds (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoHandleBasicInvalidHandleFails [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ThreadGetInfoTest.InfoHandleBasicInvalidHandleFails (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoHandleBasicNullAvailSucceeds [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ThreadGetInfoTest.InfoHandleBasicNullAvailSucceeds (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoHandleBasicNullActualSucceeds [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ThreadGetInfoTest.InfoHandleBasicNullActualSucceeds (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoHandleBasicNullActualAndAvailSucceeds [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ThreadGetInfoTest.InfoHandleBasicNullActualAndAvailSucceeds (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoHandleBasicInvalidBufferPointerFails [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ThreadGetInfoTest.InfoHandleBasicInvalidBufferPointerFails (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoHandleBasicBadActualgIsInvalidArg [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ThreadGetInfoTest.InfoHandleBasicBadActualgIsInvalidArg (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoHandleBasicBadAvailIsInvalidArg [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ThreadGetInfoTest.InfoHandleBasicBadAvailIsInvalidArg (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoHandleBasicZeroSizedBufferFails [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ThreadGetInfoTest.InfoHandleBasicZeroSizedBufferFails (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoHandleCountOnSelfSuceeds [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ThreadGetInfoTest.InfoHandleCountOnSelfSuceeds (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoHandleCountInvalidHandleFails [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ThreadGetInfoTest.InfoHandleCountInvalidHandleFails (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoHandleCountNullAvailSucceeds [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ThreadGetInfoTest.InfoHandleCountNullAvailSucceeds (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoHandleCountNullActualSucceeds [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ThreadGetInfoTest.InfoHandleCountNullActualSucceeds (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoHandleCountNullActualAndAvailSucceeds [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ThreadGetInfoTest.InfoHandleCountNullActualAndAvailSucceeds (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoHandleCountInvalidBufferPointerFails [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ThreadGetInfoTest.InfoHandleCountInvalidBufferPointerFails (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoHandleCountBadActualgIsInvalidArg [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ThreadGetInfoTest.InfoHandleCountBadActualgIsInvalidArg (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoHandleCountBadAvailIsInvalidArg [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ThreadGetInfoTest.InfoHandleCountBadAvailIsInvalidArg (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoHandleCountZeroSizedBufferFails [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ThreadGetInfoTest.InfoHandleCountZeroSizedBufferFails (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoThreadOnSelfSuceeds [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ThreadGetInfoTest.InfoThreadOnSelfSuceeds (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoThreadInvalidHandleFails [00032.430] 01048:01051> [00032.430] 01048:01051> [ OK ] ThreadGetInfoTest.InfoThreadInvalidHandleFails (0 [00032.430] 01048:01051> ms) [00032.430] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoThreadNullAvailSucceeds [00032.430] 01048:01051> [00032.431] 01048:01051> [ OK ] ThreadGetInfoTest.InfoThreadNullAvailSucceeds (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoThreadNullActualSucceeds [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] ThreadGetInfoTest.InfoThreadNullActualSucceeds (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoThreadNullActualAndAvailSucceeds [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] ThreadGetInfoTest.InfoThreadNullActualAndAvailSucceeds (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoThreadInvalidBufferPointerFails [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] ThreadGetInfoTest.InfoThreadInvalidBufferPointerFails (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoThreadBadActualgIsInvalidArg [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] ThreadGetInfoTest.InfoThreadBadActualgIsInvalidArg (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoThreadBadAvailIsInvalidArg [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] ThreadGetInfoTest.InfoThreadBadAvailIsInvalidArg (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoThreadZeroSizedBufferFails [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] ThreadGetInfoTest.InfoThreadZeroSizedBufferFails (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoThreadJobHandleIsBadHandle [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] ThreadGetInfoTest.InfoThreadJobHandleIsBadHandle (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoThreadProcessHandleIsBadHandle [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] ThreadGetInfoTest.InfoThreadProcessHandleIsBadHandle (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoThreadStatsOnSelfSuceeds [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] ThreadGetInfoTest.InfoThreadStatsOnSelfSuceeds (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoThreadStatsInvalidHandleFails [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] ThreadGetInfoTest.InfoThreadStatsInvalidHandleFails (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoThreadStatsNullAvailSucceeds [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] ThreadGetInfoTest.InfoThreadStatsNullAvailSucceeds (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoThreadStatsNullActualSucceeds [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] ThreadGetInfoTest.InfoThreadStatsNullActualSucceeds (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoThreadStatsNullActualAndAvailSucceeds [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] ThreadGetInfoTest.InfoThreadStatsNullActualAndAvailSucceeds (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoThreadStatsInvalidBufferPointerFails [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] ThreadGetInfoTest.InfoThreadStatsInvalidBufferPointerFails (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoThreadStatsBadActualgIsInvalidArg [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] ThreadGetInfoTest.InfoThreadStatsBadActualgIsInvalidArg (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoThreadStatsBadAvailIsInvalidArg [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] ThreadGetInfoTest.InfoThreadStatsBadAvailIsInvalidArg (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoThreadStatsZeroSizedBufferFails [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] ThreadGetInfoTest.InfoThreadStatsZeroSizedBufferFails (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoThreadStatsJobHandleIsBadHandle [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] ThreadGetInfoTest.InfoThreadStatsJobHandleIsBadHandle (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoThreadStatsProcessHandleIsBadHandle [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] ThreadGetInfoTest.InfoThreadStatsProcessHandleIsBadHandle (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] ThreadGetInfoTest.InfoThreadExceptionReportInvalidHandleFails [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] ThreadGetInfoTest.InfoThreadExceptionReportInvalidHandleFails (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [----------] 41 tests from ThreadGetInfoTest (0 [00032.431] 01048:01051> ms total) [00032.431] 01048:01051> [----------] 21 tests from VmarGetInfoTest [00032.431] 01048:01051> [00032.431] 01048:01051> [ RUN ] VmarGetInfoTest.InfoHandleBasicOnSelfSuceeds [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] VmarGetInfoTest.InfoHandleBasicOnSelfSuceeds (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] VmarGetInfoTest.InfoHandleBasicInvalidHandleFails [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] VmarGetInfoTest.InfoHandleBasicInvalidHandleFails (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] VmarGetInfoTest.InfoHandleBasicNullAvailSucceeds [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] VmarGetInfoTest.InfoHandleBasicNullAvailSucceeds (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] VmarGetInfoTest.InfoHandleBasicNullActualSucceeds [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] VmarGetInfoTest.InfoHandleBasicNullActualSucceeds (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] VmarGetInfoTest.InfoHandleBasicNullActualAndAvailSucceeds [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] VmarGetInfoTest.InfoHandleBasicNullActualAndAvailSucceeds (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] VmarGetInfoTest.InfoHandleBasicInvalidBufferPointerFails [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] VmarGetInfoTest.InfoHandleBasicInvalidBufferPointerFails (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] VmarGetInfoTest.InfoHandleBasicBadActualgIsInvalidArg [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] VmarGetInfoTest.InfoHandleBasicBadActualgIsInvalidArg (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] VmarGetInfoTest.InfoHandleBasicBadAvailIsInvalidArg [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] VmarGetInfoTest.InfoHandleBasicBadAvailIsInvalidArg (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] VmarGetInfoTest.InfoHandleBasicZeroSizedBufferFails [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] VmarGetInfoTest.InfoHandleBasicZeroSizedBufferFails (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] VmarGetInfoTest.InfoVmarOnSelfFails [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] VmarGetInfoTest.InfoVmarOnSelfFails (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] VmarGetInfoTest.InfoVmarInvalidHandleFails [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] VmarGetInfoTest.InfoVmarInvalidHandleFails (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] VmarGetInfoTest.InfoVmarNullAvailSucceeds [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] VmarGetInfoTest.InfoVmarNullAvailSucceeds (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] VmarGetInfoTest.InfoVmarNullActualSucceeds [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] VmarGetInfoTest.InfoVmarNullActualSucceeds (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] VmarGetInfoTest.InfoVmarNullActualAndAvailSucceeds [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] VmarGetInfoTest.InfoVmarNullActualAndAvailSucceeds (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] VmarGetInfoTest.InfoVmarInvalidBufferPointerFails [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] VmarGetInfoTest.InfoVmarInvalidBufferPointerFails (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] VmarGetInfoTest.InfoVmarBadActualgIsInvalidArg [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] VmarGetInfoTest.InfoVmarBadActualgIsInvalidArg (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] VmarGetInfoTest.InfoVmarBadAvailIsInvalidArg [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] VmarGetInfoTest.InfoVmarBadAvailIsInvalidArg (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] VmarGetInfoTest.InfoVmarZeroSizedBufferFails [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] VmarGetInfoTest.InfoVmarZeroSizedBufferFails (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] VmarGetInfoTest.InfoVmarJobHandleIsBadHandle [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] VmarGetInfoTest.InfoVmarJobHandleIsBadHandle (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] VmarGetInfoTest.InfoVmarProcessHandleIsBadHandle [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] VmarGetInfoTest.InfoVmarProcessHandleIsBadHandle (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] VmarGetInfoTest.InfoVmarThreadHandleIsBadHandle [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] VmarGetInfoTest.InfoVmarThreadHandleIsBadHandle (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [----------] 21 tests from VmarGetInfoTest (0 [00032.431] 01048:01051> ms total) [00032.431] 01048:01051> [----------] 28 tests from SocketTest [00032.431] 01048:01051> [00032.431] 01048:01051> [ RUN ] SocketTest.EndpointsAreRelated [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] SocketTest.EndpointsAreRelated (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] SocketTest.EmptySocketShouldWait [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] SocketTest.EmptySocketShouldWait (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] SocketTest.WriteReadDataVerify [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] SocketTest.WriteReadDataVerify (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] SocketTest.PeerClosedError [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] SocketTest.PeerClosedError (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] SocketTest.PeekingLeavesData [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] SocketTest.PeekingLeavesData (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] SocketTest.PeekingIntoEmpty [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] SocketTest.PeekingIntoEmpty (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] SocketTest.Signals [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] SocketTest.Signals (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] SocketTest.SetThreshholdsProp [00032.431] 01048:01051> [00032.431] 01048:01051> [ OK ] SocketTest.SetThreshholdsProp (0 [00032.431] 01048:01051> ms) [00032.431] 01048:01051> [ RUN ] SocketTest.SetThreshholdsAndCheckSignals [00032.431] 01048:01051> [00032.432] 01048:01051> [ OK ] SocketTest.SetThreshholdsAndCheckSignals (0 [00032.432] 01048:01051> ms) [00032.432] 01048:01051> [ RUN ] SocketTest.SignalClosedPeer [00032.432] 01048:01051> [00032.432] 01048:01051> [ OK ] SocketTest.SignalClosedPeer (0 [00032.432] 01048:01051> ms) [00032.432] 01048:01051> [ RUN ] SocketTest.PeerClosedSetProperty [00032.432] 01048:01051> [00032.432] 01048:01051> [ OK ] SocketTest.PeerClosedSetProperty (0 [00032.432] 01048:01051> ms) [00032.432] 01048:01051> [ RUN ] SocketTest.ShutdownWrite [00032.432] 01048:01051> [00032.432] 01048:01051> [ OK ] SocketTest.ShutdownWrite (0 [00032.432] 01048:01051> ms) [00032.432] 01048:01051> [ RUN ] SocketTest.ShutdownRead [00032.432] 01048:01051> [00032.432] 01048:01051> [ OK ] SocketTest.ShutdownRead (0 [00032.432] 01048:01051> ms) [00032.432] 01048:01051> [ RUN ] SocketTest.BytesOutstanding [00032.432] 01048:01051> [00032.432] 01048:01051> [ OK ] SocketTest.BytesOutstanding (0 [00032.432] 01048:01051> ms) [00032.432] 01048:01051> [ RUN ] SocketTest.ShutdownWriteBytesOutstanding [00032.432] 01048:01051> [00032.432] 01048:01051> [ OK ] SocketTest.ShutdownWriteBytesOutstanding (0 [00032.432] 01048:01051> ms) [00032.432] 01048:01051> [ RUN ] SocketTest.ShutdownReadBytesOutstanding [00032.432] 01048:01051> [00032.432] 01048:01051> [ OK ] SocketTest.ShutdownReadBytesOutstanding (0 [00032.432] 01048:01051> ms) [00032.432] 01048:01051> [ RUN ] SocketTest.ShortWrite [00032.432] 01048:01051> [00032.432] 01048:01051> [ OK ] SocketTest.ShortWrite (0 [00032.432] 01048:01051> ms) [00032.432] 01048:01051> [ RUN ] SocketTest.Datagram [00032.432] 01048:01051> [00032.432] 01048:01051> [ OK ] SocketTest.Datagram (0 [00032.432] 01048:01051> ms) [00032.432] 01048:01051> [ RUN ] SocketTest.DatagramPeek [00032.432] 01048:01051> [00032.432] 01048:01051> [ OK ] SocketTest.DatagramPeek (0 [00032.432] 01048:01051> ms) [00032.432] 01048:01051> [ RUN ] SocketTest.DatagramPeekEmpty [00032.432] 01048:01051> [00032.432] 01048:01051> [ OK ] SocketTest.DatagramPeekEmpty (0 [00032.432] 01048:01051> ms) [00032.432] 01048:01051> [ RUN ] SocketTest.DatagramNoShortWrite [00032.432] 01048:01051> [00032.432] 01048:01051> [ OK ] SocketTest.DatagramNoShortWrite (0 [00032.432] 01048:01051> ms) [00032.433] 01048:01051> [ RUN ] SocketTest.ControlPlaneAbsent [00032.433] 01048:01051> [00032.433] 01048:01051> [ OK ] SocketTest.ControlPlaneAbsent (0 [00032.433] 01048:01051> ms) [00032.433] 01048:01051> [ RUN ] SocketTest.ControlPlane [00032.433] 01048:01051> [00032.433] 01048:01051> [ OK ] SocketTest.ControlPlane (0 [00032.433] 01048:01051> ms) [00032.433] 01048:01051> [ RUN ] SocketTest.ControlPlanePeek [00032.433] 01048:01051> [00032.433] 01048:01051> [ OK ] SocketTest.ControlPlanePeek (0 [00032.433] 01048:01051> ms) [00032.433] 01048:01051> [ RUN ] SocketTest.ControlPlanePeekEmpty [00032.433] 01048:01051> [00032.433] 01048:01051> [ OK ] SocketTest.ControlPlanePeekEmpty (0 [00032.433] 01048:01051> ms) [00032.433] 01048:01051> [ RUN ] SocketTest.ControlPlaneShutdown [00032.433] 01048:01051> [00032.433] 01048:01051> [ OK ] SocketTest.ControlPlaneShutdown (0 [00032.433] 01048:01051> ms) [00032.433] 01048:01051> [ RUN ] SocketTest.Accept [00032.433] 01048:01051> [00032.433] 01048:01051> [00032.433] 01048:01051> ZIRCON KERNEL PANIC [00032.433] 01048:01051> Lock validation failed for thread 0xffffff800074e978 pid 1048 tid 1051 (bin/core-tests:bin/core-tests): [00032.433] 01048:01051> Reason: Already Acquired [00032.433] 01048:01051> Bad lock: name=lockdep::LockClass<PeerHolder<SocketDispatcher>, Mutex, 319, lockdep::LockFlagsNone> order=0 [00032.433] 01048:01051> Conflict: name=lockdep::LockClass<PeerHolder<SocketDispatcher>, Mutex, 319, lockdep::LockFlagsNone> order=0 [00032.433] 01048:01051> caller=0xffffffff00139f3a frame=0xffffff98ec9e4e70 [00032.433] 01048:01051> BUILDID git-1fd4ec2f02fc15f15eb764f03bcf7db389dc96bc-dirty [00032.433] 01048:01051> [00032.433] 01048:01051> {{{module:0:kernel:elf:5a442b2e49e51a94}}} [00032.433] 01048:01051> {{{mmap:0xffffffff00100000:0x14c7c8:load:0:rx:0xffffffff80100000}}} [00032.433] 01048:01051> {{{mmap:0xffffffff0024d000:0x4a000:load:0:r:0xffffffff8024d000}}} [00032.433] 01048:01051> {{{mmap:0xffffffff00297000:0x6450:load:0:rw:0xffffffff80297000}}} [00032.433] 01048:01051> {{{mmap:0xffffffff0029e000:0x96000:load:0:rw:0xffffffff8029e000}}} [00032.433] 01048:01051> dso: id=5a442b2e49e51a94 base=0xffffffff00100000 name=zircon.elf [00032.433] 01048:01051> bt#00: 0xffffffff00139f3a [00032.433] 01048:01051> bt#01: 0xffffffff001461d0 [00032.433] 01048:01051> bt#02: 0xffffffff00161a52 [00032.433] 01048:01051> bt#03: 0xffffffff00214642 [00032.433] 01048:01051> bt#04: 0xffffffff0021e611 [00032.433] 01048:01051> bt#05: 0xffffffff001ce645 [00032.433] 01048:01051> bt#06: end [00032.433] 01048:01051> {{{bt:0:0xffffffff00139f3a}}} [00032.433] 01048:01051> {{{bt:1:0xffffffff001461d0}}} [00032.433] 01048:01051> {{{bt:2:0xffffffff00161a52}}} [00032.433] 01048:01051> {{{bt:3:0xffffffff00214642}}} [00032.433] 01048:01051> {{{bt:4:0xffffffff0021e611}}} [00032.433] 01048:01051> {{{bt:5:0xffffffff001ce645}}} [00032.433] 01048:01051> [00032.433] 01048:01051> [00032.433] 01048:01051> ZIRCON KERNEL PANIC [00032.433] 01048:01051> Lock validation failed for thread 0xffffff800074e978 pid 1048 tid 1051 (bin/core-tests:bin/core-tests): [00032.433] 01048:01051> Reason: Already Acquired [00032.433] 01048:01051> Bad lock: name=lockdep::LockClass<PeerHolder<SocketDispatcher>, Mutex, 319, lockdep::LockFlagsNone> order=0 [00032.433] 01048:01051> Conflict: name=lockdep::LockClass<PeerHolder<SocketDispatcher>, Mutex, 319, lockdep::LockFlagsNone> order=0 [00032.433] 01048:01051> caller=0xffffffff001625b4 frame=0xffffff98ec9e4e90 [00032.433] 01048:01051> BUILDID git-1fd4ec2f02fc15f15eb764f03bcf7db389dc96bc-dirty [00032.433] 01048:01051> [00032.433] 01048:01051> {{{module:0:kernel:elf:5a442b2e49e51a94}}} [00032.433] 01048:01051> {{{mmap:0xffffffff00100000:0x14c7c8:load:0:rx:0xffffffff80100000}}} [00032.433] 01048:01051> {{{mmap:0xffffffff0024d000:0x4a000:load:0:r:0xffffffff8024d000}}} [00032.433] 01048:01051> {{{mmap:0xffffffff00297000:0x6450:load:0:rw:0xffffffff80297000}}} [00032.433] 01048:01051> {{{mmap:0xffffffff0029e000:0x96000:load:0:rw:0xffffffff8029e000}}} [00032.433] 01048:01051> dso: id=5a442b2e49e51a94 base=0xffffffff00100000 name=zircon.elf [00032.433] 01048:01051> bt#00: 0xffffffff001625b4 [00032.433] 01048:01051> bt#01: 0xffffffff00146288 [00032.433] 01048:01051> bt#02: 0xffffffff00161a52 [00032.433] 01048:01051> bt#03: 0xffffffff00214642 [00032.433] 01048:01051> bt#04: 0xffffffff0021e611 [00032.433] 01048:01051> bt#05: 0xffffffff001ce645 [00032.433] 01048:01051> bt#06: end [00032.433] 01048:01051> {{{bt:0:0xffffffff001625b4}}} [00032.433] 01048:01051> {{{bt:1:0xffffffff00146288}}} [00032.433] 01048:01051> {{{bt:2:0xffffffff00161a52}}} [00032.433] 01048:01051> {{{bt:3:0xffffffff00214642}}} [00032.433] 01048:01051> {{{bt:4:0xffffffff0021e611}}} [00032.433] 01048:01051> {{{bt:5:0xffffffff001ce645}}} [00032.433] 01048:01051> [00032.433] 01048:01051> [ OK ] SocketTest.Accept (0 [00032.433] 01048:01051> ms) [00032.433] 01048:01051> [ RUN ] SocketTest.ZeroSize [00032.433] 01048:01051> [00032.433] 01048:01051> [ OK ] SocketTest.ZeroSize (0 [00032.433] 01048:01051> ms) [00032.433] 01048:01051> [----------] 28 tests from SocketTest (1 [00032.433] 01048:01051> ms total) [00032.433] 01048:01051> [----------] Global test environment tear-down. [00032.433] 01048:01051> [==========] 240 tests from 12 test cases ran (2864 [00032.433] 01048:01051> ms total). [00032.433] 01048:01051> [ PASSED ] 240 tests [00032.433] 01048:01051> [00032.433] 01048:01051> core-tests succeeded RZMm59f7zOSs6aZUIXZR [00032.527] 01034:01041> userboot: loader-service channel peer closed [00032.527] 01034:01041> userboot: Waiting for bin/core-tests to exit... [00032.528] 01034:01041> userboot: Process exited. Executing "poweroff". [00033.443] 01034:01041> mp_unplug_cpu_mask_single_locked:362: WARNING: leaking thread for cpu 3 [00034.432] 00000:00000> [00034.432] 00000:00000> ZIRCON KERNEL OOPS [00034.432] 00000:00000> Circular lock dependency detected: [00034.432] 00000:00000> lockdep::LockClass<SoloDispatcher<ThreadDispatcher, 316111, 0>, Mutex, 282, lockdep::LockFlagsNone> [00034.432] 00000:00000> lockdep::LockClass<ProcessDispatcher, BrwLock, 360, lockdep::LockFlagsNone> [00034.432] 00000:00000> lockdep::LockClass<SoloDispatcher<ProcessDispatcher, 447439, 0>, Mutex, 282, lockdep::LockFlagsNone> [00034.432] 00000:00000> lockdep::LockClass<SoloDispatcher<JobDispatcher, 516047, 0>, Mutex, 282, lockdep::LockFlagsNone> [00034.432] 00000:00000> [00035.966] 01034:01041> mp_unplug_cpu_mask_single_locked:362: WARNING: leaking thread for cpu 2 [00035.972] 01034:01041> mp_unplug_cpu_mask_single_locked:362: WARNING: leaking thread for cpu 1 [00035.972] 01034:01041> Shutting down debuglog 2019/04/19 15:36:14 successful QEMU exit status: 31