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 = 1550174907, usec = 305576 } Thu Feb 14 12:08:27 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 [34780,2579817344:15:42:19.846314] Native Client module will be loaded at base address 0x000028fc00000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_nnacl_newlib_static= {0.066180, 0.001170} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test (840 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 (66360 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 [34785,2579817344:15:42:34.164924] Native Client module will be loaded at base address 0x000047ff00000000 TestNull: Measuring real time: 0.012 usec (1.165e-08 sec) per iteration: 1.165e-06 sec for 100 iterations 0.002 usec (2.349e-09 sec) per iteration: 2.349e-06 sec for 1000 iterations 0.002 usec (2.0284e-09 sec) per iteration: 2.0284e-05 sec for 10000 iterations 0.002 usec (2.02878e-09 sec) per iteration: 0.100019 sec for 49299940 iterations 0.002 usec (2.01657e-09 sec) per iteration: 0.0994166 sec for 49299940 iterations 0.002 usec (2.00344e-09 sec) per iteration: 0.0987696 sec for 49299940 iterations 0.002 usec (2.13428e-09 sec) per iteration: 0.10522 sec for 49299940 iterations 0.002 usec (2.00173e-09 sec) per iteration: 0.098685 sec for 49299940 iterations mean: 0.002037 usec stddev: 0.000050 usec relative stddev: 2.44% RESULT TestNull: x86-64_nnacl_newlib_static= {0.002037, 0.000050} us Measuring clock cycles: first runs (cycles): 216 21 18 21 36 21 36 21 21 21 ... slowest runs (cycles): ... 36 36 36 36 36 36 36 36 36 216 min: 18 cycles q1: 21 cycles median: 21 cycles q3: 21 cycles max: 216 cycles RESULT TestNull_CycleCount: x86-64_nnacl_newlib_static= {21, 0} count Apparent clock speed: 10309 MHz TestNaClSyscall: Measuring real time: 0.154 usec (1.5359e-07 sec) per iteration: 1.5359e-05 sec for 100 iterations 0.158 usec (1.57823e-07 sec) per iteration: 0.102756 sec for 651084 iterations 0.157 usec (1.56854e-07 sec) per iteration: 0.102125 sec for 651084 iterations 0.156 usec (1.5635e-07 sec) per iteration: 0.101797 sec for 651084 iterations 0.156 usec (1.56298e-07 sec) per iteration: 0.101763 sec for 651084 iterations 0.162 usec (1.62466e-07 sec) per iteration: 0.105779 sec for 651084 iterations mean: 0.157958 usec stddev: 0.002319 usec relative stddev: 1.47% RESULT TestNaClSyscall: x86-64_nnacl_newlib_static= {0.157958, 0.002319} us Measuring clock cycles: first runs (cycles): 837 312 306 297 297 291 291 291 291 291 ... slowest runs (cycles): ... 300 300 300 300 300 300 303 306 312 837 min: 288 cycles q1: 291 cycles median: 291 cycles q3: 300 cycles max: 837 cycles RESULT TestNaClSyscall_CycleCount: x86-64_nnacl_newlib_static= {291, 9} count Apparent clock speed: 1842 MHz TestSetjmpLongjmp: Measuring real time: 0.017 usec (1.654e-08 sec) per iteration: 1.654e-06 sec for 100 iterations 0.012 usec (1.2387e-08 sec) per iteration: 1.2387e-05 sec for 1000 iterations 0.012 usec (1.21078e-08 sec) per iteration: 0.0977461 sec for 8072979 iterations 0.012 usec (1.20989e-08 sec) per iteration: 0.097674 sec for 8072979 iterations 0.013 usec (1.26349e-08 sec) per iteration: 0.102001 sec for 8072979 iterations 0.012 usec (1.24806e-08 sec) per iteration: 0.100756 sec for 8072979 iterations 0.012 usec (1.20974e-08 sec) per iteration: 0.097662 sec for 8072979 iterations mean: 0.012284 usec stddev: 0.000229 usec relative stddev: 1.86% RESULT TestSetjmpLongjmp: x86-64_nnacl_newlib_static= {0.012284, 0.000229} us Measuring clock cycles: first runs (cycles): 432 57 54 27 33 33 33 33 24 24 ... slowest runs (cycles): ... 33 33 33 33 33 33 33 54 57 432 min: 24 cycles q1: 24 cycles median: 33 cycles q3: 33 cycles max: 432 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_nnacl_newlib_static= {33, 9} count Apparent clock speed: 2686 MHz TestClockGetTime: Measuring real time: 0.163 usec (1.6326e-07 sec) per iteration: 1.6326e-05 sec for 100 iterations 0.181 usec (1.80872e-07 sec) per iteration: 0.110788 sec for 612519 iterations 0.192 usec (1.91676e-07 sec) per iteration: 0.117405 sec for 612519 iterations 0.166 usec (1.66113e-07 sec) per iteration: 0.101747 sec for 612519 iterations 0.218 usec (2.17987e-07 sec) per iteration: 0.133521 sec for 612519 iterations 0.185 usec (1.8527e-07 sec) per iteration: 0.113481 sec for 612519 iterations mean: 0.188384 usec stddev: 0.017025 usec relative stddev: 9.04% RESULT TestClockGetTime: x86-64_nnacl_newlib_static= {0.188384, 0.017025} us Measuring clock cycles: first runs (cycles): 1596 453 438 438 438 444 438 438 438 435 ... slowest runs (cycles): ... 447 447 447 447 447 447 447 450 453 1596 min: 435 cycles q1: 438 cycles median: 438 cycles q3: 438 cycles max: 1596 cycles RESULT TestClockGetTime_CycleCount: x86-64_nnacl_newlib_static= {438, 0} count Apparent clock speed: 2325 MHz TestTlsVariable: Measuring real time: 0.015 usec (1.498e-08 sec) per iteration: 1.498e-06 sec for 100 iterations 0.010 usec (9.943e-09 sec) per iteration: 9.943e-06 sec for 1000 iterations 0.009 usec (8.6019e-09 sec) per iteration: 8.6019e-05 sec for 10000 iterations 0.006 usec (6.06978e-09 sec) per iteration: 0.0705632 sec for 11625338 iterations 0.009 usec (8.79239e-09 sec) per iteration: 0.102215 sec for 11625338 iterations 0.009 usec (8.90915e-09 sec) per iteration: 0.103572 sec for 11625338 iterations 0.006 usec (6.43294e-09 sec) per iteration: 0.0747851 sec for 11625338 iterations 0.006 usec (6.10397e-09 sec) per iteration: 0.0709607 sec for 11625338 iterations mean: 0.007262 usec stddev: 0.001304 usec relative stddev: 17.96% RESULT TestTlsVariable: x86-64_nnacl_newlib_static= {0.007262, 0.001304} us Measuring clock cycles: first runs (cycles): 333 66 30 21 24 27 21 21 24 21 ... slowest runs (cycles): ... 30 30 30 30 30 33 33 33 66 333 min: 18 cycles q1: 21 cycles median: 21 cycles q3: 24 cycles max: 333 cycles RESULT TestTlsVariable_CycleCount: x86-64_nnacl_newlib_static= {21, 3} count Apparent clock speed: 2892 MHz TestMmapAnonymous: Measuring real time: 10.802 usec (1.0802e-05 sec) per iteration: 0.0010802 sec for 100 iterations 9.012 usec (9.01245e-06 sec) per iteration: 0.0834283 sec for 9257 iterations 8.496 usec (8.49594e-06 sec) per iteration: 0.0786469 sec for 9257 iterations 8.446 usec (8.44584e-06 sec) per iteration: 0.0781832 sec for 9257 iterations 8.479 usec (8.47929e-06 sec) per iteration: 0.0784928 sec for 9257 iterations 8.974 usec (8.97411e-06 sec) per iteration: 0.0830734 sec for 9257 iterations mean: 8.681526 usec stddev: 0.255347 usec relative stddev: 2.94% RESULT TestMmapAnonymous: x86-64_nnacl_newlib_static= {8.681526, 0.255347} us Measuring clock cycles: first runs (cycles): 33321 23973 22899 22677 22953 22698 22647 22653 22686 22596 ... slowest runs (cycles): ... 39375 39453 39528 39735 39879 40065 40176 40389 41163 42078 min: 22512 cycles q1: 22779 cycles median: 23049 cycles q3: 34206 cycles max: 42078 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_nnacl_newlib_static= {23049, 11427} count Apparent clock speed: 2655 MHz TestAtomicIncrement: Measuring real time: 0.016 usec (1.607e-08 sec) per iteration: 1.607e-06 sec for 100 iterations 0.011 usec (1.0515e-08 sec) per iteration: 1.0515e-05 sec for 1000 iterations 0.010 usec (9.83475e-09 sec) per iteration: 0.0935307 sec for 9510223 iterations 0.010 usec (9.7377e-09 sec) per iteration: 0.0926077 sec for 9510223 iterations 0.010 usec (9.71831e-09 sec) per iteration: 0.0924233 sec for 9510223 iterations 0.010 usec (9.70402e-09 sec) per iteration: 0.0922874 sec for 9510223 iterations 0.010 usec (9.77519e-09 sec) per iteration: 0.0929642 sec for 9510223 iterations mean: 0.009754 usec stddev: 0.000047 usec relative stddev: 0.48% RESULT TestAtomicIncrement: x86-64_nnacl_newlib_static= {0.009754, 0.000047} us Measuring clock cycles: first runs (cycles): 399 57 51 54 51 54 42 51 51 57 ... slowest runs (cycles): ... 72 75 75 75 81 81 87 87 234 399 min: 42 cycles q1: 51 cycles median: 54 cycles q3: 60 cycles max: 399 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_nnacl_newlib_static= {54, 9} count Apparent clock speed: 5536 MHz TestUncontendedMutexLock: Measuring real time: 0.032 usec (3.217e-08 sec) per iteration: 3.217e-06 sec for 100 iterations 0.026 usec (2.6192e-08 sec) per iteration: 2.6192e-05 sec for 1000 iterations 0.023 usec (2.31957e-08 sec) per iteration: 0.0885603 sec for 3817959 iterations 0.023 usec (2.31462e-08 sec) per iteration: 0.0883711 sec for 3817959 iterations 0.023 usec (2.31163e-08 sec) per iteration: 0.0882571 sec for 3817959 iterations 0.023 usec (2.31676e-08 sec) per iteration: 0.0884528 sec for 3817959 iterations 0.023 usec (2.30748e-08 sec) per iteration: 0.0880987 sec for 3817959 iterations mean: 0.023140 usec stddev: 0.000042 usec relative stddev: 0.18% RESULT TestUncontendedMutexLock: x86-64_nnacl_newlib_static= {0.023140, 0.000042} us Measuring clock cycles: first runs (cycles): 429 111 81 81 81 81 81 81 81 81 ... slowest runs (cycles): ... 90 90 90 90 90 90 90 90 111 429 min: 78 cycles q1: 81 cycles median: 87 cycles q3: 90 cycles max: 429 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_nnacl_newlib_static= {87, 9} count Apparent clock speed: 3760 MHz TestCondvarSignalNoOp: Measuring real time: 0.313 usec (3.1314e-07 sec) per iteration: 3.1314e-05 sec for 100 iterations 0.164 usec (1.63525e-07 sec) per iteration: 0.052221 sec for 319345 iterations 0.163 usec (1.63195e-07 sec) per iteration: 0.0521156 sec for 319345 iterations 0.163 usec (1.63233e-07 sec) per iteration: 0.0521277 sec for 319345 iterations 0.164 usec (1.6412e-07 sec) per iteration: 0.052411 sec for 319345 iterations 0.163 usec (1.6334e-07 sec) per iteration: 0.0521618 sec for 319345 iterations mean: 0.163483 usec stddev: 0.000339 usec relative stddev: 0.21% RESULT TestCondvarSignalNoOp: x86-64_nnacl_newlib_static= {0.163483, 0.000339} us Measuring clock cycles: first runs (cycles): 1230 432 432 432 432 432 432 432 432 432 ... slowest runs (cycles): ... 435 435 435 435 435 435 435 438 444 1230 min: 420 cycles q1: 432 cycles median: 432 cycles q3: 432 cycles max: 1230 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_nnacl_newlib_static= {432, 0} count Apparent clock speed: 2642 MHz TestThreadCreateAndJoin: Measuring real time: 68.246 usec (6.82464e-05 sec) per iteration: 0.00682464 sec for 100 iterations 83.298 usec (8.32977e-05 sec) per iteration: 0.122031 sec for 1465 iterations 77.152 usec (7.71522e-05 sec) per iteration: 0.113028 sec for 1465 iterations 81.033 usec (8.10335e-05 sec) per iteration: 0.118714 sec for 1465 iterations 85.933 usec (8.59329e-05 sec) per iteration: 0.125892 sec for 1465 iterations 81.149 usec (8.11486e-05 sec) per iteration: 0.118883 sec for 1465 iterations mean: 81.712964 usec stddev: 2.894807 usec relative stddev: 3.54% RESULT TestThreadCreateAndJoin: x86-64_nnacl_newlib_static= {81.712964, 2.894807} us Measuring clock cycles: first runs (cycles): 253065 262452 235269 245292 218847 284268 208320 170130 177588 169206 ... slowest runs (cycles): ... 207477 208320 217812 218847 228534 235269 245292 253065 262452 284268 min: 166335 cycles q1: 169518 cycles median: 172062 cycles q3: 198228 cycles max: 284268 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_nnacl_newlib_static= {172062, 28710} count Apparent clock speed: 2106 MHz TestThreadWakeup: Measuring real time: 41.234 usec (4.1234e-05 sec) per iteration: 0.0041234 sec for 100 iterations 34.246 usec (3.42456e-05 sec) per iteration: 0.0830455 sec for 2425 iterations 30.983 usec (3.09825e-05 sec) per iteration: 0.0751327 sec for 2425 iterations 31.689 usec (3.16892e-05 sec) per iteration: 0.0768463 sec for 2425 iterations 29.950 usec (2.99505e-05 sec) per iteration: 0.0726299 sec for 2425 iterations 32.169 usec (3.21687e-05 sec) per iteration: 0.078009 sec for 2425 iterations mean: 31.807286 usec stddev: 1.429528 usec relative stddev: 4.49% RESULT TestThreadWakeup: x86-64_nnacl_newlib_static= {31.807286, 1.429528} us Measuring clock cycles: first runs (cycles): 102188 95212 93728 92936 95417 188751 107653 104148 120324 124631 ... slowest runs (cycles): ... 102188 104148 107653 111167 119088 119871 120324 124631 145084 188751 min: 78492 cycles q1: 80028 cycles median: 80452 cycles q3: 81068 cycles max: 188751 cycles RESULT TestThreadWakeup_CycleCount: x86-64_nnacl_newlib_static= {80452, 1040} count Apparent clock speed: 2529 MHz TestCatchingFault: Measuring real time: 57.190 usec (5.71895e-05 sec) per iteration: 0.00571895 sec for 100 iterations 32.112 usec (3.21124e-05 sec) per iteration: 0.0561325 sec for 1748 iterations 32.548 usec (3.25483e-05 sec) per iteration: 0.0568944 sec for 1748 iterations 35.438 usec (3.54383e-05 sec) per iteration: 0.0619461 sec for 1748 iterations 31.531 usec (3.1531e-05 sec) per iteration: 0.0551162 sec for 1748 iterations 33.124 usec (3.31243e-05 sec) per iteration: 0.0579013 sec for 1748 iterations mean: 32.950864 usec stddev: 1.348953 usec relative stddev: 4.09% RESULT TestCatchingFault: x86-64_nnacl_newlib_static= {32.950864, 1.348953} us Measuring clock cycles: first runs (cycles): 107742 86238 84372 83391 82560 82662 113208 188376 163308 227772 ... slowest runs (cycles): ... 136719 137739 143301 143643 152877 163308 185157 185301 188376 227772 min: 82560 cycles q1: 119283 cycles median: 120045 cycles q3: 121326 cycles max: 227772 cycles RESULT TestCatchingFault_CycleCount: x86-64_nnacl_newlib_static= {120045, 2043} count Apparent clock speed: 3643 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 89.807 usec (8.98067e-05 sec) per iteration: 0.00898068 sec for 100 iterations 79.472 usec (7.94715e-05 sec) per iteration: 0.0884518 sec for 1113 iterations 77.202 usec (7.72019e-05 sec) per iteration: 0.0859257 sec for 1113 iterations 80.055 usec (8.00554e-05 sec) per iteration: 0.0891017 sec for 1113 iterations 75.055 usec (7.50554e-05 sec) per iteration: 0.0835367 sec for 1113 iterations 83.464 usec (8.34644e-05 sec) per iteration: 0.0928959 sec for 1113 iterations mean: 79.049738 usec stddev: 2.830129 usec relative stddev: 3.58% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_nnacl_newlib_static= {79.049738, 2.830129} us Measuring clock cycles: first runs (cycles): 259872 242439 292122 239373 182130 187590 212409 174711 172437 168345 ... slowest runs (cycles): ... 174711 182130 187590 187956 212409 228930 239373 242439 259872 292122 min: 165444 cycles q1: 167115 cycles median: 167973 cycles q3: 169071 cycles max: 292122 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_nnacl_newlib_static= {167973, 1956} count Apparent clock speed: 2125 MHz [ OK ] nacl_newlib.run_performance_test (6150 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