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.5.0 kern.osrevision: 199506 kern.version: Darwin Kernel Version 17.5.0: Mon Mar 5 22:24:32 PST 2018; root:xnu-4570.51.1~1/RELEASE_X86_64 kern.posix1version: 200112 kern.osversion: 17E199 kern.osproductversion: 10.13.4 ====================================================================== CPU: ====================================================================== hw.machine: x86_64 hw.model: VMware7,1 hw.ncpu: 8 hw.ncpu: 8 hw.activecpu: 8 hw.physicalcpu: 8 hw.physicalcpu_max: 8 hw.logicalcpu: 8 hw.logicalcpu_max: 8 hw.cputype: 7 hw.cpusubtype: 4 hw.cpu64bit_capable: 1 hw.cpufamily: 526772277 hw.cpufrequency: 2574000000 hw.cpufrequency_min: 2574000000 hw.cpufrequency_max: 2574000000 ====================================================================== RAM: ====================================================================== hw.memsize: 17179869184 ====================================================================== LOAD: ====================================================================== TBD ====================================================================== UPTIME: ====================================================================== kern.boottime: { sec = 1547758631, usec = 508552 } Thu Jan 17 12:57:11 2019 ====================================================================== The following environments have been configured ====================================================================== NACL_BUILD_FAMILY: UNTRUSTED BUILD_TYPE: nacl TARGET_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-64 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/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/kitchen-workdir/native_client/toolchain/mac_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /b/s/w/ir/kitchen-workdir/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/kitchen-workdir/native_client/scons-out/nacl-x86-64/include -I/b/s/w/ir/kitchen-workdir -c -o UNKNOWN COMPILER NACL_BUILD_FAMILY: TRUSTED BUILD_TYPE: opt-mac TARGET_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-mac-x86-64 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/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/kitchen-workdir/third_party/llvm-build/Release+Asserts/bin/clang ASPPCOM: /b/s/w/ir/kitchen-workdir/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/kitchen-workdir -c -o UNKNOWN COMPILER NACL_BUILD_FAMILY: UNTRUSTED_IRT BUILD_TYPE: nacl_irt TARGET_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt-x86-64 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/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/kitchen-workdir/native_client/toolchain/mac_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /b/s/w/ir/kitchen-workdir/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/kitchen-workdir/native_client/scons-out/nacl_irt-x86-64/include -I/b/s/w/ir/kitchen-workdir -I/b/s/w/ir/kitchen-workdir/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/kitchen-workdir/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 [44449,2865189760:17:29:27.289014] Native Client module will be loaded at base address 0x0000560700000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_nnacl_newlib_static= {0.067850, 0.003530} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test (870 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/kitchen-workdir/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 (66259 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= 525.260 KB RESULT hello_world_size.out: ZIPPED_x86-64_nnacl_newlib_static= 187.859 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/kitchen-workdir/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 [44459,2865189760:17:29:41.571141] Native Client module will be loaded at base address 0x000038fc00000000 TestNull: Measuring real time: 0.007 usec (7.33e-09 sec) per iteration: 7.33e-07 sec for 100 iterations 0.002 usec (2.297e-09 sec) per iteration: 2.297e-06 sec for 1000 iterations 0.002 usec (2.0273e-09 sec) per iteration: 2.0273e-05 sec for 10000 iterations 0.002 usec (2.03171e-09 sec) per iteration: 0.100217 sec for 49326690 iterations 0.002 usec (1.99745e-09 sec) per iteration: 0.0985275 sec for 49326690 iterations 0.002 usec (2.05043e-09 sec) per iteration: 0.101141 sec for 49326690 iterations 0.002 usec (2.03444e-09 sec) per iteration: 0.100352 sec for 49326690 iterations 0.002 usec (1.98227e-09 sec) per iteration: 0.0977789 sec for 49326690 iterations mean: 0.002019 usec stddev: 0.000025 usec relative stddev: 1.25% RESULT TestNull: x86-64_nnacl_newlib_static= {0.002019, 0.000025} us Measuring clock cycles: first runs (cycles): 261 30 30 30 27 21 18 21 18 21 ... slowest runs (cycles): ... 30 30 30 30 30 30 30 30 30 261 min: 18 cycles q1: 18 cycles median: 21 cycles q3: 27 cycles max: 261 cycles RESULT TestNull_CycleCount: x86-64_nnacl_newlib_static= {21, 9} count Apparent clock speed: 10400 MHz TestNaClSyscall: Measuring real time: 0.158 usec (1.5777e-07 sec) per iteration: 1.5777e-05 sec for 100 iterations 0.156 usec (1.55692e-07 sec) per iteration: 0.0986827 sec for 633834 iterations 0.156 usec (1.55601e-07 sec) per iteration: 0.0986252 sec for 633834 iterations 0.155 usec (1.54593e-07 sec) per iteration: 0.0979864 sec for 633834 iterations 0.157 usec (1.5696e-07 sec) per iteration: 0.0994864 sec for 633834 iterations 0.156 usec (1.55501e-07 sec) per iteration: 0.0985615 sec for 633834 iterations mean: 0.155669 usec stddev: 0.000756 usec relative stddev: 0.49% RESULT TestNaClSyscall: x86-64_nnacl_newlib_static= {0.155669, 0.000756} us Measuring clock cycles: first runs (cycles): 774 303 294 288 291 288 291 300 297 297 ... slowest runs (cycles): ... 300 300 300 300 300 300 300 303 303 774 min: 288 cycles q1: 291 cycles median: 291 cycles q3: 300 cycles max: 774 cycles RESULT TestNaClSyscall_CycleCount: x86-64_nnacl_newlib_static= {291, 9} count Apparent clock speed: 1869 MHz TestSetjmpLongjmp: Measuring real time: 0.017 usec (1.73e-08 sec) per iteration: 1.73e-06 sec for 100 iterations 0.012 usec (1.2449e-08 sec) per iteration: 1.2449e-05 sec for 1000 iterations 0.012 usec (1.23255e-08 sec) per iteration: 0.0990077 sec for 8032773 iterations 0.013 usec (1.25718e-08 sec) per iteration: 0.100986 sec for 8032773 iterations 0.012 usec (1.24618e-08 sec) per iteration: 0.100103 sec for 8032773 iterations 0.013 usec (1.25712e-08 sec) per iteration: 0.100982 sec for 8032773 iterations 0.012 usec (1.20469e-08 sec) per iteration: 0.0967698 sec for 8032773 iterations mean: 0.012395 usec stddev: 0.000196 usec relative stddev: 1.58% RESULT TestSetjmpLongjmp: x86-64_nnacl_newlib_static= {0.012395, 0.000196} us Measuring clock cycles: first runs (cycles): 363 57 24 33 33 36 27 27 24 33 ... slowest runs (cycles): ... 36 36 36 36 36 36 36 36 57 363 min: 24 cycles q1: 27 cycles median: 33 cycles q3: 36 cycles max: 363 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_nnacl_newlib_static= {33, 9} count Apparent clock speed: 2662 MHz TestClockGetTime: Measuring real time: 0.161 usec (1.615e-07 sec) per iteration: 1.615e-05 sec for 100 iterations 0.158 usec (1.5803e-07 sec) per iteration: 0.0978511 sec for 619195 iterations 0.158 usec (1.58497e-07 sec) per iteration: 0.0981405 sec for 619195 iterations 0.159 usec (1.59319e-07 sec) per iteration: 0.0986497 sec for 619195 iterations 0.163 usec (1.62512e-07 sec) per iteration: 0.100626 sec for 619195 iterations 0.156 usec (1.5564e-07 sec) per iteration: 0.0963716 sec for 619195 iterations mean: 0.158799 usec stddev: 0.002223 usec relative stddev: 1.40% RESULT TestClockGetTime: x86-64_nnacl_newlib_static= {0.158799, 0.002223} us Measuring clock cycles: first runs (cycles): 1605 450 438 438 435 444 435 435 438 432 ... slowest runs (cycles): ... 444 444 447 447 447 447 447 447 450 1605 min: 432 cycles q1: 435 cycles median: 438 cycles q3: 438 cycles max: 1605 cycles RESULT TestClockGetTime_CycleCount: x86-64_nnacl_newlib_static= {438, 3} count Apparent clock speed: 2758 MHz TestTlsVariable: Measuring real time: 0.010 usec (9.98e-09 sec) per iteration: 9.98e-07 sec for 100 iterations 0.006 usec (6.287e-09 sec) per iteration: 6.287e-06 sec for 1000 iterations 0.006 usec (6.0279e-09 sec) per iteration: 6.0279e-05 sec for 10000 iterations 0.006 usec (5.94737e-09 sec) per iteration: 0.098664 sec for 16589525 iterations 0.006 usec (6.03132e-09 sec) per iteration: 0.100057 sec for 16589525 iterations 0.006 usec (6.05841e-09 sec) per iteration: 0.100506 sec for 16589525 iterations 0.006 usec (5.9541e-09 sec) per iteration: 0.0987756 sec for 16589525 iterations 0.006 usec (6.01299e-09 sec) per iteration: 0.0997527 sec for 16589525 iterations mean: 0.006001 usec stddev: 0.000043 usec relative stddev: 0.72% RESULT TestTlsVariable: x86-64_nnacl_newlib_static= {0.006001, 0.000043} us Measuring clock cycles: first runs (cycles): 357 75 18 21 21 21 18 21 18 30 ... slowest runs (cycles): ... 30 30 30 30 30 30 30 30 75 357 min: 18 cycles q1: 18 cycles median: 21 cycles q3: 27 cycles max: 357 cycles RESULT TestTlsVariable_CycleCount: x86-64_nnacl_newlib_static= {21, 9} count Apparent clock speed: 3500 MHz TestMmapAnonymous: Measuring real time: 8.586 usec (8.58571e-06 sec) per iteration: 0.000858571 sec for 100 iterations 8.270 usec (8.27037e-06 sec) per iteration: 0.096325 sec for 11647 iterations 7.991 usec (7.99081e-06 sec) per iteration: 0.093069 sec for 11647 iterations 8.292 usec (8.29206e-06 sec) per iteration: 0.0965776 sec for 11647 iterations 8.417 usec (8.41661e-06 sec) per iteration: 0.0980283 sec for 11647 iterations 8.393 usec (8.39307e-06 sec) per iteration: 0.0977541 sec for 11647 iterations mean: 8.272583 usec stddev: 0.151685 usec relative stddev: 1.83% RESULT TestMmapAnonymous: x86-64_nnacl_newlib_static= {8.272583, 0.151685} us Measuring clock cycles: first runs (cycles): 30702 23238 22791 22599 22545 22497 22656 22677 22560 22500 ... slowest runs (cycles): ... 22986 22989 23019 23058 23076 23082 23238 30423 30702 31593 min: 22230 cycles q1: 22428 cycles median: 22542 cycles q3: 22743 cycles max: 31593 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_nnacl_newlib_static= {22542, 315} count Apparent clock speed: 2725 MHz TestAtomicIncrement: Measuring real time: 0.014 usec (1.37e-08 sec) per iteration: 1.37e-06 sec for 100 iterations 0.010 usec (9.931e-09 sec) per iteration: 9.931e-06 sec for 1000 iterations 0.010 usec (9.6922e-09 sec) per iteration: 9.6922e-05 sec for 10000 iterations 0.010 usec (9.67093e-09 sec) per iteration: 0.0997806 sec for 10317574 iterations 0.010 usec (9.64725e-09 sec) per iteration: 0.0995362 sec for 10317574 iterations 0.010 usec (9.53672e-09 sec) per iteration: 0.0983958 sec for 10317574 iterations 0.010 usec (9.62232e-09 sec) per iteration: 0.099279 sec for 10317574 iterations 0.010 usec (9.63557e-09 sec) per iteration: 0.0994157 sec for 10317574 iterations mean: 0.009623 usec stddev: 0.000046 usec relative stddev: 0.48% RESULT TestAtomicIncrement: x86-64_nnacl_newlib_static= {0.009623, 0.000046} us Measuring clock cycles: first runs (cycles): 312 48 51 51 48 51 48 51 48 42 ... slowest runs (cycles): ... 51 51 51 51 51 51 51 51 87 312 min: 42 cycles q1: 51 cycles median: 51 cycles q3: 51 cycles max: 312 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_nnacl_newlib_static= {51, 0} count Apparent clock speed: 5300 MHz TestUncontendedMutexLock: Measuring real time: 0.027 usec (2.748e-08 sec) per iteration: 2.748e-06 sec for 100 iterations 0.023 usec (2.3284e-08 sec) per iteration: 2.3284e-05 sec for 1000 iterations 0.023 usec (2.29529e-08 sec) per iteration: 0.0985782 sec for 4294794 iterations 0.022 usec (2.23093e-08 sec) per iteration: 0.0958137 sec for 4294794 iterations 0.023 usec (2.30909e-08 sec) per iteration: 0.0991706 sec for 4294794 iterations 0.024 usec (2.39884e-08 sec) per iteration: 0.103025 sec for 4294794 iterations 0.023 usec (2.30562e-08 sec) per iteration: 0.0990218 sec for 4294794 iterations mean: 0.023080 usec stddev: 0.000536 usec relative stddev: 2.32% RESULT TestUncontendedMutexLock: x86-64_nnacl_newlib_static= {0.023080, 0.000536} us Measuring clock cycles: first runs (cycles): 486 111 81 81 81 81 81 81 81 90 ... slowest runs (cycles): ... 90 90 90 90 90 90 90 90 111 486 min: 81 cycles q1: 81 cycles median: 90 cycles q3: 90 cycles max: 486 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_nnacl_newlib_static= {90, 9} count Apparent clock speed: 3900 MHz TestCondvarSignalNoOp: Measuring real time: 0.264 usec (2.6414e-07 sec) per iteration: 2.6414e-05 sec for 100 iterations 0.172 usec (1.72195e-07 sec) per iteration: 0.0651909 sec for 378587 iterations 0.170 usec (1.69708e-07 sec) per iteration: 0.0642491 sec for 378587 iterations 0.160 usec (1.59955e-07 sec) per iteration: 0.0605571 sec for 378587 iterations 0.163 usec (1.62772e-07 sec) per iteration: 0.0616234 sec for 378587 iterations 0.162 usec (1.61739e-07 sec) per iteration: 0.0612321 sec for 378587 iterations mean: 0.165274 usec stddev: 0.004788 usec relative stddev: 2.90% RESULT TestCondvarSignalNoOp: x86-64_nnacl_newlib_static= {0.165274, 0.004788} us Measuring clock cycles: first runs (cycles): 1299 435 432 432 438 432 435 435 432 432 ... slowest runs (cycles): ... 435 435 435 435 435 435 438 444 444 1299 min: 432 cycles q1: 432 cycles median: 432 cycles q3: 432 cycles max: 1299 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_nnacl_newlib_static= {432, 0} count Apparent clock speed: 2614 MHz TestThreadCreateAndJoin: Measuring real time: 71.237 usec (7.12375e-05 sec) per iteration: 0.00712375 sec for 100 iterations 65.829 usec (6.58294e-05 sec) per iteration: 0.0923587 sec for 1403 iterations 64.392 usec (6.43925e-05 sec) per iteration: 0.0903426 sec for 1403 iterations 64.146 usec (6.41465e-05 sec) per iteration: 0.0899975 sec for 1403 iterations 63.241 usec (6.3241e-05 sec) per iteration: 0.0887272 sec for 1403 iterations 63.705 usec (6.3705e-05 sec) per iteration: 0.0893782 sec for 1403 iterations mean: 64.262889 usec stddev: 0.876322 usec relative stddev: 1.36% RESULT TestThreadCreateAndJoin: x86-64_nnacl_newlib_static= {64.262889, 0.876322} us Measuring clock cycles: first runs (cycles): 172209 188670 337134 198162 217956 194898 178893 173010 168402 168429 ... slowest runs (cycles): ... 186195 188670 193080 193977 194685 194898 198162 202140 217956 337134 min: 151281 cycles q1: 166659 cycles median: 167964 cycles q3: 169494 cycles max: 337134 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_nnacl_newlib_static= {167964, 2835} count Apparent clock speed: 2614 MHz TestThreadWakeup: Measuring real time: 31.942 usec (3.19416e-05 sec) per iteration: 0.00319416 sec for 100 iterations 30.923 usec (3.09225e-05 sec) per iteration: 0.0967874 sec for 3130 iterations 31.050 usec (3.10502e-05 sec) per iteration: 0.0971872 sec for 3130 iterations 31.041 usec (3.1041e-05 sec) per iteration: 0.0971583 sec for 3130 iterations 30.649 usec (3.06491e-05 sec) per iteration: 0.0959316 sec for 3130 iterations 31.363 usec (3.1363e-05 sec) per iteration: 0.0981661 sec for 3130 iterations mean: 31.005152 usec stddev: 0.230212 usec relative stddev: 0.74% RESULT TestThreadWakeup: x86-64_nnacl_newlib_static= {31.005152, 0.230212} us Measuring clock cycles: first runs (cycles): 87225 88473 98289 95376 91794 105633 88053 101745 107145 126720 ... slowest runs (cycles): ... 91965 94680 95376 95619 98289 98601 101745 105633 107145 126720 min: 77202 cycles q1: 81627 cycles median: 82113 cycles q3: 82968 cycles max: 126720 cycles RESULT TestThreadWakeup_CycleCount: x86-64_nnacl_newlib_static= {82113, 1341} count Apparent clock speed: 2648 MHz TestCatchingFault: Measuring real time: 33.484 usec (3.34842e-05 sec) per iteration: 0.00334842 sec for 100 iterations 31.457 usec (3.14567e-05 sec) per iteration: 0.0939296 sec for 2986 iterations 31.977 usec (3.19773e-05 sec) per iteration: 0.0954842 sec for 2986 iterations 31.058 usec (3.10581e-05 sec) per iteration: 0.0927394 sec for 2986 iterations 30.959 usec (3.09587e-05 sec) per iteration: 0.0924426 sec for 2986 iterations 31.407 usec (3.14068e-05 sec) per iteration: 0.0937807 sec for 2986 iterations mean: 31.371493 usec stddev: 0.358926 usec relative stddev: 1.14% RESULT TestCatchingFault: x86-64_nnacl_newlib_static= {31.371493, 0.358926} us Measuring clock cycles: first runs (cycles): 102129 86322 85602 84867 98628 130332 161403 123075 99756 95322 ... slowest runs (cycles): ... 98229 98628 99138 99756 102129 108372 108471 123075 130332 161403 min: 81858 cycles q1: 83277 cycles median: 84009 cycles q3: 84729 cycles max: 161403 cycles RESULT TestCatchingFault_CycleCount: x86-64_nnacl_newlib_static= {84009, 1452} count Apparent clock speed: 2678 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 66.429 usec (6.64289e-05 sec) per iteration: 0.00664289 sec for 100 iterations 65.051 usec (6.50509e-05 sec) per iteration: 0.0979015 sec for 1505 iterations 64.281 usec (6.42814e-05 sec) per iteration: 0.0967435 sec for 1505 iterations 64.284 usec (6.42839e-05 sec) per iteration: 0.0967472 sec for 1505 iterations 65.281 usec (6.52814e-05 sec) per iteration: 0.0982485 sec for 1505 iterations 65.049 usec (6.50488e-05 sec) per iteration: 0.0978984 sec for 1505 iterations mean: 64.789267 usec stddev: 0.422212 usec relative stddev: 0.65% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_nnacl_newlib_static= {64.789267, 0.422212} us Measuring clock cycles: first runs (cycles): 169959 206814 170697 224619 223044 213603 187278 180597 171018 171810 ... slowest runs (cycles): ... 192579 196251 202041 206814 209616 210921 211614 213603 223044 224619 min: 168333 cycles q1: 170370 cycles median: 171303 cycles q3: 172707 cycles max: 224619 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_nnacl_newlib_static= {171303, 2337} count Apparent clock speed: 2644 MHz [ OK ] nacl_newlib.run_performance_test (6530 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