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-west1-c-wpkx 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: 19515044 kB MemAvailable: 29475948 kB ====================================================================== LOAD: ====================================================================== 1.64 2.13 1.50 1/446 22449 ====================================================================== UPTIME: ====================================================================== 26311.15 206683.48 ====================================================================== 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 [22457,4147873536:10:55:49.260881] Native Client module will be loaded at base address 0x00000000b73b7000 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 [22462,4147664640:10:55:49.561193] 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.112870, 0.006560} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test (1930 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 (71340 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= 512.216 KB RESULT hello_world_size.out: ZIPPED_x86-32_nnacl_newlib_static= 171.095 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 [22490,4147267328:10:56:04.676450] Native Client module will be loaded at base address 0x0000000000000000 TestNull: Measuring real time: 0.012 usec (1.184e-08 sec) per iteration: 1.184e-06 sec for 100 iterations 0.003 usec (2.733e-09 sec) per iteration: 2.733e-06 sec for 1000 iterations 0.003 usec (2.6492e-09 sec) per iteration: 2.6492e-05 sec for 10000 iterations 0.002 usec (2.28732e-09 sec) per iteration: 0.0863401 sec for 37747244 iterations 0.002 usec (2.28922e-09 sec) per iteration: 0.0864118 sec for 37747244 iterations 0.002 usec (2.33569e-09 sec) per iteration: 0.088166 sec for 37747244 iterations 0.002 usec (2.27164e-09 sec) per iteration: 0.0857483 sec for 37747244 iterations 0.002 usec (2.28026e-09 sec) per iteration: 0.0860735 sec for 37747244 iterations mean: 0.002293 usec stddev: 0.000022 usec relative stddev: 0.97% RESULT TestNull: x86-32_nnacl_newlib_static= {0.002293, 0.000022} us Measuring clock cycles: first runs (cycles): 75 46 22 27 19 22 49 24 22 22 ... slowest runs (cycles): ... 25 25 26 26 26 27 27 46 49 75 min: 19 cycles q1: 22 cycles median: 24 cycles q3: 24 cycles max: 75 cycles RESULT TestNull_CycleCount: x86-32_nnacl_newlib_static= {24, 2} count Apparent clock speed: 10467 MHz TestNaClSyscall: Measuring real time: 0.336 usec (3.3566e-07 sec) per iteration: 3.3566e-05 sec for 100 iterations 0.239 usec (2.39347e-07 sec) per iteration: 0.0713062 sec for 297920 iterations 0.239 usec (2.38992e-07 sec) per iteration: 0.0712006 sec for 297920 iterations 0.241 usec (2.40665e-07 sec) per iteration: 0.0716988 sec for 297920 iterations 0.241 usec (2.41412e-07 sec) per iteration: 0.0719215 sec for 297920 iterations 0.241 usec (2.40938e-07 sec) per iteration: 0.0717803 sec for 297920 iterations mean: 0.240271 usec stddev: 0.000937 usec relative stddev: 0.39% RESULT TestNaClSyscall: x86-32_nnacl_newlib_static= {0.240271, 0.000937} us Measuring clock cycles: first runs (cycles): 616 594 530 528 528 528 530 530 528 532 ... slowest runs (cycles): ... 532 532 532 532 532 532 533 594 616 695 min: 527 cycles q1: 528 cycles median: 528 cycles q3: 530 cycles max: 695 cycles RESULT TestNaClSyscall_CycleCount: x86-32_nnacl_newlib_static= {528, 2} count Apparent clock speed: 2198 MHz TestSetjmpLongjmp: Measuring real time: 0.023 usec (2.312e-08 sec) per iteration: 2.312e-06 sec for 100 iterations 0.014 usec (1.3962e-08 sec) per iteration: 1.3962e-05 sec for 1000 iterations 0.012 usec (1.2285e-08 sec) per iteration: 0.087989 sec for 7162297 iterations 0.012 usec (1.22296e-08 sec) per iteration: 0.087592 sec for 7162297 iterations 0.012 usec (1.2326e-08 sec) per iteration: 0.0882823 sec for 7162297 iterations 0.012 usec (1.23106e-08 sec) per iteration: 0.0881724 sec for 7162297 iterations 0.012 usec (1.22603e-08 sec) per iteration: 0.0878122 sec for 7162297 iterations mean: 0.012282 usec stddev: 0.000035 usec relative stddev: 0.28% RESULT TestSetjmpLongjmp: x86-32_nnacl_newlib_static= {0.012282, 0.000035} us Measuring clock cycles: first runs (cycles): 101 64 42 33 33 33 36 37 37 40 ... slowest runs (cycles): ... 42 42 42 42 42 42 42 42 64 101 min: 33 cycles q1: 36 cycles median: 39 cycles q3: 40 cycles max: 101 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-32_nnacl_newlib_static= {39, 4} count Apparent clock speed: 3175 MHz TestClockGetTime: Measuring real time: 0.524 usec (5.2367e-07 sec) per iteration: 5.2367e-05 sec for 100 iterations 0.472 usec (4.71598e-07 sec) per iteration: 0.090056 sec for 190959 iterations 0.466 usec (4.65575e-07 sec) per iteration: 0.0889058 sec for 190959 iterations 0.464 usec (4.63856e-07 sec) per iteration: 0.0885775 sec for 190959 iterations 0.466 usec (4.66338e-07 sec) per iteration: 0.0890514 sec for 190959 iterations 0.469 usec (4.68681e-07 sec) per iteration: 0.0894989 sec for 190959 iterations mean: 0.467210 usec stddev: 0.002687 usec relative stddev: 0.58% RESULT TestClockGetTime: x86-32_nnacl_newlib_static= {0.467210, 0.002687} us Measuring clock cycles: first runs (cycles): 1375 1091 1021 1031 1021 1016 1019 1021 1021 1029 ... slowest runs (cycles): ... 1034 1058 1058 1058 1058 1060 1063 1064 1091 1375 min: 1014 cycles q1: 1019 cycles median: 1021 cycles q3: 1025 cycles max: 1375 cycles RESULT TestClockGetTime_CycleCount: x86-32_nnacl_newlib_static= {1021, 6} count Apparent clock speed: 2185 MHz TestTlsVariable: Measuring real time: 0.009 usec (8.67e-09 sec) per iteration: 8.67e-07 sec for 100 iterations 0.003 usec (3.47e-09 sec) per iteration: 3.47e-06 sec for 1000 iterations 0.003 usec (3.0172e-09 sec) per iteration: 3.0172e-05 sec for 10000 iterations 0.003 usec (3.02792e-09 sec) per iteration: 0.100355 sec for 33143311 iterations 0.003 usec (3.02027e-09 sec) per iteration: 0.100102 sec for 33143311 iterations 0.003 usec (3.02658e-09 sec) per iteration: 0.100311 sec for 33143311 iterations 0.003 usec (3.06107e-09 sec) per iteration: 0.101454 sec for 33143311 iterations 0.003 usec (3.07072e-09 sec) per iteration: 0.101774 sec for 33143311 iterations mean: 0.003041 usec stddev: 0.000020 usec relative stddev: 0.67% RESULT TestTlsVariable: x86-32_nnacl_newlib_static= {0.003041, 0.000020} us Measuring clock cycles: first runs (cycles): 101 48 27 26 26 27 27 26 26 27 ... slowest runs (cycles): ... 27 27 27 27 27 27 27 27 48 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: 8549 MHz TestMmapAnonymous: Measuring real time: 3.895 usec (3.89471e-06 sec) per iteration: 0.000389471 sec for 100 iterations 2.713 usec (2.71267e-06 sec) per iteration: 0.0696478 sec for 25675 iterations 2.669 usec (2.6693e-06 sec) per iteration: 0.0685342 sec for 25675 iterations 2.635 usec (2.63515e-06 sec) per iteration: 0.0676575 sec for 25675 iterations 2.658 usec (2.65782e-06 sec) per iteration: 0.0682396 sec for 25675 iterations 2.646 usec (2.64623e-06 sec) per iteration: 0.0679419 sec for 25675 iterations mean: 2.664233 usec stddev: 0.026769 usec relative stddev: 1.00% RESULT TestMmapAnonymous: x86-32_nnacl_newlib_static= {2.664233, 0.026769} us Measuring clock cycles: first runs (cycles): 7022 5763 5658 5766 5674 5702 5688 5676 5719 5744 ... slowest runs (cycles): ... 5781 5823 7022 7915 8210 8302 8541 8593 14769 21726 min: 5651 cycles q1: 5686 cycles median: 5704 cycles q3: 5735 cycles max: 21726 cycles RESULT TestMmapAnonymous_CycleCount: x86-32_nnacl_newlib_static= {5704, 49} count Apparent clock speed: 2141 MHz TestAtomicIncrement: Measuring real time: 0.020 usec (1.972e-08 sec) per iteration: 1.972e-06 sec for 100 iterations 0.012 usec (1.154e-08 sec) per iteration: 1.154e-05 sec for 1000 iterations 0.009 usec (9.4661e-09 sec) per iteration: 0.0820286 sec for 8665511 iterations 0.009 usec (9.44195e-09 sec) per iteration: 0.0818193 sec for 8665511 iterations 0.009 usec (9.46456e-09 sec) per iteration: 0.0820153 sec for 8665511 iterations 0.009 usec (9.43367e-09 sec) per iteration: 0.0817476 sec for 8665511 iterations 0.009 usec (9.40673e-09 sec) per iteration: 0.0815141 sec for 8665511 iterations mean: 0.009443 usec stddev: 0.000022 usec relative stddev: 0.23% RESULT TestAtomicIncrement: x86-32_nnacl_newlib_static= {0.009443, 0.000022} us Measuring clock cycles: first runs (cycles): 95 59 51 51 48 51 48 50 49 50 ... slowest runs (cycles): ... 51 51 51 51 51 51 51 51 59 95 min: 48 cycles q1: 49 cycles median: 50 cycles q3: 51 cycles max: 95 cycles RESULT TestAtomicIncrement_CycleCount: x86-32_nnacl_newlib_static= {50, 2} count Apparent clock speed: 5295 MHz TestUncontendedMutexLock: Measuring real time: 0.029 usec (2.924e-08 sec) per iteration: 2.924e-06 sec for 100 iterations 0.023 usec (2.2534e-08 sec) per iteration: 2.2534e-05 sec for 1000 iterations 0.022 usec (2.24114e-08 sec) per iteration: 0.0994558 sec for 4437738 iterations 0.022 usec (2.24013e-08 sec) per iteration: 0.0994109 sec for 4437738 iterations 0.022 usec (2.22781e-08 sec) per iteration: 0.0988645 sec for 4437738 iterations 0.022 usec (2.23955e-08 sec) per iteration: 0.0993855 sec for 4437738 iterations 0.022 usec (2.21882e-08 sec) per iteration: 0.0984653 sec for 4437738 iterations mean: 0.022335 usec stddev: 0.000088 usec relative stddev: 0.39% RESULT TestUncontendedMutexLock: x86-32_nnacl_newlib_static= {0.022335, 0.000088} us Measuring clock cycles: first runs (cycles): 225 138 81 81 79 82 80 79 79 79 ... slowest runs (cycles): ... 82 82 82 82 82 82 82 82 138 225 min: 79 cycles q1: 79 cycles median: 79 cycles q3: 81 cycles max: 225 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-32_nnacl_newlib_static= {79, 2} count Apparent clock speed: 3537 MHz TestCondvarSignalNoOp: Measuring real time: 0.473 usec (4.7337e-07 sec) per iteration: 4.7337e-05 sec for 100 iterations 0.414 usec (4.13967e-07 sec) per iteration: 0.0874509 sec for 211251 iterations 0.414 usec (4.13718e-07 sec) per iteration: 0.0873983 sec for 211251 iterations 0.413 usec (4.13088e-07 sec) per iteration: 0.0872652 sec for 211251 iterations 0.414 usec (4.13671e-07 sec) per iteration: 0.0873885 sec for 211251 iterations 0.413 usec (4.13369e-07 sec) per iteration: 0.0873246 sec for 211251 iterations mean: 0.413562 usec stddev: 0.000304 usec relative stddev: 0.07% RESULT TestCondvarSignalNoOp: x86-32_nnacl_newlib_static= {0.413562, 0.000304} us Measuring clock cycles: first runs (cycles): 1036 975 906 909 913 909 904 906 909 913 ... slowest runs (cycles): ... 915 916 916 917 917 917 918 935 975 1036 min: 902 cycles q1: 906 cycles median: 908 cycles q3: 913 cycles max: 1036 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-32_nnacl_newlib_static= {908, 7} count Apparent clock speed: 2196 MHz TestThreadCreateAndJoin: Measuring real time: 95.218 usec (9.52179e-05 sec) per iteration: 0.00952179 sec for 100 iterations 88.658 usec (8.86583e-05 sec) per iteration: 0.0930912 sec for 1050 iterations 86.082 usec (8.60824e-05 sec) per iteration: 0.0903865 sec for 1050 iterations 91.047 usec (9.10471e-05 sec) per iteration: 0.0955994 sec for 1050 iterations 83.759 usec (8.37589e-05 sec) per iteration: 0.0879469 sec for 1050 iterations 94.079 usec (9.40792e-05 sec) per iteration: 0.0987831 sec for 1050 iterations mean: 88.725174 usec stddev: 3.625151 usec relative stddev: 4.09% RESULT TestThreadCreateAndJoin: x86-32_nnacl_newlib_static= {88.725174, 3.625151} us Measuring clock cycles: first runs (cycles): 244074 239010 282552 220774 224381 169982 156658 155925 154207 163039 ... slowest runs (cycles): ... 174641 185693 198566 203331 220774 224381 239010 244074 282552 335007 min: 146944 cycles q1: 152432 cycles median: 155045 cycles q3: 160261 cycles max: 335007 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-32_nnacl_newlib_static= {155045, 7829} count Apparent clock speed: 1747 MHz TestThreadWakeup: Measuring real time: 13.107 usec (1.31069e-05 sec) per iteration: 0.00131069 sec for 100 iterations 16.422 usec (1.64223e-05 sec) per iteration: 0.125286 sec for 7629 iterations 17.475 usec (1.74751e-05 sec) per iteration: 0.133318 sec for 7629 iterations 16.951 usec (1.69512e-05 sec) per iteration: 0.129321 sec for 7629 iterations 16.540 usec (1.65398e-05 sec) per iteration: 0.126182 sec for 7629 iterations 16.289 usec (1.62888e-05 sec) per iteration: 0.124267 sec for 7629 iterations mean: 16.735435 usec stddev: 0.431161 usec relative stddev: 2.58% RESULT TestThreadWakeup: x86-32_nnacl_newlib_static= {16.735435, 0.431161} us Measuring clock cycles: first runs (cycles): 164289 41748 37880 37952 36601 37482 37507 38323 42493 52747 ... slowest runs (cycles): ... 46322 46385 47164 50299 52747 56044 60148 61555 66470 164289 min: 35652 cycles q1: 37558 cycles median: 37880 cycles q3: 41748 cycles max: 164289 cycles RESULT TestThreadWakeup_CycleCount: x86-32_nnacl_newlib_static= {37880, 4190} count Apparent clock speed: 2263 MHz TestCatchingFault: Measuring real time: 4.139 usec (4.13911e-06 sec) per iteration: 0.000413911 sec for 100 iterations 2.281 usec (2.28107e-06 sec) per iteration: 0.0551083 sec for 24159 iterations 2.280 usec (2.27995e-06 sec) per iteration: 0.0550814 sec for 24159 iterations 2.281 usec (2.28115e-06 sec) per iteration: 0.0551103 sec for 24159 iterations 2.278 usec (2.27784e-06 sec) per iteration: 0.0550304 sec for 24159 iterations 2.288 usec (2.28787e-06 sec) per iteration: 0.0552725 sec for 24159 iterations mean: 2.281575 usec stddev: 0.003364 usec relative stddev: 0.15% RESULT TestCatchingFault: x86-32_nnacl_newlib_static= {2.281575, 0.003364} us Measuring clock cycles: first runs (cycles): 6042 5053 5016 4993 5003 4995 5000 4979 4976 4996 ... slowest runs (cycles): ... 5002 5003 5003 5004 5005 5006 5016 5035 5053 6042 min: 4923 cycles q1: 4934 cycles median: 4945 cycles q3: 4989 cycles max: 6042 cycles RESULT TestCatchingFault_CycleCount: x86-32_nnacl_newlib_static= {4945, 55} count Apparent clock speed: 2167 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 93.697 usec (9.36967e-05 sec) per iteration: 0.00936967 sec for 100 iterations 89.053 usec (8.90534e-05 sec) per iteration: 0.0950199 sec for 1067 iterations 82.664 usec (8.26635e-05 sec) per iteration: 0.088202 sec for 1067 iterations 86.561 usec (8.6561e-05 sec) per iteration: 0.0923606 sec for 1067 iterations 87.654 usec (8.76542e-05 sec) per iteration: 0.0935271 sec for 1067 iterations 83.883 usec (8.38826e-05 sec) per iteration: 0.0895028 sec for 1067 iterations mean: 85.962946 usec stddev: 2.365692 usec relative stddev: 2.75% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-32_nnacl_newlib_static= {85.962946, 2.365692} us Measuring clock cycles: first runs (cycles): 114119 171121 186239 204008 185891 187789 191182 182351 186548 174175 ... slowest runs (cycles): ... 196060 196877 197253 201290 204008 211156 212170 223805 225875 249303 min: 112114 cycles q1: 170512 cycles median: 178486 cycles q3: 185891 cycles max: 249303 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-32_nnacl_newlib_static= {178486, 15379} count Apparent clock speed: 2076 MHz [ OK ] nacl_newlib.run_performance_test (5630 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 (1980 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 (600 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.009 usec (9.03e-09 sec) per iteration: 9.03e-07 sec for 100 iterations 0.002 usec (2.167e-09 sec) per iteration: 2.167e-06 sec for 1000 iterations 0.002 usec (2.1262e-09 sec) per iteration: 2.1262e-05 sec for 10000 iterations 0.002 usec (1.88678e-09 sec) per iteration: 0.0887397 sec for 47032264 iterations 0.002 usec (1.89047e-09 sec) per iteration: 0.0889129 sec for 47032264 iterations 0.002 usec (1.90327e-09 sec) per iteration: 0.0895151 sec for 47032264 iterations 0.002 usec (1.88457e-09 sec) per iteration: 0.0886356 sec for 47032264 iterations 0.002 usec (1.88726e-09 sec) per iteration: 0.0887621 sec for 47032264 iterations mean: 0.001890 usec stddev: 0.000007 usec relative stddev: 0.35% RESULT TestNull: trusted_linux_x86-32= {0.001890, 0.000007} us Measuring clock cycles: first runs (cycles): 79 24 26 26 24 24 22 24 22 25 ... slowest runs (cycles): ... 27 27 27 27 27 27 27 27 27 79 min: 22 cycles q1: 22 cycles median: 24 cycles q3: 26 cycles max: 79 cycles RESULT TestNull_CycleCount: trusted_linux_x86-32= {24, 4} count Apparent clock speed: 12695 MHz TestHostSyscall: Measuring real time: 0.119 usec (1.1865e-07 sec) per iteration: 1.1865e-05 sec for 100 iterations 0.105 usec (1.04593e-07 sec) per iteration: 0.0881528 sec for 842815 iterations 0.104 usec (1.04085e-07 sec) per iteration: 0.0877244 sec for 842815 iterations 0.104 usec (1.04031e-07 sec) per iteration: 0.0876788 sec for 842815 iterations 0.104 usec (1.04166e-07 sec) per iteration: 0.0877923 sec for 842815 iterations 0.104 usec (1.04131e-07 sec) per iteration: 0.0877629 sec for 842815 iterations mean: 0.104201 usec stddev: 0.000201 usec relative stddev: 0.19% RESULT TestHostSyscall: trusted_linux_x86-32= {0.104201, 0.000201} us Measuring clock cycles: first runs (cycles): 385 315 248 247 246 321 249 248 247 246 ... slowest runs (cycles): ... 249 249 251 308 315 315 321 339 354 385 min: 244 cycles q1: 246 cycles median: 246 cycles q3: 247 cycles max: 385 cycles RESULT TestHostSyscall_CycleCount: trusted_linux_x86-32= {246, 1} count Apparent clock speed: 2361 MHz TestSetjmpLongjmp: Measuring real time: 0.043 usec (4.304e-08 sec) per iteration: 4.304e-06 sec for 100 iterations 0.031 usec (3.1174e-08 sec) per iteration: 3.1174e-05 sec for 1000 iterations 0.032 usec (3.18008e-08 sec) per iteration: 0.102011 sec for 3207801 iterations 0.032 usec (3.16499e-08 sec) per iteration: 0.101527 sec for 3207801 iterations 0.032 usec (3.1744e-08 sec) per iteration: 0.101829 sec for 3207801 iterations 0.032 usec (3.21111e-08 sec) per iteration: 0.103006 sec for 3207801 iterations 0.032 usec (3.18953e-08 sec) per iteration: 0.102314 sec for 3207801 iterations mean: 0.031840 usec stddev: 0.000157 usec relative stddev: 0.49% RESULT TestSetjmpLongjmp: trusted_linux_x86-32= {0.031840, 0.000157} us Measuring clock cycles: first runs (cycles): 156 96 271 88 94 95 304 94 93 93 ... slowest runs (cycles): ... 95 95 95 95 95 96 156 271 304 332 min: 83 cycles q1: 90 cycles median: 93 cycles q3: 95 cycles max: 332 cycles RESULT TestSetjmpLongjmp_CycleCount: trusted_linux_x86-32= {93, 5} count Apparent clock speed: 2921 MHz TestClockGetTime: Measuring real time: 0.199 usec (1.9879e-07 sec) per iteration: 1.9879e-05 sec for 100 iterations 0.197 usec (1.96801e-07 sec) per iteration: 0.0989992 sec for 503043 iterations 0.197 usec (1.97224e-07 sec) per iteration: 0.099212 sec for 503043 iterations 0.197 usec (1.9747e-07 sec) per iteration: 0.0993357 sec for 503043 iterations 0.196 usec (1.96084e-07 sec) per iteration: 0.0986386 sec for 503043 iterations 0.196 usec (1.95789e-07 sec) per iteration: 0.0984901 sec for 503043 iterations mean: 0.196673 usec stddev: 0.000646 usec relative stddev: 0.33% RESULT TestClockGetTime: trusted_linux_x86-32= {0.196673, 0.000646} us Measuring clock cycles: first runs (cycles): 596 613 449 449 451 626 447 448 447 447 ... slowest runs (cycles): ... 451 451 451 596 613 620 620 626 636 674 min: 442 cycles q1: 446 cycles median: 447 cycles q3: 449 cycles max: 674 cycles RESULT TestClockGetTime_CycleCount: trusted_linux_x86-32= {447, 3} count Apparent clock speed: 2273 MHz TestTlsVariable: Measuring real time: 0.005 usec (4.6e-09 sec) per iteration: 4.6e-07 sec for 100 iterations 0.003 usec (2.622e-09 sec) per iteration: 2.622e-06 sec for 1000 iterations 0.002 usec (2.4097e-09 sec) per iteration: 2.4097e-05 sec for 10000 iterations 0.002 usec (2.49253e-09 sec) per iteration: 0.103437 sec for 41498941 iterations 0.002 usec (2.49312e-09 sec) per iteration: 0.103462 sec for 41498941 iterations 0.002 usec (2.46614e-09 sec) per iteration: 0.102342 sec for 41498941 iterations 0.002 usec (2.46708e-09 sec) per iteration: 0.102381 sec for 41498941 iterations 0.002 usec (2.46732e-09 sec) per iteration: 0.102391 sec for 41498941 iterations mean: 0.002477 usec stddev: 0.000013 usec relative stddev: 0.51% RESULT TestTlsVariable: trusted_linux_x86-32= {0.002477, 0.000013} us Measuring clock cycles: first runs (cycles): 82 27 26 24 27 51 26 29 24 26 ... slowest runs (cycles): ... 29 29 29 29 29 29 31 51 53 82 min: 22 cycles q1: 24 cycles median: 26 cycles q3: 28 cycles max: 82 cycles RESULT TestTlsVariable_CycleCount: trusted_linux_x86-32= {26, 4} count Apparent clock speed: 10496 MHz TestMmapAnonymous: Measuring real time: 1.026 usec (1.02641e-06 sec) per iteration: 0.000102641 sec for 100 iterations 0.792 usec (7.92177e-07 sec) per iteration: 0.0771787 sec for 97426 iterations 0.797 usec (7.97323e-07 sec) per iteration: 0.07768 sec for 97426 iterations 0.793 usec (7.9326e-07 sec) per iteration: 0.0772841 sec for 97426 iterations 0.800 usec (7.99711e-07 sec) per iteration: 0.0779127 sec for 97426 iterations 0.797 usec (7.97257e-07 sec) per iteration: 0.0776736 sec for 97426 iterations mean: 0.795946 usec stddev: 0.002800 usec relative stddev: 0.35% RESULT TestMmapAnonymous: trusted_linux_x86-32= {0.795946, 0.002800} us Measuring clock cycles: first runs (cycles): 2609 1954 1740 1724 1734 1944 1716 1762 1718 1725 ... slowest runs (cycles): ... 1773 1784 1944 1947 1953 1954 2095 2279 2609 17580 min: 1704 cycles q1: 1718 cycles median: 1727 cycles q3: 1735 cycles max: 17580 cycles RESULT TestMmapAnonymous_CycleCount: trusted_linux_x86-32= {1727, 17} count Apparent clock speed: 2170 MHz TestAtomicIncrement: Measuring real time: 0.013 usec (1.301e-08 sec) per iteration: 1.301e-06 sec for 100 iterations 0.009 usec (9.451e-09 sec) per iteration: 9.451e-06 sec for 1000 iterations 0.009 usec (9.3449e-09 sec) per iteration: 9.3449e-05 sec for 10000 iterations 0.009 usec (9.40315e-09 sec) per iteration: 0.100623 sec for 10701024 iterations 0.009 usec (9.40548e-09 sec) per iteration: 0.100648 sec for 10701024 iterations 0.009 usec (9.42475e-09 sec) per iteration: 0.100854 sec for 10701024 iterations 0.009 usec (9.43301e-09 sec) per iteration: 0.100943 sec for 10701024 iterations 0.009 usec (9.40907e-09 sec) per iteration: 0.100687 sec for 10701024 iterations mean: 0.009415 usec stddev: 0.000012 usec relative stddev: 0.12% RESULT TestAtomicIncrement: trusted_linux_x86-32= {0.009415, 0.000012} us Measuring clock cycles: first runs (cycles): 94 348 51 53 51 348 50 51 51 51 ... slowest runs (cycles): ... 53 53 55 94 339 348 348 358 377 470 min: 48 cycles q1: 50 cycles median: 51 cycles q3: 51 cycles max: 470 cycles RESULT TestAtomicIncrement_CycleCount: trusted_linux_x86-32= {51, 1} count Apparent clock speed: 5417 MHz TestUncontendedMutexLock: Measuring real time: 0.035 usec (3.532e-08 sec) per iteration: 3.532e-06 sec for 100 iterations 0.026 usec (2.628e-08 sec) per iteration: 2.628e-05 sec for 1000 iterations 0.026 usec (2.63368e-08 sec) per iteration: 0.100216 sec for 3805175 iterations 0.026 usec (2.63191e-08 sec) per iteration: 0.100149 sec for 3805175 iterations 0.026 usec (2.63456e-08 sec) per iteration: 0.10025 sec for 3805175 iterations 0.026 usec (2.63873e-08 sec) per iteration: 0.100408 sec for 3805175 iterations 0.026 usec (2.63357e-08 sec) per iteration: 0.100212 sec for 3805175 iterations mean: 0.026345 usec stddev: 0.000023 usec relative stddev: 0.09% RESULT TestUncontendedMutexLock: trusted_linux_x86-32= {0.026345, 0.000023} us Measuring clock cycles: first runs (cycles): 191 390 92 92 95 385 92 95 94 95 ... slowest runs (cycles): ... 110 112 126 191 385 390 394 402 410 904 min: 87 cycles q1: 90 cycles median: 92 cycles q3: 94 cycles max: 904 cycles RESULT TestUncontendedMutexLock_CycleCount: trusted_linux_x86-32= {92, 4} count Apparent clock speed: 3492 MHz TestCondvarSignalNoOp: Measuring real time: 0.025 usec (2.545e-08 sec) per iteration: 2.545e-06 sec for 100 iterations 0.021 usec (2.0935e-08 sec) per iteration: 2.0935e-05 sec for 1000 iterations 0.021 usec (2.12248e-08 sec) per iteration: 0.101384 sec for 4776689 iterations 0.021 usec (2.10628e-08 sec) per iteration: 0.100611 sec for 4776689 iterations 0.021 usec (2.12922e-08 sec) per iteration: 0.101706 sec for 4776689 iterations 0.021 usec (2.11155e-08 sec) per iteration: 0.100862 sec for 4776689 iterations 0.021 usec (2.10773e-08 sec) per iteration: 0.10068 sec for 4776689 iterations mean: 0.021155 usec stddev: 0.000089 usec relative stddev: 0.42% RESULT TestCondvarSignalNoOp: trusted_linux_x86-32= {0.021155, 0.000089} us Measuring clock cycles: first runs (cycles): 158 365 79 79 79 424 82 82 81 81 ... slowest runs (cycles): ... 82 86 158 365 407 416 424 436 445 449 min: 77 cycles q1: 79 cycles median: 79 cycles q3: 81 cycles max: 449 cycles RESULT TestCondvarSignalNoOp_CycleCount: trusted_linux_x86-32= {79, 2} count Apparent clock speed: 3734 MHz TestThreadCreateAndJoin: Measuring real time: 42.714 usec (4.27136e-05 sec) per iteration: 0.00427136 sec for 100 iterations 24.592 usec (2.45915e-05 sec) per iteration: 0.0575688 sec for 2341 iterations 26.626 usec (2.6626e-05 sec) per iteration: 0.0623314 sec for 2341 iterations 31.433 usec (3.14325e-05 sec) per iteration: 0.0735836 sec for 2341 iterations 33.709 usec (3.37087e-05 sec) per iteration: 0.0789122 sec for 2341 iterations 33.827 usec (3.38271e-05 sec) per iteration: 0.0791893 sec for 2341 iterations mean: 30.037186 usec stddev: 3.770477 usec relative stddev: 12.55% RESULT TestThreadCreateAndJoin: trusted_linux_x86-32= {30.037186, 3.770477} us Measuring clock cycles: first runs (cycles): 704323 226852 233000 300255 171786 101183 85601 73483 59925 58335 ... slowest runs (cycles): ... 100263 101183 109221 135093 141446 171786 226852 233000 300255 704323 min: 55633 cycles q1: 57910 cycles median: 59289 cycles q3: 62394 cycles max: 704323 cycles RESULT TestThreadCreateAndJoin_CycleCount: trusted_linux_x86-32= {59289, 4484} count Apparent clock speed: 1974 MHz TestThreadWakeup: Measuring real time: 21.909 usec (2.19095e-05 sec) per iteration: 0.00219095 sec for 100 iterations 21.439 usec (2.14391e-05 sec) per iteration: 0.0978481 sec for 4564 iterations 21.153 usec (2.11533e-05 sec) per iteration: 0.0965435 sec for 4564 iterations 20.402 usec (2.04017e-05 sec) per iteration: 0.0931133 sec for 4564 iterations 16.832 usec (1.68318e-05 sec) per iteration: 0.0768202 sec for 4564 iterations 21.496 usec (2.14963e-05 sec) per iteration: 0.098109 sec for 4564 iterations mean: 20.264425 usec stddev: 1.760079 usec relative stddev: 8.69% RESULT TestThreadWakeup: trusted_linux_x86-32= {20.264425, 1.760079} us Measuring clock cycles: first runs (cycles): 52404 46966 47523 45422 45745 46617 46523 47312 46979 46913 ... slowest runs (cycles): ... 49746 50358 50822 51069 51634 52404 56432 58713 62215 63150 min: 38314 cycles q1: 44891 cycles median: 45523 cycles q3: 46617 cycles max: 63150 cycles RESULT TestThreadWakeup_CycleCount: trusted_linux_x86-32= {45523, 1726} count Apparent clock speed: 2246 MHz WARNING: should have taken less than 2.000000 secs [ OK ] nacl_newlib.run_trusted_performance_test (5160 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