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-west1-c-vj9b 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: 18718076 kB MemAvailable: 29429268 kB ====================================================================== LOAD: ====================================================================== 1.40 2.11 1.86 2/439 3557 ====================================================================== UPTIME: ====================================================================== 17985.40 135643.26 ====================================================================== 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 [3565,3117078400:21:01:19.218874] Native Client module will be loaded at base address 0x0000410000000000 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 [3570,1898469248:21:01:19.520720] Native Client module will be loaded at base address 0x00007d8b00000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_nnacl_newlib_static= {0.090510, 0.004670} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test (1370 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 (74380 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= 528.111 KB RESULT hello_world_size.out: ZIPPED_x86-64_nnacl_newlib_static= 187.907 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 [3600,4004444032:21:01:35.495570] Native Client module will be loaded at base address 0x00007e0200000000 TestNull: Measuring real time: 0.006 usec (5.77e-09 sec) per iteration: 5.77e-07 sec for 100 iterations 0.002 usec (2.021e-09 sec) per iteration: 2.021e-06 sec for 1000 iterations 0.002 usec (1.8737e-09 sec) per iteration: 1.8737e-05 sec for 10000 iterations 0.002 usec (1.88487e-09 sec) per iteration: 0.100596 sec for 53370336 iterations 0.002 usec (1.89725e-09 sec) per iteration: 0.101257 sec for 53370336 iterations 0.002 usec (1.89813e-09 sec) per iteration: 0.101304 sec for 53370336 iterations 0.002 usec (1.89131e-09 sec) per iteration: 0.10094 sec for 53370336 iterations 0.002 usec (1.89604e-09 sec) per iteration: 0.101192 sec for 53370336 iterations mean: 0.001894 usec stddev: 0.000005 usec relative stddev: 0.26% RESULT TestNull: x86-64_nnacl_newlib_static= {0.001894, 0.000005} us Measuring clock cycles: first runs (cycles): 92 24 20 22 26 25 19 24 24 20 ... slowest runs (cycles): ... 28 29 29 29 29 29 29 29 29 92 min: 19 cycles q1: 24 cycles median: 25 cycles q3: 26 cycles max: 92 cycles RESULT TestNull_CycleCount: x86-64_nnacl_newlib_static= {25, 2} count Apparent clock speed: 13203 MHz TestNaClSyscall: Measuring real time: 0.141 usec (1.4062e-07 sec) per iteration: 1.4062e-05 sec for 100 iterations 0.143 usec (1.42572e-07 sec) per iteration: 0.101388 sec for 711136 iterations 0.137 usec (1.37415e-07 sec) per iteration: 0.097721 sec for 711136 iterations 0.140 usec (1.40474e-07 sec) per iteration: 0.0998964 sec for 711136 iterations 0.137 usec (1.37041e-07 sec) per iteration: 0.0974545 sec for 711136 iterations 0.127 usec (1.27316e-07 sec) per iteration: 0.0905389 sec for 711136 iterations mean: 0.136964 usec stddev: 0.005236 usec relative stddev: 3.82% RESULT TestNaClSyscall: x86-64_nnacl_newlib_static= {0.136964, 0.005236} us Measuring clock cycles: first runs (cycles): 301 229 218 198 195 196 182 178 218 218 ... slowest runs (cycles): ... 196 198 216 218 218 218 218 218 229 301 min: 176 cycles q1: 180 cycles median: 180 cycles q3: 181 cycles max: 301 cycles RESULT TestNaClSyscall_CycleCount: x86-64_nnacl_newlib_static= {180, 1} count Apparent clock speed: 1314 MHz TestSetjmpLongjmp: Measuring real time: 0.016 usec (1.58e-08 sec) per iteration: 1.58e-06 sec for 100 iterations 0.013 usec (1.2763e-08 sec) per iteration: 1.2763e-05 sec for 1000 iterations 0.013 usec (1.30085e-08 sec) per iteration: 0.101923 sec for 7835148 iterations 0.013 usec (1.28883e-08 sec) per iteration: 0.100981 sec for 7835148 iterations 0.013 usec (1.29046e-08 sec) per iteration: 0.101109 sec for 7835148 iterations 0.013 usec (1.30557e-08 sec) per iteration: 0.102293 sec for 7835148 iterations 0.013 usec (1.28117e-08 sec) per iteration: 0.100382 sec for 7835148 iterations mean: 0.012934 usec stddev: 0.000087 usec relative stddev: 0.68% RESULT TestSetjmpLongjmp: x86-64_nnacl_newlib_static= {0.012934, 0.000087} us Measuring clock cycles: first runs (cycles): 176 42 36 62 37 35 33 31 31 28 ... slowest runs (cycles): ... 29 31 31 33 35 36 37 42 62 176 min: 24 cycles q1: 26 cycles median: 27 cycles q3: 29 cycles max: 176 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_nnacl_newlib_static= {27, 3} count Apparent clock speed: 2088 MHz TestClockGetTime: Measuring real time: 0.147 usec (1.4742e-07 sec) per iteration: 1.4742e-05 sec for 100 iterations 0.157 usec (1.57352e-07 sec) per iteration: 0.106737 sec for 678334 iterations 0.155 usec (1.54796e-07 sec) per iteration: 0.105003 sec for 678334 iterations 0.156 usec (1.5566e-07 sec) per iteration: 0.10559 sec for 678334 iterations 0.150 usec (1.49525e-07 sec) per iteration: 0.101428 sec for 678334 iterations 0.151 usec (1.50547e-07 sec) per iteration: 0.102121 sec for 678334 iterations mean: 0.153576 usec stddev: 0.003022 usec relative stddev: 1.97% RESULT TestClockGetTime: x86-64_nnacl_newlib_static= {0.153576, 0.003022} us Measuring clock cycles: first runs (cycles): 578 383 361 341 336 334 337 339 338 343 ... slowest runs (cycles): ... 341 343 343 347 360 361 378 378 383 578 min: 325 cycles q1: 334 cycles median: 337 cycles q3: 339 cycles max: 578 cycles RESULT TestClockGetTime_CycleCount: x86-64_nnacl_newlib_static= {337, 5} count Apparent clock speed: 2194 MHz TestTlsVariable: Measuring real time: 0.009 usec (9.2e-09 sec) per iteration: 9.2e-07 sec for 100 iterations 0.006 usec (5.762e-09 sec) per iteration: 5.762e-06 sec for 1000 iterations 0.006 usec (5.5752e-09 sec) per iteration: 5.5752e-05 sec for 10000 iterations 0.006 usec (5.75815e-09 sec) per iteration: 0.103282 sec for 17936576 iterations 0.006 usec (5.69695e-09 sec) per iteration: 0.102184 sec for 17936576 iterations 0.006 usec (5.74294e-09 sec) per iteration: 0.103009 sec for 17936576 iterations 0.006 usec (5.74094e-09 sec) per iteration: 0.102973 sec for 17936576 iterations 0.006 usec (5.69959e-09 sec) per iteration: 0.102231 sec for 17936576 iterations mean: 0.005728 usec stddev: 0.000025 usec relative stddev: 0.43% RESULT TestTlsVariable: x86-64_nnacl_newlib_static= {0.005728, 0.000025} us Measuring clock cycles: first runs (cycles): 158 79 57 53 28 30 28 26 26 31 ... slowest runs (cycles): ... 33 33 33 33 33 33 53 57 79 158 min: 24 cycles q1: 26 cycles median: 28 cycles q3: 33 cycles max: 158 cycles RESULT TestTlsVariable_CycleCount: x86-64_nnacl_newlib_static= {28, 7} count Apparent clock speed: 4889 MHz TestMmapAnonymous: Measuring real time: 2.141 usec (2.14136e-06 sec) per iteration: 0.000214136 sec for 100 iterations 1.818 usec (1.81779e-06 sec) per iteration: 0.0848892 sec for 46699 iterations 1.837 usec (1.83704e-06 sec) per iteration: 0.0857877 sec for 46699 iterations 1.826 usec (1.82553e-06 sec) per iteration: 0.0852503 sec for 46699 iterations 1.860 usec (1.85979e-06 sec) per iteration: 0.0868502 sec for 46699 iterations 2.516 usec (2.51636e-06 sec) per iteration: 0.117511 sec for 46699 iterations mean: 1.971301 usec stddev: 0.272897 usec relative stddev: 13.84% RESULT TestMmapAnonymous: x86-64_nnacl_newlib_static= {1.971301, 0.272897} us Measuring clock cycles: first runs (cycles): 7833 4107 3958 3929 15307 4158 3931 3916 3907 3920 ... slowest runs (cycles): ... 14548 14609 14611 14717 15307 15951 16466 17003 17306 17625 min: 3865 cycles q1: 3910 cycles median: 3933 cycles q3: 4398 cycles max: 17625 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_nnacl_newlib_static= {3933, 488} count Apparent clock speed: 1995 MHz TestAtomicIncrement: Measuring real time: 0.013 usec (1.296e-08 sec) per iteration: 1.296e-06 sec for 100 iterations 0.015 usec (1.4765e-08 sec) per iteration: 1.4765e-05 sec for 1000 iterations 0.012 usec (1.15941e-08 sec) per iteration: 0.0785243 sec for 6772773 iterations 0.012 usec (1.17106e-08 sec) per iteration: 0.0793134 sec for 6772773 iterations 0.012 usec (1.19868e-08 sec) per iteration: 0.0811838 sec for 6772773 iterations 0.011 usec (1.12921e-08 sec) per iteration: 0.076479 sec for 6772773 iterations 0.010 usec (9.60946e-09 sec) per iteration: 0.0650827 sec for 6772773 iterations mean: 0.011239 usec stddev: 0.000845 usec relative stddev: 7.51% RESULT TestAtomicIncrement: x86-64_nnacl_newlib_static= {0.011239, 0.000845} us Measuring clock cycles: first runs (cycles): 121 53 50 52 50 53 53 55 51 53 ... slowest runs (cycles): ... 55 55 55 55 55 55 55 55 61 121 min: 50 cycles q1: 52 cycles median: 53 cycles q3: 53 cycles max: 121 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_nnacl_newlib_static= {53, 1} count Apparent clock speed: 4716 MHz TestUncontendedMutexLock: Measuring real time: 0.041 usec (4.05e-08 sec) per iteration: 4.05e-06 sec for 100 iterations 0.041 usec (4.0871e-08 sec) per iteration: 4.0871e-05 sec for 1000 iterations 0.027 usec (2.68237e-08 sec) per iteration: 0.0656302 sec for 2446722 iterations 0.036 usec (3.64936e-08 sec) per iteration: 0.0892897 sec for 2446722 iterations 0.036 usec (3.58708e-08 sec) per iteration: 0.0877659 sec for 2446722 iterations 0.033 usec (3.31292e-08 sec) per iteration: 0.0810579 sec for 2446722 iterations 0.034 usec (3.42478e-08 sec) per iteration: 0.0837948 sec for 2446722 iterations mean: 0.033313 usec stddev: 0.003455 usec relative stddev: 10.37% RESULT TestUncontendedMutexLock: x86-64_nnacl_newlib_static= {0.033313, 0.003455} us Measuring clock cycles: first runs (cycles): 284 143 86 83 83 84 5974 168 81 83 ... slowest runs (cycles): ... 84 84 84 84 84 86 143 168 284 5974 min: 81 cycles q1: 82 cycles median: 83 cycles q3: 84 cycles max: 5974 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_nnacl_newlib_static= {83, 2} count Apparent clock speed: 2492 MHz TestCondvarSignalNoOp: Measuring real time: 0.400 usec (4.003e-07 sec) per iteration: 4.003e-05 sec for 100 iterations 0.330 usec (3.30493e-07 sec) per iteration: 0.082561 sec for 249812 iterations 0.352 usec (3.51669e-07 sec) per iteration: 0.0878512 sec for 249812 iterations 0.278 usec (2.77747e-07 sec) per iteration: 0.0693846 sec for 249812 iterations 0.220 usec (2.19981e-07 sec) per iteration: 0.0549539 sec for 249812 iterations 0.221 usec (2.20856e-07 sec) per iteration: 0.0551724 sec for 249812 iterations mean: 0.280149 usec stddev: 0.054390 usec relative stddev: 19.41% RESULT TestCondvarSignalNoOp: x86-64_nnacl_newlib_static= {0.280149, 0.054390} us Measuring clock cycles: first runs (cycles): 684 554 480 477 480 480 475 478 481 477 ... slowest runs (cycles): ... 484 484 484 484 484 484 486 487 554 684 min: 475 cycles q1: 479 cycles median: 481 cycles q3: 484 cycles max: 684 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_nnacl_newlib_static= {481, 5} count Apparent clock speed: 1717 MHz TestThreadCreateAndJoin: Measuring real time: 47.882 usec (4.78819e-05 sec) per iteration: 0.00478819 sec for 100 iterations 36.932 usec (3.69322e-05 sec) per iteration: 0.0771145 sec for 2088 iterations 37.629 usec (3.76286e-05 sec) per iteration: 0.0785684 sec for 2088 iterations 32.153 usec (3.21528e-05 sec) per iteration: 0.067135 sec for 2088 iterations 29.052 usec (2.90516e-05 sec) per iteration: 0.0606597 sec for 2088 iterations 29.141 usec (2.9141e-05 sec) per iteration: 0.0608464 sec for 2088 iterations mean: 32.981224 usec stddev: 3.690093 usec relative stddev: 11.19% RESULT TestThreadCreateAndJoin: x86-64_nnacl_newlib_static= {32.981224, 3.690093} us Measuring clock cycles: first runs (cycles): 51925 48597 50605 52901 50616 50402 538015 140085 66486 100147 ... slowest runs (cycles): ... 87126 97156 100147 107134 134988 140085 164100 199115 238699 538015 min: 47537 cycles q1: 50486 cycles median: 52494 cycles q3: 55462 cycles max: 538015 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_nnacl_newlib_static= {52494, 4976} count Apparent clock speed: 1592 MHz TestThreadWakeup: Measuring real time: 12.668 usec (1.26679e-05 sec) per iteration: 0.00126679 sec for 100 iterations 14.934 usec (1.4934e-05 sec) per iteration: 0.117874 sec for 7893 iterations 16.213 usec (1.62134e-05 sec) per iteration: 0.127972 sec for 7893 iterations 16.656 usec (1.66564e-05 sec) per iteration: 0.131469 sec for 7893 iterations 17.051 usec (1.70507e-05 sec) per iteration: 0.134582 sec for 7893 iterations 17.022 usec (1.70218e-05 sec) per iteration: 0.134353 sec for 7893 iterations mean: 16.375269 usec stddev: 0.781897 usec relative stddev: 4.77% RESULT TestThreadWakeup: x86-64_nnacl_newlib_static= {16.375269, 0.781897} us Measuring clock cycles: first runs (cycles): 37952 36878 35686 38198 36760 37912 36755 37848 36891 41092 ... slowest runs (cycles): ... 42407 44371 44382 44606 45222 45453 45948 52502 77859 79035 min: 30751 cycles q1: 36388 cycles median: 36942 cycles q3: 38643 cycles max: 79035 cycles RESULT TestThreadWakeup_CycleCount: x86-64_nnacl_newlib_static= {36942, 2255} count Apparent clock speed: 2256 MHz TestCatchingFault: Measuring real time: 3.777 usec (3.77694e-06 sec) per iteration: 0.000377694 sec for 100 iterations 1.741 usec (1.74089e-06 sec) per iteration: 0.0460919 sec for 26476 iterations 1.732 usec (1.73214e-06 sec) per iteration: 0.04586 sec for 26476 iterations 1.741 usec (1.74056e-06 sec) per iteration: 0.0460832 sec for 26476 iterations 1.747 usec (1.74658e-06 sec) per iteration: 0.0462424 sec for 26476 iterations 1.739 usec (1.73945e-06 sec) per iteration: 0.0460537 sec for 26476 iterations mean: 1.739924 usec stddev: 0.004615 usec relative stddev: 0.27% RESULT TestCatchingFault: x86-64_nnacl_newlib_static= {1.739924, 0.004615} us Measuring clock cycles: first runs (cycles): 4651 3755 3684 3725 3724 3715 3712 3728 3694 3702 ... slowest runs (cycles): ... 3733 3735 3736 3736 3739 3740 3741 3747 3755 4651 min: 3684 cycles q1: 3716 cycles median: 3720 cycles q3: 3729 cycles max: 4651 cycles RESULT TestCatchingFault_CycleCount: x86-64_nnacl_newlib_static= {3720, 13} count Apparent clock speed: 2138 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 88.583 usec (8.85826e-05 sec) per iteration: 0.00885826 sec for 100 iterations 59.661 usec (5.96614e-05 sec) per iteration: 0.067298 sec for 1128 iterations 52.540 usec (5.25404e-05 sec) per iteration: 0.0592656 sec for 1128 iterations 49.479 usec (4.94789e-05 sec) per iteration: 0.0558122 sec for 1128 iterations 57.458 usec (5.74576e-05 sec) per iteration: 0.0648122 sec for 1128 iterations 59.404 usec (5.9404e-05 sec) per iteration: 0.0670077 sec for 1128 iterations mean: 55.708470 usec stddev: 4.029532 usec relative stddev: 7.23% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_nnacl_newlib_static= {55.708470, 4.029532} us Measuring clock cycles: first runs (cycles): 125771 88924 87346 83499 83602 136000 126944 133317 91029 75183 ... slowest runs (cycles): ... 164702 169402 178525 178765 179720 201406 210766 215465 329840 379540 min: 69249 cycles q1: 84562 cycles median: 119466 cycles q3: 134878 cycles max: 379540 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_nnacl_newlib_static= {119466, 50316} count Apparent clock speed: 2144 MHz [ OK ] nacl_newlib.run_performance_test (5930 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 (2570 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 (920 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.74e-09 sec) per iteration: 3.74e-07 sec for 100 iterations 0.002 usec (1.926e-09 sec) per iteration: 1.926e-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.89633e-09 sec) per iteration: 0.101893 sec for 53731664 iterations 0.002 usec (1.89193e-09 sec) per iteration: 0.101657 sec for 53731664 iterations 0.002 usec (1.90227e-09 sec) per iteration: 0.102212 sec for 53731664 iterations 0.002 usec (1.89845e-09 sec) per iteration: 0.102007 sec for 53731664 iterations 0.002 usec (1.89451e-09 sec) per iteration: 0.101795 sec for 53731664 iterations mean: 0.001897 usec stddev: 0.000004 usec relative stddev: 0.19% RESULT TestNull: trusted_linux_x86-64= {0.001897, 0.000004} us Measuring clock cycles: first runs (cycles): 77 24 20 24 24 20 22 20 22 22 ... slowest runs (cycles): ... 26 26 26 26 26 26 27 27 27 77 min: 20 cycles q1: 24 cycles median: 24 cycles q3: 24 cycles max: 77 cycles RESULT TestNull_CycleCount: trusted_linux_x86-64= {24, 0} count Apparent clock speed: 12654 MHz TestHostSyscall: Measuring real time: 0.067 usec (6.736e-08 sec) per iteration: 6.736e-06 sec for 100 iterations 0.057 usec (5.7102e-08 sec) per iteration: 5.7102e-05 sec for 1000 iterations 0.057 usec (5.73859e-08 sec) per iteration: 0.100497 sec for 1751252 iterations 0.057 usec (5.74729e-08 sec) per iteration: 0.10065 sec for 1751252 iterations 0.057 usec (5.73753e-08 sec) per iteration: 0.100479 sec for 1751252 iterations 0.057 usec (5.74992e-08 sec) per iteration: 0.100696 sec for 1751252 iterations 0.058 usec (5.76533e-08 sec) per iteration: 0.100965 sec for 1751252 iterations mean: 0.057477 usec stddev: 0.000100 usec relative stddev: 0.17% RESULT TestHostSyscall: trusted_linux_x86-64= {0.057477, 0.000100} us Measuring clock cycles: first runs (cycles): 288 163 145 147 145 145 145 146 147 150 ... slowest runs (cycles): ... 150 150 150 150 152 154 156 157 163 288 min: 143 cycles q1: 145 cycles median: 147 cycles q3: 148 cycles max: 288 cycles RESULT TestHostSyscall_CycleCount: trusted_linux_x86-64= {147, 3} count Apparent clock speed: 2558 MHz TestSetjmpLongjmp: Measuring real time: 0.034 usec (3.431e-08 sec) per iteration: 3.431e-06 sec for 100 iterations 0.028 usec (2.7784e-08 sec) per iteration: 2.7784e-05 sec for 1000 iterations 0.027 usec (2.73412e-08 sec) per iteration: 0.0984063 sec for 3599193 iterations 0.028 usec (2.76638e-08 sec) per iteration: 0.0995675 sec for 3599193 iterations 0.028 usec (2.76616e-08 sec) per iteration: 0.0995593 sec for 3599193 iterations 0.030 usec (3.04114e-08 sec) per iteration: 0.109457 sec for 3599193 iterations 0.031 usec (3.12482e-08 sec) per iteration: 0.112468 sec for 3599193 iterations mean: 0.028865 usec stddev: 0.001630 usec relative stddev: 5.65% RESULT TestSetjmpLongjmp: trusted_linux_x86-64= {0.028865, 0.001630} us Measuring clock cycles: first runs (cycles): 194 107 130 81 70 71 70 70 77 198 ... slowest runs (cycles): ... 79 80 81 83 107 130 194 198 250 7653 min: 70 cycles q1: 73 cycles median: 75 cycles q3: 77 cycles max: 7653 cycles RESULT TestSetjmpLongjmp_CycleCount: trusted_linux_x86-64= {75, 4} count Apparent clock speed: 2598 MHz TestClockGetTime: Measuring real time: 0.056 usec (5.596e-08 sec) per iteration: 5.596e-06 sec for 100 iterations 0.053 usec (5.2872e-08 sec) per iteration: 5.2872e-05 sec for 1000 iterations 0.066 usec (6.55206e-08 sec) per iteration: 0.123923 sec for 1891360 iterations 0.062 usec (6.16474e-08 sec) per iteration: 0.116598 sec for 1891360 iterations 0.061 usec (6.08129e-08 sec) per iteration: 0.115019 sec for 1891360 iterations 0.058 usec (5.78175e-08 sec) per iteration: 0.109354 sec for 1891360 iterations 0.057 usec (5.72993e-08 sec) per iteration: 0.108374 sec for 1891360 iterations mean: 0.060620 usec stddev: 0.002966 usec relative stddev: 4.89% RESULT TestClockGetTime: trusted_linux_x86-64= {0.060620, 0.002966} us Measuring clock cycles: first runs (cycles): 596 180 163 138 137 134 134 132 132 132 ... slowest runs (cycles): ... 132 134 134 134 134 137 138 163 180 596 min: 127 cycles q1: 129 cycles median: 130 cycles q3: 132 cycles max: 596 cycles RESULT TestClockGetTime_CycleCount: trusted_linux_x86-64= {130, 3} count Apparent clock speed: 2145 MHz TestTlsVariable: Measuring real time: 0.005 usec (4.72e-09 sec) per iteration: 4.72e-07 sec for 100 iterations 0.002 usec (1.917e-09 sec) per iteration: 1.917e-06 sec for 1000 iterations 0.002 usec (1.8615e-09 sec) per iteration: 1.8615e-05 sec for 10000 iterations 0.002 usec (2.02053e-09 sec) per iteration: 0.108543 sec for 53720118 iterations 0.002 usec (2.01618e-09 sec) per iteration: 0.108309 sec for 53720118 iterations 0.002 usec (2.02681e-09 sec) per iteration: 0.108881 sec for 53720118 iterations 0.002 usec (2.00554e-09 sec) per iteration: 0.107738 sec for 53720118 iterations 0.002 usec (1.99889e-09 sec) per iteration: 0.107381 sec for 53720118 iterations mean: 0.002014 usec stddev: 0.000010 usec relative stddev: 0.50% RESULT TestTlsVariable: trusted_linux_x86-64= {0.002014, 0.000010} us Measuring clock cycles: first runs (cycles): 84 24 22 24 24 22 22 24 22 24 ... slowest runs (cycles): ... 25 25 25 25 25 25 25 26 27 84 min: 22 cycles q1: 22 cycles median: 22 cycles q3: 24 cycles max: 84 cycles RESULT TestTlsVariable_CycleCount: trusted_linux_x86-64= {22, 2} count Apparent clock speed: 10926 MHz TestMmapAnonymous: Measuring real time: 0.899 usec (8.9927e-07 sec) per iteration: 8.9927e-05 sec for 100 iterations 0.509 usec (5.09067e-07 sec) per iteration: 0.0566087 sec for 111201 iterations 0.513 usec (5.12692e-07 sec) per iteration: 0.0570118 sec for 111201 iterations 0.503 usec (5.02503e-07 sec) per iteration: 0.0558788 sec for 111201 iterations 0.502 usec (5.01723e-07 sec) per iteration: 0.0557921 sec for 111201 iterations 0.511 usec (5.10864e-07 sec) per iteration: 0.0568086 sec for 111201 iterations mean: 0.507370 usec stddev: 0.004449 usec relative stddev: 0.88% RESULT TestMmapAnonymous: trusted_linux_x86-64= {0.507370, 0.004449} us Measuring clock cycles: first runs (cycles): 2187 1432 2215 1144 1110 1105 1105 1402 1095 1093 ... slowest runs (cycles): ... 1366 1402 1432 1500 1520 1551 1597 1668 2187 2215 min: 1083 cycles q1: 1098 cycles median: 1102 cycles q3: 1109 cycles max: 2215 cycles RESULT TestMmapAnonymous_CycleCount: trusted_linux_x86-64= {1102, 11} count Apparent clock speed: 2172 MHz TestAtomicIncrement: Measuring real time: 0.011 usec (1.138e-08 sec) per iteration: 1.138e-06 sec for 100 iterations 0.009 usec (9.292e-09 sec) per iteration: 9.292e-06 sec for 1000 iterations 0.009 usec (9.147e-09 sec) per iteration: 9.147e-05 sec for 10000 iterations 0.009 usec (9.49983e-09 sec) per iteration: 0.103857 sec for 10932546 iterations 0.010 usec (9.63694e-09 sec) per iteration: 0.105356 sec for 10932546 iterations 0.010 usec (9.95147e-09 sec) per iteration: 0.108795 sec for 10932546 iterations 0.013 usec (1.34044e-08 sec) per iteration: 0.146545 sec for 10932546 iterations 0.013 usec (1.26428e-08 sec) per iteration: 0.138218 sec for 10932546 iterations mean: 0.011027 usec stddev: 0.001654 usec relative stddev: 15.00% RESULT TestAtomicIncrement: trusted_linux_x86-64= {0.011027, 0.001654} us Measuring clock cycles: first runs (cycles): 233 51 51 48 49 48 48 357 51 50 ... slowest runs (cycles): ... 51 51 51 51 51 53 233 347 355 357 min: 48 cycles q1: 49 cycles median: 50 cycles q3: 51 cycles max: 357 cycles RESULT TestAtomicIncrement_CycleCount: trusted_linux_x86-64= {50, 2} count Apparent clock speed: 4534 MHz TestUncontendedMutexLock: Measuring real time: 0.083 usec (8.285e-08 sec) per iteration: 8.285e-06 sec for 100 iterations 0.022 usec (2.1533e-08 sec) per iteration: 2.1533e-05 sec for 1000 iterations 0.032 usec (3.18499e-08 sec) per iteration: 0.147912 sec for 4644034 iterations 0.031 usec (3.07123e-08 sec) per iteration: 0.142629 sec for 4644034 iterations 0.033 usec (3.30365e-08 sec) per iteration: 0.153423 sec for 4644034 iterations 0.029 usec (2.94506e-08 sec) per iteration: 0.13677 sec for 4644034 iterations 0.028 usec (2.84459e-08 sec) per iteration: 0.132104 sec for 4644034 iterations mean: 0.030699 usec stddev: 0.001638 usec relative stddev: 5.34% RESULT TestUncontendedMutexLock: trusted_linux_x86-64= {0.030699, 0.001638} us Measuring clock cycles: first runs (cycles): 194 106 104 103 105 103 103 104 104 106 ... slowest runs (cycles): ... 106 106 106 106 106 106 106 106 112 194 min: 101 cycles q1: 103 cycles median: 104 cycles q3: 105 cycles max: 194 cycles RESULT TestUncontendedMutexLock_CycleCount: trusted_linux_x86-64= {104, 2} count Apparent clock speed: 3388 MHz TestCondvarSignalNoOp: Measuring real time: 0.092 usec (9.247e-08 sec) per iteration: 9.247e-06 sec for 100 iterations 0.025 usec (2.5333e-08 sec) per iteration: 2.5333e-05 sec for 1000 iterations 0.021 usec (2.1371e-08 sec) per iteration: 0.0843603 sec for 3947420 iterations 0.022 usec (2.19787e-08 sec) per iteration: 0.0867592 sec for 3947420 iterations 0.023 usec (2.27051e-08 sec) per iteration: 0.0896264 sec for 3947420 iterations 0.023 usec (2.30284e-08 sec) per iteration: 0.0909027 sec for 3947420 iterations 0.024 usec (2.35301e-08 sec) per iteration: 0.0928833 sec for 3947420 iterations mean: 0.022523 usec stddev: 0.000765 usec relative stddev: 3.40% RESULT TestCondvarSignalNoOp: trusted_linux_x86-64= {0.022523, 0.000765} us Measuring clock cycles: first runs (cycles): 138 68 69 66 69 69 69 374 66 66 ... slowest runs (cycles): ... 75 75 75 75 75 86 138 347 358 374 min: 66 cycles q1: 68 cycles median: 69 cycles q3: 73 cycles max: 374 cycles RESULT TestCondvarSignalNoOp_CycleCount: trusted_linux_x86-64= {69, 5} count Apparent clock speed: 3064 MHz TestThreadCreateAndJoin: Measuring real time: 45.828 usec (4.58282e-05 sec) per iteration: 0.00458282 sec for 100 iterations 35.094 usec (3.50938e-05 sec) per iteration: 0.0765746 sec for 2182 iterations 41.100 usec (4.10998e-05 sec) per iteration: 0.0896798 sec for 2182 iterations 36.385 usec (3.63847e-05 sec) per iteration: 0.0793915 sec for 2182 iterations 31.036 usec (3.10363e-05 sec) per iteration: 0.0677213 sec for 2182 iterations 30.824 usec (3.08239e-05 sec) per iteration: 0.0672577 sec for 2182 iterations mean: 34.887704 usec stddev: 3.800573 usec relative stddev: 10.89% RESULT TestThreadCreateAndJoin: trusted_linux_x86-64= {34.887704, 3.800573} us Measuring clock cycles: first runs (cycles): 45310 43139 44982 44197 101636 45270 45185 43532 88313 225883 ... slowest runs (cycles): ... 111433 153082 169191 191372 209252 214897 225883 273513 279088 681917 min: 37129 cycles q1: 39500 cycles median: 43532 cycles q3: 64259 cycles max: 681917 cycles RESULT TestThreadCreateAndJoin_CycleCount: trusted_linux_x86-64= {43532, 24759} count Apparent clock speed: 1248 MHz TestThreadWakeup: Measuring real time: 15.477 usec (1.54768e-05 sec) per iteration: 0.00154768 sec for 100 iterations 16.197 usec (1.61972e-05 sec) per iteration: 0.10465 sec for 6461 iterations 24.583 usec (2.45834e-05 sec) per iteration: 0.158833 sec for 6461 iterations 28.027 usec (2.80266e-05 sec) per iteration: 0.18108 sec for 6461 iterations 26.445 usec (2.6445e-05 sec) per iteration: 0.170861 sec for 6461 iterations 26.286 usec (2.62864e-05 sec) per iteration: 0.169837 sec for 6461 iterations mean: 24.307736 usec stddev: 4.199267 usec relative stddev: 17.28% RESULT TestThreadWakeup: trusted_linux_x86-64= {24.307736, 4.199267} us Measuring clock cycles: first runs (cycles): 45189 69607 94362 111162 92463 81084 44654 43341 43389 43350 ... slowest runs (cycles): ... 111162 112312 122462 126198 127936 142589 174189 184176 198520 219215 min: 24027 cycles q1: 34137 cycles median: 53115 cycles q3: 83488 cycles max: 219215 cycles RESULT TestThreadWakeup_CycleCount: trusted_linux_x86-64= {53115, 49351} count Apparent clock speed: 2185 MHz WARNING: should have taken less than 2.000000 secs [ OK ] nacl_newlib.run_trusted_performance_test (6000 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