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 breakpad_tools_dir=breakpad-out large_tests scons: Reading SConscript files ... ====================================================================== SCONS ARGS:['scons.py', '--verbose', '-k', '-j1', '--mode=nacl,opt-host', 'platform=x86-64', '--clang', 'breakpad_tools_dir=breakpad-out', '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-east1-b-s3qm 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: 20708320 kB MemAvailable: 29483360 kB ====================================================================== LOAD: ====================================================================== 1.91 1.98 1.00 1/432 16287 ====================================================================== UPTIME: ====================================================================== 471.96 2760.44 ====================================================================== 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 ASPPCOM: /b/s/w/ir/kitchen-workdir/third_party/llvm-build/Release+Asserts/bin/clang -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 *** BROKEN run_ncval_annotate_test *** BROKEN run_gdb_invalid_memory_test *** BROKEN run_nanosleep_test *** BROKEN run_timefuncs_test *** BROKEN run_raw_timefuncs_test *** BROKEN run_main_thread_pthread_exit_test2 *** BROKEN run_overflowinline_test *** BROKEN run_stack_frame_noopt_frame_test *** BROKEN run_stack_frame_noopt_noframe_test *** BROKEN run_stack_frame_opt_frame_test *** BROKEN run_stack_frame_opt_noframe_test *** BROKEN run_vla_test *** BROKEN run_nacl_sync_cond_test SKIPPING test text_overlaps_rodata SKIPPING test text_overlaps_data There are 13 broken tests. ====================================================================== B U I L D - O U T P U T: ====================================================================== scons: done reading SConscript files. scons: Building targets ... /b/s/w/ir/cache/vpython/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 --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 /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 [16296,3427452800:15:38:25.512736] Native Client module will be loaded at base address 0x00005b0c00000000 Switching to untrusted: since previous switch: 151 instructions, 490 instruction bytes, 20 jumps Switching to trusted: since previous switch: 11 instructions, 48 instruction bytes, 2 jumps Switching to untrusted: since previous switch: 185 instructions, 596 instruction bytes, 18 jumps Switching to trusted: since previous switch: 9 instructions, 32 instruction bytes, 3 jumps Switching to untrusted: since previous switch: 185 instructions, 596 instruction bytes, 18 jumps Switching to trusted: since previous switch: 9 instructions, 32 instruction bytes, 3 jumps Switching to untrusted: since previous switch: 185 instructions, 596 instruction bytes, 18 jumps Switching to trusted: since previous switch: 9 instructions, 32 instruction bytes, 3 jumps Switching to untrusted: since previous switch: 185 instructions, 596 instruction bytes, 18 jumps RESULT InstructionsPerSyscall: x86-64_nnacl_newlib_static= 185 count RESULT InstructionBytesPerSyscall: x86-64_nnacl_newlib_static= 596 count RESULT JumpsPerSyscall: x86-64_nnacl_newlib_static= 18 count Switching to trusted: since previous switch: 9 instructions, 32 instruction bytes, 3 jumps [ OK ] nacl_newlib.run_signal_handler_single_step_test (0 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 --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 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 [16301,323995520:15:38:25.764414] Native Client module will be loaded at base address 0x0000493d00000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_nnacl_newlib_static= {0.102030, 0.011750} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test (1610 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 --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 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 (74810 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 --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 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 [16329,356054912:15:38:41.311019] Native Client module will be loaded at base address 0x0000053300000000 TestNull: Measuring real time: 0.008 usec (8.1e-09 sec) per iteration: 8.1e-07 sec for 100 iterations 0.004 usec (3.935e-09 sec) per iteration: 3.935e-06 sec for 1000 iterations 0.003 usec (3.2024e-09 sec) per iteration: 3.2024e-05 sec for 10000 iterations 0.002 usec (1.90644e-09 sec) per iteration: 0.0595315 sec for 31226580 iterations 0.002 usec (1.93322e-09 sec) per iteration: 0.0603679 sec for 31226580 iterations 0.002 usec (1.89896e-09 sec) per iteration: 0.0592979 sec for 31226580 iterations 0.002 usec (1.98203e-09 sec) per iteration: 0.061892 sec for 31226580 iterations 0.002 usec (1.90888e-09 sec) per iteration: 0.0596077 sec for 31226580 iterations mean: 0.001926 usec stddev: 0.000030 usec relative stddev: 1.57% RESULT TestNull: x86-64_nnacl_newlib_static= {0.001926, 0.000030} us Measuring clock cycles: first runs (cycles): 95 26 27 27 22 26 26 24 24 26 ... slowest runs (cycles): ... 29 29 29 29 29 31 31 31 31 95 min: 22 cycles q1: 26 cycles median: 26 cycles q3: 28 cycles max: 95 cycles RESULT TestNull_CycleCount: x86-64_nnacl_newlib_static= {26, 2} count Apparent clock speed: 13500 MHz TestNaClSyscall: Measuring real time: 0.108 usec (1.0827e-07 sec) per iteration: 1.0827e-05 sec for 100 iterations 0.136 usec (1.35912e-07 sec) per iteration: 0.125531 sec for 923616 iterations 0.130 usec (1.30341e-07 sec) per iteration: 0.120385 sec for 923616 iterations 0.129 usec (1.29401e-07 sec) per iteration: 0.119517 sec for 923616 iterations 0.131 usec (1.31334e-07 sec) per iteration: 0.121303 sec for 923616 iterations 0.130 usec (1.30259e-07 sec) per iteration: 0.120309 sec for 923616 iterations mean: 0.131449 usec stddev: 0.002314 usec relative stddev: 1.76% RESULT TestNaClSyscall: x86-64_nnacl_newlib_static= {0.131449, 0.002314} us Measuring clock cycles: first runs (cycles): 372 213 187 202 187 207 194 185 200 198 ... slowest runs (cycles): ... 207 207 207 209 213 213 218 218 218 372 min: 182 cycles q1: 189 cycles median: 191 cycles q3: 196 cycles max: 372 cycles RESULT TestNaClSyscall_CycleCount: x86-64_nnacl_newlib_static= {191, 7} count Apparent clock speed: 1453 MHz TestSetjmpLongjmp: Measuring real time: 0.019 usec (1.907e-08 sec) per iteration: 1.907e-06 sec for 100 iterations 0.022 usec (2.2492e-08 sec) per iteration: 2.2492e-05 sec for 1000 iterations 0.013 usec (1.29361e-08 sec) per iteration: 0.0575141 sec for 4446025 iterations 0.013 usec (1.28945e-08 sec) per iteration: 0.0573292 sec for 4446025 iterations 0.013 usec (1.28892e-08 sec) per iteration: 0.0573056 sec for 4446025 iterations 0.013 usec (1.28779e-08 sec) per iteration: 0.0572555 sec for 4446025 iterations 0.013 usec (1.292e-08 sec) per iteration: 0.0574428 sec for 4446025 iterations mean: 0.012904 usec stddev: 0.000021 usec relative stddev: 0.17% RESULT TestSetjmpLongjmp: x86-64_nnacl_newlib_static= {0.012904, 0.000021} us Measuring clock cycles: first runs (cycles): 205 68 51 68 44 44 62 63 43 48 ... slowest runs (cycles): ... 48 51 51 59 59 62 63 68 68 205 min: 29 cycles q1: 33 cycles median: 35 cycles q3: 42 cycles max: 205 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_nnacl_newlib_static= {35, 9} count Apparent clock speed: 2712 MHz TestClockGetTime: Measuring real time: 0.165 usec (1.6511e-07 sec) per iteration: 1.6511e-05 sec for 100 iterations 0.150 usec (1.4984e-07 sec) per iteration: 0.0907517 sec for 605656 iterations 0.152 usec (1.51674e-07 sec) per iteration: 0.0918624 sec for 605656 iterations 0.148 usec (1.47928e-07 sec) per iteration: 0.0895936 sec for 605656 iterations 0.153 usec (1.52656e-07 sec) per iteration: 0.0924573 sec for 605656 iterations 0.152 usec (1.5174e-07 sec) per iteration: 0.0919022 sec for 605656 iterations mean: 0.150768 usec stddev: 0.001689 usec relative stddev: 1.12% RESULT TestClockGetTime: x86-64_nnacl_newlib_static= {0.150768, 0.001689} us Measuring clock cycles: first runs (cycles): 744 422 359 374 383 354 400 352 350 358 ... slowest runs (cycles): ... 401 403 409 411 420 422 422 424 429 744 min: 343 cycles q1: 354 cycles median: 363 cycles q3: 376 cycles max: 744 cycles RESULT TestClockGetTime_CycleCount: x86-64_nnacl_newlib_static= {363, 22} count Apparent clock speed: 2408 MHz TestTlsVariable: Measuring real time: 0.012 usec (1.204e-08 sec) per iteration: 1.204e-06 sec for 100 iterations 0.009 usec (9.348e-09 sec) per iteration: 9.348e-06 sec for 1000 iterations 0.008 usec (8.1956e-09 sec) per iteration: 8.1956e-05 sec for 10000 iterations 0.006 usec (5.70387e-09 sec) per iteration: 0.0695968 sec for 12201669 iterations 0.006 usec (5.70037e-09 sec) per iteration: 0.069554 sec for 12201669 iterations 0.006 usec (5.68132e-09 sec) per iteration: 0.0693215 sec for 12201669 iterations 0.006 usec (5.71776e-09 sec) per iteration: 0.0697662 sec for 12201669 iterations 0.006 usec (5.69166e-09 sec) per iteration: 0.0694477 sec for 12201669 iterations mean: 0.005699 usec stddev: 0.000012 usec relative stddev: 0.21% RESULT TestTlsVariable: x86-64_nnacl_newlib_static= {0.005699, 0.000012} us Measuring clock cycles: first runs (cycles): 189 72 40 37 42 42 42 28 28 33 ... slowest runs (cycles): ... 42 42 42 42 44 46 49 51 72 189 min: 26 cycles q1: 29 cycles median: 31 cycles q3: 33 cycles max: 189 cycles RESULT TestTlsVariable_CycleCount: x86-64_nnacl_newlib_static= {31, 4} count Apparent clock speed: 5440 MHz TestMmapAnonymous: Measuring real time: 2.768 usec (2.76783e-06 sec) per iteration: 0.000276783 sec for 100 iterations 1.881 usec (1.88079e-06 sec) per iteration: 0.0679511 sec for 36129 iterations 1.800 usec (1.79953e-06 sec) per iteration: 0.0650154 sec for 36129 iterations 1.801 usec (1.80126e-06 sec) per iteration: 0.0650776 sec for 36129 iterations 1.881 usec (1.88059e-06 sec) per iteration: 0.0679439 sec for 36129 iterations 1.801 usec (1.80103e-06 sec) per iteration: 0.0650696 sec for 36129 iterations mean: 1.832642 usec stddev: 0.039237 usec relative stddev: 2.14% RESULT TestMmapAnonymous: x86-64_nnacl_newlib_static= {1.832642, 0.039237} us Measuring clock cycles: first runs (cycles): 8100 4948 5170 4808 23631 4723 4661 4629 5280 4857 ... slowest runs (cycles): ... 6313 6355 6694 6874 6884 6910 6980 7095 8100 23631 min: 3816 cycles q1: 4974 cycles median: 5273 cycles q3: 5745 cycles max: 23631 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_nnacl_newlib_static= {5273, 771} count Apparent clock speed: 2877 MHz TestAtomicIncrement: Measuring real time: 0.016 usec (1.628e-08 sec) per iteration: 1.628e-06 sec for 100 iterations 0.011 usec (1.0636e-08 sec) per iteration: 1.0636e-05 sec for 1000 iterations 0.009 usec (9.47035e-09 sec) per iteration: 0.0890405 sec for 9402030 iterations 0.009 usec (9.47101e-09 sec) per iteration: 0.0890468 sec for 9402030 iterations 0.009 usec (9.4959e-09 sec) per iteration: 0.0892807 sec for 9402030 iterations 0.009 usec (9.45262e-09 sec) per iteration: 0.0888739 sec for 9402030 iterations 0.009 usec (9.48704e-09 sec) per iteration: 0.0891974 sec for 9402030 iterations mean: 0.009475 usec stddev: 0.000015 usec relative stddev: 0.16% RESULT TestAtomicIncrement: x86-64_nnacl_newlib_static= {0.009475, 0.000015} us Measuring clock cycles: first runs (cycles): 130 55 53 57 53 57 52 55 51 52 ... slowest runs (cycles): ... 55 55 55 55 55 55 57 57 58 130 min: 50 cycles q1: 52 cycles median: 53 cycles q3: 53 cycles max: 130 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_nnacl_newlib_static= {53, 1} count Apparent clock speed: 5593 MHz TestUncontendedMutexLock: Measuring real time: 0.031 usec (3.13e-08 sec) per iteration: 3.13e-06 sec for 100 iterations 0.028 usec (2.7798e-08 sec) per iteration: 2.7798e-05 sec for 1000 iterations 0.025 usec (2.45917e-08 sec) per iteration: 0.0884656 sec for 3597381 iterations 0.025 usec (2.46831e-08 sec) per iteration: 0.0887943 sec for 3597381 iterations 0.025 usec (2.46849e-08 sec) per iteration: 0.0888009 sec for 3597381 iterations 0.025 usec (2.4686e-08 sec) per iteration: 0.0888049 sec for 3597381 iterations 0.025 usec (2.45805e-08 sec) per iteration: 0.0884255 sec for 3597381 iterations mean: 0.024645 usec stddev: 0.000048 usec relative stddev: 0.20% RESULT TestUncontendedMutexLock: x86-64_nnacl_newlib_static= {0.024645, 0.000048} us Measuring clock cycles: first runs (cycles): 308 110 88 92 86 95 85 84 90 88 ... slowest runs (cycles): ... 92 93 94 95 95 99 99 103 110 308 min: 83 cycles q1: 84 cycles median: 86 cycles q3: 88 cycles max: 308 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_nnacl_newlib_static= {86, 4} count Apparent clock speed: 3490 MHz TestCondvarSignalNoOp: Measuring real time: 0.371 usec (3.7072e-07 sec) per iteration: 3.7072e-05 sec for 100 iterations 0.217 usec (2.17148e-07 sec) per iteration: 0.0585747 sec for 269745 iterations 0.217 usec (2.17144e-07 sec) per iteration: 0.0585736 sec for 269745 iterations 0.217 usec (2.16587e-07 sec) per iteration: 0.0584233 sec for 269745 iterations 0.217 usec (2.16808e-07 sec) per iteration: 0.058483 sec for 269745 iterations 0.217 usec (2.16694e-07 sec) per iteration: 0.0584522 sec for 269745 iterations mean: 0.216876 usec stddev: 0.000231 usec relative stddev: 0.11% RESULT TestCondvarSignalNoOp: x86-64_nnacl_newlib_static= {0.216876, 0.000231} us Measuring clock cycles: first runs (cycles): 1010 631 546 522 492 495 493 506 511 484 ... slowest runs (cycles): ... 546 554 554 561 563 566 568 629 631 1010 min: 481 cycles q1: 493 cycles median: 499 cycles q3: 510 cycles max: 1010 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_nnacl_newlib_static= {499, 17} count Apparent clock speed: 2301 MHz TestThreadCreateAndJoin: Measuring real time: 34.701 usec (3.47008e-05 sec) per iteration: 0.00347008 sec for 100 iterations 29.562 usec (2.95625e-05 sec) per iteration: 0.0851695 sec for 2881 iterations 26.515 usec (2.6515e-05 sec) per iteration: 0.0763896 sec for 2881 iterations 26.636 usec (2.66357e-05 sec) per iteration: 0.0767375 sec for 2881 iterations 30.496 usec (3.04955e-05 sec) per iteration: 0.0878576 sec for 2881 iterations 28.522 usec (2.8522e-05 sec) per iteration: 0.0821718 sec for 2881 iterations mean: 28.346133 usec stddev: 1.575369 usec relative stddev: 5.56% RESULT TestThreadCreateAndJoin: x86-64_nnacl_newlib_static= {28.346133, 1.575369} us Measuring clock cycles: first runs (cycles): 64711 65216 95621 95515 104151 110469 53420 58919 111519 57218 ... slowest runs (cycles): ... 95515 95621 98212 98217 104151 104187 110469 111519 312553 499556 min: 47516 cycles q1: 50785 cycles median: 52533 cycles q3: 59740 cycles max: 499556 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_nnacl_newlib_static= {52533, 8955} count Apparent clock speed: 1853 MHz TestThreadWakeup: Measuring real time: 12.255 usec (1.22546e-05 sec) per iteration: 0.00122546 sec for 100 iterations 15.148 usec (1.51483e-05 sec) per iteration: 0.12361 sec for 8160 iterations 16.034 usec (1.60345e-05 sec) per iteration: 0.130841 sec for 8160 iterations 15.989 usec (1.59892e-05 sec) per iteration: 0.130472 sec for 8160 iterations 15.014 usec (1.50135e-05 sec) per iteration: 0.12251 sec for 8160 iterations 15.523 usec (1.55229e-05 sec) per iteration: 0.126667 sec for 8160 iterations mean: 15.541682 usec stddev: 0.418838 usec relative stddev: 2.69% RESULT TestThreadWakeup: x86-64_nnacl_newlib_static= {15.541682, 0.418838} us Measuring clock cycles: first runs (cycles): 31552 28971 28874 30518 30229 32531 30802 30812 48642 32284 ... slowest runs (cycles): ... 37368 38127 38861 38967 41965 42236 48642 49381 71365 113215 min: 27526 cycles q1: 32522 cycles median: 32938 cycles q3: 34304 cycles max: 113215 cycles RESULT TestThreadWakeup_CycleCount: x86-64_nnacl_newlib_static= {32938, 1782} count Apparent clock speed: 2119 MHz TestCatchingFault: Measuring real time: 2.980 usec (2.98046e-06 sec) per iteration: 0.000298046 sec for 100 iterations 1.753 usec (1.75257e-06 sec) per iteration: 0.0588005 sec for 33551 iterations 1.756 usec (1.75591e-06 sec) per iteration: 0.0589124 sec for 33551 iterations 1.744 usec (1.74364e-06 sec) per iteration: 0.058501 sec for 33551 iterations 1.753 usec (1.75251e-06 sec) per iteration: 0.0587986 sec for 33551 iterations 1.747 usec (1.74745e-06 sec) per iteration: 0.0586287 sec for 33551 iterations mean: 1.750417 usec stddev: 0.004333 usec relative stddev: 0.25% RESULT TestCatchingFault: x86-64_nnacl_newlib_static= {1.750417, 0.004333} us Measuring clock cycles: first runs (cycles): 4492 3935 3801 3783 3767 3766 3762 3766 3768 3764 ... slowest runs (cycles): ... 3801 3804 3815 3816 3824 3830 3881 3935 4492 18289 min: 3757 cycles q1: 3766 cycles median: 3771 cycles q3: 3779 cycles max: 18289 cycles RESULT TestCatchingFault_CycleCount: x86-64_nnacl_newlib_static= {3771, 13} count Apparent clock speed: 2154 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 68.594 usec (6.85936e-05 sec) per iteration: 0.00685936 sec for 100 iterations 67.267 usec (6.72673e-05 sec) per iteration: 0.0980085 sec for 1457 iterations 68.367 usec (6.83669e-05 sec) per iteration: 0.0996106 sec for 1457 iterations 56.162 usec (5.61619e-05 sec) per iteration: 0.0818279 sec for 1457 iterations 58.047 usec (5.80469e-05 sec) per iteration: 0.0845743 sec for 1457 iterations 48.792 usec (4.87915e-05 sec) per iteration: 0.0710893 sec for 1457 iterations mean: 59.726907 usec stddev: 7.302354 usec relative stddev: 12.23% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_nnacl_newlib_static= {59.726907, 7.302354} us Measuring clock cycles: first runs (cycles): 43976 49007 51926 61071 52140 57467 115690 67910 143805 84626 ... slowest runs (cycles): ... 171080 180489 184006 213784 221783 229260 266320 279785 492608 731046 min: 43655 cycles q1: 49194 cycles median: 61071 cycles q3: 109752 cycles max: 731046 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_nnacl_newlib_static= {61071, 60558} count Apparent clock speed: 1023 MHz [ OK ] nacl_newlib.run_performance_test (5430 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 --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 /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 (1780 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 --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 /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 (1680 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 --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 /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.004 usec (3.77e-09 sec) per iteration: 3.77e-07 sec for 100 iterations 0.002 usec (1.939e-09 sec) per iteration: 1.939e-06 sec for 1000 iterations 0.002 usec (1.861e-09 sec) per iteration: 1.861e-05 sec for 10000 iterations 0.002 usec (1.88712e-09 sec) per iteration: 0.101403 sec for 53734551 iterations 0.002 usec (1.89606e-09 sec) per iteration: 0.101884 sec for 53734551 iterations 0.002 usec (1.88697e-09 sec) per iteration: 0.101395 sec for 53734551 iterations 0.002 usec (1.88351e-09 sec) per iteration: 0.10121 sec for 53734551 iterations 0.002 usec (1.88693e-09 sec) per iteration: 0.101393 sec for 53734551 iterations mean: 0.001888 usec stddev: 0.000004 usec relative stddev: 0.22% RESULT TestNull: trusted_linux_x86-64= {0.001888, 0.000004} us Measuring clock cycles: first runs (cycles): 69 22 20 24 20 24 24 20 24 24 ... slowest runs (cycles): ... 25 26 26 26 26 26 27 27 27 69 min: 20 cycles q1: 24 cycles median: 24 cycles q3: 24 cycles max: 69 cycles RESULT TestNull_CycleCount: trusted_linux_x86-64= {24, 0} count Apparent clock speed: 12711 MHz TestHostSyscall: Measuring real time: 0.081 usec (8.149e-08 sec) per iteration: 8.149e-06 sec for 100 iterations 0.056 usec (5.6215e-08 sec) per iteration: 5.6215e-05 sec for 1000 iterations 0.057 usec (5.68563e-08 sec) per iteration: 0.101141 sec for 1778884 iterations 0.057 usec (5.71714e-08 sec) per iteration: 0.101701 sec for 1778884 iterations 0.057 usec (5.71368e-08 sec) per iteration: 0.10164 sec for 1778884 iterations 0.057 usec (5.67659e-08 sec) per iteration: 0.10098 sec for 1778884 iterations 0.057 usec (5.68465e-08 sec) per iteration: 0.101123 sec for 1778884 iterations mean: 0.056955 usec stddev: 0.000166 usec relative stddev: 0.29% RESULT TestHostSyscall: trusted_linux_x86-64= {0.056955, 0.000166} us Measuring clock cycles: first runs (cycles): 293 167 163 163 143 145 148 145 147 145 ... slowest runs (cycles): ... 148 148 148 148 148 163 163 167 209 293 min: 143 cycles q1: 145 cycles median: 145 cycles q3: 146 cycles max: 293 cycles RESULT TestHostSyscall_CycleCount: trusted_linux_x86-64= {145, 1} count Apparent clock speed: 2546 MHz TestSetjmpLongjmp: Measuring real time: 0.032 usec (3.247e-08 sec) per iteration: 3.247e-06 sec for 100 iterations 0.028 usec (2.7716e-08 sec) per iteration: 2.7716e-05 sec for 1000 iterations 0.027 usec (2.74387e-08 sec) per iteration: 0.0989995 sec for 3608024 iterations 0.027 usec (2.7433e-08 sec) per iteration: 0.098979 sec for 3608024 iterations 0.027 usec (2.74668e-08 sec) per iteration: 0.099101 sec for 3608024 iterations 0.027 usec (2.72632e-08 sec) per iteration: 0.0983663 sec for 3608024 iterations 0.027 usec (2.72132e-08 sec) per iteration: 0.0981858 sec for 3608024 iterations mean: 0.027363 usec stddev: 0.000104 usec relative stddev: 0.38% RESULT TestSetjmpLongjmp: trusted_linux_x86-64= {0.027363, 0.000104} us Measuring clock cycles: first runs (cycles): 207 103 77 73 70 75 73 283 73 70 ... slowest runs (cycles): ... 77 77 77 77 79 79 103 200 207 283 min: 68 cycles q1: 73 cycles median: 75 cycles q3: 75 cycles max: 283 cycles RESULT TestSetjmpLongjmp_CycleCount: trusted_linux_x86-64= {75, 2} count Apparent clock speed: 2741 MHz TestClockGetTime: Measuring real time: 0.052 usec (5.235e-08 sec) per iteration: 5.235e-06 sec for 100 iterations 0.050 usec (5.0451e-08 sec) per iteration: 5.0451e-05 sec for 1000 iterations 0.056 usec (5.60499e-08 sec) per iteration: 0.111098 sec for 1982121 iterations 0.055 usec (5.50428e-08 sec) per iteration: 0.109102 sec for 1982121 iterations 0.055 usec (5.53785e-08 sec) per iteration: 0.109767 sec for 1982121 iterations 0.059 usec (5.90698e-08 sec) per iteration: 0.117084 sec for 1982121 iterations 0.059 usec (5.90308e-08 sec) per iteration: 0.117006 sec for 1982121 iterations mean: 0.056914 usec stddev: 0.001774 usec relative stddev: 3.12% RESULT TestClockGetTime: trusted_linux_x86-64= {0.056914, 0.001774} us Measuring clock cycles: first runs (cycles): 286 183 132 132 132 132 134 139 132 130 ... slowest runs (cycles): ... 132 132 132 132 134 134 135 139 183 286 min: 127 cycles q1: 130 cycles median: 130 cycles q3: 132 cycles max: 286 cycles RESULT TestClockGetTime_CycleCount: trusted_linux_x86-64= {130, 2} count Apparent clock speed: 2284 MHz TestTlsVariable: Measuring real time: 0.005 usec (4.52e-09 sec) per iteration: 4.52e-07 sec for 100 iterations 0.002 usec (1.91e-09 sec) per iteration: 1.91e-06 sec for 1000 iterations 0.002 usec (1.8611e-09 sec) per iteration: 1.8611e-05 sec for 10000 iterations 0.002 usec (1.89417e-09 sec) per iteration: 0.101777 sec for 53731664 iterations 0.002 usec (1.89975e-09 sec) per iteration: 0.102077 sec for 53731664 iterations 0.002 usec (1.88823e-09 sec) per iteration: 0.101458 sec for 53731664 iterations 0.002 usec (1.88751e-09 sec) per iteration: 0.101419 sec for 53731664 iterations 0.002 usec (1.89522e-09 sec) per iteration: 0.101833 sec for 53731664 iterations mean: 0.001893 usec stddev: 0.000005 usec relative stddev: 0.24% RESULT TestTlsVariable: trusted_linux_x86-64= {0.001893, 0.000005} us Measuring clock cycles: first runs (cycles): 77 24 24 24 26 24 24 24 24 22 ... slowest runs (cycles): ... 25 25 26 26 26 26 27 27 27 77 min: 21 cycles q1: 22 cycles median: 24 cycles q3: 24 cycles max: 77 cycles RESULT TestTlsVariable_CycleCount: trusted_linux_x86-64= {24, 2} count Apparent clock speed: 12678 MHz TestMmapAnonymous: Measuring real time: 0.715 usec (7.1516e-07 sec) per iteration: 7.1516e-05 sec for 100 iterations 0.503 usec (5.03418e-07 sec) per iteration: 0.070392 sec for 139828 iterations 0.507 usec (5.07251e-07 sec) per iteration: 0.0709279 sec for 139828 iterations 0.504 usec (5.04175e-07 sec) per iteration: 0.0704978 sec for 139828 iterations 0.506 usec (5.0559e-07 sec) per iteration: 0.0706957 sec for 139828 iterations 0.505 usec (5.04599e-07 sec) per iteration: 0.0705571 sec for 139828 iterations mean: 0.505007 usec stddev: 0.001323 usec relative stddev: 0.26% RESULT TestMmapAnonymous: trusted_linux_x86-64= {0.505007, 0.001323} us Measuring clock cycles: first runs (cycles): 1681 1142 1168 1111 1100 1417 1111 1110 1089 1093 ... slowest runs (cycles): ... 1144 1146 1146 1168 1192 1303 1305 1417 1681 8709 min: 1084 cycles q1: 1096 cycles median: 1102 cycles q3: 1111 cycles max: 8709 cycles RESULT TestMmapAnonymous_CycleCount: trusted_linux_x86-64= {1102, 15} count Apparent clock speed: 2182 MHz TestAtomicIncrement: Measuring real time: 0.014 usec (1.438e-08 sec) per iteration: 1.438e-06 sec for 100 iterations 0.009 usec (9.268e-09 sec) per iteration: 9.268e-06 sec for 1000 iterations 0.009 usec (9.2623e-09 sec) per iteration: 9.2623e-05 sec for 10000 iterations 0.009 usec (9.40914e-09 sec) per iteration: 0.101585 sec for 10796454 iterations 0.009 usec (9.43367e-09 sec) per iteration: 0.10185 sec for 10796454 iterations 0.009 usec (9.4532e-09 sec) per iteration: 0.102061 sec for 10796454 iterations 0.010 usec (9.52758e-09 sec) per iteration: 0.102864 sec for 10796454 iterations 0.010 usec (9.58448e-09 sec) per iteration: 0.103478 sec for 10796454 iterations mean: 0.009482 usec stddev: 0.000065 usec relative stddev: 0.68% RESULT TestAtomicIncrement: trusted_linux_x86-64= {0.009482, 0.000065} us Measuring clock cycles: first runs (cycles): 95 51 51 51 61 367 51 50 49 48 ... slowest runs (cycles): ... 51 51 51 51 61 95 367 368 379 438 min: 48 cycles q1: 49 cycles median: 50 cycles q3: 51 cycles max: 438 cycles RESULT TestAtomicIncrement_CycleCount: trusted_linux_x86-64= {50, 2} count Apparent clock speed: 5273 MHz TestUncontendedMutexLock: Measuring real time: 0.027 usec (2.658e-08 sec) per iteration: 2.658e-06 sec for 100 iterations 0.022 usec (2.1544e-08 sec) per iteration: 2.1544e-05 sec for 1000 iterations 0.024 usec (2.37214e-08 sec) per iteration: 0.110107 sec for 4641663 iterations 0.023 usec (2.27468e-08 sec) per iteration: 0.105583 sec for 4641663 iterations 0.023 usec (2.28333e-08 sec) per iteration: 0.105984 sec for 4641663 iterations 0.023 usec (2.26912e-08 sec) per iteration: 0.105325 sec for 4641663 iterations 0.023 usec (2.26125e-08 sec) per iteration: 0.10496 sec for 4641663 iterations mean: 0.022921 usec stddev: 0.000407 usec relative stddev: 1.77% RESULT TestUncontendedMutexLock: trusted_linux_x86-64= {0.022921, 0.000407} us Measuring clock cycles: first runs (cycles): 185 82 84 81 79 268 79 79 81 79 ... slowest runs (cycles): ... 82 82 82 82 82 84 185 237 268 268 min: 77 cycles q1: 77 cycles median: 79 cycles q3: 79 cycles max: 268 cycles RESULT TestUncontendedMutexLock_CycleCount: trusted_linux_x86-64= {79, 2} count Apparent clock speed: 3447 MHz TestCondvarSignalNoOp: Measuring real time: 0.019 usec (1.89e-08 sec) per iteration: 1.89e-06 sec for 100 iterations 0.016 usec (1.5984e-08 sec) per iteration: 1.5984e-05 sec for 1000 iterations 0.016 usec (1.62241e-08 sec) per iteration: 0.101502 sec for 6256256 iterations 0.016 usec (1.63211e-08 sec) per iteration: 0.102109 sec for 6256256 iterations 0.016 usec (1.62952e-08 sec) per iteration: 0.101947 sec for 6256256 iterations 0.016 usec (1.62669e-08 sec) per iteration: 0.10177 sec for 6256256 iterations 0.016 usec (1.63133e-08 sec) per iteration: 0.10206 sec for 6256256 iterations mean: 0.016284 usec stddev: 0.000035 usec relative stddev: 0.22% RESULT TestCondvarSignalNoOp: trusted_linux_x86-64= {0.016284, 0.000035} us Measuring clock cycles: first runs (cycles): 158 70 70 75 73 246 73 72 71 70 ... slowest runs (cycles): ... 74 75 75 75 75 75 158 229 246 247 min: 66 cycles q1: 68 cycles median: 70 cycles q3: 73 cycles max: 247 cycles RESULT TestCondvarSignalNoOp_CycleCount: trusted_linux_x86-64= {70, 5} count Apparent clock speed: 4299 MHz TestThreadCreateAndJoin: Measuring real time: 49.761 usec (4.97614e-05 sec) per iteration: 0.00497614 sec for 100 iterations 32.008 usec (3.20077e-05 sec) per iteration: 0.0643035 sec for 2009 iterations 37.506 usec (3.75059e-05 sec) per iteration: 0.0753494 sec for 2009 iterations 38.408 usec (3.84076e-05 sec) per iteration: 0.077161 sec for 2009 iterations 37.497 usec (3.74968e-05 sec) per iteration: 0.0753311 sec for 2009 iterations 39.809 usec (3.98091e-05 sec) per iteration: 0.0799765 sec for 2009 iterations mean: 37.045445 usec stddev: 2.656612 usec relative stddev: 7.17% RESULT TestThreadCreateAndJoin: trusted_linux_x86-64= {37.045445, 2.656612} us Measuring clock cycles: first runs (cycles): 569148 213094 69837 155206 155120 101813 59194 81687 63517 60561 ... slowest runs (cycles): ... 101813 114440 155120 155206 185806 191864 213094 293635 361439 569148 min: 52758 cycles q1: 58506 cycles median: 60338 cycles q3: 71254 cycles max: 569148 cycles RESULT TestThreadCreateAndJoin_CycleCount: trusted_linux_x86-64= {60338, 12748} count Apparent clock speed: 1629 MHz TestThreadWakeup: Measuring real time: 18.722 usec (1.87223e-05 sec) per iteration: 0.00187223 sec for 100 iterations 20.005 usec (2.00048e-05 sec) per iteration: 0.106846 sec for 5341 iterations 15.512 usec (1.55119e-05 sec) per iteration: 0.0828488 sec for 5341 iterations 20.458 usec (2.0458e-05 sec) per iteration: 0.109266 sec for 5341 iterations 18.117 usec (1.81174e-05 sec) per iteration: 0.0967649 sec for 5341 iterations 16.805 usec (1.68053e-05 sec) per iteration: 0.0897569 sec for 5341 iterations mean: 18.179454 usec stddev: 1.872533 usec relative stddev: 10.30% RESULT TestThreadWakeup: trusted_linux_x86-64= {18.179454, 1.872533} us Measuring clock cycles: first runs (cycles): 43332 35911 30021 30526 35954 31520 30514 31171 30056 31345 ... slowest runs (cycles): ... 57987 71931 73055 78654 86390 91003 114918 126882 157800 328031 min: 21562 cycles q1: 33120 cycles median: 33976 cycles q3: 37152 cycles max: 328031 cycles RESULT TestThreadWakeup_CycleCount: trusted_linux_x86-64= {33976, 4032} count Apparent clock speed: 1869 MHz WARNING: should have taken less than 2.000000 secs [ OK ] nacl_newlib.run_trusted_performance_test (5380 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