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: 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 = 1554554965, usec = 343233 } Sat Apr 6 05:49:25 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 [32493,2646807424:08:07:09.497047] Native Client module will be loaded at base address 0x0000653a00000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_nnacl_newlib_static= {0.066190, 0.002320} 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/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 (66070 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= 524.896 KB RESULT hello_world_size.out: ZIPPED_x86-64_nnacl_newlib_static= 187.825 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 [32503,2646807424:08:07:23.605058] Native Client module will be loaded at base address 0x00001ac800000000 TestNull: Measuring real time: 0.008 usec (7.92e-09 sec) per iteration: 7.92e-07 sec for 100 iterations 0.002 usec (2.282e-09 sec) per iteration: 2.282e-06 sec for 1000 iterations 0.002 usec (2.0291e-09 sec) per iteration: 2.0291e-05 sec for 10000 iterations 0.002 usec (2.00028e-09 sec) per iteration: 0.0985797 sec for 49282933 iterations 0.002 usec (1.95868e-09 sec) per iteration: 0.0965295 sec for 49282933 iterations 0.002 usec (1.95778e-09 sec) per iteration: 0.096485 sec for 49282933 iterations 0.002 usec (1.93388e-09 sec) per iteration: 0.0953073 sec for 49282933 iterations 0.002 usec (1.97441e-09 sec) per iteration: 0.0973047 sec for 49282933 iterations mean: 0.001965 usec stddev: 0.000022 usec relative stddev: 1.11% RESULT TestNull: x86-64_nnacl_newlib_static= {0.001965, 0.000022} us Measuring clock cycles: first runs (cycles): 186 21 30 21 18 21 18 21 18 30 ... slowest runs (cycles): ... 30 30 30 30 30 30 30 30 30 186 min: 18 cycles q1: 18 cycles median: 21 cycles q3: 21 cycles max: 186 cycles RESULT TestNull_CycleCount: x86-64_nnacl_newlib_static= {21, 3} count Apparent clock speed: 10687 MHz TestNaClSyscall: Measuring real time: 0.152 usec (1.5162e-07 sec) per iteration: 1.5162e-05 sec for 100 iterations 0.151 usec (1.50701e-07 sec) per iteration: 0.0993941 sec for 659543 iterations 0.158 usec (1.57598e-07 sec) per iteration: 0.103943 sec for 659543 iterations 0.158 usec (1.57978e-07 sec) per iteration: 0.104193 sec for 659543 iterations 0.163 usec (1.63297e-07 sec) per iteration: 0.107701 sec for 659543 iterations 0.150 usec (1.50153e-07 sec) per iteration: 0.0990321 sec for 659543 iterations mean: 0.155945 usec stddev: 0.004939 usec relative stddev: 3.17% RESULT TestNaClSyscall: x86-64_nnacl_newlib_static= {0.155945, 0.004939} us Measuring clock cycles: first runs (cycles): 639 288 270 261 273 264 264 270 264 264 ... slowest runs (cycles): ... 273 273 273 273 273 273 273 279 288 639 min: 261 cycles q1: 261 cycles median: 264 cycles q3: 270 cycles max: 639 cycles RESULT TestNaClSyscall_CycleCount: x86-64_nnacl_newlib_static= {264, 9} count Apparent clock speed: 1693 MHz TestSetjmpLongjmp: Measuring real time: 0.016 usec (1.57e-08 sec) per iteration: 1.57e-06 sec for 100 iterations 0.012 usec (1.1996e-08 sec) per iteration: 1.1996e-05 sec for 1000 iterations 0.012 usec (1.16507e-08 sec) per iteration: 0.0971212 sec for 8336112 iterations 0.012 usec (1.19999e-08 sec) per iteration: 0.100033 sec for 8336112 iterations 0.012 usec (1.18333e-08 sec) per iteration: 0.0986436 sec for 8336112 iterations 0.012 usec (1.17228e-08 sec) per iteration: 0.0977224 sec for 8336112 iterations 0.012 usec (1.16937e-08 sec) per iteration: 0.0974802 sec for 8336112 iterations mean: 0.011780 usec stddev: 0.000125 usec relative stddev: 1.06% RESULT TestSetjmpLongjmp: x86-64_nnacl_newlib_static= {0.011780, 0.000125} us Measuring clock cycles: first runs (cycles): 423 69 30 33 33 24 27 24 36 33 ... slowest runs (cycles): ... 36 36 36 36 36 36 36 36 69 423 min: 24 cycles q1: 24 cycles median: 33 cycles q3: 33 cycles max: 423 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_nnacl_newlib_static= {33, 9} count Apparent clock speed: 2801 MHz TestClockGetTime: Measuring real time: 0.177 usec (1.7675e-07 sec) per iteration: 1.7675e-05 sec for 100 iterations 0.164 usec (1.64107e-07 sec) per iteration: 0.0928468 sec for 565770 iterations 0.169 usec (1.69487e-07 sec) per iteration: 0.0958907 sec for 565770 iterations 0.170 usec (1.70187e-07 sec) per iteration: 0.0962866 sec for 565770 iterations 0.166 usec (1.66204e-07 sec) per iteration: 0.0940335 sec for 565770 iterations 0.171 usec (1.70758e-07 sec) per iteration: 0.0966097 sec for 565770 iterations mean: 0.168149 usec stddev: 0.002564 usec relative stddev: 1.52% RESULT TestClockGetTime: x86-64_nnacl_newlib_static= {0.168149, 0.002564} us Measuring clock cycles: first runs (cycles): 1332 498 471 471 474 480 471 477 477 477 ... slowest runs (cycles): ... 480 480 480 480 480 480 480 480 498 1332 min: 465 cycles q1: 474 cycles median: 477 cycles q3: 477 cycles max: 1332 cycles RESULT TestClockGetTime_CycleCount: x86-64_nnacl_newlib_static= {477, 3} count Apparent clock speed: 2837 MHz TestTlsVariable: Measuring real time: 0.010 usec (9.76e-09 sec) per iteration: 9.76e-07 sec for 100 iterations 0.006 usec (6.308e-09 sec) per iteration: 6.308e-06 sec for 1000 iterations 0.006 usec (6.0309e-09 sec) per iteration: 6.0309e-05 sec for 10000 iterations 0.006 usec (5.95853e-09 sec) per iteration: 0.0987999 sec for 16581273 iterations 0.006 usec (5.67694e-09 sec) per iteration: 0.0941309 sec for 16581273 iterations 0.006 usec (6.34478e-09 sec) per iteration: 0.105204 sec for 16581273 iterations 0.006 usec (5.89507e-09 sec) per iteration: 0.0977478 sec for 16581273 iterations 0.006 usec (6.13248e-09 sec) per iteration: 0.101684 sec for 16581273 iterations mean: 0.006002 usec stddev: 0.000225 usec relative stddev: 3.75% RESULT TestTlsVariable: x86-64_nnacl_newlib_static= {0.006002, 0.000225} us Measuring clock cycles: first runs (cycles): 339 78 27 30 30 30 21 21 21 21 ... slowest runs (cycles): ... 30 30 30 30 30 30 30 30 78 339 min: 21 cycles q1: 21 cycles median: 27 cycles q3: 30 cycles max: 339 cycles RESULT TestTlsVariable_CycleCount: x86-64_nnacl_newlib_static= {27, 9} count Apparent clock speed: 4499 MHz TestMmapAnonymous: Measuring real time: 8.730 usec (8.73047e-06 sec) per iteration: 0.000873047 sec for 100 iterations 9.045 usec (9.04451e-06 sec) per iteration: 0.103596 sec for 11454 iterations 8.564 usec (8.56437e-06 sec) per iteration: 0.0980963 sec for 11454 iterations 8.918 usec (8.91827e-06 sec) per iteration: 0.10215 sec for 11454 iterations 9.126 usec (9.12642e-06 sec) per iteration: 0.104534 sec for 11454 iterations 8.949 usec (8.94926e-06 sec) per iteration: 0.102505 sec for 11454 iterations mean: 8.920565 usec stddev: 0.192584 usec relative stddev: 2.16% RESULT TestMmapAnonymous: x86-64_nnacl_newlib_static= {8.920565, 0.192584} us Measuring clock cycles: first runs (cycles): 33633 23973 23352 23334 23520 23349 23418 23544 23397 23643 ... slowest runs (cycles): ... 23643 23676 23715 23736 23838 23934 23961 23973 23979 33633 min: 23028 cycles q1: 23115 cycles median: 23256 cycles q3: 23418 cycles max: 33633 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_nnacl_newlib_static= {23256, 303} count Apparent clock speed: 2607 MHz TestAtomicIncrement: Measuring real time: 0.014 usec (1.408e-08 sec) per iteration: 1.408e-06 sec for 100 iterations 0.010 usec (9.911e-09 sec) per iteration: 9.911e-06 sec for 1000 iterations 0.010 usec (9.6902e-09 sec) per iteration: 9.6902e-05 sec for 10000 iterations 0.010 usec (9.57491e-09 sec) per iteration: 0.0988102 sec for 10319704 iterations 0.009 usec (9.16448e-09 sec) per iteration: 0.0945747 sec for 10319704 iterations 0.009 usec (9.41077e-09 sec) per iteration: 0.0971164 sec for 10319704 iterations 0.009 usec (9.40187e-09 sec) per iteration: 0.0970245 sec for 10319704 iterations 0.009 usec (9.1176e-09 sec) per iteration: 0.0940909 sec for 10319704 iterations mean: 0.009334 usec stddev: 0.000170 usec relative stddev: 1.82% RESULT TestAtomicIncrement: x86-64_nnacl_newlib_static= {0.009334, 0.000170} us Measuring clock cycles: first runs (cycles): 207 39 48 42 51 42 42 51 42 51 ... slowest runs (cycles): ... 51 51 51 51 51 51 51 51 75 207 min: 39 cycles q1: 42 cycles median: 42 cycles q3: 51 cycles max: 207 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_nnacl_newlib_static= {42, 9} count Apparent clock speed: 4500 MHz TestUncontendedMutexLock: Measuring real time: 0.028 usec (2.821e-08 sec) per iteration: 2.821e-06 sec for 100 iterations 0.023 usec (2.3258e-08 sec) per iteration: 2.3258e-05 sec for 1000 iterations 0.022 usec (2.15205e-08 sec) per iteration: 0.0925294 sec for 4299595 iterations 0.023 usec (2.30064e-08 sec) per iteration: 0.0989181 sec for 4299595 iterations 0.022 usec (2.21452e-08 sec) per iteration: 0.0952153 sec for 4299595 iterations 0.023 usec (2.29346e-08 sec) per iteration: 0.0986094 sec for 4299595 iterations 0.023 usec (2.29059e-08 sec) per iteration: 0.0984861 sec for 4299595 iterations mean: 0.022503 usec stddev: 0.000582 usec relative stddev: 2.59% RESULT TestUncontendedMutexLock: x86-64_nnacl_newlib_static= {0.022503, 0.000582} us Measuring clock cycles: first runs (cycles): 411 111 90 90 90 90 90 90 90 81 ... slowest runs (cycles): ... 90 90 90 90 90 90 90 90 111 411 min: 78 cycles q1: 81 cycles median: 90 cycles q3: 90 cycles max: 411 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_nnacl_newlib_static= {90, 9} count Apparent clock speed: 4000 MHz TestCondvarSignalNoOp: Measuring real time: 0.256 usec (2.5578e-07 sec) per iteration: 2.5578e-05 sec for 100 iterations 0.158 usec (1.57863e-07 sec) per iteration: 0.0617182 sec for 390960 iterations 0.170 usec (1.69535e-07 sec) per iteration: 0.0662815 sec for 390960 iterations 0.175 usec (1.75365e-07 sec) per iteration: 0.0685609 sec for 390960 iterations 0.160 usec (1.59677e-07 sec) per iteration: 0.0624273 sec for 390960 iterations 0.160 usec (1.60364e-07 sec) per iteration: 0.062696 sec for 390960 iterations mean: 0.164561 usec stddev: 0.006750 usec relative stddev: 4.10% RESULT TestCondvarSignalNoOp: x86-64_nnacl_newlib_static= {0.164561, 0.006750} us Measuring clock cycles: first runs (cycles): 1404 438 435 432 429 432 432 432 432 432 ... slowest runs (cycles): ... 435 435 435 438 438 438 438 438 444 1404 min: 429 cycles q1: 432 cycles median: 432 cycles q3: 432 cycles max: 1404 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_nnacl_newlib_static= {432, 0} count Apparent clock speed: 2625 MHz TestThreadCreateAndJoin: Measuring real time: 65.186 usec (6.51864e-05 sec) per iteration: 0.00651864 sec for 100 iterations 67.189 usec (6.71889e-05 sec) per iteration: 0.103068 sec for 1534 iterations 61.842 usec (6.18416e-05 sec) per iteration: 0.0948649 sec for 1534 iterations 61.595 usec (6.15954e-05 sec) per iteration: 0.0944873 sec for 1534 iterations 61.908 usec (6.1908e-05 sec) per iteration: 0.0949669 sec for 1534 iterations 61.424 usec (6.14242e-05 sec) per iteration: 0.0942248 sec for 1534 iterations mean: 62.791613 usec stddev: 2.205453 usec relative stddev: 3.51% RESULT TestThreadCreateAndJoin: x86-64_nnacl_newlib_static= {62.791613, 2.205453} us Measuring clock cycles: first runs (cycles): 202707 174144 230139 197994 190362 191070 173709 169890 166764 168240 ... slowest runs (cycles): ... 190362 191070 191367 197994 198624 202707 205917 210174 214602 230139 min: 159093 cycles q1: 167817 cycles median: 169389 cycles q3: 173286 cycles max: 230139 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_nnacl_newlib_static= {169389, 5469} count Apparent clock speed: 2698 MHz TestThreadWakeup: Measuring real time: 31.031 usec (3.10314e-05 sec) per iteration: 0.00310314 sec for 100 iterations 32.583 usec (3.25825e-05 sec) per iteration: 0.104981 sec for 3222 iterations 30.503 usec (3.05031e-05 sec) per iteration: 0.0982809 sec for 3222 iterations 30.202 usec (3.02024e-05 sec) per iteration: 0.0973121 sec for 3222 iterations 30.452 usec (3.04521e-05 sec) per iteration: 0.0981166 sec for 3222 iterations 30.089 usec (3.00893e-05 sec) per iteration: 0.0969478 sec for 3222 iterations mean: 30.765878 usec stddev: 0.921190 usec relative stddev: 2.99% RESULT TestThreadWakeup: x86-64_nnacl_newlib_static= {30.765878, 0.921190} us Measuring clock cycles: first runs (cycles): 92883 97233 95322 86082 103854 101055 89865 77847 81705 89037 ... slowest runs (cycles): ... 92883 93291 94659 95322 96384 97233 101055 103854 111813 132927 min: 71826 cycles q1: 82539 cycles median: 83430 cycles q3: 85500 cycles max: 132927 cycles RESULT TestThreadWakeup_CycleCount: x86-64_nnacl_newlib_static= {83430, 2961} count Apparent clock speed: 2712 MHz TestCatchingFault: Measuring real time: 32.147 usec (3.21472e-05 sec) per iteration: 0.00321472 sec for 100 iterations 29.876 usec (2.98758e-05 sec) per iteration: 0.0929136 sec for 3110 iterations 30.325 usec (3.03252e-05 sec) per iteration: 0.0943115 sec for 3110 iterations 29.291 usec (2.92905e-05 sec) per iteration: 0.0910935 sec for 3110 iterations 29.628 usec (2.96279e-05 sec) per iteration: 0.0921429 sec for 3110 iterations 29.550 usec (2.95495e-05 sec) per iteration: 0.091899 sec for 3110 iterations mean: 29.733799 usec stddev: 0.349743 usec relative stddev: 1.18% RESULT TestCatchingFault: x86-64_nnacl_newlib_static= {29.733799, 0.349743} us Measuring clock cycles: first runs (cycles): 103488 96873 130896 170673 133440 106905 119388 123099 97161 82965 ... slowest runs (cycles): ... 103296 103488 106905 109509 109974 119388 123099 130896 133440 170673 min: 79704 cycles q1: 80352 cycles median: 80700 cycles q3: 81834 cycles max: 170673 cycles RESULT TestCatchingFault_CycleCount: x86-64_nnacl_newlib_static= {80700, 1482} count Apparent clock speed: 2714 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 63.291 usec (6.32913e-05 sec) per iteration: 0.00632913 sec for 100 iterations 62.579 usec (6.25785e-05 sec) per iteration: 0.0988115 sec for 1579 iterations 61.169 usec (6.11687e-05 sec) per iteration: 0.0965854 sec for 1579 iterations 61.940 usec (6.19397e-05 sec) per iteration: 0.0978028 sec for 1579 iterations 62.830 usec (6.28299e-05 sec) per iteration: 0.0992084 sec for 1579 iterations 64.515 usec (6.45147e-05 sec) per iteration: 0.101869 sec for 1579 iterations mean: 62.606307 usec stddev: 1.113882 usec relative stddev: 1.78% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_nnacl_newlib_static= {62.606307, 1.113882} us Measuring clock cycles: first runs (cycles): 184242 847125 230229 155709 195177 191208 191181 180258 172080 179031 ... slowest runs (cycles): ... 179031 180258 182100 184242 191181 191208 195177 212979 230229 847125 min: 155709 cycles q1: 168510 cycles median: 169932 cycles q3: 171429 cycles max: 847125 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_nnacl_newlib_static= {169932, 2919} count Apparent clock speed: 2714 MHz [ OK ] nacl_newlib.run_performance_test (6570 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