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 force_sel_ldr=../out_64/sel_ldr force_irt=../out_64/irt_x64/irt_core.nexe perf_prefix=gn_ force_bootstrap=../out_64/nacl_helper_bootstrap scons: Reading SConscript files ... ====================================================================== SCONS ARGS:['scons.py', '--verbose', '-k', '-j1', '--mode=nacl,opt-host', 'platform=x86-64', '--clang', '--asan', 'large_tests', 'force_sel_ldr=../out_64/sel_ldr', 'force_irt=../out_64/irt_x64/irt_core.nexe', 'perf_prefix=gn_', 'force_bootstrap=../out_64/nacl_helper_bootstrap'] ====================================================================== ====================================================================== 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: 21669420 kB MemAvailable: 29486536 kB ====================================================================== LOAD: ====================================================================== 1.22 1.60 1.23 1/432 15558 ====================================================================== UPTIME: ====================================================================== 956.94 5890.92 ====================================================================== 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 gn_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 gn_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 gn_x86-64_nnacl_newlib_static [15566,1680312320:13:00:24.319910] Native Client module will be loaded at base address 0x0000574d00000000 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: gn_x86-64_nnacl_newlib_static= 375 count RESULT InstructionBytesPerSyscall: gn_x86-64_nnacl_newlib_static= 1506 count RESULT JumpsPerSyscall: gn_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 gn_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 /b/s/w/ir/kitchen-workdir/out_64/nacl_helper_bootstrap /b/s/w/ir/kitchen-workdir/out_64/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -- scons-out/nacl-x86-64/obj/tests/benchmark/benchmark_test.nexe gn_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 /b/s/w/ir/kitchen-workdir/out_64/nacl_helper_bootstrap /b/s/w/ir/kitchen-workdir/out_64/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -- scons-out/nacl-x86-64/obj/tests/benchmark/benchmark_test.nexe gn_x86-64_nnacl_newlib_static [15571,4071315328:13:00:24.526535] Native Client module will be loaded at base address 0x0000081500000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: gn_x86-64_nnacl_newlib_static= {0.109910, 0.004030} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test (1730 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 gn_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 /b/s/w/ir/kitchen-workdir/out_64/nacl_helper_bootstrap /b/s/w/ir/kitchen-workdir/out_64/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 /b/s/w/ir/kitchen-workdir/out_64/nacl_helper_bootstrap /b/s/w/ir/kitchen-workdir/out_64/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 (74830 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: gn_x86-64_nnacl_newlib_static= 525.291 KB RESULT hello_world_size.out: ZIPPED_gn_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 gn_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 /b/s/w/ir/kitchen-workdir/out_64/nacl_helper_bootstrap /b/s/w/ir/kitchen-workdir/out_64/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -e -- scons-out/nacl-x86-64/obj/tests/performance/performance_test.nexe gn_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 /b/s/w/ir/kitchen-workdir/out_64/nacl_helper_bootstrap /b/s/w/ir/kitchen-workdir/out_64/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -e -- scons-out/nacl-x86-64/obj/tests/performance/performance_test.nexe gn_x86-64_nnacl_newlib_static [15599,2509264768:13:00:40.279087] Native Client module will be loaded at base address 0x00005cb100000000 TestNull: Measuring real time: 0.006 usec (5.64e-09 sec) per iteration: 5.64e-07 sec for 100 iterations 0.002 usec (2.048e-09 sec) per iteration: 2.048e-06 sec for 1000 iterations 0.002 usec (1.8765e-09 sec) per iteration: 1.8765e-05 sec for 10000 iterations 0.002 usec (1.8921e-09 sec) per iteration: 0.100831 sec for 53290700 iterations 0.002 usec (1.8901e-09 sec) per iteration: 0.100725 sec for 53290700 iterations 0.002 usec (1.88626e-09 sec) per iteration: 0.10052 sec for 53290700 iterations 0.002 usec (1.89264e-09 sec) per iteration: 0.10086 sec for 53290700 iterations 0.002 usec (1.88727e-09 sec) per iteration: 0.100574 sec for 53290700 iterations mean: 0.001890 usec stddev: 0.000003 usec relative stddev: 0.13% RESULT TestNull: gn_x86-64_nnacl_newlib_static= {0.001890, 0.000003} us Measuring clock cycles: first runs (cycles): 94 24 26 22 25 29 20 24 24 29 ... slowest runs (cycles): ... 27 27 27 27 27 28 29 29 29 94 min: 20 cycles q1: 24 cycles median: 24 cycles q3: 25 cycles max: 94 cycles RESULT TestNull_CycleCount: gn_x86-64_nnacl_newlib_static= {24, 1} count Apparent clock speed: 12701 MHz TestNaClSyscall: Measuring real time: 0.152 usec (1.5157e-07 sec) per iteration: 1.5157e-05 sec for 100 iterations 0.131 usec (1.30795e-07 sec) per iteration: 0.0862934 sec for 659761 iterations 0.133 usec (1.33157e-07 sec) per iteration: 0.0878518 sec for 659761 iterations 0.129 usec (1.29143e-07 sec) per iteration: 0.0852035 sec for 659761 iterations 0.141 usec (1.40527e-07 sec) per iteration: 0.0927145 sec for 659761 iterations 0.142 usec (1.42452e-07 sec) per iteration: 0.0939842 sec for 659761 iterations mean: 0.135215 usec stddev: 0.005315 usec relative stddev: 3.93% RESULT TestNaClSyscall: gn_x86-64_nnacl_newlib_static= {0.135215, 0.005315} us Measuring clock cycles: first runs (cycles): 318 209 205 187 187 183 185 184 185 183 ... slowest runs (cycles): ... 187 187 187 187 189 205 209 226 226 318 min: 182 cycles q1: 183 cycles median: 185 cycles q3: 186 cycles max: 318 cycles RESULT TestNaClSyscall_CycleCount: gn_x86-64_nnacl_newlib_static= {185, 3} count Apparent clock speed: 1368 MHz TestSetjmpLongjmp: Measuring real time: 0.015 usec (1.501e-08 sec) per iteration: 1.501e-06 sec for 100 iterations 0.013 usec (1.2781e-08 sec) per iteration: 1.2781e-05 sec for 1000 iterations 0.013 usec (1.27695e-08 sec) per iteration: 0.0999101 sec for 7824113 iterations 0.013 usec (1.27611e-08 sec) per iteration: 0.0998442 sec for 7824113 iterations 0.013 usec (1.31091e-08 sec) per iteration: 0.102567 sec for 7824113 iterations 0.013 usec (1.31383e-08 sec) per iteration: 0.102796 sec for 7824113 iterations 0.013 usec (1.27865e-08 sec) per iteration: 0.100043 sec for 7824113 iterations mean: 0.012913 usec stddev: 0.000173 usec relative stddev: 1.34% RESULT TestSetjmpLongjmp: gn_x86-64_nnacl_newlib_static= {0.012913, 0.000173} us Measuring clock cycles: first runs (cycles): 139 57 62 66 37 31 29 26 26 27 ... slowest runs (cycles): ... 29 29 31 31 31 37 57 62 66 139 min: 24 cycles q1: 26 cycles median: 27 cycles q3: 28 cycles max: 139 cycles RESULT TestSetjmpLongjmp_CycleCount: gn_x86-64_nnacl_newlib_static= {27, 2} count Apparent clock speed: 2091 MHz TestClockGetTime: Measuring real time: 0.228 usec (2.2826e-07 sec) per iteration: 2.2826e-05 sec for 100 iterations 0.154 usec (1.53667e-07 sec) per iteration: 0.067321 sec for 438096 iterations 0.154 usec (1.53758e-07 sec) per iteration: 0.0673608 sec for 438096 iterations 0.148 usec (1.48459e-07 sec) per iteration: 0.0650395 sec for 438096 iterations 0.154 usec (1.53628e-07 sec) per iteration: 0.0673037 sec for 438096 iterations 0.151 usec (1.50656e-07 sec) per iteration: 0.0660016 sec for 438096 iterations mean: 0.152034 usec stddev: 0.002138 usec relative stddev: 1.41% RESULT TestClockGetTime: gn_x86-64_nnacl_newlib_static= {0.152034, 0.002138} us Measuring clock cycles: first runs (cycles): 530 365 341 396 341 341 339 343 340 339 ... slowest runs (cycles): ... 341 341 341 343 343 345 365 381 396 530 min: 334 cycles q1: 337 cycles median: 339 cycles q3: 339 cycles max: 530 cycles RESULT TestClockGetTime_CycleCount: gn_x86-64_nnacl_newlib_static= {339, 2} count Apparent clock speed: 2230 MHz TestTlsVariable: Measuring real time: 0.009 usec (8.58e-09 sec) per iteration: 8.58e-07 sec for 100 iterations 0.006 usec (5.776e-09 sec) per iteration: 5.776e-06 sec for 1000 iterations 0.006 usec (5.5855e-09 sec) per iteration: 5.5855e-05 sec for 10000 iterations 0.006 usec (5.66767e-09 sec) per iteration: 0.101471 sec for 17903500 iterations 0.006 usec (5.65836e-09 sec) per iteration: 0.101305 sec for 17903500 iterations 0.006 usec (5.65562e-09 sec) per iteration: 0.101255 sec for 17903500 iterations 0.006 usec (5.66592e-09 sec) per iteration: 0.10144 sec for 17903500 iterations 0.006 usec (5.6672e-09 sec) per iteration: 0.101463 sec for 17903500 iterations mean: 0.005663 usec stddev: 0.000005 usec relative stddev: 0.09% RESULT TestTlsVariable: gn_x86-64_nnacl_newlib_static= {0.005663, 0.000005} us Measuring clock cycles: first runs (cycles): 159 75 55 71 48 30 26 33 26 29 ... slowest runs (cycles): ... 33 33 33 33 33 48 55 71 75 159 min: 22 cycles q1: 26 cycles median: 29 cycles q3: 31 cycles max: 159 cycles RESULT TestTlsVariable_CycleCount: gn_x86-64_nnacl_newlib_static= {29, 5} count Apparent clock speed: 5121 MHz TestMmapAnonymous: Measuring real time: 2.195 usec (2.19466e-06 sec) per iteration: 0.000219466 sec for 100 iterations 1.815 usec (1.81544e-06 sec) per iteration: 0.0827204 sec for 45565 iterations 1.812 usec (1.81212e-06 sec) per iteration: 0.0825691 sec for 45565 iterations 1.822 usec (1.82177e-06 sec) per iteration: 0.0830089 sec for 45565 iterations 1.828 usec (1.82781e-06 sec) per iteration: 0.0832842 sec for 45565 iterations 1.810 usec (1.80963e-06 sec) per iteration: 0.0824559 sec for 45565 iterations mean: 1.817353 usec stddev: 0.006626 usec relative stddev: 0.36% RESULT TestMmapAnonymous: gn_x86-64_nnacl_newlib_static= {1.817353, 0.006626} us Measuring clock cycles: first runs (cycles): 7810 5002 5244 5046 5150 4959 5210 4805 5401 19526 ... slowest runs (cycles): ... 6380 6411 6553 6606 6910 7079 7126 7363 7810 19526 min: 3872 cycles q1: 3947 cycles median: 5319 cycles q3: 5724 cycles max: 19526 cycles RESULT TestMmapAnonymous_CycleCount: gn_x86-64_nnacl_newlib_static= {5319, 1777} count Apparent clock speed: 2927 MHz TestAtomicIncrement: Measuring real time: 0.017 usec (1.689e-08 sec) per iteration: 1.689e-06 sec for 100 iterations 0.011 usec (1.0802e-08 sec) per iteration: 1.0802e-05 sec for 1000 iterations 0.009 usec (9.4336e-09 sec) per iteration: 0.0873319 sec for 9257544 iterations 0.009 usec (9.44704e-09 sec) per iteration: 0.0874564 sec for 9257544 iterations 0.009 usec (9.44507e-09 sec) per iteration: 0.0874382 sec for 9257544 iterations 0.009 usec (9.42431e-09 sec) per iteration: 0.0872459 sec for 9257544 iterations 0.009 usec (9.43918e-09 sec) per iteration: 0.0873836 sec for 9257544 iterations mean: 0.009438 usec stddev: 0.000008 usec relative stddev: 0.09% RESULT TestAtomicIncrement: gn_x86-64_nnacl_newlib_static= {0.009438, 0.000008} us Measuring clock cycles: first runs (cycles): 117 53 50 62 52 51 50 55 53 53 ... slowest runs (cycles): ... 62 62 62 64 64 64 64 66 117 220 min: 48 cycles q1: 53 cycles median: 55 cycles q3: 57 cycles max: 220 cycles RESULT TestAtomicIncrement_CycleCount: gn_x86-64_nnacl_newlib_static= {55, 4} count Apparent clock speed: 5828 MHz TestUncontendedMutexLock: Measuring real time: 0.035 usec (3.524e-08 sec) per iteration: 3.524e-06 sec for 100 iterations 0.026 usec (2.6401e-08 sec) per iteration: 2.6401e-05 sec for 1000 iterations 0.025 usec (2.51887e-08 sec) per iteration: 0.0954082 sec for 3787735 iterations 0.025 usec (2.4513e-08 sec) per iteration: 0.0928488 sec for 3787735 iterations 0.025 usec (2.53731e-08 sec) per iteration: 0.0961068 sec for 3787735 iterations 0.025 usec (2.45725e-08 sec) per iteration: 0.0930741 sec for 3787735 iterations 0.029 usec (2.87562e-08 sec) per iteration: 0.108921 sec for 3787735 iterations mean: 0.025681 usec stddev: 0.001574 usec relative stddev: 6.13% RESULT TestUncontendedMutexLock: gn_x86-64_nnacl_newlib_static= {0.025681, 0.001574} us Measuring clock cycles: first runs (cycles): 253 108 86 84 84 86 86 83 84 85 ... slowest runs (cycles): ... 90 90 92 93 93 96 97 99 108 253 min: 79 cycles q1: 84 cycles median: 86 cycles q3: 86 cycles max: 253 cycles RESULT TestUncontendedMutexLock_CycleCount: gn_x86-64_nnacl_newlib_static= {86, 2} count Apparent clock speed: 3349 MHz TestCondvarSignalNoOp: Measuring real time: 0.299 usec (2.988e-07 sec) per iteration: 2.988e-05 sec for 100 iterations 0.221 usec (2.21363e-07 sec) per iteration: 0.074084 sec for 334672 iterations 0.220 usec (2.19786e-07 sec) per iteration: 0.0735563 sec for 334672 iterations 0.220 usec (2.20051e-07 sec) per iteration: 0.0736449 sec for 334672 iterations 0.220 usec (2.20092e-07 sec) per iteration: 0.0736586 sec for 334672 iterations 0.223 usec (2.22544e-07 sec) per iteration: 0.0744793 sec for 334672 iterations mean: 0.220767 usec stddev: 0.001043 usec relative stddev: 0.47% RESULT TestCondvarSignalNoOp: gn_x86-64_nnacl_newlib_static= {0.220767, 0.001043} us Measuring clock cycles: first runs (cycles): 1234 523 509 501 506 502 519 510 501 504 ... slowest runs (cycles): ... 545 547 554 554 555 559 564 609 658 1234 min: 497 cycles q1: 506 cycles median: 517 cycles q3: 528 cycles max: 1234 cycles RESULT TestCondvarSignalNoOp_CycleCount: gn_x86-64_nnacl_newlib_static= {517, 22} count Apparent clock speed: 2342 MHz TestThreadCreateAndJoin: Measuring real time: 56.589 usec (5.65892e-05 sec) per iteration: 0.00565892 sec for 100 iterations 28.529 usec (2.85294e-05 sec) per iteration: 0.0504115 sec for 1767 iterations 25.974 usec (2.59741e-05 sec) per iteration: 0.0458962 sec for 1767 iterations 29.955 usec (2.99546e-05 sec) per iteration: 0.0529297 sec for 1767 iterations 34.416 usec (3.44164e-05 sec) per iteration: 0.0608139 sec for 1767 iterations 32.933 usec (3.29335e-05 sec) per iteration: 0.0581934 sec for 1767 iterations mean: 30.361589 usec stddev: 3.027515 usec relative stddev: 9.97% RESULT TestThreadCreateAndJoin: gn_x86-64_nnacl_newlib_static= {30.361589, 3.027515} us Measuring clock cycles: first runs (cycles): 70525 367744 74425 147313 84021 159839 72142 97864 106337 52503 ... slowest runs (cycles): ... 147313 148877 158788 159839 161258 178751 204453 242468 263853 367744 min: 47793 cycles q1: 51401 cycles median: 68426 cycles q3: 92442 cycles max: 367744 cycles RESULT TestThreadCreateAndJoin_CycleCount: gn_x86-64_nnacl_newlib_static= {68426, 41041} count Apparent clock speed: 2254 MHz TestThreadWakeup: Measuring real time: 14.858 usec (1.48584e-05 sec) per iteration: 0.00148584 sec for 100 iterations 14.997 usec (1.49971e-05 sec) per iteration: 0.10093 sec for 6730 iterations 15.073 usec (1.50727e-05 sec) per iteration: 0.101439 sec for 6730 iterations 15.869 usec (1.58694e-05 sec) per iteration: 0.106801 sec for 6730 iterations 15.989 usec (1.59891e-05 sec) per iteration: 0.107607 sec for 6730 iterations 16.422 usec (1.64217e-05 sec) per iteration: 0.110518 sec for 6730 iterations mean: 15.669993 usec stddev: 0.550694 usec relative stddev: 3.51% RESULT TestThreadWakeup: gn_x86-64_nnacl_newlib_static= {15.669993, 0.550694} us Measuring clock cycles: first runs (cycles): 58071 35379 34744 34836 37079 35327 35725 36239 43886 39591 ... slowest runs (cycles): ... 42577 42595 43405 43589 43886 43941 48887 53363 58071 204141 min: 30286 cycles q1: 34662 cycles median: 35353 cycles q3: 36413 cycles max: 204141 cycles RESULT TestThreadWakeup_CycleCount: gn_x86-64_nnacl_newlib_static= {35353, 1751} count Apparent clock speed: 2256 MHz TestCatchingFault: Measuring real time: 3.000 usec (3.00042e-06 sec) per iteration: 0.000300042 sec for 100 iterations 1.746 usec (1.74628e-06 sec) per iteration: 0.0582002 sec for 33328 iterations 1.763 usec (1.76304e-06 sec) per iteration: 0.0587586 sec for 33328 iterations 1.775 usec (1.77525e-06 sec) per iteration: 0.0591655 sec for 33328 iterations 1.761 usec (1.76138e-06 sec) per iteration: 0.0587032 sec for 33328 iterations 1.787 usec (1.78684e-06 sec) per iteration: 0.0595519 sec for 33328 iterations mean: 1.766559 usec stddev: 0.013692 usec relative stddev: 0.78% RESULT TestCatchingFault: gn_x86-64_nnacl_newlib_static= {1.766559, 0.013692} us Measuring clock cycles: first runs (cycles): 4303 3800 3748 3736 3727 3735 3727 3738 3700 3733 ... slowest runs (cycles): ... 4475 4481 4483 4490 4550 4613 4620 4620 4675 4769 min: 3696 cycles q1: 3727 cycles median: 3740 cycles q3: 3913 cycles max: 4769 cycles RESULT TestCatchingFault_CycleCount: gn_x86-64_nnacl_newlib_static= {3740, 186} count Apparent clock speed: 2117 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 33.840 usec (3.38404e-05 sec) per iteration: 0.00338404 sec for 100 iterations 34.051 usec (3.40515e-05 sec) per iteration: 0.100622 sec for 2955 iterations 31.295 usec (3.12947e-05 sec) per iteration: 0.0924759 sec for 2955 iterations 33.087 usec (3.30873e-05 sec) per iteration: 0.0977729 sec for 2955 iterations 27.921 usec (2.79206e-05 sec) per iteration: 0.0825054 sec for 2955 iterations 27.875 usec (2.78749e-05 sec) per iteration: 0.0823702 sec for 2955 iterations mean: 30.845778 usec stddev: 2.564580 usec relative stddev: 8.31% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: gn_x86-64_nnacl_newlib_static= {30.845778, 2.564580} us Measuring clock cycles: first runs (cycles): 52921 76007 50176 49424 54916 60203 57862 61875 101409 100631 ... slowest runs (cycles): ... 112745 114633 123433 141147 153000 274327 280865 588032 865803 2854543 min: 47879 cycles q1: 51852 cycles median: 55028 cycles q3: 75224 cycles max: 2854543 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: gn_x86-64_nnacl_newlib_static= {55028, 23372} count Apparent clock speed: 1784 MHz [ OK ] nacl_newlib.run_performance_test (5649 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 gn_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 (5220 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 gn_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 (1050 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 gn_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 gn_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 gn_trusted_linux_x86-64 TestNull: Measuring real time: 0.007 usec (7.4e-09 sec) per iteration: 7.4e-07 sec for 100 iterations 0.002 usec (2.002e-09 sec) per iteration: 2.002e-06 sec for 1000 iterations 0.002 usec (1.8674e-09 sec) per iteration: 1.8674e-05 sec for 10000 iterations 0.002 usec (1.8903e-09 sec) per iteration: 0.101226 sec for 53550390 iterations 0.002 usec (1.8881e-09 sec) per iteration: 0.101109 sec for 53550390 iterations 0.002 usec (1.88582e-09 sec) per iteration: 0.100986 sec for 53550390 iterations 0.002 usec (1.94278e-09 sec) per iteration: 0.104037 sec for 53550390 iterations 0.002 usec (1.88529e-09 sec) per iteration: 0.100958 sec for 53550390 iterations mean: 0.001898 usec stddev: 0.000022 usec relative stddev: 1.17% RESULT TestNull: gn_trusted_linux_x86-64= {0.001898, 0.000022} us Measuring clock cycles: first runs (cycles): 64 24 25 22 26 22 25 24 24 24 ... slowest runs (cycles): ... 27 27 27 27 27 27 27 27 27 64 min: 22 cycles q1: 25 cycles median: 26 cycles q3: 27 cycles max: 64 cycles RESULT TestNull_CycleCount: gn_trusted_linux_x86-64= {26, 2} count Apparent clock speed: 13695 MHz TestHostSyscall: Measuring real time: 0.071 usec (7.11e-08 sec) per iteration: 7.11e-06 sec for 100 iterations 0.062 usec (6.1974e-08 sec) per iteration: 6.1974e-05 sec for 1000 iterations 0.058 usec (5.81243e-08 sec) per iteration: 0.0937881 sec for 1613579 iterations 0.058 usec (5.81383e-08 sec) per iteration: 0.0938107 sec for 1613579 iterations 0.058 usec (5.81459e-08 sec) per iteration: 0.093823 sec for 1613579 iterations 0.058 usec (5.80252e-08 sec) per iteration: 0.0936282 sec for 1613579 iterations 0.058 usec (5.8122e-08 sec) per iteration: 0.0937845 sec for 1613579 iterations mean: 0.058111 usec stddev: 0.000044 usec relative stddev: 0.08% RESULT TestHostSyscall: gn_trusted_linux_x86-64= {0.058111, 0.000044} us Measuring clock cycles: first runs (cycles): 674 165 145 145 146 149 150 148 149 147 ... slowest runs (cycles): ... 150 150 150 150 150 150 150 150 165 674 min: 145 cycles q1: 147 cycles median: 148 cycles q3: 150 cycles max: 674 cycles RESULT TestHostSyscall_CycleCount: gn_trusted_linux_x86-64= {148, 3} count Apparent clock speed: 2547 MHz TestSetjmpLongjmp: Measuring real time: 0.207 usec (2.0712e-07 sec) per iteration: 2.0712e-05 sec for 100 iterations 0.130 usec (1.30402e-07 sec) per iteration: 0.0629595 sec for 482811 iterations 0.130 usec (1.30212e-07 sec) per iteration: 0.0628679 sec for 482811 iterations 0.135 usec (1.35474e-07 sec) per iteration: 0.0654084 sec for 482811 iterations 0.130 usec (1.3002e-07 sec) per iteration: 0.0627752 sec for 482811 iterations 0.130 usec (1.30291e-07 sec) per iteration: 0.0629061 sec for 482811 iterations mean: 0.131280 usec stddev: 0.002101 usec relative stddev: 1.60% RESULT TestSetjmpLongjmp: gn_trusted_linux_x86-64= {0.131280, 0.002101} us Measuring clock cycles: first runs (cycles): 939 403 361 352 350 350 352 350 352 348 ... slowest runs (cycles): ... 356 356 356 357 357 357 361 403 517 939 min: 347 cycles q1: 350 cycles median: 352 cycles q3: 354 cycles max: 939 cycles RESULT TestSetjmpLongjmp_CycleCount: gn_trusted_linux_x86-64= {352, 4} count Apparent clock speed: 2681 MHz TestClockGetTime: Measuring real time: 0.068 usec (6.819e-08 sec) per iteration: 6.819e-06 sec for 100 iterations 0.065 usec (6.4552e-08 sec) per iteration: 6.4552e-05 sec for 1000 iterations 0.069 usec (6.94688e-08 sec) per iteration: 0.107617 sec for 1549138 iterations 0.072 usec (7.17684e-08 sec) per iteration: 0.111179 sec for 1549138 iterations 0.070 usec (6.97701e-08 sec) per iteration: 0.108084 sec for 1549138 iterations 0.069 usec (6.91036e-08 sec) per iteration: 0.107051 sec for 1549138 iterations 0.070 usec (7.00871e-08 sec) per iteration: 0.108575 sec for 1549138 iterations mean: 0.070040 usec stddev: 0.000924 usec relative stddev: 1.32% RESULT TestClockGetTime: gn_trusted_linux_x86-64= {0.070040, 0.000924} us Measuring clock cycles: first runs (cycles): 352 216 469 167 163 163 160 163 161 161 ... slowest runs (cycles): ... 165 165 165 165 167 167 168 216 352 469 min: 160 cycles q1: 161 cycles median: 161 cycles q3: 163 cycles max: 469 cycles RESULT TestClockGetTime_CycleCount: gn_trusted_linux_x86-64= {161, 2} count Apparent clock speed: 2299 MHz TestTlsVariable: Measuring real time: 0.005 usec (4.89e-09 sec) per iteration: 4.89e-07 sec for 100 iterations 0.002 usec (1.959e-09 sec) per iteration: 1.959e-06 sec for 1000 iterations 0.002 usec (1.8631e-09 sec) per iteration: 1.8631e-05 sec for 10000 iterations 0.002 usec (1.89468e-09 sec) per iteration: 0.101695 sec for 53673984 iterations 0.002 usec (1.8873e-09 sec) per iteration: 0.101299 sec for 53673984 iterations 0.002 usec (1.88477e-09 sec) per iteration: 0.101163 sec for 53673984 iterations 0.002 usec (1.8861e-09 sec) per iteration: 0.101235 sec for 53673984 iterations 0.002 usec (1.92457e-09 sec) per iteration: 0.103299 sec for 53673984 iterations mean: 0.001895 usec stddev: 0.000015 usec relative stddev: 0.79% RESULT TestTlsVariable: gn_trusted_linux_x86-64= {0.001895, 0.000015} us Measuring clock cycles: first runs (cycles): 75 27 26 26 29 26 25 26 24 26 ... slowest runs (cycles): ... 29 29 29 29 29 29 29 29 29 75 min: 24 cycles q1: 26 cycles median: 26 cycles q3: 28 cycles max: 75 cycles RESULT TestTlsVariable_CycleCount: gn_trusted_linux_x86-64= {26, 2} count Apparent clock speed: 13717 MHz TestMmapAnonymous: Measuring real time: 0.830 usec (8.2987e-07 sec) per iteration: 8.2987e-05 sec for 100 iterations 0.711 usec (7.10643e-07 sec) per iteration: 0.0856325 sec for 120500 iterations 0.697 usec (6.96812e-07 sec) per iteration: 0.0839659 sec for 120500 iterations 0.698 usec (6.97662e-07 sec) per iteration: 0.0840683 sec for 120500 iterations 0.697 usec (6.97445e-07 sec) per iteration: 0.0840421 sec for 120500 iterations 0.697 usec (6.97364e-07 sec) per iteration: 0.0840324 sec for 120500 iterations mean: 0.699985 usec stddev: 0.005336 usec relative stddev: 0.76% RESULT TestMmapAnonymous: gn_trusted_linux_x86-64= {0.699985, 0.005336} us Measuring clock cycles: first runs (cycles): 2390 1587 1524 1532 1511 1514 1518 1562 1525 1536 ... slowest runs (cycles): ... 1551 1551 1562 1562 1566 1571 1573 1587 1812 2390 min: 1502 cycles q1: 1514 cycles median: 1524 cycles q3: 1532 cycles max: 2390 cycles RESULT TestMmapAnonymous_CycleCount: gn_trusted_linux_x86-64= {1524, 18} count Apparent clock speed: 2177 MHz TestAtomicIncrement: Measuring real time: 0.014 usec (1.355e-08 sec) per iteration: 1.355e-06 sec for 100 iterations 0.009 usec (9.323e-09 sec) per iteration: 9.323e-06 sec for 1000 iterations 0.010 usec (9.91e-09 sec) per iteration: 9.91e-05 sec for 10000 iterations 0.009 usec (9.42525e-09 sec) per iteration: 0.0951084 sec for 10090817 iterations 0.009 usec (9.42138e-09 sec) per iteration: 0.0950694 sec for 10090817 iterations 0.009 usec (9.42987e-09 sec) per iteration: 0.0951551 sec for 10090817 iterations 0.009 usec (9.42198e-09 sec) per iteration: 0.0950754 sec for 10090817 iterations 0.010 usec (9.67978e-09 sec) per iteration: 0.0976769 sec for 10090817 iterations mean: 0.009476 usec stddev: 0.000102 usec relative stddev: 1.08% RESULT TestAtomicIncrement: gn_trusted_linux_x86-64= {0.009476, 0.000102} us Measuring clock cycles: first runs (cycles): 110 53 51 53 53 51 50 54 50 53 ... slowest runs (cycles): ... 55 55 55 55 55 55 55 55 55 110 min: 50 cycles q1: 52 cycles median: 53 cycles q3: 53 cycles max: 110 cycles RESULT TestAtomicIncrement_CycleCount: gn_trusted_linux_x86-64= {53, 1} count Apparent clock speed: 5593 MHz TestUncontendedMutexLock: Measuring real time: 0.052 usec (5.212e-08 sec) per iteration: 5.212e-06 sec for 100 iterations 0.039 usec (3.9409e-08 sec) per iteration: 3.9409e-05 sec for 1000 iterations 0.026 usec (2.5766e-08 sec) per iteration: 0.0653811 sec for 2537491 iterations 0.026 usec (2.61709e-08 sec) per iteration: 0.0664083 sec for 2537491 iterations 0.026 usec (2.60696e-08 sec) per iteration: 0.0661514 sec for 2537491 iterations 0.026 usec (2.56414e-08 sec) per iteration: 0.0650648 sec for 2537491 iterations 0.026 usec (2.56748e-08 sec) per iteration: 0.0651497 sec for 2537491 iterations mean: 0.025865 usec stddev: 0.000215 usec relative stddev: 0.83% RESULT TestUncontendedMutexLock: gn_trusted_linux_x86-64= {0.025865, 0.000215} us Measuring clock cycles: first runs (cycles): 493 112 82 85 86 286 83 83 82 83 ... slowest runs (cycles): ... 86 86 86 86 86 86 112 127 286 493 min: 81 cycles q1: 84 cycles median: 84 cycles q3: 86 cycles max: 493 cycles RESULT TestUncontendedMutexLock_CycleCount: gn_trusted_linux_x86-64= {84, 2} count Apparent clock speed: 3248 MHz TestCondvarSignalNoOp: Measuring real time: 0.022 usec (2.188e-08 sec) per iteration: 2.188e-06 sec for 100 iterations 0.017 usec (1.6756e-08 sec) per iteration: 1.6756e-05 sec for 1000 iterations 0.017 usec (1.71862e-08 sec) per iteration: 0.102567 sec for 5968011 iterations 0.017 usec (1.70674e-08 sec) per iteration: 0.101858 sec for 5968011 iterations 0.017 usec (1.70727e-08 sec) per iteration: 0.10189 sec for 5968011 iterations 0.017 usec (1.714e-08 sec) per iteration: 0.102292 sec for 5968011 iterations 0.017 usec (1.70791e-08 sec) per iteration: 0.101929 sec for 5968011 iterations mean: 0.017109 usec stddev: 0.000047 usec relative stddev: 0.27% RESULT TestCondvarSignalNoOp: gn_trusted_linux_x86-64= {0.017109, 0.000047} us Measuring clock cycles: first runs (cycles): 154 75 68 68 68 279 69 69 68 68 ... slowest runs (cycles): ... 70 70 70 70 70 71 71 75 154 279 min: 68 cycles q1: 68 cycles median: 68 cycles q3: 69 cycles max: 279 cycles RESULT TestCondvarSignalNoOp_CycleCount: gn_trusted_linux_x86-64= {68, 1} count Apparent clock speed: 3974 MHz TestThreadCreateAndJoin: Measuring real time: 89.051 usec (8.90511e-05 sec) per iteration: 0.00890511 sec for 100 iterations 89.226 usec (8.9226e-05 sec) per iteration: 0.100112 sec for 1122 iterations 83.163 usec (8.31633e-05 sec) per iteration: 0.0933092 sec for 1122 iterations 91.819 usec (9.18189e-05 sec) per iteration: 0.103021 sec for 1122 iterations 81.160 usec (8.11598e-05 sec) per iteration: 0.0910612 sec for 1122 iterations 85.976 usec (8.59757e-05 sec) per iteration: 0.0964648 sec for 1122 iterations mean: 86.268734 usec stddev: 3.882809 usec relative stddev: 4.50% RESULT TestThreadCreateAndJoin: gn_trusted_linux_x86-64= {86.268734, 3.882809} us Measuring clock cycles: first runs (cycles): 231577 197680 210101 257922 199297 199308 184430 181528 200229 294527 ... slowest runs (cycles): ... 244285 245479 257922 279576 294527 298610 325363 340747 376288 437524 min: 166487 cycles q1: 177874 cycles median: 193300 cycles q3: 209229 cycles max: 437524 cycles RESULT TestThreadCreateAndJoin_CycleCount: gn_trusted_linux_x86-64= {193300, 31355} count Apparent clock speed: 2241 MHz TestThreadWakeup: Measuring real time: 13.173 usec (1.31734e-05 sec) per iteration: 0.00131734 sec for 100 iterations 15.717 usec (1.57173e-05 sec) per iteration: 0.11931 sec for 7591 iterations 15.215 usec (1.52148e-05 sec) per iteration: 0.115495 sec for 7591 iterations 14.637 usec (1.46368e-05 sec) per iteration: 0.111108 sec for 7591 iterations 14.775 usec (1.47749e-05 sec) per iteration: 0.112156 sec for 7591 iterations 15.168 usec (1.51676e-05 sec) per iteration: 0.115138 sec for 7591 iterations mean: 15.102291 usec stddev: 0.379201 usec relative stddev: 2.51% RESULT TestThreadWakeup: gn_trusted_linux_x86-64= {15.102291, 0.379201} us Measuring clock cycles: first runs (cycles): 105710 153785 28869 28755 29626 29399 28598 28448 31498 32704 ... slowest runs (cycles): ... 37048 37161 43870 44320 48818 64428 105710 113276 134912 153785 min: 24841 cycles q1: 28448 cycles median: 28891 cycles q3: 30056 cycles max: 153785 cycles RESULT TestThreadWakeup_CycleCount: gn_trusted_linux_x86-64= {28891, 1608} count Apparent clock speed: 1913 MHz WARNING: should have taken less than 2.000000 secs [ OK ] nacl_newlib.run_trusted_performance_test (5240 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