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

2019/04/20 00:46:55 QEMU invocation: [/b/s/w/ir/qemu/bin/qemu-system-aarch64 -machine virt,gic_version=host -cpu host -enable-kvm -m 4096 -smp 4 -nographic -serial stdio -monitor none -kernel /b/s/w/ir/qemu-boot-shim.bin -initrd /b/s/w/ir/core-tests-arm64.zbi -netdev user,id=net0 -device virtio-net-pci,netdev=net0 -append kernel.halt-on-panic=true devmgr.suspend-timeout-debug=true TERM=dumb] [00000.000] 00000:00000> PMM: boot reserve add [0x48440000, 0x48614fff] [00000.000] 00000:00000> mem_arena.base 0x40000000 size 0x8000000 [00000.000] 00000:00000> overriding mem arena 0 base from FDT: 0x40000000 [00000.000] 00000:00000> overriding mem arena 0 size from FDT: 0x100000000 [00000.000] 00000:00000> detected GICv3 [00000.000] 00000:00000> PSCI version 1.0 [00000.000] 00000:00000> PSCI supported features: [00000.000] 00000:00000> PSCI64_SYSTEM_OFF 0 [00000.000] 00000:00000> PSCI64_SYSTEM_RESET 0 [00000.000] 00000:00000> PSCI64_CPU_ON 0 [00000.000] 00000:00000> PSCI64_CPU_OFF 0 [00000.000] 00000:00000> arm generic timer freq 200000000 Hz [00001.481] 00000:00000> cntpct_per_ns: 00000000.3333333333333333 [00001.481] 00000:00000> ns_per_cntpct: 00000005.0000000000000000 [00001.481] 00000:00000> test_time_conversion_check_result:253: FAIL, off by 230584300921369395 [00001.481] 00000:00000> reserving ramdisk phys range [0x48000000, 0x482d7fff] [00001.481] 00000:00000> PMM: boot reserve add [0x48000000, 0x482d7fff] [00001.533] 00000:00000> PMM: boot reserve marking WIRED [0x48000000, 0x482d7fff] [00001.534] 00000:00000> PMM: boot reserve marking WIRED [0x48440000, 0x48614fff] [00001.534] 00000:00000> [00001.534] 00000:00000> welcome to Zircon [00001.534] 00000:00000> [00001.534] 00000:00000> KASLR: .text section at 0xffffffff10000000 [00001.534] 00000:00000> INIT: cpu 0, calling hook 0xffffffff10017c10 (global_prng_seed) at level 0x30000, flags 0x1 [00001.548] 00000:00000> initializing vm pre-heap [00001.548] 00000:00000> VM: marking boot alloc used range [0x48615000, 0x4861a040) [00001.550] 00000:00000> INIT: cpu 0, calling hook 0xffffffff100251a0 (elf_build_id) at level 0x4fffe, flags 0x1 [00001.550] 00000:00000> INIT: cpu 0, calling hook 0xffffffff10025370 (version) at level 0x4ffff, flags 0x1 [00001.550] 00000:00000> version: [00001.550] 00000:00000> arch: arm64 [00001.550] 00000:00000> platform: XXX [00001.550] 00000:00000> target: XXX [00001.550] 00000:00000> project: arm64 [00001.550] 00000:00000> buildid: git-4c4ef74431fea26725fc448731c7b805fa6e01e8-dirty [00001.550] 00000:00000> ELF build ID: 1f848e8089a8f0c0fda56293df9491a7a36b8492 [00001.550] 00000:00000> initializing heap [00001.550] 00000:00000> INIT: cpu 0, calling hook 0xffffffff100016a0 (arm_resource_init) at level 0x50000, flags 0x1 [00001.550] 00000:00000> INIT: cpu 0, calling hook 0xffffffff10003780 (console) at level 0x50000, flags 0x1 [00001.550] 00000:00000> initializing vm [00001.550] 00000:00000> VM: reserving kernel region [0xffffffff10000000, 0xffffffff10115000) flags 0x28 name 'kernel_code' [00001.550] 00000:00000> VM: reserving kernel region [0xffffffff10115000, 0xffffffff1015b000) flags 0x8 name 'kernel_rodata' [00001.550] 00000:00000> VM: reserving kernel region [0xffffffff1015b000, 0xffffffff1015d000) flags 0x18 name 'kernel_data' [00001.550] 00000:00000> VM: reserving kernel region [0xffffffff10160000, 0xffffffff101d5000) flags 0x18 name 'kernel_bss' [00001.550] 00000:00000> INIT: cpu 0, calling hook 0xffffffff10001690 (platform_postvm) at level 0x60000, flags 0x1 [00001.550] 00000:00000> CreateSubVmarInternal:114: warning: mapping periph has conflicting cache policies: vmo 00 arch_mmu_flags 02. [00001.550] 00000:00000> INIT: cpu 0, calling hook 0xffffffff10001ae0 (platform_init_pre_thread) at level 0x60000, flags 0x1 [00001.553] 00000:00000> System topology: CPU 0:0:0:0 [00001.553] 00000:00000> System topology: CPU 0:0:0:1 [00001.553] 00000:00000> System topology: CPU 0:0:0:2 [00001.553] 00000:00000> System topology: CPU 0:0:0:3 [00001.553] 00000:00000> INIT: cpu 0, calling hook 0xffffffff100fdd50 (percpu_heap_init) at level 0x60003, flags 0x1 [00001.553] 00000:00000> initializing kernel [00001.553] 00000:00000> initializing mp [00001.553] 00000:00000> INIT: cpu 0, calling hook 0xffffffff10017e60 (global_prng_thread_safe) at level 0x7ffff, flags 0x1 [00001.553] 00000:00000> creating bootstrap completion thread [00001.553] 00000:00000> top of bootstrap2() [00001.553] 00000:00000> INIT: cpu 0, calling hook 0xffffffff100366f0 (libobject) at level 0x80000, flags 0x1 [00001.553] 00000:00000> OOM: started thread [00001.553] 00000:00000> INIT: cpu 0, calling hook 0xffffffff100fa470 (dpc) at level 0x80000, flags 0x1 [00001.553] 00000:00000> ARM boot EL1 [00001.553] 00000:00000> ARM cpu 0: midr 0x431f0af1 'Cavium CN99XX r1p1' mpidr 0x80000000 aff 0:0:0:0 [00001.553] 00000:00000> ARM Features: fp asimd aes pmull sha1 sha2 crc32 atomics rdm [00001.553] 00000:00000> ARM cache line sizes: icache 64 dcache 64 zva 64 [00001.553] 00000:00000> ==== ARM64 CACHE INFO CORE 0 ==== [00001.553] 00000:00000> Inner Boundary = L0 [00001.553] 00000:00000> Level of Unification Uniprocessor = L1 [00001.553] 00000:00000> Level of Coherence = L3 [00001.553] 00000:00000> Level of Unification Inner Shareable = L1 [00001.553] 00000:00000> L1 Details: Data Cache, sets=64, associativity=8, line size=64 bytes [00001.553] 00000:00000> Instruction Cache, sets=64, associativity=8, line size=64 bytes [00001.553] 00000:00000> L2 Details: Unified Cache, sets=512, associativity=8, line size=64 bytes [00001.553] 00000:00000> L3 Details: Unified Cache, sets=16384, associativity=32, line size=64 bytes [00001.553] 00000:00000> L4 Details: Not Implemented [00001.553] 00000:00000> L5 Details: Not Implemented [00001.553] 00000:00000> L6 Details: Not Implemented [00001.553] 00000:00000> L7 Details: Not Implemented [00001.553] 00000:00000> initializing platform [00001.553] 00000:00000> INIT: cpu 0, calling hook 0xffffffff1009a210 (arm64_perfmon) at level 0x90000, flags 0x1 [00001.553] 00000:00000> Trying to start cpu 1 returned: 0 [00001.553] 00000:00000> Trying to start cpu 2 returned: 0 [00001.553] 00000:00000> Trying to start cpu 3 returned: 0 [00001.553] 00000:00000> initializing target [00001.553] 00000:00000> INIT: cpu 0, calling hook 0xffffffff1008f860 (platform_dev_init) at level 0xa0000, flags 0x1 [00001.553] 00000:00000> UART: started IRQ driven TX [00001.553] 00000:00000> moving to last init level [00001.553] 00000:00000> INIT: cpu 0, calling hook 0xffffffff10024250 (debuglog) at level 0xb0000, flags 0x1 [00001.553] 00000:00000> INIT: cpu 0, calling hook 0xffffffff10080eb0 (kcounters) at level 0xb0000, flags 0x1 [00001.553] 00000:00000> INIT: cpu 0, calling hook 0xffffffff10004260 (kernel_shell) at level 0xc0000, flags 0x1 [00001.553] 00000:00000> INIT: cpu 0, calling hook 0xffffffff100f6d30 (ktrace) at level 0xc0000, flags 0x1 [00001.636] 00000:00000> ktrace: buffer at 0xffff00879d391000 (33554432 bytes) [00001.636] 00000:00000> INIT: cpu 0, calling hook 0xffffffff10110f40 (userboot) at level 0xc0000, flags 0x1 [00001.636] 00000:00000> userboot: ramdisk 0x2d8000 @ 0xffff000048000000 [00001.636] 00000:00000> userboot: userboot rodata 0 @ [0x264ea78a1000,0x264ea78a4000) [00001.636] 00000:00000> userboot: userboot code 0x3000 @ [0x264ea78a4000,0x264ea78ae000) [00001.636] 00000:00000> userboot: vdso/full rodata 0 @ [0x264ea78ae000,0x264ea78b5000) [00001.636] 00000:00000> userboot: vdso/full code 0x7000 @ [0x264ea78b5000,0x264ea78b7000) [00001.636] 00000:00000> userboot: entry point @ 0x264ea78a4c30 [00001.642] 01032:01039> userboot: option "userboot=bin/core-tests" [00001.642] 01032:01039> userboot: option "userboot.shutdown=" [00001.642] 01032:01039> userboot: option "kernel.halt-on-panic=true" [00001.642] 01032:01039> userboot: option "devmgr.suspend-timeout-debug=true" [00001.642] 01032:01039> userboot: option "TERM=dumb" [00001.674] 01032:01039> userboot: searching bootfs for 'bin/core-tests' [00001.674] 01032:01039> userboot: 'bin/core-tests' has PT_INTERP "lib/ld.so.1" [00001.674] 01032:01039> userboot: searching bootfs for 'lib/ld.so.1' [00001.674] 01032:01039> userboot: userboot: loaded lib/ld.so.1 at 0xaa1f15404000, entry point 0xaa1f15474124 [00001.674] 01032:01039> userboot: userboot: loaded vDSO at 0xd456cbff8000, entry point 0xd456cbfffa28 [00001.674] 01032:01039> userboot: process bin/core-tests started. [00001.674] 01032:01039> userboot: waiting for loader-service requests... [00001.681] 01032:01039> userboot: searching bootfs for 'lib/libfdio.so' [00001.688] 01032:01039> userboot: searching bootfs for 'lib/libunittest.so' [00001.697] 01032:01039> userboot: searching bootfs for 'lib/libmini-process.so' [00001.706] 01032:01039> userboot: searching bootfs for 'lib/libinspector.so' [00001.717] 01032:01039> userboot: searching bootfs for 'lib/libbacktrace.so' [00001.723] 01046:01049> {{{reset}}} [00001.723] 01046:01049> {{{module:0:<application>:elf:e58b6a71c54e7a19}}} [00001.723] 01046:01049> {{{mmap:0x4abc4bb85000:0x5c000:load:0:r:0}}} [00001.723] 01046:01049> {{{mmap:0x4abc4bbe1000:0x279000:load:0:rx:0x5c000}}} [00001.723] 01046:01049> {{{mmap:0x4abc4be5a000:0x9000:load:0:rw:0x2d5000}}} [00001.723] 01046:01049> {{{mmap:0x4abc4be63000:0x18a000:load:0:rw:0x2de000}}} [00001.723] 01046:01049> dso: id=e58b6a71c54e7a19 base=0x00004abc4bb85000 name=<application> [00001.723] 01046:01049> {{{module:0x3:libfdio.so:elf:b1b5470c3a9e6bd4}}} [00001.723] 01046:01049> {{{mmap:0x661b024c4000:0xf000:load:0x3:r:0}}} [00001.723] 01046:01049> {{{mmap:0x661b024d3000:0x32000:load:0x3:rx:0xf000}}} [00001.723] 01046:01049> {{{mmap:0x661b02505000:0x3000:load:0x3:rw:0x41000}}} [00001.723] 01046:01049> {{{mmap:0x661b02508000:0x1d000:load:0x3:rw:0x44000}}} [00001.723] 01046:01049> dso: id=b1b5470c3a9e6bd4 base=0x0000661b024c4000 name=libfdio.so [00001.723] 01046:01049> {{{module:0x4:libunittest.so:elf:299b294c2c2c1390}}} [00001.723] 01046:01049> {{{mmap:0xe7a4bf30d000:0x5000:load:0x4:r:0}}} [00001.723] 01046:01049> {{{mmap:0xe7a4bf312000:0x5000:load:0x4:rx:0x5000}}} [00001.723] 01046:01049> {{{mmap:0xe7a4bf317000:0x1000:load:0x4:rw:0xa000}}} [00001.723] 01046:01049> {{{mmap:0xe7a4bf318000:0x5000:load:0x4:rw:0xb000}}} [00001.723] 01046:01049> dso: id=299b294c2c2c1390 base=0x0000e7a4bf30d000 name=libunittest.so [00001.723] 01046:01049> {{{module:0x2:<vDSO>:elf:aeeda98a53ad2bd123cf7ab8d5ac069964282d51}}} [00001.723] 01046:01049> {{{mmap:0xd456cbff8000:0x7000:load:0x2:r:0}}} [00001.723] 01046:01049> {{{mmap:0xd456cbfff000:0x2000:load:0x2:rx:0x7000}}} [00001.723] 01046:01049> dso: id=aeeda98a53ad2bd123cf7ab8d5ac069964282d51 base=0x0000d456cbff8000 name=<vDSO> [00001.723] 01046:01049> {{{module:0x5:libmini-process.so:elf:02d6fb0e21c2d44e}}} [00001.723] 01046:01049> {{{mmap:0x64478d04e000:0x3000:load:0x5:r:0}}} [00001.723] 01046:01049> {{{mmap:0x64478d051000:0x4000:load:0x5:rx:0x3000}}} [00001.723] 01046:01049> {{{mmap:0x64478d055000:0x1000:load:0x5:rw:0x7000}}} [00001.723] 01046:01049> {{{mmap:0x64478d056000:0x3000:load:0x5:rw:0x8000}}} [00001.723] 01046:01049> dso: id=02d6fb0e21c2d44e base=0x000064478d04e000 name=libmini-process.so [00001.723] 01046:01049> {{{module:0x6:libinspector.so:elf:7644fc26c39c96fc}}} [00001.723] 01046:01049> {{{mmap:0x70502402b000:0x8000:load:0x6:r:0}}} [00001.723] 01046:01049> {{{mmap:0x705024033000:0x1d000:load:0x6:rx:0x8000}}} [00001.723] 01046:01049> {{{mmap:0x705024050000:0x1000:load:0x6:rw:0x25000}}} [00001.723] 01046:01049> {{{mmap:0x705024051000:0x45000:load:0x6:rw:0x26000}}} [00001.723] 01046:01049> dso: id=7644fc26c39c96fc base=0x000070502402b000 name=libinspector.so [00001.723] 01046:01049> {{{module:0x7:libbacktrace.so:elf:906a9381741aaa12}}} [00001.723] 01046:01049> {{{mmap:0xcbdc12b7c000:0x3000:load:0x7:r:0}}} [00001.723] 01046:01049> {{{mmap:0xcbdc12b7f000:0xe000:load:0x7:rx:0x3000}}} [00001.723] 01046:01049> {{{mmap:0xcbdc12b8d000:0x1000:load:0x7:rw:0x11000}}} [00001.723] 01046:01049> {{{mmap:0xcbdc12b8e000:0x5000:load:0x7:rw:0x12000}}} [00001.723] 01046:01049> dso: id=906a9381741aaa12 base=0x0000cbdc12b7c000 name=libbacktrace.so [00001.723] 01046:01049> {{{module:0x1:libc.so:elf:3f0004f71f72416d}}} [00001.723] 01046:01049> {{{mmap:0xaa1f15404000:0x60000:load:0x1:r:0}}} [00001.723] 01046:01049> {{{mmap:0xaa1f15464000:0x8a000:load:0x1:rx:0x60000}}} [00001.723] 01046:01049> {{{mmap:0xaa1f154ee000:0x2000:load:0x1:rw:0xea000}}} [00001.723] 01046:01049> {{{mmap:0xaa1f154f0000:0x4d000:load:0x1:rw:0xec000}}} [00001.723] 01046:01049> dso: id=3f0004f71f72416d base=0x0000aa1f15404000 name=libc.so [00001.726] 01046:01049> TEST [00001.737] 01046:01049> CASE resource_tests [STARTED] [00001.737] 01046:01049> probe_address_space [RUNNING] [00001.737] 01046:01049> [PASSED] (0 ms) [00001.737] 01046:01049> TestBasicActions [RUNNING] [00001.737] 01046:01049> [PASSED] (0 ms) [00001.737] 01046:01049> TestExclusiveShared [RUNNING] [00001.737] 01046:01049> [PASSED] (0 ms) [00001.737] 01046:01049> TestSharedExclusive [RUNNING] [00001.737] 01046:01049> [PASSED] (0 ms) [00001.737] 01046:01049> TestInvalidArgs [RUNNING] [00001.737] 01046:01049> [PASSED] (0 ms) [00001.737] 01046:01049> TestVmoCreation [RUNNING] [00001.737] 01046:01049> [PASSED] (0 ms) [00001.737] 01046:01049> TestVmoCreationSmaller [RUNNING] [00001.737] 01046:01049> [PASSED] (0 ms) [00001.737] 01046:01049> TestVmoCreationUnaligned [RUNNING] [00001.737] 01046:01049> [PASSED] (0 ms) [00001.737] 01046:01049> TestVmoReplaceAsExecutable [RUNNING] [00001.737] 01046:01049> [PASSED] (0 ms) [00001.737] 01046:01049> CASE resource_tests [PASSED] [00001.737] 01046:01049> CASE profile_tests [STARTED] [00001.737] 01046:01049> make_profile_fails [RUNNING] [00001.737] 01046:01049> [PASSED] (0 ms) [00001.737] 01046:01049> change_priority_via_profile [RUNNING] [00001.737] 01046:01049> [PASSED] (0 ms) [00001.737] 01046:01049> CASE profile_tests [PASSED] [00001.737] 01046:01049> CASE interrupt_tests [STARTED] [00001.737] 01046:01049> interrupt_test [RUNNING] [00001.737] 01046:01049> [PASSED] (0 ms) [00001.737] 01046:01049> interrupt_port_bound_test [RUNNING] [00001.737] 01046:01049> [PASSED] (0 ms) [00001.737] 01046:01049> interrupt_port_non_bindable_test [RUNNING] [00001.737] 01046:01049> [PASSED] (0 ms) [00001.737] 01046:01049> interrupt_suspend_test [RUNNING] [00001.750] 01046:01049> [PASSED] (13 ms) [00001.750] 01046:01049> interrupt_bind_vcpu_test [RUNNING] [00001.750] 01046:01049> Guest creation not supported [00001.750] 01046:01049> [PASSED] (0 ms) [00001.750] 01046:01049> interrupt_bind_vcpu_not_supported_test [RUNNING] [00001.750] 01046:01049> Guest creation not supported [00001.750] 01046:01049> [PASSED] (0 ms) [00001.750] 01046:01049> interrupt_bind_vcpu_already_bound_test [RUNNING] [00001.750] 01046:01049> Guest creation not supported [00001.750] 01046:01049> [PASSED] (0 ms) [00001.750] 01046:01049> interrupt_bind_vcpu_multiple_guests_test [RUNNING] [00001.750] 01046:01049> Guest creation not supported [00001.750] 01046:01049> [PASSED] (0 ms) [00001.750] 01046:01049> CASE interrupt_tests [PASSED] [00001.750] 01046:01049> CASE vmo_signal_tests [STARTED] [00001.750] 01046:01049> vmo_signal_sanity_test [RUNNING] [00001.750] 01046:01049> [PASSED] (0 ms) [00001.750] 01046:01049> vmo_child_signal_clone_test [RUNNING] [00001.751] 01046:01049> [PASSED] (0 ms) [00001.751] 01046:01049> vmo_child_signal_map_test [RUNNING] [00001.751] 01046:01049> [PASSED] (0 ms) [00001.751] 01046:01049> CASE vmo_signal_tests [PASSED] [00001.751] 01046:01049> CASE vmar_tests [STARTED] [00001.751] 01046:01049> destroy_root_test [RUNNING] [00001.751] 01046:01049> [PASSED] (0 ms) [00001.751] 01046:01049> basic_allocate_test [RUNNING] [00001.751] 01046:01049> [PASSED] (0 ms) [00001.751] 01046:01049> allocate_oob_test [RUNNING] [00001.751] 01046:01049> [PASSED] (0 ms) [00001.751] 01046:01049> allocate_unsatisfiable_test [RUNNING] [00001.751] 01046:01049> [PASSED] (0 ms) [00001.751] 01046:01049> destroyed_vmar_test [RUNNING] [00001.756] 01046:01049> [PASSED] (5 ms) [00001.756] 01046:01049> map_over_destroyed_test [RUNNING] [00001.756] 01046:01049> [PASSED] (0 ms) [00001.756] 01046:01049> map_in_compact_test [RUNNING] [00001.756] 01046:01049> [PASSED] (0 ms) [00001.756] 01046:01049> overmapping_test [RUNNING] [00001.756] 01046:01049> [PASSED] (0 ms) [00001.756] 01046:01049> invalid_args_test [RUNNING] [00001.756] 01046:01049> [PASSED] (0 ms) [00001.756] 01046:01049> unaligned_len_test [RUNNING] [00001.756] 01046:01049> [PASSED] (0 ms) [00001.756] 01046:01049> unaligned_len_map_test [RUNNING] [00001.756] 01046:01049> [PASSED] (0 ms) [00001.756] 01046:01049> rights_drop_test [RUNNING] [00001.756] 01046:01049> [PASSED] (0 ms) [00001.756] 01046:01049> protect_test [RUNNING] [00001.757] 01046:01049> [PASSED] (0 ms) [00001.757] 01046:01049> nested_region_perms_test [RUNNING] [00001.757] 01046:01049> [PASSED] (0 ms) [00001.757] 01046:01049> object_info_test [RUNNING] [00001.757] 01046:01049> [PASSED] (0 ms) [00001.757] 01046:01049> unmap_split_test [RUNNING] [00001.757] 01046:01049> [PASSED] (0 ms) [00001.757] 01046:01049> unmap_multiple_test [RUNNING] [00001.757] 01046:01049> [PASSED] (0 ms) [00001.757] 01046:01049> unmap_base_not_mapped_test [RUNNING] [00001.757] 01046:01049> [PASSED] (0 ms) [00001.757] 01046:01049> map_specific_overwrite_test [RUNNING] [00001.757] 01046:01049> [PASSED] (0 ms) [00001.757] 01046:01049> protect_split_test [RUNNING] [00001.757] 01046:01049> [PASSED] (0 ms) [00001.757] 01046:01049> protect_multiple_test [RUNNING] [00001.757] 01046:01049> [PASSED] (0 ms) [00001.757] 01046:01049> protect_over_demand_paged_test [RUNNING] [00001.823] 01046:01049> [PASSED] (65 ms) [00001.823] 01046:01049> protect_large_uncommitted_test [RUNNING] [00001.862] 01046:01049> [PASSED] (39 ms) [00001.862] 01046:01049> unmap_large_uncommitted_test [RUNNING] [00001.864] 01046:01283> PageFault: 1028409 free pages [00001.865] 01046:01286> PageFault: 1028409 free pages [00001.866] 01046:01289> PageFault: 1028409 free pages [00001.866] 01046:01049> [PASSED] (3 ms) [00001.866] 01046:01049> partial_unmap_and_read [RUNNING] [00001.866] 01046:01049> [PASSED] (0 ms) [00001.866] 01046:01049> partial_unmap_and_write [RUNNING] [00001.866] 01046:01049> [PASSED] (0 ms) [00001.866] 01046:01049> partial_unmap_with_vmar_offset [RUNNING] [00001.866] 01046:01049> [PASSED] (0 ms) [00001.866] 01046:01049> CASE vmar_tests [PASSED] [00001.866] 01046:01049> CASE ticks_tests [STARTED] [00001.866] 01046:01049> elapsed_time_using_ticks [RUNNING] [00001.866] 01046:01049> [PASSED] (0 ms) [00001.866] 01046:01049> CASE ticks_tests [PASSED] [00001.866] 01046:01049> CASE threads_tests [STARTED] [00001.866] 01046:01049> TestBasics [RUNNING] [00001.967] 01046:01049> [PASSED] (100 ms) [00001.967] 01046:01049> TestInvalidRights [RUNNING] [00001.967] 01046:01049> [PASSED] (0 ms) [00001.967] 01046:01049> TestDetach [RUNNING] [00001.970] 01046:01049> [PASSED] (3 ms) [00001.970] 01046:01049> TestLongNameSucceeds [RUNNING] [00001.970] 01046:01049> [PASSED] (0 ms) [00001.970] 01046:01049> TestThreadStartOnInitialThread [RUNNING] [00001.970] 01046:01049> [PASSED] (0 ms) [00001.970] 01046:01049> TestThreadStartWithZeroInstructionPointer [RUNNING] [00001.975] 01046:01049> [PASSED] (5 ms) [00001.975] 01046:01049> TestKillBusyThread [RUNNING] [00002.081] 01046:01049> [PASSED] (106 ms) [00002.081] 01046:01049> TestKillSleepThread [RUNNING] [00002.181] 01046:01049> [PASSED] (100 ms) [00002.181] 01046:01049> TestKillWaitThread [RUNNING] [00002.281] 01046:01049> [PASSED] (100 ms) [00002.281] 01046:01049> TestNonstartedThread [RUNNING] [00002.281] 01046:01049> [PASSED] (0 ms) [00002.281] 01046:01049> TestThreadKillsItself [RUNNING] [00002.285] 01046:01049> [PASSED] (3 ms) [00002.285] 01046:01049> TestInfoTaskStatsFails [RUNNING] [00002.385] 01046:01049> [PASSED] (100 ms) [00002.385] 01046:01049> TestResumeSuspended [RUNNING] [00002.487] 01046:01049> [PASSED] (101 ms) [00002.487] 01046:01049> TestSuspendSleeping [RUNNING] [00002.587] 01046:01049> [PASSED] (100 ms) [00002.587] 01046:01049> TestSuspendChannelCall [RUNNING] [00002.589] 01046:01049> [PASSED] (1 ms) [00002.589] 01046:01049> TestSuspendPortCall [RUNNING] [00002.660] 00000:00000> INIT: cpu 2, calling hook 0xffffffff10092150 (arm_generic_timer_init_secondary_cpu) at level 0x7ffff, flags 0x2 [00002.660] 00000:00000> ARM cpu 2: midr 0x431f0af1 'Cavium CN99XX r1p1' mpidr 0x80000002 aff 0:0:0:2 [00002.660] 00000:00000> entering scheduler on cpu 2 [00002.675] 00000:00000> INIT: cpu 3, calling hook 0xffffffff10092150 (arm_generic_timer_init_secondary_cpu) at level 0x7ffff, flags 0x2 [00002.675] 00000:00000> ARM cpu 3: midr 0x431f0af1 'Cavium CN99XX r1p1' mpidr 0x80000003 aff 0:0:0:3 [00002.675] 00000:00000> entering scheduler on cpu 3 [00002.690] 00000:00000> INIT: cpu 1, calling hook 0xffffffff10092150 (arm_generic_timer_init_secondary_cpu) at level 0x7ffff, flags 0x2 [00002.690] 00000:00000> ARM cpu 1: midr 0x431f0af1 'Cavium CN99XX r1p1' mpidr 0x80000001 aff 0:0:0:1 [00002.690] 00000:00000> entering scheduler on cpu 1 [00002.789] 01046:01049> [PASSED] (200 ms) [00002.789] 01046:01049> TestSuspendStopsThread [RUNNING] [00002.839] 01046:01049> [PASSED] (50 ms) [00002.839] 01046:01049> TestSuspendMultiple [RUNNING] [00002.839] 01046:01049> [PASSED] (0 ms) [00002.839] 01046:01049> TestSuspendSelf [RUNNING] [00002.839] 01046:01049> [PASSED] (0 ms) [00002.839] 01046:01049> TestKillSuspendedThread [RUNNING] [00002.839] 01046:01049> [PASSED] (0 ms) [00002.840] 01046:01049> TestStartSuspendedThread [RUNNING] [00002.840] 01046:01049> [PASSED] (0 ms) [00002.840] 01046:01049> TestStartSuspendedAndResumedThread [RUNNING] [00002.840] 01046:01049> [PASSED] (0 ms) [00002.840] 01046:01049> TestSuspendSingleWaitAsyncSignalDelivery [RUNNING] [00002.840] 01046:01049> [PASSED] (0 ms) [00002.840] 01046:01049> TestSuspendRepeatingWaitAsyncSignalDelivery [RUNNING] [00002.840] 01046:01049> [PASSED] (0 ms) [00002.840] 01046:01049> TestReadingGeneralRegisterState [RUNNING] [00002.940] 01046:01049> [PASSED] (100 ms) [00002.940] 01046:01049> TestWritingGeneralRegisterState [RUNNING] [00003.040] 01046:01049> [PASSED] (100 ms) [00003.040] 01046:01049> TestThreadLocalRegisterState [RUNNING] [00003.141] 01046:01049> [PASSED] (100 ms) [00003.141] 01046:01049> TestNoncanonicalRipAddress [RUNNING] [00003.141] 01046:01049> [PASSED] (0 ms) [00003.141] 01046:01049> TestWritingArmFlagsRegister [RUNNING] [00003.141] 01046:01049> [PASSED] (0 ms) [00003.141] 01046:01049> CASE threads_tests [PASSED] [00003.141] 01046:01049> CASE sync_mutex_tests [STARTED] [00003.141] 01046:01049> test_mutexes [RUNNING] [00003.150] 01046:01049> [PASSED] (9 ms) [00003.150] 01046:01049> test_try_mutexes [RUNNING] [00003.155] 01046:01049> [PASSED] (4 ms) [00003.155] 01046:01049> test_timeout_elapsed [RUNNING] [00003.655] 01046:01049> [PASSED] (500 ms) [00003.655] 01046:01049> CASE sync_mutex_tests [PASSED] [00003.655] 01046:01049> CASE sync_condition_tests [STARTED] [00003.655] 01046:01049> Condition::condition_test [RUNNING] [00003.656] 01046:01049> [PASSED] (0 ms) [00003.656] 01046:01049> Condition::condition_timeout_test [RUNNING] [00003.657] 01046:01049> [PASSED] (1 ms) [00003.657] 01046:01049> CASE sync_condition_tests [PASSED] [00003.657] 01046:01049> CASE sync_completion_tests [STARTED] [00003.657] 01046:01049> test_initializer [RUNNING] [00003.657] 01046:01049> [PASSED] (0 ms) [00003.657] 01046:01049> test_completions [RUNNING] [00003.660] 01046:01049> [PASSED] (3 ms) [00003.660] 01046:01049> test_timeout [RUNNING] [00003.661] 01046:01049> [PASSED] (0 ms) [00003.661] 01046:01049> CASE sync_completion_tests [PASSED] [00003.661] 01046:01049> CASE sync_completion_tests [STARTED] [00003.661] 01046:01049> test_initializer [RUNNING] [00003.661] 01046:01049> [PASSED] (0 ms) [00003.661] 01046:01049> test_completions [RUNNING] [00003.664] 01046:01049> [PASSED] (3 ms) [00003.664] 01046:01049> test_timeout [RUNNING] [00004.685] 01046:01049> [PASSED] (1020 ms) [00004.685] 01046:01049> test_signal_requeue [RUNNING] [00004.697] 01046:01049> [PASSED] (12 ms) [00004.697] 01046:01049> test_completion_signaled [RUNNING] [00004.697] 01046:01049> [PASSED] (0 ms) [00004.697] 01046:01049> CASE sync_completion_tests [PASSED] [00004.697] 01046:01049> CASE stack_tests [STARTED] [00004.697] 01046:01049> main_thread_stack_tests [RUNNING] [00004.697] 01046:01049> [PASSED] (0 ms) [00004.697] 01046:01049> other_thread_stack_tests [RUNNING] [00004.697] 01046:01049> [PASSED] (0 ms) [00004.697] 01046:01049> CASE stack_tests [PASSED] [00004.697] 01046:01049> CASE tls_tests [STARTED] [00004.697] 01046:01049> tls_test [RUNNING] [00006.703] 01046:01049> [PASSED] (2005 ms) [00006.703] 01046:01049> CASE tls_tests [PASSED] [00006.703] 01046:01049> CASE pthread_barrier_tests [STARTED] [00006.703] 01046:01049> test_barrier [RUNNING] [00006.725] 01046:01049> [PASSED] (22 ms) [00006.725] 01046:01049> CASE pthread_barrier_tests [PASSED] [00006.725] 01046:01049> CASE pthread_tests [STARTED] [00006.725] 01046:01049> pthread_test [RUNNING] [00009.829] 01046:01049> [PASSED] (3103 ms) [00009.829] 01046:01049> pthread_self_main_thread_test [RUNNING] [00009.829] 01046:01049> [PASSED] (0 ms) [00009.829] 01046:01049> pthread_big_stack_size [RUNNING] [00009.899] 01046:01049> [PASSED] (69 ms) [00009.899] 01046:01049> pthread_getstack_main_thread [RUNNING] [00009.899] 01046:01049> [PASSED] (0 ms) [00009.899] 01046:01049> pthread_getstack_other_thread [RUNNING] [00009.900] 01046:01049> [PASSED] (0 ms) [00009.900] 01046:01049> CASE pthread_tests [PASSED] [00009.900] 01046:01049> CASE process_tests [STARTED] [00009.900] 01046:01049> mini_process_sanity [RUNNING] [00009.900] 02018:02020> PageFault: 1028266 free pages [00009.900] 02018:02020> KERN: fatal page fault in user thread 'mi' in process 'min' [00009.900] 02018:02020> data abort: PC at 0xc78cf71ac024, address 0xc78cf71b0178 user read [00009.900] 02018:02020> iframe 0xffff00879f3c3ee0: [00009.900] 02018:02020> x0 0x7a2c9db x1 0xb2fc6bebd95c x2 0 x3 0 [00009.900] 02018:02020> x4 0 x5 0 x6 0 x7 0 [00009.900] 02018:02020> x8 0 x9 0xc78cf71b0000 x10 0 x11 0 [00009.900] 02018:02020> x12 0 x13 0 x14 0 x15 0 [00009.900] 02018:02020> x16 0 x17 0 x18 0 x19 0 [00009.900] 02018:02020> x20 0 x21 0 x22 0 x23 0 [00009.900] 02018:02020> x24 0 x25 0 x26 0 x27 0 [00009.900] 02018:02020> x28 0 x29 0xc78cf71afff0 lr 0 usp 0xc78cf71afef0 [00009.900] 02018:02020> elr 0xc78cf71ac024 [00009.900] 02018:02020> spsr 0x100 [00009.900] 02018:02020> bottom of user stack at 0xc78cf71afef0: [00009.900] 02018:02020> 0x0000c78cf71afef0: 00000000 00000000 00000000 00000000 |................| [00009.900] 02018:02020> ..... [00009.900] 02018:02020> 0x0000c78cf71affe0: 00000000 00000000 00000000 00000000 |................| [00009.900] 01046:01049> [FAILED] ../system/utest/core/process/process.cpp:36:bool (anonymous namespace)::mini_process_sanity(): <no message>: Comparison failed: start_mini_process_etc(proc, thread, vmar, event, &cmd_channel [00009.900] 01046:01049> ) == ZX_OK is false Specifically, -28 (0xffffffffffffffe4) == 0 (0x0) is false [00009.900] 01046:01049> [FAILED] ../system/utest/core/process/process.cpp:38:bool (anonymous namespace)::mini_process_sanity(): <no message>: Comparison failed: mini_process_cmd(cmd_channel, MINIP_CMD_ECHO_MSG, nullptr) == [00009.900] 01046:01049> ZX_OK is false Specifically, -11 (0xfffffffffffffff5) == 0 (0x0) is false [00009.900] 01046:01049> [FAILED] ../system/utest/core/process/process.cpp:41:bool (anonymous namespace)::mini_process_sanity(): <no message>: Comparison failed: mini_process_cmd(cmd_channel, MINIP_CMD_CREATE_EVENT, &oev) == [00009.900] 01046:01049> ZX_OK is false Specifically, -11 (0xfffffffffffffff5) == 0 (0x0) is false [00009.900] 01046:01049> [FAILED] ../system/utest/core/process/process.cpp:43:bool (anonymous namespace)::mini_process_sanity(): <no message>: Comparison failed: mini_process_cmd(cmd_channel, MINIP_CMD_EXIT_NORMAL, nullptr) [00009.900] 01046:01049> == ZX_ERR_PEER_CLOSED is false Specifically, -11 (0xfffffffffffffff5) == -24 (0xffffffffffffffe8) is false [00009.900] 01046:01049> [FAILED] (0 ms) [00009.900] 01046:01049> process_start_fail [RUNNING] [00009.900] 02029:02031> PageFault: 1028266 free pages [00009.900] 02029:02031> KERN: fatal page fault in user thread 'minith' in process 'minipr' [00009.900] 02029:02031> data abort: PC at 0x3d21504d4024, address 0x3d21504d8178 user read [00009.900] 02029:02031> iframe 0xffff00879f3c3ee0: [00009.900] 02029:02031> x0 0x64fca40b x1 0x6cfd674d695c x2 0 x3 0 [00009.900] 02029:02031> x4 0 x5 0 x6 0 x7 0 [00009.900] 02029:02031> x8 0 x9 0x3d21504d8000 x10 0 x11 0 [00009.900] 02029:02031> x12 0 x13 0 x14 0 x15 0 [00009.900] 02029:02031> x16 0 x17 0 x18 0 x19 0 [00009.900] 02029:02031> x20 0 x21 0 x22 0 x23 0 [00009.900] 02029:02031> x24 0 x25 0 x26 0 x27 0 [00009.900] 02029:02031> x28 0 x29 0x3d21504d7ff0 lr 0 usp 0x3d21504d7ef0 [00009.900] 02029:02031> elr 0x3d21504d4024 [00009.900] 02029:02031> spsr 0x100 [00009.900] 02029:02031> bottom of user stack at 0x3d21504d7ef0: [00009.900] 02029:02031> 0x00003d21504d7ef0: 00000000 00000000 00000000 00000000 |................| [00009.900] 02029:02031> ..... [00009.900] 02029:02031> 0x00003d21504d7fe0: 00000000 00000000 00000000 00000000 |................| [00009.900] 01046:01049> [FAILED] ../system/utest/core/process/process.cpp:105:bool (anonymous namespace)::process_start_fail(): <no message>: Comparison failed: start_mini_process(zx_job_default(), event1, &process, &thread [00009.900] 01046:01049> ) == ZX_OK is false Specifically, -28 (0xffffffffffffffe4) == 0 (0x0) is false [00009.900] 01046:01049> [FAILED] (0 ms) [00009.900] 01046:01049> process_start_no_handle [RUNNING] [00009.900] 01046:01049> [PASSED] (0 ms) [00009.900] 01046:01049> process_not_killed_via_thread_close [RUNNING] [00009.901] 02043:02045> PageFault: 1028264 free pages [00009.901] 02043:02045> KERN: fatal page fault in user thread 'minith' in process 'minipr' [00009.901] 02043:02045> data abort: PC at 0x5986392024, address 0x5986396178 user read [00009.901] 02043:02045> iframe 0xffff00879f3c3ee0: [00009.901] 02043:02045> x0 0x1d3a7017 x1 0xd3a10635695c x2 0 x3 0 [00009.901] 02043:02045> x4 0 x5 0 x6 0 x7 0 [00009.901] 02043:02045> x8 0 x9 0x5986396000 x10 0 x11 0 [00009.901] 02043:02045> x12 0 x13 0 x14 0 x15 0 [00009.901] 02043:02045> x16 0 x17 0 x18 0 x19 0 [00009.901] 02043:02045> x20 0 x21 0 x22 0 x23 0 [00009.901] 02043:02045> x24 0 x25 0 x26 0 x27 0 [00009.901] 02043:02045> x28 0 x29 0x5986395ff0 lr 0 usp 0x5986395ef0 [00009.901] 02043:02045> elr 0x5986392024 [00009.901] 02043:02045> spsr 0x100 [00009.901] 02043:02045> bottom of user stack at 0x5986395ef0: [00009.901] 02043:02045> 0x0000005986395ef0: 00000000 00000000 00000000 00000000 |................| [00009.901] 02043:02045> ..... [00009.901] 02043:02045> 0x0000005986395fe0: 00000000 00000000 00000000 00000000 |................| [00009.901] 01046:01049> [FAILED] ../system/utest/core/process/process.cpp:129:bool (anonymous namespace)::process_not_killed_via_thread_close(): <no message>: Comparison failed: start_mini_process(zx_job_default(), event, & [00009.901] 01046:01049> process, &thread) == ZX_OK is false Specifically, -28 (0xffffffffffffffe4) == 0 (0x0) is false [00009.901] 01046:01049> [FAILED] (0 ms) [00009.901] 01046:01049> process_not_killed_via_process_close [RUNNING] [00009.901] 02052:02054> PageFault: 1028263 free pages [00009.901] 02052:02054> KERN: fatal page fault in user thread 'minith' in process 'minipr' [00009.901] 02052:02054> data abort: PC at 0x16580426a024, address 0x16580426e178 user read [00009.901] 02052:02054> iframe 0xffff00879f3c3ee0: [00009.901] 02052:02054> x0 0x611f3503 x1 0x3ea5f158d95c x2 0 x3 0 [00009.901] 02052:02054> x4 0 x5 0 x6 0 x7 0 [00009.901] 02052:02054> x8 0 x9 0x16580426e000 x10 0 x11 0 [00009.901] 02052:02054> x12 0 x13 0 x14 0 x15 0 [00009.901] 02052:02054> x16 0 x17 0 x18 0 x19 0 [00009.901] 02052:02054> x20 0 x21 0 x22 0 x23 0 [00009.901] 02052:02054> x24 0 x25 0 x26 0 x27 0 [00009.901] 02052:02054> x28 0 x29 0x16580426dff0 lr 0 usp 0x16580426def0 [00009.901] 02052:02054> elr 0x16580426a024 [00009.901] 02052:02054> spsr 0x100 [00009.901] 02052:02054> bottom of user stack at 0x16580426def0: [00009.901] 02052:02054> 0x000016580426def0: 00000000 00000000 00000000 00000000 |................| [00009.901] 02052:02054> ..... [00009.901] 02052:02054> 0x000016580426dfe0: 00000000 00000000 00000000 00000000 |................| [00009.901] 01046:01049> [FAILED] ../system/utest/core/process/process.cpp:152:bool (anonymous namespace)::process_not_killed_via_process_close(): <no message>: Comparison failed: start_mini_process(zx_job_default(), event, [00009.901] 01046:01049> &process, &thread) == ZX_OK is false Specifically, -28 (0xffffffffffffffe4) == 0 (0x0) is false [00009.901] 01046:01049> [FAILED] (0 ms) [00009.901] 01046:01049> kill_process_via_thread_kill [RUNNING] [00009.901] 02061:02063> PageFault: 1028262 free pages [00009.901] 02061:02063> KERN: fatal page fault in user thread 'minith' in process 'minipr' [00009.901] 02061:02063> data abort: PC at 0x8c6eba32f024, address 0x8c6eba333178 user read [00009.901] 02061:02063> iframe 0xffff00879f3c3ee0: [00009.901] 02061:02063> x0 0x3cd81f33 x1 0xf7abfd82f95c x2 0 x3 0 [00009.901] 02061:02063> x4 0 x5 0 x6 0 x7 0 [00009.901] 02061:02063> x8 0 x9 0x8c6eba333000 x10 0 x11 0 [00009.901] 02061:02063> x12 0 x13 0 x14 0 x15 0 [00009.901] 02061:02063> x16 0 x17 0 x18 0 x19 0 [00009.901] 02061:02063> x20 0 x21 0 x22 0 x23 0 [00009.901] 02061:02063> x24 0 x25 0 x26 0 x27 0 [00009.901] 02061:02063> x28 0 x29 0x8c6eba332ff0 lr 0 usp 0x8c6eba332ef0 [00009.901] 02061:02063> elr 0x8c6eba32f024 [00009.901] 02061:02063> spsr 0x100 [00009.901] 02061:02063> bottom of user stack at 0x8c6eba332ef0: [00009.901] 02061:02063> 0x00008c6eba332ef0: 00000000 00000000 00000000 00000000 |................| [00009.901] 02061:02063> ..... [00009.901] 02061:02063> 0x00008c6eba332fe0: 00000000 00000000 00000000 00000000 |................| [00009.901] 01046:01049> [FAILED] ../system/utest/core/process/process.cpp:174:bool (anonymous namespace)::kill_process_via_thread_kill(): <no message>: Comparison failed: start_mini_process(zx_job_default(), event, &process [00009.901] 01046:01049> , &thread) == ZX_OK is false Specifically, -28 (0xffffffffffffffe4) == 0 (0x0) is false [00009.901] 01046:01049> [FAILED] (0 ms) [00009.901] 01046:01049> kill_process_via_vmar_destroy [RUNNING] [00009.901] 02077:02079> PageFault: 1028251 free pages [00009.902] 01046:01049> [PASSED] (0 ms) [00009.902] 01046:01049> kill_channel_handle_cycle [RUNNING] [00009.902] 02085:02089> PageFault: 1028245 free pages [00009.902] 02085:02089> KERN: fatal page fault in user thread 'th1' in process 'ttp1' [00009.902] 02085:02089> data abort: PC at 0x7cbf6347f024, address 0x7cbf63483178 user read [00009.902] 02085:02089> iframe 0xffff00879f3c3ee0: [00009.902] 02085:02089> x0 0x7689bcf7 x1 0xb9dd1eb2e95c x2 0 x3 0 [00009.902] 02085:02089> x4 0 x5 0 x6 0 x7 0 [00009.902] 02085:02089> x8 0 x9 0x7cbf63483000 x10 0 x11 0 [00009.902] 02085:02089> x12 0 x13 0 x14 0 x15 0 [00009.902] 02085:02089> x16 0 x17 0 x18 0 x19 0 [00009.902] 02085:02089> x20 0 x21 0 x22 0 x23 0 [00009.902] 02085:02089> x24 0 x25 0 x26 0 x27 0 [00009.902] 02085:02089> x28 0 x29 0x7cbf63482ff0 lr 0 usp 0x7cbf63482ef0 [00009.902] 02085:02089> elr 0x7cbf6347f024 [00009.902] 02085:02089> spsr 0x100 [00009.902] 02085:02089> bottom of user stack at 0x7cbf63482ef0: [00009.902] 02085:02089> 0x00007cbf63482ef0: 00000000 00000000 00000000 00000000 |................| [00009.902] 02085:02089> ..... [00009.902] 02085:02089> 0x00007cbf63482fe0: 00000000 00000000 00000000 00000000 |................| [00009.902] 01046:01049> [FAILED] ../system/utest/core/process/process.cpp:264:bool (anonymous namespace)::kill_channel_handle_cycle(): <no message>: Comparison failed: start_mini_process_etc(proc1, thread1, vmar1, chan[0], [00009.902] 01046:01049> &minip_chn[0]) == ZX_OK is false Specifically, -28 (0xffffffffffffffe4) == 0 (0x0) is false [00009.902] 02087:02090> PageFault: 1028247 free pages [00009.902] 02087:02090> KERN: fatal page fault in user thread 'th2' in process 'ttp2' [00009.902] 02087:02090> data abort: PC at 0x97754fc01024, address 0x97754fc05178 user read [00009.902] 02087:02090> iframe 0xffff00879f3c7ee0: [00009.902] 02087:02090> x0 0x78df9063 x1 0xde2a533c895c x2 0 x3 0 [00009.902] 02087:02090> x4 0 x5 0 x6 0 x7 0 [00009.902] 02087:02090> x8 0 x9 0x97754fc05000 x10 0 x11 0 [00009.902] 02087:02090> x12 0 x13 0 x14 0 x15 0 [00009.902] 02087:02090> x16 0 x17 0 x18 0 x19 0 [00009.902] 02087:02090> x20 0 x21 0 x22 0 x23 0 [00009.902] 02087:02090> x24 0 x25 0 x26 0 x27 0 [00009.902] 02087:02090> x28 0 x29 0x97754fc04ff0 lr 0 usp 0x97754fc04ef0 [00009.902] 02087:02090> elr 0x97754fc01024 [00009.902] 02087:02090> spsr 0x100 [00009.902] 02087:02090> bottom of user stack at 0x97754fc04ef0: [00009.902] 02087:02090> 0x000097754fc04ef0: 00000000 00000000 00000000 00000000 |................| [00009.902] 02087:02090> ..... [00009.902] 02087:02090> 0x000097754fc04fe0: 00000000 00000000 00000000 00000000 |................| [00009.902] 01046:01049> [FAILED] ../system/utest/core/process/process.cpp:266:bool (anonymous namespace)::kill_channel_handle_cycle(): <no message>: Comparison failed: start_mini_process_etc(proc2, thread2, vmar2, chan[1], [00009.902] 01046:01049> &minip_chn[1]) == ZX_OK is false Specifically, -28 (0xffffffffffffffe4) == 0 (0x0) is false [00009.902] 01046:01049> [FAILED] ../system/utest/core/process/process.cpp:278:bool (anonymous namespace)::kill_channel_handle_cycle(): <no message>: Comparison failed: zx_object_wait_one( thread1, ZX_TASK_TERMINATED, zx_dea [00009.902] 01046:01049> dline_after(kTimeoutNs), &signals) == ZX_ERR_TIMED_OUT is false Specifically, 0 (0x0) == -21 (0xffffffffffffffeb) is false [00009.902] 01046:01049> [FAILED] ../system/utest/core/process/process.cpp:281:bool (anonymous namespace)::kill_channel_handle_cycle(): <no message>: Comparison failed: zx_object_wait_one( thread2, ZX_TASK_TERMINATED, zx_dea [00009.902] 01046:01049> dline_after(kTimeoutNs), &signals) == ZX_ERR_TIMED_OUT is false Specifically, 0 (0x0) == -21 (0xffffffffffffffeb) is false [00009.902] 01046:01049> [FAILED] ../system/utest/core/process/process.cpp:287:bool (anonymous namespace)::kill_channel_handle_cycle(): <no message>: Comparison failed: zx_object_wait_one( thread2, ZX_TASK_TERMINATED, zx_dea [00009.902] 01046:01049> dline_after(kTimeoutNs), &signals) == ZX_ERR_TIMED_OUT is false Specifically, 0 (0x0) == -21 (0xffffffffffffffeb) is false [00009.902] 01046:01049> [FAILED] (0 ms) [00009.902] 01046:01049> info_reflects_process_state [RUNNING] [00009.902] 02103:02105> PageFault: 1028252 free pages [00009.902] 02103:02105> KERN: fatal page fault in user thread 'th' in process 'ttp' [00009.902] 02103:02105> data abort: PC at 0x72324685d024, address 0x723246861178 user read [00009.902] 02103:02105> iframe 0xffff00879f3c3ee0: [00009.902] 02103:02105> x0 0xaa61693 x1 0xd66dbb80a95c x2 0 x3 0 [00009.902] 02103:02105> x4 0 x5 0 x6 0 x7 0 [00009.902] 02103:02105> x8 0 x9 0x723246861000 x10 0 x11 0 [00009.902] 02103:02105> x12 0 x13 0 x14 0 x15 0 [00009.902] 02103:02105> x16 0 x17 0 x18 0 x19 0 [00009.902] 02103:02105> x20 0 x21 0 x22 0 x23 0 [00009.902] 02103:02105> x24 0 x25 0 x26 0 x27 0 [00009.902] 02103:02105> x28 0 x29 0x723246860ff0 lr 0 usp 0x723246860ef0 [00009.902] 02103:02105> elr 0x72324685d024 [00009.902] 02103:02105> spsr 0x100 [00009.902] 02103:02105> bottom of user stack at 0x723246860ef0: [00009.902] 02103:02105> 0x0000723246860ef0: 00000000 00000000 00000000 00000000 |................| [00009.902] 02103:02105> ..... [00009.902] 02103:02105> 0x0000723246860fe0: 00000000 00000000 00000000 00000000 |................| [00009.902] 01046:01049> [FAILED] ../system/utest/core/process/process.cpp:331:bool (anonymous namespace)::info_reflects_process_state(): <no message>: Comparison failed: start_mini_process_etc(proc, thread, vmar, event, &mi [00009.902] 01046:01049> nip_chn) == ZX_OK is false Specifically, -28 (0xffffffffffffffe4) == 0 (0x0) is false [00009.902] 01046:01049> [FAILED] (0 ms) [00009.903] 01046:01049> suspend [RUNNING] [00009.903] 02111:02113> PageFault: 1028249 free pages [00009.903] 01046:01049> [PASSED] (0 ms) [00009.903] 01046:01049> suspend_self [RUNNING] [00009.903] 02111:02113> KERN: fatal page fault in user thread 'test_thread_0' in process 'test_process' [00009.903] 01046:01049> [PASSED] (0 ms) [00009.903] 02111:02113> data abort: PC at 0xa0c4df70a024, address 0xa0c4df70e178 user read [00009.903] 01046:01049> suspend_multiple_threads [RUNNING] [00009.903] 02111:02113> iframe 0xffff00879f3c7ee0: [00009.903] 02111:02113> x0 0x3744ba17 x1 0 x2 0 x3 0 [00009.903] 02111:02113> x4 0 x5 0 x6 0 x7 0 [00009.903] 02111:02113> x8 0 x9 0xa0c4df70e000 x10 0 x11 0 [00009.903] 02111:02113> x12 0 x13 0 x14 0 x15 0 [00009.903] 02111:02113> x16 0 x17 0 x18 0 x19 0 [00009.903] 02111:02113> x20 0 x21 0 x22 0 x23 0 [00009.903] 02111:02113> x24 0 x25 0 x26 0 x27 0 [00009.903] 02111:02113> x28 0 x29 0xa0c4df70dff0 lr 0 usp 0xa0c4df70def0 [00009.903] 02111:02113> elr 0xa0c4df70a024 [00009.903] 02111:02113> spsr 0x100 [00009.903] 02111:02113> bottom of user stack at 0xa0c4df70def0: [00009.903] 02111:02113> 0x0000a0c4df70def0: 00000000 00000000 00000000 00000000 |................| [00009.903] 02111:02113> ..... [00009.903] 02111:02113> 0x0000a0c4df70dfe0: 00000000 00000000 00000000 00000000 |................| [00009.903] 02119:02121> PageFault: 1028244 free pages [00009.903] 02119:02122> PageFault: 1028239 free pages [00009.903] 02119:02121> KERN: fatal page fault in user thread 'test_thread_0' in process 'test_process' [00009.903] 02119:02122> KERN: fatal page fault in user thread 'test_thread_1' in process 'test_process' [00009.903] 02119:02121> data abort: PC at 0xe2e370e9b024, address 0xe2e370e9f178 user read [00009.903] 02119:02121> iframe 0xffff00879f3cbee0: [00009.903] 02119:02122> data abort: PC at 0xc2e1bc8df024, address 0xc2e1bc8e3178 user read [00009.903] 02119:02122> iframe 0xffff00879f3cfee0: [00009.903] 02119:02121> x0 0x7e54143f x1 0 x2 0 x3 0 [00009.903] 02119:02121> x4 0 x5 0 x6 0 x7 0 [00009.903] 02119:02122> x0 0 x1 0 x2 0 x3 0 [00009.903] 02119:02121> x8 0 x9 0xe2e370e9f000 x10 0 x11 0 [00009.903] 02119:02122> x4 0 x5 0 x6 0 x7 0 [00009.903] 02119:02122> x8 0 x9 0xc2e1bc8e3000 x10 0 x11 0 [00009.903] 02119:02121> x12 0 x13 0 x14 0 x15 0 [00009.903] 02119:02122> x12 0 x13 0 x14 0 x15 0 [00009.903] 02119:02122> x16 0 x17 0 x18 0 x19 0 [00009.903] 02119:02121> x16 0 x17 0 x18 0 x19 0 [00009.903] 02119:02122> x20 0 x21 0 x22 0 x23 0 [00009.903] 02119:02122> x24 0 x25 0 x26 0 x27 0 [00009.903] 02119:02121> x20 0 x21 0 x22 0 x23 0 [00009.903] 02119:02122> x28 0 x29 0xc2e1bc8e2ff0 lr 0 usp 0xc2e1bc8e2ef0 [00009.903] 02119:02122> elr 0xc2e1bc8df024 [00009.903] 02119:02121> x24 0 x25 0 x26 0 x27 0 [00009.903] 02119:02122> spsr 0x100 [00009.903] 02119:02122> bottom of user stack at 0xc2e1bc8e2ef0: [00009.903] 02119:02121> x28 0 x29 0xe2e370e9eff0 lr 0 usp 0xe2e370e9eef0 [00009.903] 02119:02122> 0x0000c2e1bc8e2ef0: 00000000 00000000 00000000 00000000 |................| [00009.903] 02119:02121> elr 0xe2e370e9b024 [00009.903] 02119:02122> ..... [00009.903] 02119:02121> spsr 0x100 [00009.903] 02119:02121> bottom of user stack at 0xe2e370e9eef0: [00009.903] 02119:02122> 0x0000c2e1bc8e2fe0: 00000000 00000000 00000000 00000000 |................| [00009.903] 02119:02121> 0x0000e2e370e9eef0: 00000000 00000000 00000000 00000000 |................| [00009.903] 02119:02121> ..... [00009.903] 02119:02121> 0x0000e2e370e9efe0: 00000000 00000000 00000000 00000000 |................| [00030.734] 01046:01105> *** WATCHDOG TIMER FIRED, test: suspend_multiple_threads *** [00030.736] 01032:01039> userboot: loader-service data-sink "llvm-profile" DATA DROPPED: "llvm-profile.1046", 0 bytes [00030.736] 01046:01105> LLVM Profile: {{{dumpfile:llvm-profile:llvm-profile.1046}}} [00030.736] 01046:01105> [00030.825] 01032:01039> userboot: loader-service channel peer closed [00030.825] 01032:01039> userboot: Waiting for bin/core-tests to exit... [00030.826] 01032:01039> userboot: Process exited. Executing "poweroff". [00030.827] 01032:01039> mp_unplug_cpu_mask_single_locked:362: WARNING: leaking thread for cpu 3 [00030.828] 01032:01039> mp_unplug_cpu_mask_single_locked:362: WARNING: leaking thread for cpu 2 [00030.831] 01032:01039> mp_unplug_cpu_mask_single_locked:362: WARNING: leaking thread for cpu 1 [00030.831] 01032:01039> Shutting down debuglog