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-cfkp 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: 3891908 kB MemAvailable: 29399892 kB ====================================================================== LOAD: ====================================================================== 1.92 2.78 2.19 1/443 15241 ====================================================================== UPTIME: ====================================================================== 33517.16 260718.80 ====================================================================== 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 [15251,2114918272:13:01:44.959194] Native Client module will be loaded at base address 0x000017d400000000 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 [15256,1844983680:13:01:45.229803] Native Client module will be loaded at base address 0x00007e5e00000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_nnacl_newlib_static= {0.103090, 0.003610} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test (1600 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 (75470 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 [15300,991307648:13:02:01.453442] Native Client module will be loaded at base address 0x00007a7200000000 TestNull: Measuring real time: 0.011 usec (1.08e-08 sec) per iteration: 1.08e-06 sec for 100 iterations 0.002 usec (2.458e-09 sec) per iteration: 2.458e-06 sec for 1000 iterations 0.002 usec (2.2995e-09 sec) per iteration: 2.2995e-05 sec for 10000 iterations 0.002 usec (1.90854e-09 sec) per iteration: 0.0829979 sec for 43487714 iterations 0.002 usec (1.91296e-09 sec) per iteration: 0.0831904 sec for 43487714 iterations 0.002 usec (1.91246e-09 sec) per iteration: 0.0831685 sec for 43487714 iterations 0.002 usec (1.90042e-09 sec) per iteration: 0.0826448 sec for 43487714 iterations 0.002 usec (1.8974e-09 sec) per iteration: 0.0825137 sec for 43487714 iterations mean: 0.001906 usec stddev: 0.000006 usec relative stddev: 0.33% RESULT TestNull: x86-64_nnacl_newlib_static= {0.001906, 0.000006} us Measuring clock cycles: first runs (cycles): 99 24 26 22 24 24 27 22 22 22 ... slowest runs (cycles): ... 31 33 33 33 33 35 35 37 40 99 min: 22 cycles q1: 25 cycles median: 27 cycles q3: 29 cycles max: 99 cycles RESULT TestNull_CycleCount: x86-64_nnacl_newlib_static= {27, 4} count Apparent clock speed: 14163 MHz TestNaClSyscall: Measuring real time: 0.112 usec (1.1169e-07 sec) per iteration: 1.1169e-05 sec for 100 iterations 0.130 usec (1.29947e-07 sec) per iteration: 0.116346 sec for 895335 iterations 0.130 usec (1.30369e-07 sec) per iteration: 0.116724 sec for 895335 iterations 0.131 usec (1.30645e-07 sec) per iteration: 0.116971 sec for 895335 iterations 0.129 usec (1.28658e-07 sec) per iteration: 0.115192 sec for 895335 iterations 0.133 usec (1.32868e-07 sec) per iteration: 0.118962 sec for 895335 iterations mean: 0.130497 usec stddev: 0.001367 usec relative stddev: 1.05% RESULT TestNaClSyscall: x86-64_nnacl_newlib_static= {0.130497, 0.001367} us Measuring clock cycles: first runs (cycles): 383 264 222 185 195 194 218 196 189 191 ... slowest runs (cycles): ... 211 218 220 220 222 224 225 264 359 383 min: 182 cycles q1: 189 cycles median: 193 cycles q3: 196 cycles max: 383 cycles RESULT TestNaClSyscall_CycleCount: x86-64_nnacl_newlib_static= {193, 7} count Apparent clock speed: 1479 MHz TestSetjmpLongjmp: Measuring real time: 0.018 usec (1.752e-08 sec) per iteration: 1.752e-06 sec for 100 iterations 0.014 usec (1.3653e-08 sec) per iteration: 1.3653e-05 sec for 1000 iterations 0.013 usec (1.29765e-08 sec) per iteration: 0.0950448 sec for 7324397 iterations 0.013 usec (1.29336e-08 sec) per iteration: 0.0947306 sec for 7324397 iterations 0.013 usec (1.30109e-08 sec) per iteration: 0.0952969 sec for 7324397 iterations 0.013 usec (1.29515e-08 sec) per iteration: 0.0948616 sec for 7324397 iterations 0.013 usec (1.30491e-08 sec) per iteration: 0.0955765 sec for 7324397 iterations mean: 0.012984 usec stddev: 0.000042 usec relative stddev: 0.32% RESULT TestSetjmpLongjmp: x86-64_nnacl_newlib_static= {0.012984, 0.000042} us Measuring clock cycles: first runs (cycles): 198 42 33 68 66 51 53 51 44 40 ... slowest runs (cycles): ... 51 53 53 55 55 55 58 66 68 198 min: 29 cycles q1: 33 cycles median: 37 cycles q3: 40 cycles max: 198 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_nnacl_newlib_static= {37, 7} count Apparent clock speed: 2850 MHz TestClockGetTime: Measuring real time: 0.179 usec (1.7866e-07 sec) per iteration: 1.7866e-05 sec for 100 iterations 0.160 usec (1.59685e-07 sec) per iteration: 0.089379 sec for 559722 iterations 0.156 usec (1.5552e-07 sec) per iteration: 0.087048 sec for 559722 iterations 0.155 usec (1.54838e-07 sec) per iteration: 0.0866664 sec for 559722 iterations 0.156 usec (1.55702e-07 sec) per iteration: 0.0871499 sec for 559722 iterations 0.155 usec (1.55313e-07 sec) per iteration: 0.0869322 sec for 559722 iterations mean: 0.156212 usec stddev: 0.001760 usec relative stddev: 1.13% RESULT TestClockGetTime: x86-64_nnacl_newlib_static= {0.156212, 0.001760} us Measuring clock cycles: first runs (cycles): 1100 402 355 370 361 368 376 347 370 369 ... slowest runs (cycles): ... 392 396 398 400 402 403 423 550 554 1100 min: 345 cycles q1: 358 cycles median: 365 cycles q3: 376 cycles max: 1100 cycles RESULT TestClockGetTime_CycleCount: x86-64_nnacl_newlib_static= {365, 18} count Apparent clock speed: 2337 MHz TestTlsVariable: Measuring real time: 0.013 usec (1.283e-08 sec) per iteration: 1.283e-06 sec for 100 iterations 0.008 usec (7.621e-09 sec) per iteration: 7.621e-06 sec for 1000 iterations 0.008 usec (7.9031e-09 sec) per iteration: 7.9031e-05 sec for 10000 iterations 0.006 usec (5.94201e-09 sec) per iteration: 0.0751858 sec for 12653262 iterations 0.006 usec (5.73117e-09 sec) per iteration: 0.072518 sec for 12653262 iterations 0.006 usec (5.71618e-09 sec) per iteration: 0.0723283 sec for 12653262 iterations 0.006 usec (5.71377e-09 sec) per iteration: 0.0722979 sec for 12653262 iterations 0.006 usec (5.71002e-09 sec) per iteration: 0.0722504 sec for 12653262 iterations mean: 0.005763 usec stddev: 0.000090 usec relative stddev: 1.56% RESULT TestTlsVariable: x86-64_nnacl_newlib_static= {0.005763, 0.000090} us Measuring clock cycles: first runs (cycles): 185 81 55 66 49 35 38 35 50 42 ... slowest runs (cycles): ... 55 55 57 59 64 66 66 66 81 185 min: 26 cycles q1: 33 cycles median: 38 cycles q3: 49 cycles max: 185 cycles RESULT TestTlsVariable_CycleCount: x86-64_nnacl_newlib_static= {38, 16} count Apparent clock speed: 6594 MHz TestMmapAnonymous: Measuring real time: 3.143 usec (3.14299e-06 sec) per iteration: 0.000314299 sec for 100 iterations 1.819 usec (1.81905e-06 sec) per iteration: 0.0578749 sec for 31816 iterations 1.808 usec (1.8079e-06 sec) per iteration: 0.05752 sec for 31816 iterations 1.809 usec (1.80909e-06 sec) per iteration: 0.057558 sec for 31816 iterations 1.828 usec (1.82822e-06 sec) per iteration: 0.0581666 sec for 31816 iterations 1.831 usec (1.83114e-06 sec) per iteration: 0.0582596 sec for 31816 iterations mean: 1.819080 usec stddev: 0.009528 usec relative stddev: 0.52% RESULT TestMmapAnonymous: x86-64_nnacl_newlib_static= {1.819080, 0.009528} us Measuring clock cycles: first runs (cycles): 10599 4991 5080 4880 5095 5016 5168 5508 4985 4661 ... slowest runs (cycles): ... 6140 6326 6399 6424 6779 6976 7021 7116 7184 10599 min: 4661 cycles q1: 5040 cycles median: 5407 cycles q3: 5716 cycles max: 10599 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_nnacl_newlib_static= {5407, 676} count Apparent clock speed: 2972 MHz TestAtomicIncrement: Measuring real time: 0.016 usec (1.61e-08 sec) per iteration: 1.61e-06 sec for 100 iterations 0.011 usec (1.0762e-08 sec) per iteration: 1.0762e-05 sec for 1000 iterations 0.010 usec (9.553e-09 sec) per iteration: 0.088766 sec for 9291953 iterations 0.010 usec (9.54454e-09 sec) per iteration: 0.0886875 sec for 9291953 iterations 0.010 usec (9.51789e-09 sec) per iteration: 0.0884398 sec for 9291953 iterations 0.009 usec (9.48943e-09 sec) per iteration: 0.0881754 sec for 9291953 iterations 0.009 usec (9.49042e-09 sec) per iteration: 0.0881846 sec for 9291953 iterations mean: 0.009519 usec stddev: 0.000026 usec relative stddev: 0.28% RESULT TestAtomicIncrement: x86-64_nnacl_newlib_static= {0.009519, 0.000026} us Measuring clock cycles: first runs (cycles): 165 62 52 51 53 53 51 50 51 52 ... slowest runs (cycles): ... 60 61 61 62 62 66 66 70 75 165 min: 48 cycles q1: 52 cycles median: 53 cycles q3: 53 cycles max: 165 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_nnacl_newlib_static= {53, 1} count Apparent clock speed: 5568 MHz TestUncontendedMutexLock: Measuring real time: 0.030 usec (3.048e-08 sec) per iteration: 3.048e-06 sec for 100 iterations 0.026 usec (2.589e-08 sec) per iteration: 2.589e-05 sec for 1000 iterations 0.025 usec (2.46619e-08 sec) per iteration: 0.0952565 sec for 3862495 iterations 0.025 usec (2.47629e-08 sec) per iteration: 0.0956466 sec for 3862495 iterations 0.025 usec (2.47375e-08 sec) per iteration: 0.0955485 sec for 3862495 iterations 0.025 usec (2.46656e-08 sec) per iteration: 0.0952708 sec for 3862495 iterations 0.025 usec (2.47237e-08 sec) per iteration: 0.0954953 sec for 3862495 iterations mean: 0.024710 usec stddev: 0.000040 usec relative stddev: 0.16% RESULT TestUncontendedMutexLock: x86-64_nnacl_newlib_static= {0.024710, 0.000040} us Measuring clock cycles: first runs (cycles): 532 110 83 84 86 88 86 88 93 91 ... slowest runs (cycles): ... 90 90 90 91 93 93 94 103 110 532 min: 81 cycles q1: 84 cycles median: 86 cycles q3: 86 cycles max: 532 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_nnacl_newlib_static= {86, 2} count Apparent clock speed: 3480 MHz TestCondvarSignalNoOp: Measuring real time: 0.266 usec (2.6585e-07 sec) per iteration: 2.6585e-05 sec for 100 iterations 0.220 usec (2.20367e-07 sec) per iteration: 0.0828913 sec for 376151 iterations 0.218 usec (2.17928e-07 sec) per iteration: 0.0819737 sec for 376151 iterations 0.216 usec (2.15647e-07 sec) per iteration: 0.0811158 sec for 376151 iterations 0.216 usec (2.16083e-07 sec) per iteration: 0.08128 sec for 376151 iterations 0.218 usec (2.17998e-07 sec) per iteration: 0.0820001 sec for 376151 iterations mean: 0.217605 usec stddev: 0.001676 usec relative stddev: 0.77% RESULT TestCondvarSignalNoOp: x86-64_nnacl_newlib_static= {0.217605, 0.001676} us Measuring clock cycles: first runs (cycles): 1201 541 532 515 487 504 535 504 733 514 ... slowest runs (cycles): ... 532 535 537 537 541 541 543 563 733 1201 min: 484 cycles q1: 497 cycles median: 502 cycles q3: 511 cycles max: 1201 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_nnacl_newlib_static= {502, 14} count Apparent clock speed: 2307 MHz TestThreadCreateAndJoin: Measuring real time: 66.313 usec (6.6313e-05 sec) per iteration: 0.0066313 sec for 100 iterations 42.031 usec (4.20309e-05 sec) per iteration: 0.0633406 sec for 1507 iterations 32.188 usec (3.21879e-05 sec) per iteration: 0.0485072 sec for 1507 iterations 28.880 usec (2.88799e-05 sec) per iteration: 0.043522 sec for 1507 iterations 31.080 usec (3.10805e-05 sec) per iteration: 0.0468383 sec for 1507 iterations 29.595 usec (2.95952e-05 sec) per iteration: 0.0446 sec for 1507 iterations mean: 32.754894 usec stddev: 4.778483 usec relative stddev: 14.59% RESULT TestThreadCreateAndJoin: x86-64_nnacl_newlib_static= {32.754894, 4.778483} us Measuring clock cycles: first runs (cycles): 54052 51044 71615 52080 50959 58735 62171 54764 51302 55022 ... slowest runs (cycles): ... 86567 86699 88791 114349 131071 149572 176466 202798 205559 245718 min: 44084 cycles q1: 50959 cycles median: 52574 cycles q3: 57837 cycles max: 245718 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_nnacl_newlib_static= {52574, 6878} count Apparent clock speed: 1605 MHz TestThreadWakeup: Measuring real time: 15.856 usec (1.58561e-05 sec) per iteration: 0.00158561 sec for 100 iterations 17.469 usec (1.74692e-05 sec) per iteration: 0.110161 sec for 6306 iterations 15.917 usec (1.59169e-05 sec) per iteration: 0.100372 sec for 6306 iterations 16.405 usec (1.64046e-05 sec) per iteration: 0.103447 sec for 6306 iterations 16.611 usec (1.66106e-05 sec) per iteration: 0.104746 sec for 6306 iterations 19.814 usec (1.98143e-05 sec) per iteration: 0.124949 sec for 6306 iterations mean: 17.243117 usec stddev: 1.380150 usec relative stddev: 8.00% RESULT TestThreadWakeup: x86-64_nnacl_newlib_static= {17.243117, 1.380150} us Measuring clock cycles: first runs (cycles): 61170 52754 51189 50787 49951 50859 49032 51753 51533 50316 ... slowest runs (cycles): ... 72083 72932 73792 78212 95812 100676 104592 138423 150788 436198 min: 38545 cycles q1: 39421 cycles median: 41596 cycles q3: 51753 cycles max: 436198 cycles RESULT TestThreadWakeup_CycleCount: x86-64_nnacl_newlib_static= {41596, 12332} count Apparent clock speed: 2412 MHz TestCatchingFault: Measuring real time: 3.571 usec (3.57104e-06 sec) per iteration: 0.000357104 sec for 100 iterations 1.957 usec (1.95677e-06 sec) per iteration: 0.0547955 sec for 28003 iterations 1.952 usec (1.95236e-06 sec) per iteration: 0.0546718 sec for 28003 iterations 1.941 usec (1.94128e-06 sec) per iteration: 0.0543617 sec for 28003 iterations 1.944 usec (1.94351e-06 sec) per iteration: 0.0544242 sec for 28003 iterations 1.948 usec (1.94774e-06 sec) per iteration: 0.0545426 sec for 28003 iterations mean: 1.948333 usec stddev: 0.005670 usec relative stddev: 0.29% RESULT TestCatchingFault: x86-64_nnacl_newlib_static= {1.948333, 0.005670} us Measuring clock cycles: first runs (cycles): 7697 4578 4553 4409 4404 4549 4752 4380 4375 4575 ... slowest runs (cycles): ... 5431 5568 5610 5654 5730 5746 5753 5773 6507 7697 min: 4329 cycles q1: 4553 cycles median: 4723 cycles q3: 4995 cycles max: 7697 cycles RESULT TestCatchingFault_CycleCount: x86-64_nnacl_newlib_static= {4723, 442} count Apparent clock speed: 2424 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 43.522 usec (4.35219e-05 sec) per iteration: 0.00435219 sec for 100 iterations 39.493 usec (3.94933e-05 sec) per iteration: 0.0907161 sec for 2297 iterations 43.352 usec (4.33515e-05 sec) per iteration: 0.0995784 sec for 2297 iterations 45.209 usec (4.52089e-05 sec) per iteration: 0.103845 sec for 2297 iterations 43.119 usec (4.31193e-05 sec) per iteration: 0.0990451 sec for 2297 iterations 44.820 usec (4.48196e-05 sec) per iteration: 0.102951 sec for 2297 iterations mean: 43.198519 usec stddev: 2.021283 usec relative stddev: 4.68% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_nnacl_newlib_static= {43.198519, 2.021283} us Measuring clock cycles: first runs (cycles): 54021 73643 52543 44139 104504 153591 62034 71888 88070 91098 ... slowest runs (cycles): ... 125389 129758 132970 133053 134808 135389 141646 141854 148452 153591 min: 44139 cycles q1: 68181 cycles median: 92018 cycles q3: 106380 cycles max: 153591 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_nnacl_newlib_static= {92018, 38199} count Apparent clock speed: 2130 MHz [ OK ] nacl_newlib.run_performance_test (5709 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 (2280 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.005 usec (4.62e-09 sec) per iteration: 4.62e-07 sec for 100 iterations 0.002 usec (1.928e-09 sec) per iteration: 1.928e-06 sec for 1000 iterations 0.003 usec (2.7243e-09 sec) per iteration: 2.7243e-05 sec for 10000 iterations 0.002 usec (1.90271e-09 sec) per iteration: 0.0698423 sec for 36706676 iterations 0.002 usec (1.89713e-09 sec) per iteration: 0.0696374 sec for 36706676 iterations 0.002 usec (1.88963e-09 sec) per iteration: 0.0693621 sec for 36706676 iterations 0.002 usec (1.89118e-09 sec) per iteration: 0.0694189 sec for 36706676 iterations 0.002 usec (1.89832e-09 sec) per iteration: 0.0696808 sec for 36706676 iterations mean: 0.001896 usec stddev: 0.000005 usec relative stddev: 0.25% RESULT TestNull: trusted_linux_x86-64= {0.001896, 0.000005} us Measuring clock cycles: first runs (cycles): 176 22 41 22 22 25 22 24 24 22 ... slowest runs (cycles): ... 25 25 25 26 26 27 27 27 41 176 min: 22 cycles q1: 22 cycles median: 24 cycles q3: 24 cycles max: 176 cycles RESULT TestNull_CycleCount: trusted_linux_x86-64= {24, 2} count Apparent clock speed: 12660 MHz TestHostSyscall: Measuring real time: 0.077 usec (7.749e-08 sec) per iteration: 7.749e-06 sec for 100 iterations 0.056 usec (5.606e-08 sec) per iteration: 5.606e-05 sec for 1000 iterations 0.057 usec (5.72324e-08 sec) per iteration: 0.102091 sec for 1783803 iterations 0.057 usec (5.72467e-08 sec) per iteration: 0.102117 sec for 1783803 iterations 0.057 usec (5.71599e-08 sec) per iteration: 0.101962 sec for 1783803 iterations 0.057 usec (5.71153e-08 sec) per iteration: 0.101882 sec for 1783803 iterations 0.057 usec (5.72138e-08 sec) per iteration: 0.102058 sec for 1783803 iterations mean: 0.057194 usec stddev: 0.000049 usec relative stddev: 0.09% RESULT TestHostSyscall: trusted_linux_x86-64= {0.057194, 0.000049} us Measuring clock cycles: first runs (cycles): 717 165 145 145 150 145 145 147 145 146 ... slowest runs (cycles): ... 148 148 148 148 148 148 148 150 165 717 min: 143 cycles q1: 145 cycles median: 145 cycles q3: 146 cycles max: 717 cycles RESULT TestHostSyscall_CycleCount: trusted_linux_x86-64= {145, 1} count Apparent clock speed: 2535 MHz TestSetjmpLongjmp: Measuring real time: 0.035 usec (3.523e-08 sec) per iteration: 3.523e-06 sec for 100 iterations 0.034 usec (3.3926e-08 sec) per iteration: 3.3926e-05 sec for 1000 iterations 0.027 usec (2.74266e-08 sec) per iteration: 0.0808424 sec for 2947591 iterations 0.027 usec (2.74439e-08 sec) per iteration: 0.0808934 sec for 2947591 iterations 0.028 usec (2.84848e-08 sec) per iteration: 0.0839614 sec for 2947591 iterations 0.029 usec (2.86349e-08 sec) per iteration: 0.0844041 sec for 2947591 iterations 0.028 usec (2.84459e-08 sec) per iteration: 0.0838468 sec for 2947591 iterations mean: 0.028087 usec stddev: 0.000536 usec relative stddev: 1.91% RESULT TestSetjmpLongjmp: trusted_linux_x86-64= {0.028087, 0.000536} us Measuring clock cycles: first runs (cycles): 344 103 106 79 81 77 74 75 72 73 ... slowest runs (cycles): ... 79 79 79 79 81 86 103 106 124 344 min: 70 cycles q1: 74 cycles median: 75 cycles q3: 77 cycles max: 344 cycles RESULT TestSetjmpLongjmp_CycleCount: trusted_linux_x86-64= {75, 3} count Apparent clock speed: 2670 MHz TestClockGetTime: Measuring real time: 0.054 usec (5.38e-08 sec) per iteration: 5.38e-06 sec for 100 iterations 0.051 usec (5.0949e-08 sec) per iteration: 5.0949e-05 sec for 1000 iterations 0.055 usec (5.50384e-08 sec) per iteration: 0.108026 sec for 1962747 iterations 0.057 usec (5.69484e-08 sec) per iteration: 0.111775 sec for 1962747 iterations 0.059 usec (5.94014e-08 sec) per iteration: 0.11659 sec for 1962747 iterations 0.058 usec (5.76145e-08 sec) per iteration: 0.113083 sec for 1962747 iterations 0.058 usec (5.77654e-08 sec) per iteration: 0.113379 sec for 1962747 iterations mean: 0.057354 usec stddev: 0.001411 usec relative stddev: 2.46% RESULT TestClockGetTime: trusted_linux_x86-64= {0.057354, 0.001411} us Measuring clock cycles: first runs (cycles): 774 179 150 130 129 128 130 129 130 127 ... slowest runs (cycles): ... 132 132 132 132 132 132 132 150 179 774 min: 125 cycles q1: 128 cycles median: 129 cycles q3: 130 cycles max: 774 cycles RESULT TestClockGetTime_CycleCount: trusted_linux_x86-64= {129, 2} count Apparent clock speed: 2249 MHz TestTlsVariable: Measuring real time: 0.004 usec (4.02e-09 sec) per iteration: 4.02e-07 sec for 100 iterations 0.002 usec (1.93e-09 sec) per iteration: 1.93e-06 sec for 1000 iterations 0.002 usec (1.8615e-09 sec) per iteration: 1.8615e-05 sec for 10000 iterations 0.002 usec (1.93178e-09 sec) per iteration: 0.103776 sec for 53720118 iterations 0.002 usec (1.89741e-09 sec) per iteration: 0.101929 sec for 53720118 iterations 0.002 usec (1.88811e-09 sec) per iteration: 0.101429 sec for 53720118 iterations 0.002 usec (1.88759e-09 sec) per iteration: 0.101401 sec for 53720118 iterations 0.002 usec (1.88751e-09 sec) per iteration: 0.101397 sec for 53720118 iterations mean: 0.001898 usec stddev: 0.000017 usec relative stddev: 0.90% RESULT TestTlsVariable: trusted_linux_x86-64= {0.001898, 0.000017} us Measuring clock cycles: first runs (cycles): 189 51 22 24 22 22 24 24 25 24 ... slowest runs (cycles): ... 25 26 26 26 26 26 26 27 51 189 min: 22 cycles q1: 22 cycles median: 24 cycles q3: 24 cycles max: 189 cycles RESULT TestTlsVariable_CycleCount: trusted_linux_x86-64= {24, 2} count Apparent clock speed: 12642 MHz TestMmapAnonymous: Measuring real time: 0.666 usec (6.6605e-07 sec) per iteration: 6.6605e-05 sec for 100 iterations 0.503 usec (5.02829e-07 sec) per iteration: 0.0754937 sec for 150138 iterations 0.502 usec (5.02386e-07 sec) per iteration: 0.0754272 sec for 150138 iterations 0.502 usec (5.0178e-07 sec) per iteration: 0.0753362 sec for 150138 iterations 0.503 usec (5.02731e-07 sec) per iteration: 0.0754791 sec for 150138 iterations 0.503 usec (5.02619e-07 sec) per iteration: 0.0754623 sec for 150138 iterations mean: 0.502469 usec stddev: 0.000375 usec relative stddev: 0.07% RESULT TestMmapAnonymous: trusted_linux_x86-64= {0.502469, 0.000375} us Measuring clock cycles: first runs (cycles): 2400 1129 1102 1100 1095 1096 1099 1100 1100 1107 ... slowest runs (cycles): ... 1113 1129 1146 1147 1150 1150 1304 1315 1329 2400 min: 1082 cycles q1: 1097 cycles median: 1100 cycles q3: 1105 cycles max: 2400 cycles RESULT TestMmapAnonymous_CycleCount: trusted_linux_x86-64= {1100, 8} count Apparent clock speed: 2189 MHz TestAtomicIncrement: Measuring real time: 0.011 usec (1.142e-08 sec) per iteration: 1.142e-06 sec for 100 iterations 0.009 usec (9.3e-09 sec) per iteration: 9.3e-06 sec for 1000 iterations 0.009 usec (9.2617e-09 sec) per iteration: 9.2617e-05 sec for 10000 iterations 0.009 usec (9.40633e-09 sec) per iteration: 0.101562 sec for 10797153 iterations 0.009 usec (9.39265e-09 sec) per iteration: 0.101414 sec for 10797153 iterations 0.009 usec (9.41793e-09 sec) per iteration: 0.101687 sec for 10797153 iterations 0.009 usec (9.40053e-09 sec) per iteration: 0.101499 sec for 10797153 iterations 0.009 usec (9.39853e-09 sec) per iteration: 0.101477 sec for 10797153 iterations mean: 0.009403 usec stddev: 0.000009 usec relative stddev: 0.09% RESULT TestAtomicIncrement: trusted_linux_x86-64= {0.009403, 0.000009} us Measuring clock cycles: first runs (cycles): 176 51 51 372 51 51 50 51 51 50 ... slowest runs (cycles): ... 51 51 51 51 51 62 176 343 372 373 min: 48 cycles q1: 50 cycles median: 50 cycles q3: 51 cycles max: 373 cycles RESULT TestAtomicIncrement_CycleCount: trusted_linux_x86-64= {50, 1} count Apparent clock speed: 5317 MHz TestUncontendedMutexLock: Measuring real time: 0.033 usec (3.292e-08 sec) per iteration: 3.292e-06 sec for 100 iterations 0.022 usec (2.1539e-08 sec) per iteration: 2.1539e-05 sec for 1000 iterations 0.023 usec (2.27583e-08 sec) per iteration: 0.105661 sec for 4642741 iterations 0.022 usec (2.238e-08 sec) per iteration: 0.103905 sec for 4642741 iterations 0.023 usec (2.27867e-08 sec) per iteration: 0.105793 sec for 4642741 iterations 0.022 usec (2.24984e-08 sec) per iteration: 0.104454 sec for 4642741 iterations 0.022 usec (2.23142e-08 sec) per iteration: 0.103599 sec for 4642741 iterations mean: 0.022548 usec stddev: 0.000193 usec relative stddev: 0.86% RESULT TestUncontendedMutexLock: trusted_linux_x86-64= {0.022548, 0.000193} us Measuring clock cycles: first runs (cycles): 442 103 79 79 81 81 79 79 79 80 ... slowest runs (cycles): ... 81 81 82 82 82 82 103 392 442 1107 min: 77 cycles q1: 79 cycles median: 79 cycles q3: 80 cycles max: 1107 cycles RESULT TestUncontendedMutexLock_CycleCount: trusted_linux_x86-64= {79, 1} count Apparent clock speed: 3504 MHz TestCondvarSignalNoOp: Measuring real time: 0.018 usec (1.845e-08 sec) per iteration: 1.845e-06 sec for 100 iterations 0.016 usec (1.6004e-08 sec) per iteration: 1.6004e-05 sec for 1000 iterations 0.017 usec (1.66651e-08 sec) per iteration: 0.104131 sec for 6248437 iterations 0.016 usec (1.62889e-08 sec) per iteration: 0.10178 sec for 6248437 iterations 0.016 usec (1.62959e-08 sec) per iteration: 0.101824 sec for 6248437 iterations 0.016 usec (1.63021e-08 sec) per iteration: 0.101863 sec for 6248437 iterations 0.016 usec (1.62698e-08 sec) per iteration: 0.101661 sec for 6248437 iterations mean: 0.016364 usec stddev: 0.000151 usec relative stddev: 0.92% RESULT TestCondvarSignalNoOp: trusted_linux_x86-64= {0.016364, 0.000151} us Measuring clock cycles: first runs (cycles): 250 70 68 66 73 68 74 73 68 72 ... slowest runs (cycles): ... 73 73 73 74 74 75 75 250 382 385 min: 66 cycles q1: 68 cycles median: 68 cycles q3: 72 cycles max: 385 cycles RESULT TestCondvarSignalNoOp_CycleCount: trusted_linux_x86-64= {68, 4} count Apparent clock speed: 4155 MHz TestThreadCreateAndJoin: Measuring real time: 49.793 usec (4.97927e-05 sec) per iteration: 0.00497927 sec for 100 iterations 41.927 usec (4.19274e-05 sec) per iteration: 0.0841902 sec for 2008 iterations 37.100 usec (3.70999e-05 sec) per iteration: 0.0744967 sec for 2008 iterations 34.036 usec (3.40362e-05 sec) per iteration: 0.0683448 sec for 2008 iterations 31.343 usec (3.13429e-05 sec) per iteration: 0.0629365 sec for 2008 iterations 35.026 usec (3.50263e-05 sec) per iteration: 0.0703329 sec for 2008 iterations mean: 35.886549 usec stddev: 3.543235 usec relative stddev: 9.87% RESULT TestThreadCreateAndJoin: trusted_linux_x86-64= {35.886549, 3.543235} us Measuring clock cycles: first runs (cycles): 61630 47448 51200 48814 49364 45545 53336 116403 46456 45272 ... slowest runs (cycles): ... 198126 198533 205948 207409 217680 266304 270433 304687 330306 551555 min: 39625 cycles q1: 49469 cycles median: 56566 cycles q3: 107782 cycles max: 551555 cycles RESULT TestThreadCreateAndJoin_CycleCount: trusted_linux_x86-64= {56566, 58313} count Apparent clock speed: 1576 MHz TestThreadWakeup: Measuring real time: 15.766 usec (1.57663e-05 sec) per iteration: 0.00157663 sec for 100 iterations 17.866 usec (1.78657e-05 sec) per iteration: 0.113304 sec for 6342 iterations 16.469 usec (1.64694e-05 sec) per iteration: 0.104449 sec for 6342 iterations 18.255 usec (1.82552e-05 sec) per iteration: 0.115774 sec for 6342 iterations 20.357 usec (2.0357e-05 sec) per iteration: 0.129104 sec for 6342 iterations 25.003 usec (2.50028e-05 sec) per iteration: 0.158568 sec for 6342 iterations mean: 19.590015 usec stddev: 2.979263 usec relative stddev: 15.21% RESULT TestThreadWakeup: trusted_linux_x86-64= {19.590015, 2.979263} us Measuring clock cycles: first runs (cycles): 48464 43864 60319 43903 44463 43807 44243 43974 45406 50884 ... slowest runs (cycles): ... 50884 51746 52012 54623 55381 60319 62179 65591 105333 110982 min: 36224 cycles q1: 44038 cycles median: 44815 cycles q3: 46883 cycles max: 110982 cycles RESULT TestThreadWakeup_CycleCount: trusted_linux_x86-64= {44815, 2845} count Apparent clock speed: 2288 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