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.2.0 kern.osrevision: 199506 kern.version: Darwin Kernel Version 17.2.0: Fri Sep 29 18:27:05 PDT 2017; root:xnu-4570.20.62~3/RELEASE_X86_64 kern.posix1version: 200112 kern.osversion: 17B1002 ====================================================================== 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 = 1552934317, usec = 778909 } Mon Mar 18 11:38:37 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 [13586,2972603200:15:20:26.420844] Native Client module will be loaded at base address 0x000008de00000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_nnacl_newlib_static= {0.127770, 0.004060} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test (1290 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 [ OK ] nacl_newlib.run_clock_cputime_test (59060 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 [13592,2972603200:15:20:43.788378] Native Client module will be loaded at base address 0x0000537100000000 TestNull: Measuring real time: 0.004 usec (4.41e-09 sec) per iteration: 4.41e-07 sec for 100 iterations 0.002 usec (1.647e-09 sec) per iteration: 1.647e-06 sec for 1000 iterations 0.001 usec (1.4948e-09 sec) per iteration: 1.4948e-05 sec for 10000 iterations 0.001 usec (1.43876e-09 sec) per iteration: 0.0962508 sec for 66898581 iterations 0.001 usec (1.43253e-09 sec) per iteration: 0.0958344 sec for 66898581 iterations 0.001 usec (1.44534e-09 sec) per iteration: 0.096691 sec for 66898581 iterations 0.001 usec (1.43836e-09 sec) per iteration: 0.0962239 sec for 66898581 iterations 0.001 usec (1.43492e-09 sec) per iteration: 0.0959941 sec for 66898581 iterations mean: 0.001438 usec stddev: 0.000004 usec relative stddev: 0.30% RESULT TestNull: x86-64_nnacl_newlib_static= {0.001438, 0.000004} us Measuring clock cycles: first runs (cycles): 81 33 18 27 18 18 18 27 18 18 ... slowest runs (cycles): ... 27 27 27 27 27 27 27 27 33 81 min: 18 cycles q1: 18 cycles median: 18 cycles q3: 27 cycles max: 81 cycles RESULT TestNull_CycleCount: x86-64_nnacl_newlib_static= {18, 9} count Apparent clock speed: 12518 MHz TestNaClSyscall: Measuring real time: 0.152 usec (1.5194e-07 sec) per iteration: 1.5194e-05 sec for 100 iterations 0.129 usec (1.28888e-07 sec) per iteration: 0.084828 sec for 658154 iterations 0.129 usec (1.28564e-07 sec) per iteration: 0.0846151 sec for 658154 iterations 0.128 usec (1.28227e-07 sec) per iteration: 0.0843929 sec for 658154 iterations 0.129 usec (1.29091e-07 sec) per iteration: 0.084962 sec for 658154 iterations 0.129 usec (1.29062e-07 sec) per iteration: 0.0849424 sec for 658154 iterations mean: 0.128766 usec stddev: 0.000329 usec relative stddev: 0.26% RESULT TestNaClSyscall: x86-64_nnacl_newlib_static= {0.128766, 0.000329} us Measuring clock cycles: first runs (cycles): 369 300 312 312 303 291 288 279 279 288 ... slowest runs (cycles): ... 294 294 294 294 297 300 303 312 312 369 min: 279 cycles q1: 288 cycles median: 288 cycles q3: 291 cycles max: 369 cycles RESULT TestNaClSyscall_CycleCount: x86-64_nnacl_newlib_static= {288, 3} count Apparent clock speed: 2237 MHz TestSetjmpLongjmp: Measuring real time: 0.017 usec (1.745e-08 sec) per iteration: 1.745e-06 sec for 100 iterations 0.013 usec (1.3455e-08 sec) per iteration: 1.3455e-05 sec for 1000 iterations 0.010 usec (9.72156e-09 sec) per iteration: 0.0722524 sec for 7432181 iterations 0.010 usec (9.67512e-09 sec) per iteration: 0.0719072 sec for 7432181 iterations 0.010 usec (9.65838e-09 sec) per iteration: 0.0717828 sec for 7432181 iterations 0.010 usec (9.65353e-09 sec) per iteration: 0.0717468 sec for 7432181 iterations 0.010 usec (9.68878e-09 sec) per iteration: 0.0720088 sec for 7432181 iterations mean: 0.009679 usec stddev: 0.000024 usec relative stddev: 0.25% RESULT TestSetjmpLongjmp: x86-64_nnacl_newlib_static= {0.009679, 0.000024} us Measuring clock cycles: first runs (cycles): 144 24 18 18 21 21 21 21 21 21 ... slowest runs (cycles): ... 30 30 30 30 30 30 30 30 30 144 min: 18 cycles q1: 21 cycles median: 21 cycles q3: 30 cycles max: 144 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_nnacl_newlib_static= {21, 9} count Apparent clock speed: 2170 MHz TestClockGetTime: Measuring real time: 0.213 usec (2.1335e-07 sec) per iteration: 2.1335e-05 sec for 100 iterations 0.141 usec (1.40591e-07 sec) per iteration: 0.0658968 sec for 468713 iterations 0.140 usec (1.40379e-07 sec) per iteration: 0.0657974 sec for 468713 iterations 0.141 usec (1.40935e-07 sec) per iteration: 0.0660583 sec for 468713 iterations 0.140 usec (1.40476e-07 sec) per iteration: 0.0658428 sec for 468713 iterations 0.142 usec (1.41941e-07 sec) per iteration: 0.0665297 sec for 468713 iterations mean: 0.140864 usec stddev: 0.000570 usec relative stddev: 0.40% RESULT TestClockGetTime: x86-64_nnacl_newlib_static= {0.140864, 0.000570} us Measuring clock cycles: first runs (cycles): 645 441 420 417 420 423 432 423 429 429 ... slowest runs (cycles): ... 438 438 438 438 438 438 441 477 495 645 min: 417 cycles q1: 423 cycles median: 426 cycles q3: 429 cycles max: 645 cycles RESULT TestClockGetTime_CycleCount: x86-64_nnacl_newlib_static= {426, 6} count Apparent clock speed: 3024 MHz TestTlsVariable: Measuring real time: 0.008 usec (7.56e-09 sec) per iteration: 7.56e-07 sec for 100 iterations 0.005 usec (4.601e-09 sec) per iteration: 4.601e-06 sec for 1000 iterations 0.010 usec (9.8148e-09 sec) per iteration: 9.8148e-05 sec for 10000 iterations 0.004 usec (4.30462e-09 sec) per iteration: 0.0438585 sec for 10188694 iterations 0.004 usec (4.30614e-09 sec) per iteration: 0.0438739 sec for 10188694 iterations 0.004 usec (4.31045e-09 sec) per iteration: 0.0439179 sec for 10188694 iterations 0.004 usec (4.29851e-09 sec) per iteration: 0.0437962 sec for 10188694 iterations 0.004 usec (4.32795e-09 sec) per iteration: 0.0440961 sec for 10188694 iterations mean: 0.004310 usec stddev: 0.000010 usec relative stddev: 0.23% RESULT TestTlsVariable: x86-64_nnacl_newlib_static= {0.004310, 0.000010} us Measuring clock cycles: first runs (cycles): 207 60 42 45 21 27 18 18 27 18 ... slowest runs (cycles): ... 27 27 27 27 27 27 42 45 60 207 min: 18 cycles q1: 18 cycles median: 18 cycles q3: 27 cycles max: 207 cycles RESULT TestTlsVariable_CycleCount: x86-64_nnacl_newlib_static= {18, 9} count Apparent clock speed: 4177 MHz TestMmapAnonymous: Measuring real time: 4.783 usec (4.78266e-06 sec) per iteration: 0.000478266 sec for 100 iterations 2.655 usec (2.65494e-06 sec) per iteration: 0.0555094 sec for 20908 iterations 2.633 usec (2.63298e-06 sec) per iteration: 0.0550503 sec for 20908 iterations 2.635 usec (2.63502e-06 sec) per iteration: 0.0550929 sec for 20908 iterations 2.633 usec (2.63286e-06 sec) per iteration: 0.0550478 sec for 20908 iterations 2.649 usec (2.64875e-06 sec) per iteration: 0.05538 sec for 20908 iterations mean: 2.640908 usec stddev: 0.009172 usec relative stddev: 0.35% RESULT TestMmapAnonymous: x86-64_nnacl_newlib_static= {2.640908, 0.009172} us Measuring clock cycles: first runs (cycles): 10824 8370 8148 8163 8106 8088 8175 8076 8142 8094 ... slowest runs (cycles): ... 14637 14670 16008 16743 32940 34794 34803 40971 42360 70512 min: 7806 cycles q1: 7896 cycles median: 8091 cycles q3: 11106 cycles max: 70512 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_nnacl_newlib_static= {8091, 3210} count Apparent clock speed: 3064 MHz TestAtomicIncrement: Measuring real time: 0.013 usec (1.252e-08 sec) per iteration: 1.252e-06 sec for 100 iterations 0.009 usec (9.262e-09 sec) per iteration: 9.262e-06 sec for 1000 iterations 0.009 usec (9.1431e-09 sec) per iteration: 9.1431e-05 sec for 10000 iterations 0.007 usec (7.47644e-09 sec) per iteration: 0.0817714 sec for 10937209 iterations 0.007 usec (7.46967e-09 sec) per iteration: 0.0816973 sec for 10937209 iterations 0.008 usec (7.50093e-09 sec) per iteration: 0.0820393 sec for 10937209 iterations 0.007 usec (7.49179e-09 sec) per iteration: 0.0819393 sec for 10937209 iterations 0.007 usec (7.46356e-09 sec) per iteration: 0.0816305 sec for 10937209 iterations mean: 0.007480 usec stddev: 0.000014 usec relative stddev: 0.19% RESULT TestAtomicIncrement: x86-64_nnacl_newlib_static= {0.007480, 0.000014} us Measuring clock cycles: first runs (cycles): 132 48 48 39 39 48 54 48 48 48 ... slowest runs (cycles): ... 48 48 48 48 48 48 48 48 54 132 min: 39 cycles q1: 48 cycles median: 48 cycles q3: 48 cycles max: 132 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_nnacl_newlib_static= {48, 0} count Apparent clock speed: 6417 MHz TestUncontendedMutexLock: Measuring real time: 0.028 usec (2.763e-08 sec) per iteration: 2.763e-06 sec for 100 iterations 0.024 usec (2.4289e-08 sec) per iteration: 2.4289e-05 sec for 1000 iterations 0.019 usec (1.87437e-08 sec) per iteration: 0.0771695 sec for 4117090 iterations 0.019 usec (1.8717e-08 sec) per iteration: 0.0770596 sec for 4117090 iterations 0.019 usec (1.87166e-08 sec) per iteration: 0.0770579 sec for 4117090 iterations 0.019 usec (1.90771e-08 sec) per iteration: 0.0785421 sec for 4117090 iterations 0.019 usec (1.87033e-08 sec) per iteration: 0.0770031 sec for 4117090 iterations mean: 0.018792 usec stddev: 0.000143 usec relative stddev: 0.76% RESULT TestUncontendedMutexLock: x86-64_nnacl_newlib_static= {0.018792, 0.000143} us Measuring clock cycles: first runs (cycles): 243 105 75 78 84 78 75 87 75 78 ... slowest runs (cycles): ... 87 87 87 87 87 87 87 87 105 243 min: 75 cycles q1: 75 cycles median: 78 cycles q3: 84 cycles max: 243 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_nnacl_newlib_static= {78, 9} count Apparent clock speed: 4151 MHz TestCondvarSignalNoOp: Measuring real time: 1.147 usec (1.14695e-06 sec) per iteration: 0.000114695 sec for 100 iterations 0.136 usec (1.36068e-07 sec) per iteration: 0.0118633 sec for 87187 iterations 0.136 usec (1.36213e-07 sec) per iteration: 0.011876 sec for 87187 iterations 0.136 usec (1.35554e-07 sec) per iteration: 0.0118185 sec for 87187 iterations 0.138 usec (1.37896e-07 sec) per iteration: 0.0120227 sec for 87187 iterations 0.136 usec (1.35948e-07 sec) per iteration: 0.0118529 sec for 87187 iterations mean: 0.136336 usec stddev: 0.000810 usec relative stddev: 0.59% RESULT TestCondvarSignalNoOp: x86-64_nnacl_newlib_static= {0.136336, 0.000810} us Measuring clock cycles: first runs (cycles): 582 420 414 411 414 405 396 402 414 408 ... slowest runs (cycles): ... 411 411 411 411 414 414 414 414 420 582 min: 396 cycles q1: 405 cycles median: 408 cycles q3: 411 cycles max: 582 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_nnacl_newlib_static= {408, 6} count Apparent clock speed: 2993 MHz TestThreadCreateAndJoin: Measuring real time: 34.267 usec (3.42669e-05 sec) per iteration: 0.00342669 sec for 100 iterations 19.794 usec (1.97936e-05 sec) per iteration: 0.0577576 sec for 2918 iterations 21.230 usec (2.12297e-05 sec) per iteration: 0.0619481 sec for 2918 iterations 21.685 usec (2.16851e-05 sec) per iteration: 0.063277 sec for 2918 iterations 21.619 usec (2.16192e-05 sec) per iteration: 0.0630848 sec for 2918 iterations 21.772 usec (2.17723e-05 sec) per iteration: 0.0635315 sec for 2918 iterations mean: 21.219951 usec stddev: 0.736952 usec relative stddev: 3.47% RESULT TestThreadCreateAndJoin: x86-64_nnacl_newlib_static= {21.219951, 0.736952} us Measuring clock cycles: first runs (cycles): 55074 65952 67617 59709 171441 174687 163683 90183 54348 160401 ... slowest runs (cycles): ... 81303 88167 90183 138426 154596 160401 163683 171441 172038 174687 min: 45657 cycles q1: 57324 cycles median: 66684 cycles q3: 67443 cycles max: 174687 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_nnacl_newlib_static= {66684, 10119} count Apparent clock speed: 3143 MHz TestThreadWakeup: Measuring real time: 10.569 usec (1.05692e-05 sec) per iteration: 0.00105692 sec for 100 iterations 11.361 usec (1.13607e-05 sec) per iteration: 0.107484 sec for 9461 iterations 7.466 usec (7.46607e-06 sec) per iteration: 0.0706365 sec for 9461 iterations 7.722 usec (7.72205e-06 sec) per iteration: 0.0730583 sec for 9461 iterations 7.693 usec (7.69304e-06 sec) per iteration: 0.0727839 sec for 9461 iterations 7.451 usec (7.45051e-06 sec) per iteration: 0.0704893 sec for 9461 iterations mean: 8.338475 usec stddev: 1.515254 usec relative stddev: 18.17% RESULT TestThreadWakeup: x86-64_nnacl_newlib_static= {8.338475, 1.515254} us Measuring clock cycles: first runs (cycles): 24540 20982 20454 21423 21039 21393 23853 27942 25677 25386 ... slowest runs (cycles): ... 27942 28461 28584 29805 31437 34701 39573 42219 66051 145920 min: 17463 cycles q1: 20190 cycles median: 21396 cycles q3: 24546 cycles max: 145920 cycles RESULT TestThreadWakeup_CycleCount: x86-64_nnacl_newlib_static= {21396, 4356} count Apparent clock speed: 2566 MHz TestCatchingFault: Measuring real time: 10.139 usec (1.01394e-05 sec) per iteration: 0.00101394 sec for 100 iterations 8.006 usec (8.00603e-06 sec) per iteration: 0.0789554 sec for 9862 iterations 8.059 usec (8.05917e-06 sec) per iteration: 0.0794795 sec for 9862 iterations 8.059 usec (8.05896e-06 sec) per iteration: 0.0794774 sec for 9862 iterations 8.118 usec (8.11836e-06 sec) per iteration: 0.0800633 sec for 9862 iterations 8.160 usec (8.15981e-06 sec) per iteration: 0.080472 sec for 9862 iterations mean: 8.080465 usec stddev: 0.053270 usec relative stddev: 0.66% RESULT TestCatchingFault: x86-64_nnacl_newlib_static= {8.080465, 0.053270} us Measuring clock cycles: first runs (cycles): 34644 29430 28767 29064 29049 28956 27630 31434 33918 34521 ... slowest runs (cycles): ... 37773 38925 39372 39600 39957 41904 44628 48090 59982 60171 min: 22773 cycles q1: 23883 cycles median: 24108 cycles q3: 28767 cycles max: 60171 cycles RESULT TestCatchingFault_CycleCount: x86-64_nnacl_newlib_static= {24108, 4884} count Apparent clock speed: 2983 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 27.605 usec (2.76054e-05 sec) per iteration: 0.00276054 sec for 100 iterations 19.630 usec (1.96299e-05 sec) per iteration: 0.0710994 sec for 3622 iterations 18.033 usec (1.80327e-05 sec) per iteration: 0.0653143 sec for 3622 iterations 18.062 usec (1.80619e-05 sec) per iteration: 0.0654204 sec for 3622 iterations 18.160 usec (1.81596e-05 sec) per iteration: 0.0657741 sec for 3622 iterations 18.162 usec (1.81621e-05 sec) per iteration: 0.0657831 sec for 3622 iterations mean: 18.409239 usec stddev: 0.612495 usec relative stddev: 3.33% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_nnacl_newlib_static= {18.409239, 0.612495} us Measuring clock cycles: first runs (cycles): 84267 117750 118272 81768 106629 84762 96246 92355 65307 55338 ... slowest runs (cycles): ... 65307 70614 81768 84267 84762 92355 96246 106629 117750 118272 min: 53385 cycles q1: 54018 cycles median: 54495 cycles q3: 55248 cycles max: 118272 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_nnacl_newlib_static= {54495, 1230} count Apparent clock speed: 2960 MHz [ OK ] nacl_newlib.run_performance_test (4600 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