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-32 --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-32', '--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-5n3b 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: 20821392 kB MemAvailable: 29347504 kB ====================================================================== LOAD: ====================================================================== 1.62 1.84 0.95 1/433 15438 ====================================================================== UPTIME: ====================================================================== 488.20 2841.35 ====================================================================== 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-32 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-32/obj BUILD_TYPE_DESCRIPTION: NaCl module build BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 32 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 32 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 -m32 -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=32 -I/b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-32/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-32 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-linux-x86-32/obj BUILD_TYPE_DESCRIPTION: Linux opt build BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 32 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 32 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 -m32 -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-32 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt-x86-32/obj BUILD_TYPE_DESCRIPTION: NaCl IRT build BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 32 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 32 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 -m32 -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=32 -I/b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt-x86-32/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_nacl_sync_cond_test There are 12 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-32_nnacl_newlib_static --subarch 32 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-32/test_results/signal_handler_single_step.out /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-32/obj/tests/signal_handler_single_step/step_test_host /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-32/obj/tests/signal_handler_single_step/step_test_guest.nexe x86-32_nnacl_newlib_static [ RUN ] nacl_newlib.run_signal_handler_single_step_test /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-32/obj/tests/signal_handler_single_step/step_test_host /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-32/obj/tests/signal_handler_single_step/step_test_guest.nexe x86-32_nnacl_newlib_static [15447,4148147968:15:40:22.930232] Native Client module will be loaded at base address 0x00000000b73fa000 Switching to untrusted: since previous switch: 89 instructions, 212 instruction bytes, 18 jumps Switching to trusted: since previous switch: 12 instructions, 39 instruction bytes, 3 jumps Switching to untrusted: since previous switch: 228 instructions, 559 instruction bytes, 22 jumps Switching to trusted: since previous switch: 10 instructions, 23 instruction bytes, 4 jumps Switching to untrusted: since previous switch: 228 instructions, 559 instruction bytes, 22 jumps Switching to trusted: since previous switch: 10 instructions, 23 instruction bytes, 4 jumps Switching to untrusted: since previous switch: 228 instructions, 559 instruction bytes, 22 jumps Switching to trusted: since previous switch: 10 instructions, 23 instruction bytes, 4 jumps Switching to untrusted: since previous switch: 228 instructions, 559 instruction bytes, 22 jumps RESULT InstructionsPerSyscall: x86-32_nnacl_newlib_static= 228 count RESULT InstructionBytesPerSyscall: x86-32_nnacl_newlib_static= 559 count RESULT JumpsPerSyscall: x86-32_nnacl_newlib_static= 22 count Switching to trusted: since previous switch: 10 instructions, 23 instruction bytes, 4 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-32_nnacl_newlib_static --using_nacl_signal_handler True --subarch 32 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-32/test_results/benchmark_test.out scons-out/opt-linux-x86-32/staging/nacl_helper_bootstrap scons-out/opt-linux-x86-32/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -- scons-out/nacl-x86-32/obj/tests/benchmark/benchmark_test.nexe x86-32_nnacl_newlib_static [ RUN ] nacl_newlib.run_benchmark_test scons-out/opt-linux-x86-32/staging/nacl_helper_bootstrap scons-out/opt-linux-x86-32/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -- scons-out/nacl-x86-32/obj/tests/benchmark/benchmark_test.nexe x86-32_nnacl_newlib_static [15452,4147660544:15:40:23.190831] Native Client module will be loaded at base address 0x0000000000000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-32_nnacl_newlib_static= {0.109440, 0.003040} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test (1870 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-32_nnacl_newlib_static --using_nacl_signal_handler True --subarch 32 --arch x86 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-32/test_results/clock_cputime_test.out scons-out/opt-linux-x86-32/staging/nacl_helper_bootstrap scons-out/opt-linux-x86-32/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -- scons-out/nacl-x86-32/obj/tests/clock/clock_test.nexe -c [ RUN ] nacl_newlib.run_clock_cputime_test scons-out/opt-linux-x86-32/staging/nacl_helper_bootstrap scons-out/opt-linux-x86-32/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -- scons-out/nacl-x86-32/obj/tests/clock/clock_test.nexe -c WARNING: should have taken less than 60.000000 secs [ OK ] nacl_newlib.run_clock_cputime_test (71200 ms) doSizeCheck(["scons-out/nacl-x86-32/obj/tests/hello_world/hello_world_size.out"], ["scons-out/nacl-x86-32/obj/tests/hello_world/hello_world.nexe"]) RESULT hello_world_size.out: x86-32_nnacl_newlib_static= 509.324 KB RESULT hello_world_size.out: ZIPPED_x86-32_nnacl_newlib_static= 171.073 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-32_nnacl_newlib_static --using_nacl_signal_handler True --subarch 32 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-32/test_results/performance_test.out scons-out/opt-linux-x86-32/staging/nacl_helper_bootstrap scons-out/opt-linux-x86-32/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -e -- scons-out/nacl-x86-32/obj/tests/performance/performance_test.nexe x86-32_nnacl_newlib_static [ RUN ] nacl_newlib.run_performance_test scons-out/opt-linux-x86-32/staging/nacl_helper_bootstrap scons-out/opt-linux-x86-32/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -e -- scons-out/nacl-x86-32/obj/tests/performance/performance_test.nexe x86-32_nnacl_newlib_static [15741,4147382016:15:40:37.847312] Native Client module will be loaded at base address 0x0000000000000000 TestNull: Measuring real time: 0.011 usec (1.109e-08 sec) per iteration: 1.109e-06 sec for 100 iterations 0.003 usec (2.706e-09 sec) per iteration: 2.706e-06 sec for 1000 iterations 0.002 usec (2.277e-09 sec) per iteration: 2.277e-05 sec for 10000 iterations 0.002 usec (2.27187e-09 sec) per iteration: 0.0997748 sec for 43917435 iterations 0.002 usec (2.27074e-09 sec) per iteration: 0.0997249 sec for 43917435 iterations 0.002 usec (2.2673e-09 sec) per iteration: 0.099574 sec for 43917435 iterations 0.002 usec (2.37794e-09 sec) per iteration: 0.104433 sec for 43917435 iterations 0.002 usec (2.28319e-09 sec) per iteration: 0.100272 sec for 43917435 iterations mean: 0.002294 usec stddev: 0.000042 usec relative stddev: 1.84% RESULT TestNull: x86-32_nnacl_newlib_static= {0.002294, 0.000042} us Measuring clock cycles: first runs (cycles): 88 49 24 22 24 22 48 25 25 25 ... slowest runs (cycles): ... 25 26 26 27 27 27 27 48 49 88 min: 22 cycles q1: 22 cycles median: 24 cycles q3: 24 cycles max: 88 cycles RESULT TestNull_CycleCount: x86-32_nnacl_newlib_static= {24, 2} count Apparent clock speed: 10461 MHz TestNaClSyscall: Measuring real time: 0.243 usec (2.434e-07 sec) per iteration: 2.434e-05 sec for 100 iterations 0.239 usec (2.39391e-07 sec) per iteration: 0.0983528 sec for 410846 iterations 0.239 usec (2.39292e-07 sec) per iteration: 0.0983122 sec for 410846 iterations 0.239 usec (2.38901e-07 sec) per iteration: 0.0981514 sec for 410846 iterations 0.239 usec (2.38945e-07 sec) per iteration: 0.0981695 sec for 410846 iterations 0.239 usec (2.39046e-07 sec) per iteration: 0.0982113 sec for 410846 iterations mean: 0.239115 usec stddev: 0.000193 usec relative stddev: 0.08% RESULT TestNaClSyscall: x86-32_nnacl_newlib_static= {0.239115, 0.000193} us Measuring clock cycles: first runs (cycles): 629 564 531 530 528 530 528 530 528 530 ... slowest runs (cycles): ... 532 532 532 532 533 533 533 539 564 629 min: 527 cycles q1: 528 cycles median: 530 cycles q3: 530 cycles max: 629 cycles RESULT TestNaClSyscall_CycleCount: x86-32_nnacl_newlib_static= {530, 2} count Apparent clock speed: 2217 MHz TestSetjmpLongjmp: Measuring real time: 0.020 usec (2e-08 sec) per iteration: 2e-06 sec for 100 iterations 0.013 usec (1.3235e-08 sec) per iteration: 1.3235e-05 sec for 1000 iterations 0.012 usec (1.22207e-08 sec) per iteration: 0.0923361 sec for 7555723 iterations 0.012 usec (1.2206e-08 sec) per iteration: 0.0922255 sec for 7555723 iterations 0.012 usec (1.21955e-08 sec) per iteration: 0.092146 sec for 7555723 iterations 0.012 usec (1.22017e-08 sec) per iteration: 0.0921925 sec for 7555723 iterations 0.012 usec (1.21984e-08 sec) per iteration: 0.092168 sec for 7555723 iterations mean: 0.012204 usec stddev: 0.000009 usec relative stddev: 0.07% RESULT TestSetjmpLongjmp: x86-32_nnacl_newlib_static= {0.012204, 0.000009} us Measuring clock cycles: first runs (cycles): 137 88 42 37 37 40 39 42 38 41 ... slowest runs (cycles): ... 42 42 42 42 42 42 44 44 88 137 min: 33 cycles q1: 35 cycles median: 38 cycles q3: 40 cycles max: 137 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-32_nnacl_newlib_static= {38, 5} count Apparent clock speed: 3114 MHz TestClockGetTime: Measuring real time: 0.458 usec (4.5826e-07 sec) per iteration: 4.5826e-05 sec for 100 iterations 0.460 usec (4.60123e-07 sec) per iteration: 0.100406 sec for 218216 iterations 0.460 usec (4.59821e-07 sec) per iteration: 0.10034 sec for 218216 iterations 0.461 usec (4.6091e-07 sec) per iteration: 0.100578 sec for 218216 iterations 0.461 usec (4.61334e-07 sec) per iteration: 0.10067 sec for 218216 iterations 0.460 usec (4.59592e-07 sec) per iteration: 0.10029 sec for 218216 iterations mean: 0.460356 usec stddev: 0.000661 usec relative stddev: 0.14% RESULT TestClockGetTime: x86-32_nnacl_newlib_static= {0.460356, 0.000661} us Measuring clock cycles: first runs (cycles): 1340 1036 1014 1008 1009 1008 1010 1007 1012 1005 ... slowest runs (cycles): ... 1014 1016 1034 1036 1042 1047 1047 1048 1050 1340 min: 1003 cycles q1: 1008 cycles median: 1010 cycles q3: 1012 cycles max: 1340 cycles RESULT TestClockGetTime_CycleCount: x86-32_nnacl_newlib_static= {1010, 4} count Apparent clock speed: 2194 MHz TestTlsVariable: Measuring real time: 0.009 usec (8.54e-09 sec) per iteration: 8.54e-07 sec for 100 iterations 0.003 usec (3.481e-09 sec) per iteration: 3.481e-06 sec for 1000 iterations 0.004 usec (4.3958e-09 sec) per iteration: 4.3958e-05 sec for 10000 iterations 0.003 usec (3.02541e-09 sec) per iteration: 0.0688251 sec for 22748987 iterations 0.003 usec (3.02099e-09 sec) per iteration: 0.0687245 sec for 22748987 iterations 0.003 usec (3.03368e-09 sec) per iteration: 0.0690131 sec for 22748987 iterations 0.003 usec (3.02427e-09 sec) per iteration: 0.068799 sec for 22748987 iterations 0.003 usec (3.02585e-09 sec) per iteration: 0.0688351 sec for 22748987 iterations mean: 0.003026 usec stddev: 0.000004 usec relative stddev: 0.14% RESULT TestTlsVariable: x86-32_nnacl_newlib_static= {0.003026, 0.000004} us Measuring clock cycles: first runs (cycles): 101 48 50 27 26 26 27 27 26 26 ... slowest runs (cycles): ... 27 27 27 27 27 27 27 48 50 101 min: 24 cycles q1: 26 cycles median: 26 cycles q3: 27 cycles max: 101 cycles RESULT TestTlsVariable_CycleCount: x86-32_nnacl_newlib_static= {26, 1} count Apparent clock speed: 8592 MHz TestMmapAnonymous: Measuring real time: 3.226 usec (3.22593e-06 sec) per iteration: 0.000322593 sec for 100 iterations 2.715 usec (2.71483e-06 sec) per iteration: 0.0841542 sec for 30998 iterations 2.654 usec (2.65441e-06 sec) per iteration: 0.0822815 sec for 30998 iterations 2.643 usec (2.64254e-06 sec) per iteration: 0.0819134 sec for 30998 iterations 2.627 usec (2.62674e-06 sec) per iteration: 0.0814236 sec for 30998 iterations 2.608 usec (2.60757e-06 sec) per iteration: 0.0808295 sec for 30998 iterations mean: 2.649218 usec stddev: 0.036376 usec relative stddev: 1.37% RESULT TestMmapAnonymous: x86-32_nnacl_newlib_static= {2.649218, 0.036376} us Measuring clock cycles: first runs (cycles): 6797 5746 5733 5715 5680 5663 5742 5664 5667 5702 ... slowest runs (cycles): ... 5760 5765 5768 5800 5994 6012 6074 6417 6797 14510 min: 5638 cycles q1: 5667 cycles median: 5682 cycles q3: 5707 cycles max: 14510 cycles RESULT TestMmapAnonymous_CycleCount: x86-32_nnacl_newlib_static= {5682, 40} count Apparent clock speed: 2145 MHz TestAtomicIncrement: Measuring real time: 0.017 usec (1.652e-08 sec) per iteration: 1.652e-06 sec for 100 iterations 0.010 usec (9.75e-09 sec) per iteration: 9.75e-06 sec for 1000 iterations 0.010 usec (9.8337e-09 sec) per iteration: 9.8337e-05 sec for 10000 iterations 0.009 usec (9.43411e-09 sec) per iteration: 0.0959365 sec for 10169112 iterations 0.009 usec (9.43787e-09 sec) per iteration: 0.0959748 sec for 10169112 iterations 0.009 usec (9.4274e-09 sec) per iteration: 0.0958683 sec for 10169112 iterations 0.009 usec (9.4251e-09 sec) per iteration: 0.0958449 sec for 10169112 iterations 0.009 usec (9.41713e-09 sec) per iteration: 0.0957638 sec for 10169112 iterations mean: 0.009428 usec stddev: 0.000007 usec relative stddev: 0.08% RESULT TestAtomicIncrement: x86-32_nnacl_newlib_static= {0.009428, 0.000007} us Measuring clock cycles: first runs (cycles): 101 68 50 51 50 51 49 50 49 48 ... slowest runs (cycles): ... 51 51 51 51 51 51 51 68 101 283 min: 48 cycles q1: 49 cycles median: 50 cycles q3: 51 cycles max: 283 cycles RESULT TestAtomicIncrement_CycleCount: x86-32_nnacl_newlib_static= {50, 2} count Apparent clock speed: 5303 MHz TestUncontendedMutexLock: Measuring real time: 0.029 usec (2.865e-08 sec) per iteration: 2.865e-06 sec for 100 iterations 0.023 usec (2.2523e-08 sec) per iteration: 2.2523e-05 sec for 1000 iterations 0.022 usec (2.22631e-08 sec) per iteration: 0.0988461 sec for 4439905 iterations 0.022 usec (2.22673e-08 sec) per iteration: 0.0988647 sec for 4439905 iterations 0.022 usec (2.22356e-08 sec) per iteration: 0.0987241 sec for 4439905 iterations 0.022 usec (2.22752e-08 sec) per iteration: 0.0988999 sec for 4439905 iterations 0.022 usec (2.22354e-08 sec) per iteration: 0.0987233 sec for 4439905 iterations mean: 0.022255 usec stddev: 0.000017 usec relative stddev: 0.07% RESULT TestUncontendedMutexLock: x86-32_nnacl_newlib_static= {0.022255, 0.000017} us Measuring clock cycles: first runs (cycles): 246 121 77 79 82 82 82 82 79 81 ... slowest runs (cycles): ... 82 82 82 82 82 82 82 82 121 246 min: 77 cycles q1: 79 cycles median: 79 cycles q3: 81 cycles max: 246 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-32_nnacl_newlib_static= {79, 2} count Apparent clock speed: 3550 MHz TestCondvarSignalNoOp: Measuring real time: 0.438 usec (4.3757e-07 sec) per iteration: 4.3757e-05 sec for 100 iterations 0.409 usec (4.09096e-07 sec) per iteration: 0.0934924 sec for 228534 iterations 0.410 usec (4.10402e-07 sec) per iteration: 0.0937908 sec for 228534 iterations 0.410 usec (4.09622e-07 sec) per iteration: 0.0936124 sec for 228534 iterations 0.409 usec (4.09499e-07 sec) per iteration: 0.0935845 sec for 228534 iterations 0.409 usec (4.09498e-07 sec) per iteration: 0.0935841 sec for 228534 iterations mean: 0.409623 usec stddev: 0.000428 usec relative stddev: 0.10% RESULT TestCondvarSignalNoOp: x86-32_nnacl_newlib_static= {0.409623, 0.000428} us Measuring clock cycles: first runs (cycles): 1080 924 963 900 900 898 897 898 900 895 ... slowest runs (cycles): ... 902 902 902 902 904 907 924 935 963 1080 min: 891 cycles q1: 897 cycles median: 898 cycles q3: 900 cycles max: 1080 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-32_nnacl_newlib_static= {898, 3} count Apparent clock speed: 2192 MHz TestThreadCreateAndJoin: Measuring real time: 94.700 usec (9.47005e-05 sec) per iteration: 0.00947005 sec for 100 iterations 90.948 usec (9.09479e-05 sec) per iteration: 0.09595 sec for 1055 iterations 97.856 usec (9.78557e-05 sec) per iteration: 0.103238 sec for 1055 iterations 84.279 usec (8.42789e-05 sec) per iteration: 0.0889142 sec for 1055 iterations 85.753 usec (8.57533e-05 sec) per iteration: 0.0904697 sec for 1055 iterations 93.370 usec (9.33702e-05 sec) per iteration: 0.0985055 sec for 1055 iterations mean: 90.441185 usec stddev: 4.975165 usec relative stddev: 5.50% RESULT TestThreadCreateAndJoin: x86-32_nnacl_newlib_static= {90.441185, 4.975165} us Measuring clock cycles: first runs (cycles): 122334 194758 204281 258531 197655 181401 179320 207732 186612 196342 ... slowest runs (cycles): ... 214717 220804 220806 229102 232879 242778 252597 258531 272785 320612 min: 122334 cycles q1: 186726 cycles median: 190387 cycles q3: 204281 cycles max: 320612 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-32_nnacl_newlib_static= {190387, 17555} count Apparent clock speed: 2105 MHz TestThreadWakeup: Measuring real time: 13.071 usec (1.30706e-05 sec) per iteration: 0.00130706 sec for 100 iterations 14.486 usec (1.44857e-05 sec) per iteration: 0.110815 sec for 7650 iterations 14.773 usec (1.47733e-05 sec) per iteration: 0.113016 sec for 7650 iterations 14.463 usec (1.44629e-05 sec) per iteration: 0.110641 sec for 7650 iterations 14.908 usec (1.49082e-05 sec) per iteration: 0.114048 sec for 7650 iterations 14.687 usec (1.46869e-05 sec) per iteration: 0.112354 sec for 7650 iterations mean: 14.663389 usec stddev: 0.169923 usec relative stddev: 1.16% RESULT TestThreadWakeup: x86-32_nnacl_newlib_static= {14.663389, 0.169923} us Measuring clock cycles: first runs (cycles): 65009 45677 31767 32978 31262 30762 31499 30597 32771 32817 ... slowest runs (cycles): ... 34242 34496 41840 45677 45908 47653 57916 65009 112848 499659 min: 24405 cycles q1: 27713 cycles median: 29510 cycles q3: 31767 cycles max: 499659 cycles RESULT TestThreadWakeup_CycleCount: x86-32_nnacl_newlib_static= {29510, 4054} count Apparent clock speed: 2012 MHz TestCatchingFault: Measuring real time: 3.284 usec (3.28446e-06 sec) per iteration: 0.000328446 sec for 100 iterations 2.291 usec (2.29117e-06 sec) per iteration: 0.0697568 sec for 30446 iterations 2.290 usec (2.29024e-06 sec) per iteration: 0.0697286 sec for 30446 iterations 2.291 usec (2.29111e-06 sec) per iteration: 0.0697553 sec for 30446 iterations 2.295 usec (2.29539e-06 sec) per iteration: 0.0698854 sec for 30446 iterations 2.274 usec (2.27404e-06 sec) per iteration: 0.0692355 sec for 30446 iterations mean: 2.288390 usec stddev: 0.007395 usec relative stddev: 0.32% RESULT TestCatchingFault: x86-32_nnacl_newlib_static= {2.288390, 0.007395} us Measuring clock cycles: first runs (cycles): 5566 5037 4978 4981 4995 4989 4989 4978 4983 4982 ... slowest runs (cycles): ... 4989 4989 4989 4992 4993 4995 5037 5174 5566 17420 min: 4912 cycles q1: 4958 cycles median: 4973 cycles q3: 4980 cycles max: 17420 cycles RESULT TestCatchingFault_CycleCount: x86-32_nnacl_newlib_static= {4973, 22} count Apparent clock speed: 2173 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 90.388 usec (9.03879e-05 sec) per iteration: 0.00903879 sec for 100 iterations 88.995 usec (8.89953e-05 sec) per iteration: 0.0984288 sec for 1106 iterations 94.358 usec (9.43584e-05 sec) per iteration: 0.10436 sec for 1106 iterations 88.728 usec (8.87285e-05 sec) per iteration: 0.0981337 sec for 1106 iterations 85.116 usec (8.51157e-05 sec) per iteration: 0.0941379 sec for 1106 iterations 87.885 usec (8.78852e-05 sec) per iteration: 0.0972011 sec for 1106 iterations mean: 89.016624 usec stddev: 3.003852 usec relative stddev: 3.37% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-32_nnacl_newlib_static= {89.016624, 3.003852} us Measuring clock cycles: first runs (cycles): 125030 182309 174291 192407 110061 111432 164062 178290 160789 173094 ... slowest runs (cycles): ... 188713 189103 192407 195245 207110 208557 213056 234498 236266 418180 min: 110061 cycles q1: 167071 cycles median: 169674 cycles q3: 179828 cycles max: 418180 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-32_nnacl_newlib_static= {169674, 12757} count Apparent clock speed: 1906 MHz [ OK ] nacl_newlib.run_performance_test (5960 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-32 --subarch 32 --arch x86 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-linux-x86-32/test_results/nacl_clock_cputime_test.out /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-linux-x86-32/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-32/obj/src/shared/platform/nacl_clock_test -c [ OK ] nacl_newlib.run_nacl_clock_cputime_test (2990 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-32 --subarch 32 --arch x86 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-linux-x86-32/test_results/nacl_interval_multi_reg_test.out /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-linux-x86-32/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-32/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-32 --subarch 32 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-linux-x86-32/test_results/performance_test.out /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-linux-x86-32/obj/tests/performance/performance_test trusted_linux_x86-32 [ RUN ] nacl_newlib.run_trusted_performance_test /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-linux-x86-32/obj/tests/performance/performance_test trusted_linux_x86-32 TestNull: Measuring real time: 0.007 usec (6.6e-09 sec) per iteration: 6.6e-07 sec for 100 iterations 0.002 usec (2.091e-09 sec) per iteration: 2.091e-06 sec for 1000 iterations 0.002 usec (1.8755e-09 sec) per iteration: 1.8755e-05 sec for 10000 iterations 0.002 usec (1.88993e-09 sec) per iteration: 0.100769 sec for 53319114 iterations 0.002 usec (1.89238e-09 sec) per iteration: 0.1009 sec for 53319114 iterations 0.002 usec (1.8933e-09 sec) per iteration: 0.100949 sec for 53319114 iterations 0.002 usec (1.94192e-09 sec) per iteration: 0.103541 sec for 53319114 iterations 0.002 usec (1.88899e-09 sec) per iteration: 0.100719 sec for 53319114 iterations mean: 0.001901 usec stddev: 0.000020 usec relative stddev: 1.07% RESULT TestNull: trusted_linux_x86-32= {0.001901, 0.000020} us Measuring clock cycles: first runs (cycles): 81 22 24 22 22 24 24 27 25 25 ... slowest runs (cycles): ... 27 27 27 28 28 28 29 29 29 81 min: 19 cycles q1: 22 cycles median: 24 cycles q3: 25 cycles max: 81 cycles RESULT TestNull_CycleCount: trusted_linux_x86-32= {24, 3} count Apparent clock speed: 12623 MHz TestHostSyscall: Measuring real time: 0.119 usec (1.1909e-07 sec) per iteration: 1.1909e-05 sec for 100 iterations 0.108 usec (1.07664e-07 sec) per iteration: 0.0904059 sec for 839701 iterations 0.108 usec (1.08403e-07 sec) per iteration: 0.0910265 sec for 839701 iterations 0.109 usec (1.08561e-07 sec) per iteration: 0.0911586 sec for 839701 iterations 0.108 usec (1.08123e-07 sec) per iteration: 0.0907911 sec for 839701 iterations 0.108 usec (1.07733e-07 sec) per iteration: 0.0904634 sec for 839701 iterations mean: 0.108097 usec stddev: 0.000355 usec relative stddev: 0.33% RESULT TestHostSyscall: trusted_linux_x86-32= {0.108097, 0.000355} us Measuring clock cycles: first runs (cycles): 334 321 255 251 251 308 250 253 256 252 ... slowest runs (cycles): ... 256 257 257 258 308 321 334 347 447 451 min: 250 cycles q1: 253 cycles median: 253 cycles q3: 255 cycles max: 451 cycles RESULT TestHostSyscall_CycleCount: trusted_linux_x86-32= {253, 2} count Apparent clock speed: 2340 MHz TestSetjmpLongjmp: Measuring real time: 0.039 usec (3.909e-08 sec) per iteration: 3.909e-06 sec for 100 iterations 0.030 usec (3.0345e-08 sec) per iteration: 3.0345e-05 sec for 1000 iterations 0.032 usec (3.18173e-08 sec) per iteration: 0.104852 sec for 3295435 iterations 0.032 usec (3.17338e-08 sec) per iteration: 0.104577 sec for 3295435 iterations 0.032 usec (3.17569e-08 sec) per iteration: 0.104653 sec for 3295435 iterations 0.032 usec (3.16871e-08 sec) per iteration: 0.104423 sec for 3295435 iterations 0.032 usec (3.16244e-08 sec) per iteration: 0.104216 sec for 3295435 iterations mean: 0.031724 usec stddev: 0.000065 usec relative stddev: 0.21% RESULT TestSetjmpLongjmp: trusted_linux_x86-32= {0.031724, 0.000065} us Measuring clock cycles: first runs (cycles): 297 95 172 93 88 88 185 90 90 88 ... slowest runs (cycles): ... 95 95 95 95 95 95 172 185 187 297 min: 85 cycles q1: 88 cycles median: 90 cycles q3: 93 cycles max: 297 cycles RESULT TestSetjmpLongjmp_CycleCount: trusted_linux_x86-32= {90, 5} count Apparent clock speed: 2837 MHz TestClockGetTime: Measuring real time: 0.199 usec (1.9914e-07 sec) per iteration: 1.9914e-05 sec for 100 iterations 0.199 usec (1.99325e-07 sec) per iteration: 0.100093 sec for 502159 iterations 0.199 usec (1.99121e-07 sec) per iteration: 0.0999904 sec for 502159 iterations 0.199 usec (1.98814e-07 sec) per iteration: 0.099836 sec for 502159 iterations 0.200 usec (1.99639e-07 sec) per iteration: 0.100251 sec for 502159 iterations 0.200 usec (1.99909e-07 sec) per iteration: 0.100386 sec for 502159 iterations mean: 0.199361 usec stddev: 0.000384 usec relative stddev: 0.19% RESULT TestClockGetTime: trusted_linux_x86-32= {0.199361, 0.000384} us Measuring clock cycles: first runs (cycles): 616 534 453 451 453 506 453 453 454 460 ... slowest runs (cycles): ... 457 458 460 460 506 509 534 616 687 733 min: 451 cycles q1: 453 cycles median: 453 cycles q3: 455 cycles max: 733 cycles RESULT TestClockGetTime_CycleCount: trusted_linux_x86-32= {453, 2} count Apparent clock speed: 2272 MHz TestTlsVariable: Measuring real time: 0.005 usec (4.94e-09 sec) per iteration: 4.94e-07 sec for 100 iterations 0.003 usec (2.632e-09 sec) per iteration: 2.632e-06 sec for 1000 iterations 0.002 usec (2.4526e-09 sec) per iteration: 2.4526e-05 sec for 10000 iterations 0.002 usec (2.48657e-09 sec) per iteration: 0.101385 sec for 40773057 iterations 0.002 usec (2.48057e-09 sec) per iteration: 0.10114 sec for 40773057 iterations 0.002 usec (2.47809e-09 sec) per iteration: 0.101039 sec for 40773057 iterations 0.003 usec (2.5387e-09 sec) per iteration: 0.103511 sec for 40773057 iterations 0.002 usec (2.48176e-09 sec) per iteration: 0.101189 sec for 40773057 iterations mean: 0.002493 usec stddev: 0.000023 usec relative stddev: 0.92% RESULT TestTlsVariable: trusted_linux_x86-32= {0.002493, 0.000023} us Measuring clock cycles: first runs (cycles): 68 29 26 27 24 26 29 24 27 26 ... slowest runs (cycles): ... 29 29 29 29 29 29 29 55 68 697 min: 20 cycles q1: 24 cycles median: 26 cycles q3: 27 cycles max: 697 cycles RESULT TestTlsVariable_CycleCount: trusted_linux_x86-32= {26, 3} count Apparent clock speed: 10429 MHz TestMmapAnonymous: Measuring real time: 0.994 usec (9.9403e-07 sec) per iteration: 9.9403e-05 sec for 100 iterations 0.795 usec (7.95035e-07 sec) per iteration: 0.0799806 sec for 100600 iterations 0.796 usec (7.9565e-07 sec) per iteration: 0.0800424 sec for 100600 iterations 0.803 usec (8.02759e-07 sec) per iteration: 0.0807576 sec for 100600 iterations 0.799 usec (7.99128e-07 sec) per iteration: 0.0803923 sec for 100600 iterations 0.798 usec (7.98472e-07 sec) per iteration: 0.0803263 sec for 100600 iterations mean: 0.798209 usec stddev: 0.002765 usec relative stddev: 0.35% RESULT TestMmapAnonymous: trusted_linux_x86-32= {0.798209, 0.002765} us Measuring clock cycles: first runs (cycles): 2442 1832 1786 1737 1729 1795 1745 1729 1740 1736 ... slowest runs (cycles): ... 1832 1911 1920 1933 2165 2233 2252 2343 2442 2565 min: 1709 cycles q1: 1729 cycles median: 1737 cycles q3: 1747 cycles max: 2565 cycles RESULT TestMmapAnonymous_CycleCount: trusted_linux_x86-32= {1737, 18} count Apparent clock speed: 2176 MHz TestAtomicIncrement: Measuring real time: 0.013 usec (1.321e-08 sec) per iteration: 1.321e-06 sec for 100 iterations 0.009 usec (9.464e-09 sec) per iteration: 9.464e-06 sec for 1000 iterations 0.009 usec (9.3359e-09 sec) per iteration: 9.3359e-05 sec for 10000 iterations 0.009 usec (9.43331e-09 sec) per iteration: 0.101043 sec for 10711340 iterations 0.009 usec (9.43281e-09 sec) per iteration: 0.101038 sec for 10711340 iterations 0.010 usec (9.64257e-09 sec) per iteration: 0.103285 sec for 10711340 iterations 0.009 usec (9.48337e-09 sec) per iteration: 0.10158 sec for 10711340 iterations 0.009 usec (9.49031e-09 sec) per iteration: 0.101654 sec for 10711340 iterations mean: 0.009496 usec stddev: 0.000077 usec relative stddev: 0.81% RESULT TestAtomicIncrement: trusted_linux_x86-32= {0.009496, 0.000077} us Measuring clock cycles: first runs (cycles): 94 240 51 50 51 248 51 55 50 55 ... slowest runs (cycles): ... 55 57 57 94 207 240 248 349 361 445 min: 48 cycles q1: 50 cycles median: 51 cycles q3: 51 cycles max: 445 cycles RESULT TestAtomicIncrement_CycleCount: trusted_linux_x86-32= {51, 1} count Apparent clock speed: 5370 MHz TestUncontendedMutexLock: Measuring real time: 0.037 usec (3.672e-08 sec) per iteration: 3.672e-06 sec for 100 iterations 0.026 usec (2.6136e-08 sec) per iteration: 2.6136e-05 sec for 1000 iterations 0.027 usec (2.66032e-08 sec) per iteration: 0.101788 sec for 3826140 iterations 0.027 usec (2.65242e-08 sec) per iteration: 0.101485 sec for 3826140 iterations 0.026 usec (2.64193e-08 sec) per iteration: 0.101084 sec for 3826140 iterations 0.026 usec (2.64133e-08 sec) per iteration: 0.101061 sec for 3826140 iterations 0.026 usec (2.64631e-08 sec) per iteration: 0.101252 sec for 3826140 iterations mean: 0.026485 usec stddev: 0.000071 usec relative stddev: 0.27% RESULT TestUncontendedMutexLock: trusted_linux_x86-32= {0.026485, 0.000071} us Measuring clock cycles: first runs (cycles): 148 292 93 93 93 264 92 94 93 92 ... slowest runs (cycles): ... 95 95 95 96 148 264 268 292 413 416 min: 88 cycles q1: 90 cycles median: 90 cycles q3: 93 cycles max: 416 cycles RESULT TestUncontendedMutexLock_CycleCount: trusted_linux_x86-32= {90, 3} count Apparent clock speed: 3398 MHz TestCondvarSignalNoOp: Measuring real time: 0.028 usec (2.76e-08 sec) per iteration: 2.76e-06 sec for 100 iterations 0.021 usec (2.0948e-08 sec) per iteration: 2.0948e-05 sec for 1000 iterations 0.021 usec (2.11141e-08 sec) per iteration: 0.100793 sec for 4773725 iterations 0.021 usec (2.11288e-08 sec) per iteration: 0.100863 sec for 4773725 iterations 0.021 usec (2.11052e-08 sec) per iteration: 0.10075 sec for 4773725 iterations 0.021 usec (2.12623e-08 sec) per iteration: 0.1015 sec for 4773725 iterations 0.022 usec (2.19742e-08 sec) per iteration: 0.104899 sec for 4773725 iterations mean: 0.021317 usec stddev: 0.000334 usec relative stddev: 1.56% RESULT TestCondvarSignalNoOp: trusted_linux_x86-32= {0.021317, 0.000334} us Measuring clock cycles: first runs (cycles): 195 295 79 81 81 326 82 82 79 81 ... slowest runs (cycles): ... 82 82 83 90 195 295 321 326 403 407 min: 77 cycles q1: 79 cycles median: 81 cycles q3: 82 cycles max: 407 cycles RESULT TestCondvarSignalNoOp_CycleCount: trusted_linux_x86-32= {81, 3} count Apparent clock speed: 3800 MHz TestThreadCreateAndJoin: Measuring real time: 35.089 usec (3.50893e-05 sec) per iteration: 0.00350893 sec for 100 iterations 22.504 usec (2.25038e-05 sec) per iteration: 0.0641133 sec for 2849 iterations 21.616 usec (2.16157e-05 sec) per iteration: 0.0615831 sec for 2849 iterations 23.197 usec (2.31967e-05 sec) per iteration: 0.0660873 sec for 2849 iterations 21.498 usec (2.14984e-05 sec) per iteration: 0.0612491 sec for 2849 iterations 22.453 usec (2.24528e-05 sec) per iteration: 0.0639681 sec for 2849 iterations mean: 22.253485 usec stddev: 0.627510 usec relative stddev: 2.82% RESULT TestThreadCreateAndJoin: trusted_linux_x86-32= {22.253485, 0.627510} us Measuring clock cycles: first runs (cycles): 42734 41539 43261 45211 41198 42681 45444 47661 48299 51931 ... slowest runs (cycles): ... 105494 128884 142899 159897 180708 203760 263747 265667 841961 884408 min: 34193 cycles q1: 42493 cycles median: 45211 cycles q3: 50492 cycles max: 884408 cycles RESULT TestThreadCreateAndJoin_CycleCount: trusted_linux_x86-32= {45211, 7999} count Apparent clock speed: 2032 MHz TestThreadWakeup: Measuring real time: 15.285 usec (1.52855e-05 sec) per iteration: 0.00152855 sec for 100 iterations 15.017 usec (1.50165e-05 sec) per iteration: 0.0982382 sec for 6542 iterations 14.711 usec (1.47113e-05 sec) per iteration: 0.0962416 sec for 6542 iterations 14.597 usec (1.45974e-05 sec) per iteration: 0.0954959 sec for 6542 iterations 14.616 usec (1.46158e-05 sec) per iteration: 0.0956163 sec for 6542 iterations 17.215 usec (1.72154e-05 sec) per iteration: 0.112623 sec for 6542 iterations mean: 15.231271 usec stddev: 1.003368 usec relative stddev: 6.59% RESULT TestThreadWakeup: trusted_linux_x86-32= {15.231271, 1.003368} us Measuring clock cycles: first runs (cycles): 36829 34009 34942 34887 34403 52219 34533 34216 34354 33877 ... slowest runs (cycles): ... 41022 41752 42727 43110 44080 44594 46892 46964 51711 52219 min: 30892 cycles q1: 34196 cycles median: 34574 cycles q3: 35879 cycles max: 52219 cycles RESULT TestThreadWakeup_CycleCount: trusted_linux_x86-32= {34574, 1683} count Apparent clock speed: 2270 MHz WARNING: should have taken less than 2.000000 secs [ OK ] nacl_newlib.run_trusted_performance_test (5330 ms) scons: done building targets. ********************************************************************** COMMAND EXECUTION REPORT ********************************************************************** 7 command_tester.py 1 doSizeCheck ********************************************************************** ENVIRONMENT USAGE REPORT ********************************************************************** 5 nacl-x86-32 3 opt-linux-x86-32 Command return code: 0