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

Running command: /b/s/w/ir/cache/vpython/b574a6/bin/python scons.py --verbose -k -j1 --mode=nacl,opt-host platform=x86-64 --clang --asan large_tests scons: Reading SConscript files ... ====================================================================== SCONS ARGS:['scons.py', '--verbose', '-k', '-j1', '--mode=nacl,opt-host', 'platform=x86-64', '--clang', '--asan', 'large_tests'] ====================================================================== ====================================================================== Python Info: ====================================================================== linux2 2.7.14+chromium14 (df075345c7-dirty:df075345c7, Oct 3 2017, 17:29:00) [GCC 4.8.4] ====================================================================== ENV: ====================================================================== PATH /b/s/w/ir/cache/vpython/b574a6/bin:/b/s/w/ir/cipd_bin_packages:/b/s/w/ir/cipd_bin_packages/bin:/b/s/cipd_cache/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin:/b/s/w/ir/kitchen-checkout/depot_tools ====================================================================== OS: ====================================================================== Linux gce-trusty-e833d7b0-us-west1-b-nxxd 4.4.0-78-generic #99~14.04.2-Ubuntu SMP Thu Apr 27 18:49:46 UTC 2017 x86_64 x86_64 x86_64 GNU/Linux ====================================================================== CPU: ====================================================================== model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2200.000 model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2200.000 model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2200.000 model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2200.000 model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2200.000 model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2200.000 model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2200.000 model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2200.000 ====================================================================== RAM: ====================================================================== MemTotal: 30882896 kB MemFree: 22254976 kB MemAvailable: 29484336 kB ====================================================================== LOAD: ====================================================================== 1.13 1.69 0.99 1/430 9622 ====================================================================== UPTIME: ====================================================================== 537.65 3166.70 ====================================================================== 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/linux_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /b/s/w/ir/kitchen-workdir/native_client/toolchain/linux_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-linux TARGET_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-linux-x86-64 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-linux-x86-64/obj BUILD_TYPE_DESCRIPTION: Linux 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 -fsanitize=address -gline-tables-only -fno-omit-frame-pointer -DADDRESS_SANITIZER ASPPCOM: /b/s/w/ir/kitchen-workdir/third_party/llvm-build/Release+Asserts/bin/clang -fsanitize=address -gline-tables-only -fno-omit-frame-pointer -DADDRESS_SANITIZER -fPIE -m64 -Wall -pedantic -Wextra -Wno-long-long -Wswitch-enum -Wsign-compare -Wundef -fdiagnostics-show-option -fvisibility=hidden -fstack-protector -Werror -Wno-variadic-macros -Wno-covered-switch-default -Wno-c++11-extensions -O2 -D_FORTIFY_SOURCE=2 -D_POSIX_C_SOURCE=199506 -D_XOPEN_SOURCE=600 -D_GNU_SOURCE=1 -D_LARGEFILE64_SOURCE=1 -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/linux_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /b/s/w/ir/kitchen-workdir/native_client/toolchain/linux_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 Skipping death test "nacl_text_small_pad_ro_test.out" under AddressSanitizer Skipping death test "nacl_text_large_pad_ro_test.out" under AddressSanitizer *** BROKEN run_ncval_annotate_test Skipping death test "run_crash_in_syscall_test.out" under AddressSanitizer Skipping death test "run_log_fatal_in_syscall_test.out" under AddressSanitizer Skipping death test "run_check_failure_in_syscall_test.out" under AddressSanitizer Skipping death test "run_jump_to_zero_in_syscall_test.out" under AddressSanitizer Skipping death test "run_jump_into_sandbox_in_syscall_crash_test.out" under AddressSanitizer Skipping death test "execute_data.out" under AddressSanitizer Skipping death test "write_to_dyncode_before_alloc.out" under AddressSanitizer Skipping death test "write_to_dyncode_after_alloc.out" under AddressSanitizer Skipping death test "dyncode_demand_alloc_test.out" under AddressSanitizer Skipping death test "exception_test.out" under AddressSanitizer Skipping death test "exception_test_bad_handler.out" under AddressSanitizer Skipping death test "exception_test_stack_outside_sandbox.out" under AddressSanitizer Skipping death test "exception_test_stack_in_rwdata.out" under AddressSanitizer Skipping death test "exception_test_stack_in_rodata.out" under AddressSanitizer Skipping death test "exception_test_stack_in_code.out" under AddressSanitizer Skipping death test "exception_test_crash_in_syscall.out" under AddressSanitizer Skipping death test "exceptions_disabled_test.out" under AddressSanitizer *** BROKEN run_gdb_invalid_memory_test Skipping death test "mmap_race_socketpair_test.out" under AddressSanitizer Skipping death test "mmap_race_connect_test.out" under AddressSanitizer *** BROKEN run_nanosleep_test Skipping death test "nullptr.out" under AddressSanitizer *** BROKEN run_divide_by_zero_test Skipping death test "write_to_rodata.out" under AddressSanitizer Skipping death test "untrusted_crash_test.out" under AddressSanitizer Skipping death test "trusted_crash_test.out" under AddressSanitizer Skipping death test "crash_in_syscall_test.out" under AddressSanitizer *** BROKEN run_timefuncs_test *** BROKEN run_raw_timefuncs_test *** BROKEN run_nacl_thread_capture_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 *** BROKEN run_untrusted_check_test *** BROKEN run_nacl_sync_cond_test Skipping death test "nacl_signal_frame_test.out" under AddressSanitizer SKIPPING test text_overlaps_rodata SKIPPING test text_overlaps_data Skipping death test "sel_ldr_thread_death_test.out" under AddressSanitizer Skipping death test "nacl_error_log_test.out" under AddressSanitizer *** BROKEN run_seccomp_open_errno_test *** BROKEN run_seccomp_check_arch_test There are 18 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/b574a6/bin/python /b/s/w/ir/kitchen-workdir/native_client/tools/command_tester.py --name nacl_newlib.run_signal_handler_single_step_test --time_warning 2 --time_error 20 --perf_env_description x86-64_nnacl_newlib_static --subarch 64 --osenv ASAN_OPTIONS=handle_segv=0:handle_sigfpe=0:abort_on_error=0:detect_leaks=0 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-64/test_results/signal_handler_single_step.out /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-64/obj/tests/signal_handler_single_step/step_test_host /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-64/obj/tests/signal_handler_single_step/step_test_guest.nexe x86-64_nnacl_newlib_static [ RUN ] nacl_newlib.run_signal_handler_single_step_test ====================================================================== setting environment ====================================================================== [ASAN_OPTIONS] = [handle_segv=0:handle_sigfpe=0:abort_on_error=0:detect_leaks=0] ASAN_OPTIONS=handle_segv=0:handle_sigfpe=0:abort_on_error=0:detect_leaks=0 /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-64/obj/tests/signal_handler_single_step/step_test_host /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-64/obj/tests/signal_handler_single_step/step_test_guest.nexe x86-64_nnacl_newlib_static [9630,2537236480:12:53:25.183222] Native Client module will be loaded at base address 0x0000338200000000 Switching to untrusted: since previous switch: 959 instructions, 3891 instruction bytes, 96 jumps Switching to trusted: since previous switch: 11 instructions, 48 instruction bytes, 2 jumps Switching to untrusted: since previous switch: 375 instructions, 1506 instruction bytes, 22 jumps Switching to trusted: since previous switch: 9 instructions, 32 instruction bytes, 3 jumps Switching to untrusted: since previous switch: 375 instructions, 1506 instruction bytes, 22 jumps Switching to trusted: since previous switch: 9 instructions, 32 instruction bytes, 3 jumps Switching to untrusted: since previous switch: 375 instructions, 1506 instruction bytes, 22 jumps Switching to trusted: since previous switch: 9 instructions, 32 instruction bytes, 3 jumps Switching to untrusted: since previous switch: 375 instructions, 1506 instruction bytes, 22 jumps RESULT InstructionsPerSyscall: x86-64_nnacl_newlib_static= 375 count RESULT InstructionBytesPerSyscall: x86-64_nnacl_newlib_static= 1506 count RESULT JumpsPerSyscall: x86-64_nnacl_newlib_static= 22 count Switching to trusted: since previous switch: 9 instructions, 32 instruction bytes, 3 jumps [ OK ] nacl_newlib.run_signal_handler_single_step_test (20 ms) /b/s/w/ir/cache/vpython/b574a6/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 --using_nacl_signal_handler True --subarch 64 --osenv ASAN_OPTIONS=handle_segv=0:handle_sigfpe=0:abort_on_error=0:detect_leaks=0 --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-linux-x86-64/staging/nacl_helper_bootstrap scons-out/opt-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -- scons-out/nacl-x86-64/obj/tests/benchmark/benchmark_test.nexe x86-64_nnacl_newlib_static [ RUN ] nacl_newlib.run_benchmark_test ====================================================================== setting environment ====================================================================== [ASAN_OPTIONS] = [handle_segv=0:handle_sigfpe=0:abort_on_error=0:detect_leaks=0] ASAN_OPTIONS=handle_segv=0:handle_sigfpe=0:abort_on_error=0:detect_leaks=0 scons-out/opt-linux-x86-64/staging/nacl_helper_bootstrap scons-out/opt-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -- scons-out/nacl-x86-64/obj/tests/benchmark/benchmark_test.nexe x86-64_nnacl_newlib_static [9635,595232768:12:53:25.528813] Native Client module will be loaded at base address 0x0000797100000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_nnacl_newlib_static= {0.117750, 0.003940} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test (1920 ms) /b/s/w/ir/cache/vpython/b574a6/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 --using_nacl_signal_handler True --subarch 64 --osenv ASAN_OPTIONS=handle_segv=0:handle_sigfpe=0:abort_on_error=0:detect_leaks=0 --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-linux-x86-64/staging/nacl_helper_bootstrap scons-out/opt-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -- scons-out/nacl-x86-64/obj/tests/clock/clock_test.nexe -c [ RUN ] nacl_newlib.run_clock_cputime_test ====================================================================== setting environment ====================================================================== [ASAN_OPTIONS] = [handle_segv=0:handle_sigfpe=0:abort_on_error=0:detect_leaks=0] ASAN_OPTIONS=handle_segv=0:handle_sigfpe=0:abort_on_error=0:detect_leaks=0 scons-out/opt-linux-x86-64/staging/nacl_helper_bootstrap scons-out/opt-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -- 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 (75630 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.291 KB RESULT hello_world_size.out: ZIPPED_x86-64_nnacl_newlib_static= 187.863 KB /b/s/w/ir/cache/vpython/b574a6/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 --using_nacl_signal_handler True --subarch 64 --osenv ASAN_OPTIONS=handle_segv=0:handle_sigfpe=0:abort_on_error=0:detect_leaks=0 --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-linux-x86-64/staging/nacl_helper_bootstrap scons-out/opt-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -e -- scons-out/nacl-x86-64/obj/tests/performance/performance_test.nexe x86-64_nnacl_newlib_static [ RUN ] nacl_newlib.run_performance_test ====================================================================== setting environment ====================================================================== [ASAN_OPTIONS] = [handle_segv=0:handle_sigfpe=0:abort_on_error=0:detect_leaks=0] ASAN_OPTIONS=handle_segv=0:handle_sigfpe=0:abort_on_error=0:detect_leaks=0 scons-out/opt-linux-x86-64/staging/nacl_helper_bootstrap scons-out/opt-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -e -- scons-out/nacl-x86-64/obj/tests/performance/performance_test.nexe x86-64_nnacl_newlib_static [9663,4111292416:12:53:41.135232] Native Client module will be loaded at base address 0x0000274100000000 TestNull: Measuring real time: 0.013 usec (1.273e-08 sec) per iteration: 1.273e-06 sec for 100 iterations 0.002 usec (2.165e-09 sec) per iteration: 2.165e-06 sec for 1000 iterations 0.002 usec (1.8933e-09 sec) per iteration: 1.8933e-05 sec for 10000 iterations 0.002 usec (1.92838e-09 sec) per iteration: 0.101853 sec for 52817831 iterations 0.002 usec (1.92697e-09 sec) per iteration: 0.101778 sec for 52817831 iterations 0.002 usec (1.91459e-09 sec) per iteration: 0.101124 sec for 52817831 iterations 0.002 usec (1.95437e-09 sec) per iteration: 0.103226 sec for 52817831 iterations 0.002 usec (1.9071e-09 sec) per iteration: 0.100729 sec for 52817831 iterations mean: 0.001926 usec stddev: 0.000016 usec relative stddev: 0.84% RESULT TestNull: x86-64_nnacl_newlib_static= {0.001926, 0.000016} us Measuring clock cycles: first runs (cycles): 99 22 26 25 19 24 24 20 26 24 ... slowest runs (cycles): ... 27 27 27 27 27 27 29 29 29 99 min: 19 cycles q1: 24 cycles median: 24 cycles q3: 26 cycles max: 99 cycles RESULT TestNull_CycleCount: x86-64_nnacl_newlib_static= {24, 2} count Apparent clock speed: 12459 MHz TestNaClSyscall: Measuring real time: 0.166 usec (1.6637e-07 sec) per iteration: 1.6637e-05 sec for 100 iterations 0.154 usec (1.53666e-07 sec) per iteration: 0.0923639 sec for 601069 iterations 0.157 usec (1.5674e-07 sec) per iteration: 0.0942113 sec for 601069 iterations 0.159 usec (1.5897e-07 sec) per iteration: 0.0955522 sec for 601069 iterations 0.159 usec (1.58831e-07 sec) per iteration: 0.0954682 sec for 601069 iterations 0.161 usec (1.61217e-07 sec) per iteration: 0.0969023 sec for 601069 iterations mean: 0.157885 usec stddev: 0.002541 usec relative stddev: 1.61% RESULT TestNaClSyscall: x86-64_nnacl_newlib_static= {0.157885, 0.002541} us Measuring clock cycles: first runs (cycles): 343 280 233 228 236 226 229 233 233 227 ... slowest runs (cycles): ... 231 231 233 233 233 233 235 236 280 343 min: 224 cycles q1: 227 cycles median: 229 cycles q3: 229 cycles max: 343 cycles RESULT TestNaClSyscall_CycleCount: x86-64_nnacl_newlib_static= {229, 2} count Apparent clock speed: 1450 MHz TestSetjmpLongjmp: Measuring real time: 0.024 usec (2.35e-08 sec) per iteration: 2.35e-06 sec for 100 iterations 0.013 usec (1.2884e-08 sec) per iteration: 1.2884e-05 sec for 1000 iterations 0.013 usec (1.2953e-08 sec) per iteration: 0.100536 sec for 7761564 iterations 0.013 usec (1.31135e-08 sec) per iteration: 0.101781 sec for 7761564 iterations 0.013 usec (1.29774e-08 sec) per iteration: 0.100725 sec for 7761564 iterations 0.013 usec (1.28179e-08 sec) per iteration: 0.0994866 sec for 7761564 iterations 0.013 usec (1.2833e-08 sec) per iteration: 0.099604 sec for 7761564 iterations mean: 0.012939 usec stddev: 0.000108 usec relative stddev: 0.83% RESULT TestSetjmpLongjmp: x86-64_nnacl_newlib_static= {0.012939, 0.000108} us Measuring clock cycles: first runs (cycles): 170 42 40 35 31 33 30 31 31 31 ... slowest runs (cycles): ... 31 31 31 31 31 33 35 40 42 170 min: 24 cycles q1: 26 cycles median: 27 cycles q3: 29 cycles max: 170 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_nnacl_newlib_static= {27, 3} count Apparent clock speed: 2087 MHz TestClockGetTime: Measuring real time: 0.293 usec (2.9319e-07 sec) per iteration: 2.9319e-05 sec for 100 iterations 0.241 usec (2.41449e-07 sec) per iteration: 0.0823521 sec for 341075 iterations 0.232 usec (2.31747e-07 sec) per iteration: 0.0790432 sec for 341075 iterations 0.232 usec (2.31527e-07 sec) per iteration: 0.0789681 sec for 341075 iterations 0.231 usec (2.31405e-07 sec) per iteration: 0.0789264 sec for 341075 iterations 0.232 usec (2.31786e-07 sec) per iteration: 0.0790566 sec for 341075 iterations mean: 0.233583 usec stddev: 0.003935 usec relative stddev: 1.68% RESULT TestClockGetTime: x86-64_nnacl_newlib_static= {0.233583, 0.003935} us Measuring clock cycles: first runs (cycles): 1287 578 542 537 522 519 522 513 519 519 ... slowest runs (cycles): ... 524 524 524 526 530 531 537 542 578 1287 min: 504 cycles q1: 513 cycles median: 517 cycles q3: 520 cycles max: 1287 cycles RESULT TestClockGetTime_CycleCount: x86-64_nnacl_newlib_static= {517, 7} count Apparent clock speed: 2213 MHz TestTlsVariable: Measuring real time: 0.014 usec (1.403e-08 sec) per iteration: 1.403e-06 sec for 100 iterations 0.009 usec (8.915e-09 sec) per iteration: 8.915e-06 sec for 1000 iterations 0.007 usec (6.5021e-09 sec) per iteration: 6.5021e-05 sec for 10000 iterations 0.006 usec (5.69086e-09 sec) per iteration: 0.0875235 sec for 15379646 iterations 0.006 usec (5.92143e-09 sec) per iteration: 0.0910695 sec for 15379646 iterations 0.006 usec (5.68976e-09 sec) per iteration: 0.0875065 sec for 15379646 iterations 0.006 usec (5.69872e-09 sec) per iteration: 0.0876443 sec for 15379646 iterations 0.006 usec (5.68209e-09 sec) per iteration: 0.0873886 sec for 15379646 iterations mean: 0.005737 usec stddev: 0.000093 usec relative stddev: 1.61% RESULT TestTlsVariable: x86-64_nnacl_newlib_static= {0.005737, 0.000093} us Measuring clock cycles: first runs (cycles): 171 57 51 33 35 33 31 33 30 30 ... slowest runs (cycles): ... 33 33 33 33 33 33 35 51 57 171 min: 29 cycles q1: 33 cycles median: 33 cycles q3: 33 cycles max: 171 cycles RESULT TestTlsVariable_CycleCount: x86-64_nnacl_newlib_static= {33, 0} count Apparent clock speed: 5753 MHz TestMmapAnonymous: Measuring real time: 3.774 usec (3.77413e-06 sec) per iteration: 0.000377413 sec for 100 iterations 2.850 usec (2.84971e-06 sec) per iteration: 0.0755058 sec for 26496 iterations 2.764 usec (2.76389e-06 sec) per iteration: 0.0732321 sec for 26496 iterations 2.768 usec (2.76795e-06 sec) per iteration: 0.0733395 sec for 26496 iterations 2.769 usec (2.76892e-06 sec) per iteration: 0.0733654 sec for 26496 iterations 2.766 usec (2.76565e-06 sec) per iteration: 0.0732786 sec for 26496 iterations mean: 2.783224 usec stddev: 0.033288 usec relative stddev: 1.20% RESULT TestMmapAnonymous: x86-64_nnacl_newlib_static= {2.783224, 0.033288} us Measuring clock cycles: first runs (cycles): 7809 5931 6049 5766 5742 5764 5748 5717 5784 5722 ... slowest runs (cycles): ... 8824 8903 8944 9271 9505 9572 10606 10956 16653 22177 min: 5645 cycles q1: 5709 cycles median: 5748 cycles q3: 5931 cycles max: 22177 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_nnacl_newlib_static= {5748, 222} count Apparent clock speed: 2065 MHz TestAtomicIncrement: Measuring real time: 0.022 usec (2.16e-08 sec) per iteration: 2.16e-06 sec for 100 iterations 0.011 usec (1.0909e-08 sec) per iteration: 1.0909e-05 sec for 1000 iterations 0.009 usec (9.45746e-09 sec) per iteration: 0.0866941 sec for 9166743 iterations 0.009 usec (9.45902e-09 sec) per iteration: 0.0867084 sec for 9166743 iterations 0.009 usec (9.47821e-09 sec) per iteration: 0.0868843 sec for 9166743 iterations 0.009 usec (9.46313e-09 sec) per iteration: 0.086746 sec for 9166743 iterations 0.010 usec (9.5003e-09 sec) per iteration: 0.0870868 sec for 9166743 iterations mean: 0.009472 usec stddev: 0.000016 usec relative stddev: 0.17% RESULT TestAtomicIncrement: x86-64_nnacl_newlib_static= {0.009472, 0.000016} us Measuring clock cycles: first runs (cycles): 108 53 50 50 52 53 53 51 55 52 ... slowest runs (cycles): ... 55 55 55 55 55 55 55 55 55 108 min: 50 cycles q1: 52 cycles median: 53 cycles q3: 53 cycles max: 108 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_nnacl_newlib_static= {53, 1} count Apparent clock speed: 5596 MHz TestUncontendedMutexLock: Measuring real time: 0.031 usec (3.088e-08 sec) per iteration: 3.088e-06 sec for 100 iterations 0.027 usec (2.7364e-08 sec) per iteration: 2.7364e-05 sec for 1000 iterations 0.025 usec (2.46303e-08 sec) per iteration: 0.0900098 sec for 3654436 iterations 0.025 usec (2.46789e-08 sec) per iteration: 0.0901874 sec for 3654436 iterations 0.025 usec (2.46171e-08 sec) per iteration: 0.0899616 sec for 3654436 iterations 0.025 usec (2.46202e-08 sec) per iteration: 0.0899729 sec for 3654436 iterations 0.026 usec (2.55386e-08 sec) per iteration: 0.0933293 sec for 3654436 iterations mean: 0.024817 usec stddev: 0.000361 usec relative stddev: 1.46% RESULT TestUncontendedMutexLock: x86-64_nnacl_newlib_static= {0.024817, 0.000361} us Measuring clock cycles: first runs (cycles): 257 108 83 106 83 84 82 81 81 82 ... slowest runs (cycles): ... 84 84 84 84 84 84 93 106 108 257 min: 81 cycles q1: 82 cycles median: 83 cycles q3: 84 cycles max: 257 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_nnacl_newlib_static= {83, 2} count Apparent clock speed: 3344 MHz TestCondvarSignalNoOp: Measuring real time: 0.287 usec (2.8697e-07 sec) per iteration: 2.8697e-05 sec for 100 iterations 0.258 usec (2.57747e-07 sec) per iteration: 0.0898167 sec for 348468 iterations 0.257 usec (2.56745e-07 sec) per iteration: 0.0894676 sec for 348468 iterations 0.257 usec (2.56929e-07 sec) per iteration: 0.0895314 sec for 348468 iterations 0.256 usec (2.56377e-07 sec) per iteration: 0.0893392 sec for 348468 iterations 0.256 usec (2.56037e-07 sec) per iteration: 0.0892206 sec for 348468 iterations mean: 0.256767 usec stddev: 0.000578 usec relative stddev: 0.23% RESULT TestCondvarSignalNoOp: x86-64_nnacl_newlib_static= {0.256767, 0.000578} us Measuring clock cycles: first runs (cycles): 823 596 578 570 561 568 565 569 576 568 ... slowest runs (cycles): ... 572 572 574 574 575 576 578 596 607 823 min: 557 cycles q1: 563 cycles median: 565 cycles q3: 568 cycles max: 823 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_nnacl_newlib_static= {565, 5} count Apparent clock speed: 2200 MHz TestThreadCreateAndJoin: Measuring real time: 135.796 usec (0.000135796 sec) per iteration: 0.0135796 sec for 100 iterations 120.742 usec (0.000120742 sec) per iteration: 0.0888661 sec for 736 iterations 113.653 usec (0.000113653 sec) per iteration: 0.0836487 sec for 736 iterations 116.293 usec (0.000116293 sec) per iteration: 0.0855916 sec for 736 iterations 123.931 usec (0.000123931 sec) per iteration: 0.0912132 sec for 736 iterations 118.376 usec (0.000118376 sec) per iteration: 0.0871245 sec for 736 iterations mean: 118.598949 usec stddev: 3.545462 usec relative stddev: 2.99% RESULT TestThreadCreateAndJoin: x86-64_nnacl_newlib_static= {118.598949, 3.545462} us Measuring clock cycles: first runs (cycles): 171199 657990 294194 241110 274952 262648 219688 178791 292491 320803 ... slowest runs (cycles): ... 349852 351544 353539 355224 365919 385989 389572 418549 492539 657990 min: 171199 cycles q1: 226602 cycles median: 256490 cycles q3: 282016 cycles max: 657990 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_nnacl_newlib_static= {256490, 55414} count Apparent clock speed: 2163 MHz TestThreadWakeup: Measuring real time: 13.311 usec (1.33108e-05 sec) per iteration: 0.00133108 sec for 100 iterations 14.467 usec (1.44669e-05 sec) per iteration: 0.108675 sec for 7512 iterations 16.014 usec (1.60137e-05 sec) per iteration: 0.120295 sec for 7512 iterations 16.445 usec (1.64452e-05 sec) per iteration: 0.123537 sec for 7512 iterations 15.837 usec (1.58371e-05 sec) per iteration: 0.118969 sec for 7512 iterations 15.156 usec (1.51557e-05 sec) per iteration: 0.11385 sec for 7512 iterations mean: 15.583740 usec stddev: 0.695970 usec relative stddev: 4.47% RESULT TestThreadWakeup: x86-64_nnacl_newlib_static= {15.583740, 0.695970} us Measuring clock cycles: first runs (cycles): 29235 26509 27169 25712 26214 26894 26419 27288 27438 26682 ... slowest runs (cycles): ... 43561 43703 44808 44810 45261 50963 59171 72894 85148 85324 min: 25712 cycles q1: 28845 cycles median: 35189 cycles q3: 36096 cycles max: 85324 cycles RESULT TestThreadWakeup_CycleCount: x86-64_nnacl_newlib_static= {35189, 7251} count Apparent clock speed: 2258 MHz TestCatchingFault: Measuring real time: 4.002 usec (4.00159e-06 sec) per iteration: 0.000400159 sec for 100 iterations 1.863 usec (1.86327e-06 sec) per iteration: 0.0465631 sec for 24990 iterations 1.849 usec (1.84932e-06 sec) per iteration: 0.0462145 sec for 24990 iterations 1.852 usec (1.85243e-06 sec) per iteration: 0.0462923 sec for 24990 iterations 1.855 usec (1.85454e-06 sec) per iteration: 0.0463449 sec for 24990 iterations 1.847 usec (1.84732e-06 sec) per iteration: 0.0461645 sec for 24990 iterations mean: 1.853376 usec stddev: 0.005537 usec relative stddev: 0.30% RESULT TestCatchingFault: x86-64_nnacl_newlib_static= {1.853376, 0.005537} us Measuring clock cycles: first runs (cycles): 5348 4147 4052 4063 4020 4017 4024 3991 4004 3997 ... slowest runs (cycles): ... 5020 5025 5025 5126 5188 5200 5348 5487 5497 19001 min: 3989 cycles q1: 4001 cycles median: 4021 cycles q3: 4571 cycles max: 19001 cycles RESULT TestCatchingFault_CycleCount: x86-64_nnacl_newlib_static= {4021, 570} count Apparent clock speed: 2170 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 130.848 usec (0.000130848 sec) per iteration: 0.0130848 sec for 100 iterations 169.615 usec (0.000169615 sec) per iteration: 0.129586 sec for 764 iterations 213.169 usec (0.000213169 sec) per iteration: 0.162861 sec for 764 iterations 163.544 usec (0.000163544 sec) per iteration: 0.124948 sec for 764 iterations 173.393 usec (0.000173393 sec) per iteration: 0.132472 sec for 764 iterations 163.406 usec (0.000163406 sec) per iteration: 0.124842 sec for 764 iterations mean: 176.625218 usec stddev: 18.659434 usec relative stddev: 10.56% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_nnacl_newlib_static= {176.625218, 18.659434} us Measuring clock cycles: first runs (cycles): 240150 693382 287487 535655 303115 292329 441481 394935 252281 404952 ... slowest runs (cycles): ... 576912 592151 612060 621267 683760 693382 717830 721583 802258 948392 min: 200198 cycles q1: 260800 cycles median: 339273 cycles q3: 437816 cycles max: 948392 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_nnacl_newlib_static= {339273, 177016} count Apparent clock speed: 1921 MHz [ OK ] nacl_newlib.run_performance_test (7279 ms) /b/s/w/ir/cache/vpython/b574a6/bin/python /b/s/w/ir/kitchen-workdir/native_client/tools/command_tester.py --name nacl_newlib.run_nacl_clock_cputime_test --time_warning 60 --time_error 600 --perf_env_description trusted_linux_x86-64 --subarch 64 --osenv ASAN_OPTIONS=handle_segv=0:handle_sigfpe=0:abort_on_error=0:detect_leaks=0 --arch x86 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-linux-x86-64/test_results/nacl_clock_cputime_test.out /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-linux-x86-64/obj/src/shared/platform/nacl_clock_test -c [ RUN ] nacl_newlib.run_nacl_clock_cputime_test ====================================================================== setting environment ====================================================================== [ASAN_OPTIONS] = [handle_segv=0:handle_sigfpe=0:abort_on_error=0:detect_leaks=0] ASAN_OPTIONS=handle_segv=0:handle_sigfpe=0:abort_on_error=0:detect_leaks=0 /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-linux-x86-64/obj/src/shared/platform/nacl_clock_test -c [ OK ] nacl_newlib.run_nacl_clock_cputime_test (4390 ms) /b/s/w/ir/cache/vpython/b574a6/bin/python /b/s/w/ir/kitchen-workdir/native_client/tools/command_tester.py --name nacl_newlib.run_nacl_interval_multi_reg_test --time_warning 60 --time_error 600 --perf_env_description trusted_linux_x86-64 --subarch 64 --osenv ASAN_OPTIONS=handle_segv=0:handle_sigfpe=0:abort_on_error=0:detect_leaks=0 --arch x86 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-linux-x86-64/test_results/nacl_interval_multi_reg_test.out /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-linux-x86-64/obj/src/trusted/interval_multiset/nacl_interval_test -k NaClIntervalListMultiset -k NaClIntervalRangeTree -c 1000000 [ RUN ] nacl_newlib.run_nacl_interval_multi_reg_test ====================================================================== setting environment ====================================================================== [ASAN_OPTIONS] = [handle_segv=0:handle_sigfpe=0:abort_on_error=0:detect_leaks=0] ASAN_OPTIONS=handle_segv=0:handle_sigfpe=0:abort_on_error=0:detect_leaks=0 /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-linux-x86-64/obj/src/trusted/interval_multiset/nacl_interval_test -k NaClIntervalListMultiset -k NaClIntervalRangeTree -c 1000000 [ OK ] nacl_newlib.run_nacl_interval_multi_reg_test (1110 ms) /b/s/w/ir/cache/vpython/b574a6/bin/python /b/s/w/ir/kitchen-workdir/native_client/tools/command_tester.py --name nacl_newlib.run_trusted_performance_test --time_warning 2 --time_error 20 --perf_env_description trusted_linux_x86-64 --subarch 64 --osenv ASAN_OPTIONS=handle_segv=0:handle_sigfpe=0:abort_on_error=0:detect_leaks=0 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-linux-x86-64/test_results/performance_test.out /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-linux-x86-64/obj/tests/performance/performance_test trusted_linux_x86-64 [ RUN ] nacl_newlib.run_trusted_performance_test ====================================================================== setting environment ====================================================================== [ASAN_OPTIONS] = [handle_segv=0:handle_sigfpe=0:abort_on_error=0:detect_leaks=0] ASAN_OPTIONS=handle_segv=0:handle_sigfpe=0:abort_on_error=0:detect_leaks=0 /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-linux-x86-64/obj/tests/performance/performance_test trusted_linux_x86-64 TestNull: Measuring real time: 0.010 usec (1.048e-08 sec) per iteration: 1.048e-06 sec for 100 iterations 0.002 usec (2.374e-09 sec) per iteration: 2.374e-06 sec for 1000 iterations 0.002 usec (2.3072e-09 sec) per iteration: 2.3072e-05 sec for 10000 iterations 0.002 usec (1.901e-09 sec) per iteration: 0.082394 sec for 43342579 iterations 0.002 usec (1.89038e-09 sec) per iteration: 0.0819341 sec for 43342579 iterations 0.002 usec (1.89011e-09 sec) per iteration: 0.0819222 sec for 43342579 iterations 0.002 usec (1.88727e-09 sec) per iteration: 0.0817994 sec for 43342579 iterations 0.002 usec (1.88739e-09 sec) per iteration: 0.0818045 sec for 43342579 iterations mean: 0.001891 usec stddev: 0.000005 usec relative stddev: 0.27% RESULT TestNull: trusted_linux_x86-64= {0.001891, 0.000005} us Measuring clock cycles: first runs (cycles): 121 24 26 24 25 24 24 26 24 24 ... slowest runs (cycles): ... 28 29 29 29 29 29 29 29 29 121 min: 24 cycles q1: 26 cycles median: 27 cycles q3: 27 cycles max: 121 cycles RESULT TestNull_CycleCount: trusted_linux_x86-64= {27, 1} count Apparent clock speed: 14276 MHz TestHostSyscall: Measuring real time: 0.075 usec (7.464e-08 sec) per iteration: 7.464e-06 sec for 100 iterations 0.058 usec (5.8139e-08 sec) per iteration: 5.8139e-05 sec for 1000 iterations 0.058 usec (5.82029e-08 sec) per iteration: 0.10011 sec for 1720015 iterations 0.058 usec (5.84413e-08 sec) per iteration: 0.10052 sec for 1720015 iterations 0.058 usec (5.82039e-08 sec) per iteration: 0.100112 sec for 1720015 iterations 0.058 usec (5.81957e-08 sec) per iteration: 0.100097 sec for 1720015 iterations 0.058 usec (5.83737e-08 sec) per iteration: 0.100404 sec for 1720015 iterations mean: 0.058284 usec stddev: 0.000104 usec relative stddev: 0.18% RESULT TestHostSyscall: trusted_linux_x86-64= {0.058284, 0.000104} us Measuring clock cycles: first runs (cycles): 552 204 319 174 169 178 163 176 187 185 ... slowest runs (cycles): ... 185 187 187 189 189 190 196 204 319 552 min: 149 cycles q1: 154 cycles median: 165 cycles q3: 172 cycles max: 552 cycles RESULT TestHostSyscall_CycleCount: trusted_linux_x86-64= {165, 18} count Apparent clock speed: 2831 MHz TestSetjmpLongjmp: Measuring real time: 0.410 usec (4.0999e-07 sec) per iteration: 4.0999e-05 sec for 100 iterations 0.159 usec (1.58869e-07 sec) per iteration: 0.0387495 sec for 243908 iterations 0.155 usec (1.54773e-07 sec) per iteration: 0.0377505 sec for 243908 iterations 0.155 usec (1.55482e-07 sec) per iteration: 0.0379233 sec for 243908 iterations 0.155 usec (1.55313e-07 sec) per iteration: 0.0378821 sec for 243908 iterations 0.154 usec (1.54384e-07 sec) per iteration: 0.0376555 sec for 243908 iterations mean: 0.155764 usec stddev: 0.001601 usec relative stddev: 1.03% RESULT TestSetjmpLongjmp: trusted_linux_x86-64= {0.155764, 0.001601} us Measuring clock cycles: first runs (cycles): 1129 477 422 410 400 391 396 424 394 398 ... slowest runs (cycles): ... 475 477 484 487 504 508 541 546 550 1129 min: 391 cycles q1: 407 cycles median: 422 cycles q3: 447 cycles max: 1129 cycles RESULT TestSetjmpLongjmp_CycleCount: trusted_linux_x86-64= {422, 40} count Apparent clock speed: 2709 MHz TestClockGetTime: Measuring real time: 0.082 usec (8.208e-08 sec) per iteration: 8.208e-06 sec for 100 iterations 0.080 usec (8.0472e-08 sec) per iteration: 8.0472e-05 sec for 1000 iterations 0.070 usec (7.03746e-08 sec) per iteration: 0.0874523 sec for 1242668 iterations 0.071 usec (7.12839e-08 sec) per iteration: 0.0885823 sec for 1242668 iterations 0.069 usec (6.91739e-08 sec) per iteration: 0.0859601 sec for 1242668 iterations 0.070 usec (6.96585e-08 sec) per iteration: 0.0865624 sec for 1242668 iterations 0.070 usec (7.01943e-08 sec) per iteration: 0.0872282 sec for 1242668 iterations mean: 0.070137 usec stddev: 0.000712 usec relative stddev: 1.01% RESULT TestClockGetTime: trusted_linux_x86-64= {0.070137, 0.000712} us Measuring clock cycles: first runs (cycles): 418 378 201 204 178 205 224 212 202 407 ... slowest runs (cycles): ... 222 223 223 224 224 227 378 396 407 418 min: 167 cycles q1: 178 cycles median: 190 cycles q3: 203 cycles max: 418 cycles RESULT TestClockGetTime_CycleCount: trusted_linux_x86-64= {190, 25} count Apparent clock speed: 2709 MHz TestTlsVariable: Measuring real time: 0.008 usec (7.51e-09 sec) per iteration: 7.51e-07 sec for 100 iterations 0.003 usec (2.88e-09 sec) per iteration: 2.88e-06 sec for 1000 iterations 0.003 usec (2.746e-09 sec) per iteration: 2.746e-05 sec for 10000 iterations 0.002 usec (1.89373e-09 sec) per iteration: 0.0689633 sec for 36416605 iterations 0.002 usec (1.89024e-09 sec) per iteration: 0.0688361 sec for 36416605 iterations 0.002 usec (1.89551e-09 sec) per iteration: 0.0690282 sec for 36416605 iterations 0.002 usec (1.89029e-09 sec) per iteration: 0.0688379 sec for 36416605 iterations 0.002 usec (1.90052e-09 sec) per iteration: 0.0692105 sec for 36416605 iterations mean: 0.001894 usec stddev: 0.000004 usec relative stddev: 0.20% RESULT TestTlsVariable: trusted_linux_x86-64= {0.001894, 0.000004} us Measuring clock cycles: first runs (cycles): 148 20 24 25 24 24 25 26 24 22 ... slowest runs (cycles): ... 31 31 31 31 31 31 33 33 33 148 min: 20 cycles q1: 26 cycles median: 29 cycles q3: 29 cycles max: 148 cycles RESULT TestTlsVariable_CycleCount: trusted_linux_x86-64= {29, 3} count Apparent clock speed: 15311 MHz TestMmapAnonymous: Measuring real time: 1.077 usec (1.07716e-06 sec) per iteration: 0.000107716 sec for 100 iterations 0.706 usec (7.05734e-07 sec) per iteration: 0.0655176 sec for 92836 iterations 0.702 usec (7.02337e-07 sec) per iteration: 0.0652021 sec for 92836 iterations 0.704 usec (7.04274e-07 sec) per iteration: 0.065382 sec for 92836 iterations 0.706 usec (7.06085e-07 sec) per iteration: 0.0655501 sec for 92836 iterations 0.704 usec (7.0434e-07 sec) per iteration: 0.0653881 sec for 92836 iterations mean: 0.704554 usec stddev: 0.001325 usec relative stddev: 0.19% RESULT TestMmapAnonymous: trusted_linux_x86-64= {0.704554, 0.001325} us Measuring clock cycles: first runs (cycles): 2182 2444 1526 1536 1531 1540 1529 1529 1526 1527 ... slowest runs (cycles): ... 1553 1567 1578 1683 1683 1687 1736 1877 2182 2444 min: 1494 cycles q1: 1518 cycles median: 1525 cycles q3: 1534 cycles max: 2444 cycles RESULT TestMmapAnonymous_CycleCount: trusted_linux_x86-64= {1525, 16} count Apparent clock speed: 2164 MHz TestAtomicIncrement: Measuring real time: 0.018 usec (1.768e-08 sec) per iteration: 1.768e-06 sec for 100 iterations 0.010 usec (1.001e-08 sec) per iteration: 1.001e-05 sec for 1000 iterations 0.009 usec (9.44529e-09 sec) per iteration: 0.0943585 sec for 9990009 iterations 0.009 usec (9.42017e-09 sec) per iteration: 0.0941076 sec for 9990009 iterations 0.009 usec (9.42994e-09 sec) per iteration: 0.0942052 sec for 9990009 iterations 0.009 usec (9.46791e-09 sec) per iteration: 0.0945846 sec for 9990009 iterations 0.009 usec (9.44475e-09 sec) per iteration: 0.0943531 sec for 9990009 iterations mean: 0.009442 usec stddev: 0.000016 usec relative stddev: 0.17% RESULT TestAtomicIncrement: trusted_linux_x86-64= {0.009442, 0.000016} us Measuring clock cycles: first runs (cycles): 106 53 50 53 50 53 52 51 52 51 ... slowest runs (cycles): ... 53 53 53 53 53 53 53 53 53 106 min: 48 cycles q1: 51 cycles median: 51 cycles q3: 53 cycles max: 106 cycles RESULT TestAtomicIncrement_CycleCount: trusted_linux_x86-64= {51, 2} count Apparent clock speed: 5402 MHz TestUncontendedMutexLock: Measuring real time: 0.112 usec (1.1179e-07 sec) per iteration: 1.1179e-05 sec for 100 iterations 0.026 usec (2.58565e-08 sec) per iteration: 0.0231295 sec for 894534 iterations 0.026 usec (2.55219e-08 sec) per iteration: 0.0228302 sec for 894534 iterations 0.026 usec (2.58591e-08 sec) per iteration: 0.0231318 sec for 894534 iterations 0.027 usec (2.68052e-08 sec) per iteration: 0.0239781 sec for 894534 iterations 0.027 usec (2.69603e-08 sec) per iteration: 0.0241169 sec for 894534 iterations mean: 0.026201 usec stddev: 0.000572 usec relative stddev: 2.18% RESULT TestUncontendedMutexLock: trusted_linux_x86-64= {0.026201, 0.000572} us Measuring clock cycles: first runs (cycles): 213 402 84 81 84 84 84 84 81 416 ... slowest runs (cycles): ... 84 86 86 86 86 86 213 402 416 456 min: 81 cycles q1: 83 cycles median: 84 cycles q3: 84 cycles max: 456 cycles RESULT TestUncontendedMutexLock_CycleCount: trusted_linux_x86-64= {84, 1} count Apparent clock speed: 3206 MHz TestCondvarSignalNoOp: Measuring real time: 0.021 usec (2.056e-08 sec) per iteration: 2.056e-06 sec for 100 iterations 0.017 usec (1.6914e-08 sec) per iteration: 1.6914e-05 sec for 1000 iterations 0.018 usec (1.76665e-08 sec) per iteration: 0.104449 sec for 5912262 iterations 0.017 usec (1.71925e-08 sec) per iteration: 0.101647 sec for 5912262 iterations 0.017 usec (1.71804e-08 sec) per iteration: 0.101575 sec for 5912262 iterations 0.018 usec (1.75879e-08 sec) per iteration: 0.103984 sec for 5912262 iterations 0.017 usec (1.71378e-08 sec) per iteration: 0.101323 sec for 5912262 iterations mean: 0.017353 usec stddev: 0.000226 usec relative stddev: 1.30% RESULT TestCondvarSignalNoOp: trusted_linux_x86-64= {0.017353, 0.000226} us Measuring clock cycles: first runs (cycles): 227 101 68 12938 82 66 68 68 68 68 ... slowest runs (cycles): ... 70 71 71 71 71 77 82 101 227 12938 min: 66 cycles q1: 68 cycles median: 68 cycles q3: 69 cycles max: 12938 cycles RESULT TestCondvarSignalNoOp_CycleCount: trusted_linux_x86-64= {68, 1} count Apparent clock speed: 3919 MHz TestThreadCreateAndJoin: Measuring real time: 109.872 usec (0.000109872 sec) per iteration: 0.0109872 sec for 100 iterations 88.069 usec (8.80686e-05 sec) per iteration: 0.0801425 sec for 910 iterations 86.875 usec (8.68754e-05 sec) per iteration: 0.0790566 sec for 910 iterations 93.625 usec (9.3625e-05 sec) per iteration: 0.0851987 sec for 910 iterations 97.990 usec (9.79903e-05 sec) per iteration: 0.0891712 sec for 910 iterations 109.001 usec (0.000109001 sec) per iteration: 0.0991914 sec for 910 iterations mean: 95.112163 usec stddev: 8.010789 usec relative stddev: 8.42% RESULT TestThreadCreateAndJoin: trusted_linux_x86-64= {95.112163, 8.010789} us Measuring clock cycles: first runs (cycles): 268864 227744 258521 228888 181207 233431 191740 265470 199090 203690 ... slowest runs (cycles): ... 299646 342703 366671 421336 443279 472482 503812 520955 538275 575480 min: 144488 cycles q1: 156594 cycles median: 186044 cycles q3: 221494 cycles max: 575480 cycles RESULT TestThreadCreateAndJoin_CycleCount: trusted_linux_x86-64= {186044, 64900} count Apparent clock speed: 1956 MHz TestThreadWakeup: Measuring real time: 15.225 usec (1.52251e-05 sec) per iteration: 0.00152251 sec for 100 iterations 16.374 usec (1.63741e-05 sec) per iteration: 0.107545 sec for 6568 iterations 20.170 usec (2.01698e-05 sec) per iteration: 0.132475 sec for 6568 iterations 17.712 usec (1.77118e-05 sec) per iteration: 0.116331 sec for 6568 iterations 17.676 usec (1.76761e-05 sec) per iteration: 0.116097 sec for 6568 iterations 17.176 usec (1.71759e-05 sec) per iteration: 0.112811 sec for 6568 iterations mean: 17.821533 usec stddev: 1.269605 usec relative stddev: 7.12% RESULT TestThreadWakeup: trusted_linux_x86-64= {17.821533, 1.269605} us Measuring clock cycles: first runs (cycles): 36272 33702 54719 31397 31864 32781 33005 32678 32620 32037 ... slowest runs (cycles): ... 42025 44108 44238 44924 45162 46665 47973 50939 54719 59594 min: 31397 cycles q1: 35953 cycles median: 36652 cycles q3: 37986 cycles max: 59594 cycles RESULT TestThreadWakeup_CycleCount: trusted_linux_x86-64= {36652, 2033} count Apparent clock speed: 2057 MHz WARNING: should have taken less than 2.000000 secs [ OK ] nacl_newlib.run_trusted_performance_test (4440 ms) scons: done building targets. ********************************************************************** COMMAND EXECUTION REPORT ********************************************************************** 7 command_tester.py 1 doSizeCheck ********************************************************************** ENVIRONMENT USAGE REPORT ********************************************************************** 5 nacl-x86-64 3 opt-linux-x86-64 Command return code: 0