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

Running command: /b/s/w/ir/cache/vpython/7c7ecd/bin/python scons.py --verbose -k -j1 --mode=nacl,opt-host platform=x86-64 nacl_clang=1 skip_trusted_tests=1 large_tests scons: Reading SConscript files ... ====================================================================== SCONS ARGS:['scons.py', '--verbose', '-k', '-j1', '--mode=nacl,opt-host', 'platform=x86-64', 'nacl_clang=1', 'skip_trusted_tests=1', 'large_tests'] ====================================================================== ====================================================================== Python Info: ====================================================================== darwin 2.7.14+chromium14 (6cc0aad35b-dirty:6cc0aad35b, Oct 3 2017, 17:23:56) [GCC 4.2.1 Compatible Apple LLVM 8.0.0 (clang-800.0.38)] ====================================================================== ENV: ====================================================================== PATH /b/s/w/ir/cache/vpython/7c7ecd/bin:/b/s/w/ir/cipd_bin_packages:/b/s/w/ir/cipd_bin_packages/bin:/b/s/cipd_cache/bin:/opt/local/bin:/opt/local/sbin:/usr/local/sbin:/usr/local/git/bin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/b/s/w/ir/kitchen-checkout/depot_tools ====================================================================== OS: ====================================================================== kern.ostype: Darwin kern.osrelease: 17.7.0 kern.osrevision: 199506 kern.version: Darwin Kernel Version 17.7.0: Thu Jun 21 22:53:14 PDT 2018; root:xnu-4570.71.2~1/RELEASE_X86_64 kern.posix1version: 200112 kern.osversion: 17G65 kern.osproductversion: 10.13.6 ====================================================================== CPU: ====================================================================== hw.machine: x86_64 hw.model: Macmini7,1 hw.ncpu: 4 hw.ncpu: 4 hw.activecpu: 4 hw.physicalcpu: 2 hw.physicalcpu_max: 2 hw.logicalcpu: 4 hw.logicalcpu_max: 4 hw.cputype: 7 hw.cpusubtype: 8 hw.cpu64bit_capable: 1 hw.cpufamily: 280134364 hw.cpufrequency: 3000000000 hw.cpufrequency_min: 3000000000 hw.cpufrequency_max: 3000000000 hw.cputhreadtype: 1 ====================================================================== RAM: ====================================================================== hw.memsize: 17179869184 ====================================================================== LOAD: ====================================================================== TBD ====================================================================== UPTIME: ====================================================================== kern.boottime: { sec = 1552672107, usec = 164509 } Fri Mar 15 10:48:27 2019 ====================================================================== The following environments have been configured ====================================================================== NACL_BUILD_FAMILY: UNTRUSTED BUILD_TYPE: nacl TARGET_ROOT: /b/s/w/ir/k/native_client/scons-out/nacl-x86-64 OBJ_ROOT: /b/s/w/ir/k/native_client/scons-out/nacl-x86-64/obj BUILD_TYPE_DESCRIPTION: NaCl module build BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 64 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 64 CC: /b/s/w/ir/k/native_client/toolchain/mac_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /b/s/w/ir/k/native_client/toolchain/mac_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang -m64 -D__STDC_LIMIT_MACROS=1 -D__STDC_FORMAT_MACROS=1 -D_GNU_SOURCE=1 -D_POSIX_C_SOURCE=199506 -D_XOPEN_SOURCE=600 -DDYNAMIC_ANNOTATIONS_ENABLED=1 -DDYNAMIC_ANNOTATIONS_PREFIX=NACL_ -DNACL_BUILD_ARCH=x86 -DNACL_BUILD_SUBARCH=64 -I/b/s/w/ir/k/native_client/scons-out/nacl-x86-64/include -I/b/s/w/ir/k -c -o UNKNOWN COMPILER NACL_BUILD_FAMILY: TRUSTED BUILD_TYPE: opt-mac TARGET_ROOT: /b/s/w/ir/k/native_client/scons-out/opt-mac-x86-64 OBJ_ROOT: /b/s/w/ir/k/native_client/scons-out/opt-mac-x86-64/obj BUILD_TYPE_DESCRIPTION: MacOS opt build BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 64 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 64 CC: /b/s/w/ir/k/third_party/llvm-build/Release+Asserts/bin/clang ASPPCOM: /b/s/w/ir/k/third_party/llvm-build/Release+Asserts/bin/clang -isysroot /b/s/w/ir/cache/osx_sdk/XCode.app/Contents/Developer/Platforms/MacOSX.platform/Developer/SDKs/MacOSX10.13.sdk -mmacosx-version-min=10.6 -m64 -D__STDC_LIMIT_MACROS=1 -D__STDC_FORMAT_MACROS=1 -DNDEBUG -I/b/s/w/ir/k -c -o UNKNOWN COMPILER NACL_BUILD_FAMILY: UNTRUSTED_IRT BUILD_TYPE: nacl_irt TARGET_ROOT: /b/s/w/ir/k/native_client/scons-out/nacl_irt-x86-64 OBJ_ROOT: /b/s/w/ir/k/native_client/scons-out/nacl_irt-x86-64/obj BUILD_TYPE_DESCRIPTION: NaCl IRT build BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 64 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 64 CC: /b/s/w/ir/k/native_client/toolchain/mac_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /b/s/w/ir/k/native_client/toolchain/mac_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang -m64 -D__STDC_LIMIT_MACROS=1 -D__STDC_FORMAT_MACROS=1 -D_GNU_SOURCE=1 -D_POSIX_C_SOURCE=199506 -D_XOPEN_SOURCE=600 -DDYNAMIC_ANNOTATIONS_ENABLED=1 -DDYNAMIC_ANNOTATIONS_PREFIX=NACL_ -DNACL_BUILD_ARCH=x86 -DNACL_BUILD_SUBARCH=64 -I/b/s/w/ir/k/native_client/scons-out/nacl_irt-x86-64/include -I/b/s/w/ir/k -I/b/s/w/ir/k/native_client/src/untrusted/pthread -c -o UNKNOWN COMPILER *** BROKEN run_ncval_annotate_test *** BROKEN run_gdb_invalid_memory_test *** BROKEN run_nanosleep_test *** BROKEN run_timefuncs_test *** BROKEN run_raw_timefuncs_test *** BROKEN run_main_thread_pthread_exit_test2 *** BROKEN run_overflowinline_test *** BROKEN run_stack_frame_noopt_frame_test *** BROKEN run_stack_frame_noopt_noframe_test *** BROKEN run_stack_frame_opt_frame_test *** BROKEN run_stack_frame_opt_noframe_test *** BROKEN run_vla_test *** SKIPPING opt-mac-64 : run_package_version_tests *** SKIPPING opt-mac-64 : run_pynacl_tests *** SKIPPING opt-mac-64 : run_gio_test *** SKIPPING opt-mac-64 : run_gprintf_test *** SKIPPING opt-mac-64 : run_sigpipe_test *** SKIPPING opt-mac-64 : run_platform_tests *** SKIPPING opt-mac-64 : run_nacl_semaphore_test *** SKIPPING opt-mac-64 : run_nacl_host_dir_test *** SKIPPING opt-mac-64 : run_nacl_clock_test *** SKIPPING opt-mac-64 : run_nacl_clock_cputime_test *** SKIPPING opt-mac-64 : run_nacl_sync_lock_lock_test *** SKIPPING opt-mac-64 : run_nacl_sync_lock_trylock_test *** SKIPPING opt-mac-64 : run_nacl_sync_trylock_lock_test *** SKIPPING opt-mac-64 : run_nacl_sync_trylock_trylock_test *** SKIPPING opt-mac-64 : run_nacl_host_desc_mmap_test *** SKIPPING opt-mac-64 : run_nacl_host_desc_pread_pwrite_test *** SKIPPING opt-mac-64 : run_nacl_host_desc_big_file_test *** SKIPPING opt-mac-64 : run_cpu_x86_test *** SKIPPING opt-mac-64 : run_gdb_rsp_tests *** SKIPPING opt-mac-64 : run_transport_ipc_test *** SKIPPING opt-mac-64 : run_nacl_desc_quota_test *** SKIPPING opt-mac-64 : run_metadata_test *** SKIPPING opt-mac-64 : run_nacl_desc_io_alloc_ctor_test *** SKIPPING opt-mac-64 : run_nacl_fi_test *** SKIPPING opt-mac-64 : run_nacl_interval_list_test *** SKIPPING opt-mac-64 : run_nacl_interval_tree_test *** SKIPPING opt-mac-64 : run_nacl_interval_list_random_test *** SKIPPING opt-mac-64 : run_nacl_interval_tree_random_test *** SKIPPING opt-mac-64 : run_nacl_interval_multi_test *** SKIPPING opt-mac-64 : run_nacl_interval_multi_reg_test *** SKIPPING opt-mac-64 : run_nacl_base_test *** SKIPPING opt-mac-64 : run_nacl_min_max_test *** SKIPPING opt-mac-64 : run_nacl_min_max_cpp_test *** SKIPPING opt-mac-64 : run_nacl_perf_counter_test *** SKIPPING opt-mac-64 : run_platform_qual_test *** SKIPPING opt-mac-64 : run_cpuwhitelist_test *** SKIPPING opt-mac-64 : run_service_runtime_tests *** SKIPPING opt-mac-64 : run_format_string_test *** SKIPPING opt-mac-64 : run_trusted_mmap_test *** BROKEN run_nacl_sync_cond_test *** SKIPPING opt-mac-64 : run_env_cleanser_test *** SKIPPING opt-mac-64 : run_nacl_resource_test *** SKIPPING opt-mac-64 : run_check_test *** SKIPPING opt-mac-64 : run_check_test_death *** SKIPPING opt-mac-64 : run_dcheck_test_death *** SKIPPING opt-mac-64 : run_check_test_always_death *** SKIPPING opt-mac-64 : run_check_test_always_death_abort *** SKIPPING opt-mac-64 : run_dcheck_test_never_death *** SKIPPING opt-mac-64 : run_dcheck_test_always_death *** SKIPPING opt-mac-64 : run_sel_ldr_exe_not_found_test *** SKIPPING opt-mac-64 : run_fuzz_nullptr_test *** SKIPPING opt-mac-64 : run_unaligned_data_test *** SKIPPING opt-mac-64 : run_unaligned_data_irt_test *** SKIPPING opt-mac-64 : run_hello_x32_test *** SKIPPING opt-mac-64 : run_service_runtime_hello_world_test *** SKIPPING opt-mac-64 : run_old_abi_death_test *** SKIPPING opt-mac-64 : run_integer_overflow_while_madvising_death_test *** SKIPPING opt-mac-64 : run_negative_hole_death_test *** SKIPPING opt-mac-64 : run_rodata_data_overlap_death_test *** SKIPPING opt-mac-64 : run_data_not_last_death_test SKIPPING test text_overlaps_rodata SKIPPING test text_overlaps_data *** SKIPPING opt-mac-64 : run_text_too_big_death_test *** SKIPPING opt-mac-64 : run_sel_ldr_thread_death_test *** SKIPPING opt-mac-64 : run_nacl_error_gio_test *** SKIPPING opt-mac-64 : run_nacl_error_log_test *** SKIPPING opt-mac-64 : run_dyn_array_test *** SKIPPING opt-mac-64 : run_validation_cache_test *** SKIPPING opt-mac-64 : run_validator_debug_stub_test *** SKIPPING opt-mac-64 : run_disable_nontemporals_test *** SKIPPING opt-mac-64 : run_validation_rewrite_test *** SKIPPING opt-mac-64 : run_gen_dfa_test *** SKIPPING opt-mac-64 : run_trie_test *** SKIPPING opt-mac-64 : run_proof_tools_test *** SKIPPING opt-mac-64 : run_validator_py_test *** SKIPPING opt-mac-64 : run_rdfa_targeted_tests_32 *** SKIPPING opt-mac-64 : run_dis_section_test_32 *** SKIPPING opt-mac-64 : run_spec_val_test_32 *** SKIPPING opt-mac-64 : run_rdfa_targeted_tests_64 *** SKIPPING opt-mac-64 : run_dis_section_test_64 *** SKIPPING opt-mac-64 : run_spec_val_test_64 *** SKIPPING opt-mac-64 : run_nacl_file_lock_all_test *** SKIPPING opt-mac-64 : run_nacl_file_lock_any_test *** SKIPPING opt-mac-64 : run_nacl_file_lock_t3f2_test *** SKIPPING opt-mac-64 : run_nacl_file_lock_t3f3_test *** SKIPPING opt-mac-64 : run_nacl_file_lock_all_real_test *** SKIPPING opt-mac-64 : run_nacl_file_lock_any_real_test *** SKIPPING opt-mac-64 : run_nacl_file_lock_t3f2_real_test *** SKIPPING opt-mac-64 : run_nacl_file_lock_t3f3_real_test *** SKIPPING opt-mac-64 : run_trusted_performance_test *** SKIPPING opt-mac-64 : run_python_version_test *** SKIPPING opt-mac-64 : run_tool_tests *** SKIPPING opt-mac-64 : run_nacl_close_test *** SKIPPING opt-mac-64 : run_double_nacl_close_test *** SKIPPING opt-mac-64 : run_nacl_create_memory_object_test *** SKIPPING opt-mac-64 : run_nacl_thread_create_joinable_test *** SKIPPING opt-mac-64 : run_atomic_ops_test *** SKIPPING opt-mac-64 : run_bits_test *** SKIPPING opt-mac-64 : run_kernel_version_test *** SKIPPING opt-mac-64 : run_nacl_memory_test *** SKIPPING opt-mac-64 : run_toolchain_python_tests *** SKIPPING nacl_irt-64 : run_irt_thread_test There are 13 broken tests. ====================================================================== B U I L D - O U T P U T: ====================================================================== scons: done reading SConscript files. scons: Building targets ... /b/s/w/ir/cache/vpython/7c7ecd/bin/python tools/command_tester.py --name nacl_newlib.run_benchmark_test --time_warning 10 --time_error 100 --perf_env_description x86-64_nnacl_newlib_static --subarch 64 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/k/native_client/scons-out/nacl-x86-64/test_results/benchmark_test.out scons-out/opt-mac-x86-64/staging/sel_ldr -- scons-out/nacl-x86-64/obj/tests/benchmark/benchmark_test.nexe x86-64_nnacl_newlib_static [ RUN ] nacl_newlib.run_benchmark_test scons-out/opt-mac-x86-64/staging/sel_ldr -- scons-out/nacl-x86-64/obj/tests/benchmark/benchmark_test.nexe x86-64_nnacl_newlib_static [10839,2795467648:14:56:51.416269] Native Client module will be loaded at base address 0x000003b800000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_nnacl_newlib_static= {0.141000, 0.000830} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test (1370 ms) /b/s/w/ir/cache/vpython/7c7ecd/bin/python tools/command_tester.py --name nacl_newlib.run_clock_cputime_test --time_warning 60 --time_error 600 --perf_env_description x86-64_nnacl_newlib_static --subarch 64 --arch x86 --output_stamp /b/s/w/ir/k/native_client/scons-out/nacl-x86-64/test_results/clock_cputime_test.out scons-out/opt-mac-x86-64/staging/sel_ldr -- scons-out/nacl-x86-64/obj/tests/clock/clock_test.nexe -c [ RUN ] nacl_newlib.run_clock_cputime_test scons-out/opt-mac-x86-64/staging/sel_ldr -- scons-out/nacl-x86-64/obj/tests/clock/clock_test.nexe -c WARNING: should have taken less than 60.000000 secs [ OK ] nacl_newlib.run_clock_cputime_test (66970 ms) doSizeCheck(["scons-out/nacl-x86-64/obj/tests/hello_world/hello_world_size.out"], ["scons-out/nacl-x86-64/obj/tests/hello_world/hello_world.nexe"]) RESULT hello_world_size.out: x86-64_nnacl_newlib_static= 522.811 KB RESULT hello_world_size.out: ZIPPED_x86-64_nnacl_newlib_static= 187.698 KB /b/s/w/ir/cache/vpython/7c7ecd/bin/python tools/command_tester.py --name nacl_newlib.run_performance_test --time_warning 10 --time_error 100 --perf_env_description x86-64_nnacl_newlib_static --subarch 64 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/k/native_client/scons-out/nacl-x86-64/test_results/performance_test.out scons-out/opt-mac-x86-64/staging/sel_ldr -e -- scons-out/nacl-x86-64/obj/tests/performance/performance_test.nexe x86-64_nnacl_newlib_static [ RUN ] nacl_newlib.run_performance_test scons-out/opt-mac-x86-64/staging/sel_ldr -e -- scons-out/nacl-x86-64/obj/tests/performance/performance_test.nexe x86-64_nnacl_newlib_static [10849,2795467648:14:57:11.292699] Native Client module will be loaded at base address 0x0000397e00000000 TestNull: Measuring real time: 0.006 usec (5.8e-09 sec) per iteration: 5.8e-07 sec for 100 iterations 0.002 usec (1.861e-09 sec) per iteration: 1.861e-06 sec for 1000 iterations 0.002 usec (1.6952e-09 sec) per iteration: 1.6952e-05 sec for 10000 iterations 0.002 usec (1.67824e-09 sec) per iteration: 0.0989998 sec for 58990089 iterations 0.002 usec (1.674e-09 sec) per iteration: 0.0987495 sec for 58990089 iterations 0.002 usec (1.67354e-09 sec) per iteration: 0.0987221 sec for 58990089 iterations 0.002 usec (1.67476e-09 sec) per iteration: 0.0987942 sec for 58990089 iterations 0.002 usec (1.67467e-09 sec) per iteration: 0.098789 sec for 58990089 iterations mean: 0.001675 usec stddev: 0.000002 usec relative stddev: 0.10% RESULT TestNull: x86-64_nnacl_newlib_static= {0.001675, 0.000002} us Measuring clock cycles: first runs (cycles): 152 40 24 24 24 24 24 24 24 24 ... slowest runs (cycles): ... 24 24 24 24 24 24 24 24 40 152 min: 24 cycles q1: 24 cycles median: 24 cycles q3: 24 cycles max: 152 cycles RESULT TestNull_CycleCount: x86-64_nnacl_newlib_static= {24, 0} count Apparent clock speed: 14328 MHz TestNaClSyscall: Measuring real time: 0.152 usec (1.5239e-07 sec) per iteration: 1.5239e-05 sec for 100 iterations 0.151 usec (1.51131e-07 sec) per iteration: 0.0991738 sec for 656211 iterations 0.151 usec (1.50537e-07 sec) per iteration: 0.098784 sec for 656211 iterations 0.150 usec (1.50448e-07 sec) per iteration: 0.098726 sec for 656211 iterations 0.151 usec (1.50959e-07 sec) per iteration: 0.0990609 sec for 656211 iterations 0.151 usec (1.50623e-07 sec) per iteration: 0.0988408 sec for 656211 iterations mean: 0.150740 usec stddev: 0.000261 usec relative stddev: 0.17% RESULT TestNaClSyscall: x86-64_nnacl_newlib_static= {0.150740, 0.000261} us Measuring clock cycles: first runs (cycles): 532 384 368 328 348 328 340 344 340 332 ... slowest runs (cycles): ... 348 348 352 352 356 356 360 368 384 532 min: 328 cycles q1: 336 cycles median: 340 cycles q3: 344 cycles max: 532 cycles RESULT TestNaClSyscall_CycleCount: x86-64_nnacl_newlib_static= {340, 8} count Apparent clock speed: 2256 MHz TestSetjmpLongjmp: Measuring real time: 0.014 usec (1.444e-08 sec) per iteration: 1.444e-06 sec for 100 iterations 0.012 usec (1.2047e-08 sec) per iteration: 1.2047e-05 sec for 1000 iterations 0.011 usec (1.12686e-08 sec) per iteration: 0.093539 sec for 8300821 iterations 0.011 usec (1.12911e-08 sec) per iteration: 0.0937257 sec for 8300821 iterations 0.011 usec (1.12561e-08 sec) per iteration: 0.0934351 sec for 8300821 iterations 0.011 usec (1.1312e-08 sec) per iteration: 0.0938991 sec for 8300821 iterations 0.011 usec (1.12944e-08 sec) per iteration: 0.0937531 sec for 8300821 iterations mean: 0.011284 usec stddev: 0.000020 usec relative stddev: 0.18% RESULT TestSetjmpLongjmp: x86-64_nnacl_newlib_static= {0.011284, 0.000020} us Measuring clock cycles: first runs (cycles): 284 52 28 24 28 24 28 24 28 24 ... slowest runs (cycles): ... 28 28 28 28 28 28 28 28 52 284 min: 24 cycles q1: 24 cycles median: 28 cycles q3: 28 cycles max: 284 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_nnacl_newlib_static= {28, 4} count Apparent clock speed: 2481 MHz TestClockGetTime: Measuring real time: 0.192 usec (1.9208e-07 sec) per iteration: 1.9208e-05 sec for 100 iterations 0.167 usec (1.66751e-07 sec) per iteration: 0.0868135 sec for 520616 iterations 0.167 usec (1.67356e-07 sec) per iteration: 0.0871281 sec for 520616 iterations 0.166 usec (1.66405e-07 sec) per iteration: 0.0866329 sec for 520616 iterations 0.166 usec (1.66064e-07 sec) per iteration: 0.0864558 sec for 520616 iterations 0.166 usec (1.66332e-07 sec) per iteration: 0.0865953 sec for 520616 iterations mean: 0.166582 usec stddev: 0.000445 usec relative stddev: 0.27% RESULT TestClockGetTime: x86-64_nnacl_newlib_static= {0.166582, 0.000445} us Measuring clock cycles: first runs (cycles): 880 528 492 488 492 504 496 492 504 504 ... slowest runs (cycles): ... 508 508 508 508 508 512 512 528 580 880 min: 488 cycles q1: 500 cycles median: 504 cycles q3: 508 cycles max: 880 cycles RESULT TestClockGetTime_CycleCount: x86-64_nnacl_newlib_static= {504, 8} count Apparent clock speed: 3026 MHz TestTlsVariable: Measuring real time: 0.009 usec (8.67e-09 sec) per iteration: 8.67e-07 sec for 100 iterations 0.005 usec (5.221e-09 sec) per iteration: 5.221e-06 sec for 1000 iterations 0.009 usec (8.5967e-09 sec) per iteration: 8.5967e-05 sec for 10000 iterations 0.005 usec (5.0382e-09 sec) per iteration: 0.0586062 sec for 11632370 iterations 0.005 usec (5.02646e-09 sec) per iteration: 0.0584697 sec for 11632370 iterations 0.005 usec (5.02562e-09 sec) per iteration: 0.0584599 sec for 11632370 iterations 0.005 usec (5.07451e-09 sec) per iteration: 0.0590286 sec for 11632370 iterations 0.005 usec (5.03314e-09 sec) per iteration: 0.0585473 sec for 11632370 iterations mean: 0.005040 usec stddev: 0.000018 usec relative stddev: 0.36% RESULT TestTlsVariable: x86-64_nnacl_newlib_static= {0.005040, 0.000018} us Measuring clock cycles: first runs (cycles): 276 76 52 44 24 24 24 24 24 24 ... slowest runs (cycles): ... 24 24 24 24 24 24 44 52 76 276 min: 24 cycles q1: 24 cycles median: 24 cycles q3: 24 cycles max: 276 cycles RESULT TestTlsVariable_CycleCount: x86-64_nnacl_newlib_static= {24, 0} count Apparent clock speed: 4762 MHz TestMmapAnonymous: Measuring real time: 8.165 usec (8.16465e-06 sec) per iteration: 0.000816465 sec for 100 iterations 5.103 usec (5.10313e-06 sec) per iteration: 0.062498 sec for 12247 iterations 5.105 usec (5.10546e-06 sec) per iteration: 0.0625266 sec for 12247 iterations 5.151 usec (5.15132e-06 sec) per iteration: 0.0630882 sec for 12247 iterations 5.117 usec (5.11723e-06 sec) per iteration: 0.0626708 sec for 12247 iterations 5.090 usec (5.0895e-06 sec) per iteration: 0.0623312 sec for 12247 iterations mean: 5.113330 usec stddev: 0.020938 usec relative stddev: 0.41% RESULT TestMmapAnonymous: x86-64_nnacl_newlib_static= {5.113330, 0.020938} us Measuring clock cycles: first runs (cycles): 18908 15700 15236 15156 15116 15080 15056 15072 15036 15000 ... slowest runs (cycles): ... 23904 23908 23944 24004 24024 24052 24212 24236 24824 24980 min: 15000 cycles q1: 15192 cycles median: 15292 cycles q3: 19256 cycles max: 24980 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_nnacl_newlib_static= {15292, 4064} count Apparent clock speed: 2991 MHz TestAtomicIncrement: Measuring real time: 0.015 usec (1.492e-08 sec) per iteration: 1.492e-06 sec for 100 iterations 0.010 usec (1.0451e-08 sec) per iteration: 1.0451e-05 sec for 1000 iterations 0.009 usec (8.71007e-09 sec) per iteration: 0.083342 sec for 9568462 iterations 0.009 usec (8.6856e-09 sec) per iteration: 0.0831079 sec for 9568462 iterations 0.009 usec (8.69764e-09 sec) per iteration: 0.0832231 sec for 9568462 iterations 0.009 usec (8.71547e-09 sec) per iteration: 0.0833937 sec for 9568462 iterations 0.009 usec (8.71108e-09 sec) per iteration: 0.0833517 sec for 9568462 iterations mean: 0.008704 usec stddev: 0.000011 usec relative stddev: 0.13% RESULT TestAtomicIncrement: x86-64_nnacl_newlib_static= {0.008704, 0.000011} us Measuring clock cycles: first runs (cycles): 176 52 52 52 52 52 52 52 52 52 ... slowest runs (cycles): ... 52 52 52 52 52 52 52 52 56 176 min: 52 cycles q1: 52 cycles median: 52 cycles q3: 52 cycles max: 176 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_nnacl_newlib_static= {52, 0} count Apparent clock speed: 5974 MHz TestUncontendedMutexLock: Measuring real time: 0.031 usec (3.11e-08 sec) per iteration: 3.11e-06 sec for 100 iterations 0.024 usec (2.4211e-08 sec) per iteration: 2.4211e-05 sec for 1000 iterations 0.022 usec (2.17814e-08 sec) per iteration: 0.0899648 sec for 4130353 iterations 0.022 usec (2.17963e-08 sec) per iteration: 0.0900266 sec for 4130353 iterations 0.022 usec (2.1733e-08 sec) per iteration: 0.0897649 sec for 4130353 iterations 0.022 usec (2.1793e-08 sec) per iteration: 0.0900128 sec for 4130353 iterations 0.022 usec (2.17497e-08 sec) per iteration: 0.089834 sec for 4130353 iterations mean: 0.021771 usec stddev: 0.000025 usec relative stddev: 0.11% RESULT TestUncontendedMutexLock: x86-64_nnacl_newlib_static= {0.021771, 0.000025} us Measuring clock cycles: first runs (cycles): 364 116 88 92 88 92 88 92 88 92 ... slowest runs (cycles): ... 92 92 92 92 92 92 92 92 116 364 min: 88 cycles q1: 88 cycles median: 92 cycles q3: 92 cycles max: 364 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_nnacl_newlib_static= {92, 4} count Apparent clock speed: 4226 MHz TestCondvarSignalNoOp: Measuring real time: 0.330 usec (3.2985e-07 sec) per iteration: 3.2985e-05 sec for 100 iterations 0.158 usec (1.58482e-07 sec) per iteration: 0.0480465 sec for 303168 iterations 0.158 usec (1.58085e-07 sec) per iteration: 0.0479264 sec for 303168 iterations 0.159 usec (1.58919e-07 sec) per iteration: 0.0481793 sec for 303168 iterations 0.158 usec (1.57729e-07 sec) per iteration: 0.0478184 sec for 303168 iterations 0.159 usec (1.58744e-07 sec) per iteration: 0.0481262 sec for 303168 iterations mean: 0.158392 usec stddev: 0.000435 usec relative stddev: 0.27% RESULT TestCondvarSignalNoOp: x86-64_nnacl_newlib_static= {0.158392, 0.000435} us Measuring clock cycles: first runs (cycles): 776 488 460 460 456 456 468 464 472 460 ... slowest runs (cycles): ... 472 472 472 472 472 472 472 476 488 776 min: 456 cycles q1: 460 cycles median: 468 cycles q3: 472 cycles max: 776 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_nnacl_newlib_static= {468, 12} count Apparent clock speed: 2955 MHz TestThreadCreateAndJoin: Measuring real time: 48.350 usec (4.83499e-05 sec) per iteration: 0.00483499 sec for 100 iterations 45.841 usec (4.58407e-05 sec) per iteration: 0.0947986 sec for 2068 iterations 45.909 usec (4.59094e-05 sec) per iteration: 0.0949407 sec for 2068 iterations 45.726 usec (4.57264e-05 sec) per iteration: 0.0945621 sec for 2068 iterations 45.436 usec (4.54358e-05 sec) per iteration: 0.0939612 sec for 2068 iterations 45.933 usec (4.59328e-05 sec) per iteration: 0.094989 sec for 2068 iterations mean: 45.769011 usec stddev: 0.181405 usec relative stddev: 0.40% RESULT TestThreadCreateAndJoin: x86-64_nnacl_newlib_static= {45.769011, 0.181405} us Measuring clock cycles: first runs (cycles): 102994 109864 145646 209040 227364 162976 122031 187710 288338 116298 ... slowest runs (cycles): ... 168784 176823 187710 194521 202989 209040 226137 227364 252216 288338 min: 71076 cycles q1: 135396 cycles median: 136176 cycles q3: 137433 cycles max: 288338 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_nnacl_newlib_static= {136176, 2037} count Apparent clock speed: 2975 MHz TestThreadWakeup: Measuring real time: 15.076 usec (1.5076e-05 sec) per iteration: 0.0015076 sec for 100 iterations 10.439 usec (1.04389e-05 sec) per iteration: 0.0692415 sec for 6633 iterations 10.206 usec (1.0206e-05 sec) per iteration: 0.0676965 sec for 6633 iterations 10.430 usec (1.04296e-05 sec) per iteration: 0.0691798 sec for 6633 iterations 10.439 usec (1.04386e-05 sec) per iteration: 0.069239 sec for 6633 iterations 10.519 usec (1.05186e-05 sec) per iteration: 0.0697696 sec for 6633 iterations mean: 10.406343 usec stddev: 0.105234 usec relative stddev: 1.01% RESULT TestThreadWakeup: x86-64_nnacl_newlib_static= {10.406343, 0.105234} us Measuring clock cycles: first runs (cycles): 38972 32912 32180 43344 256048 50328 57264 96976 49416 69660 ... slowest runs (cycles): ... 38972 41232 43344 49416 50328 57264 69660 96976 112832 256048 min: 29548 cycles q1: 29876 cycles median: 30248 cycles q3: 31172 cycles max: 256048 cycles RESULT TestThreadWakeup_CycleCount: x86-64_nnacl_newlib_static= {30248, 1296} count Apparent clock speed: 2907 MHz TestCatchingFault: Measuring real time: 17.840 usec (1.78399e-05 sec) per iteration: 0.00178399 sec for 100 iterations 15.594 usec (1.55945e-05 sec) per iteration: 0.087407 sec for 5605 iterations 15.545 usec (1.55453e-05 sec) per iteration: 0.0871315 sec for 5605 iterations 15.473 usec (1.54735e-05 sec) per iteration: 0.0867289 sec for 5605 iterations 15.482 usec (1.5482e-05 sec) per iteration: 0.0867766 sec for 5605 iterations 15.536 usec (1.55365e-05 sec) per iteration: 0.0870821 sec for 5605 iterations mean: 15.526354 usec stddev: 0.044415 usec relative stddev: 0.29% RESULT TestCatchingFault: x86-64_nnacl_newlib_static= {15.526354, 0.044415} us Measuring clock cycles: first runs (cycles): 53708 52068 69400 73304 57376 110552 148688 66760 57876 58388 ... slowest runs (cycles): ... 75064 76584 79928 81332 82936 83596 84812 96648 110552 148688 min: 39516 cycles q1: 46576 cycles median: 58512 cycles q3: 66760 cycles max: 148688 cycles RESULT TestCatchingFault_CycleCount: x86-64_nnacl_newlib_static= {58512, 20184} count Apparent clock speed: 3769 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 39.508 usec (3.95081e-05 sec) per iteration: 0.00395081 sec for 100 iterations 45.632 usec (4.56323e-05 sec) per iteration: 0.115495 sec for 2531 iterations 45.775 usec (4.57753e-05 sec) per iteration: 0.115857 sec for 2531 iterations 45.701 usec (4.57013e-05 sec) per iteration: 0.11567 sec for 2531 iterations 46.332 usec (4.63317e-05 sec) per iteration: 0.117265 sec for 2531 iterations 45.921 usec (4.59214e-05 sec) per iteration: 0.116227 sec for 2531 iterations mean: 45.872392 usec stddev: 0.248869 usec relative stddev: 0.54% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_nnacl_newlib_static= {45.872392, 0.248869} us Measuring clock cycles: first runs (cycles): 125908 131964 113776 155734 261573 139295 225266 170439 209007 123393 ... slowest runs (cycles): ... 158300 165285 170439 192508 194849 209007 225266 251808 252156 261573 min: 72897 cycles q1: 134613 cycles median: 135333 cycles q3: 143211 cycles max: 261573 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_nnacl_newlib_static= {135333, 8598} count Apparent clock speed: 2950 MHz [ OK ] nacl_newlib.run_performance_test (5750 ms) scons: done building targets. ********************************************************************** COMMAND EXECUTION REPORT ********************************************************************** 3 command_tester.py 1 doSizeCheck ********************************************************************** ENVIRONMENT USAGE REPORT ********************************************************************** 4 nacl-x86-64 Command return code: 0