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,dbg-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,dbg-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-vmp4 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: 14574040 kB MemAvailable: 29414184 kB ====================================================================== LOAD: ====================================================================== 1.63 1.81 1.22 1/440 3746 ====================================================================== UPTIME: ====================================================================== 10542.01 78884.08 ====================================================================== 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: dbg-linux TARGET_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/dbg-linux-x86-32 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/dbg-linux-x86-32/obj BUILD_TYPE_DESCRIPTION: Linux dbg 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 -O0 -g -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 -D_DEBUG -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 [3754,4148086528:12:18:50.942913] Native Client module will be loaded at base address 0x000000006ca60000 Switching to untrusted: since previous switch: 201 instructions, 509 instruction bytes, 23 jumps Switching to trusted: since previous switch: 12 instructions, 39 instruction bytes, 3 jumps Switching to untrusted: since previous switch: 796 instructions, 1989 instruction bytes, 85 jumps Switching to trusted: since previous switch: 10 instructions, 23 instruction bytes, 4 jumps Switching to untrusted: since previous switch: 796 instructions, 1989 instruction bytes, 85 jumps Switching to trusted: since previous switch: 10 instructions, 23 instruction bytes, 4 jumps Switching to untrusted: since previous switch: 796 instructions, 1989 instruction bytes, 85 jumps Switching to trusted: since previous switch: 10 instructions, 23 instruction bytes, 4 jumps Switching to untrusted: since previous switch: 796 instructions, 1989 instruction bytes, 85 jumps RESULT InstructionsPerSyscall: x86-32_nnacl_newlib_static= 796 count RESULT InstructionBytesPerSyscall: x86-32_nnacl_newlib_static= 1989 count RESULT JumpsPerSyscall: x86-32_nnacl_newlib_static= 85 count Switching to trusted: since previous switch: 10 instructions, 23 instruction bytes, 4 jumps [ OK ] nacl_newlib.run_signal_handler_single_step_test (20 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/dbg-linux-x86-32/staging/nacl_helper_bootstrap scons-out/dbg-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/dbg-linux-x86-32/staging/nacl_helper_bootstrap scons-out/dbg-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 [3759,4147570432:12:18:51.248024] 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.112940, 0.004120} 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/dbg-linux-x86-32/staging/nacl_helper_bootstrap scons-out/dbg-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/dbg-linux-x86-32/staging/nacl_helper_bootstrap scons-out/dbg-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 (70470 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/dbg-linux-x86-32/staging/nacl_helper_bootstrap scons-out/dbg-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/dbg-linux-x86-32/staging/nacl_helper_bootstrap scons-out/dbg-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 [3788,4147255040:12:19:06.115637] Native Client module will be loaded at base address 0x0000000000000000 TestNull: Measuring real time: 0.014 usec (1.408e-08 sec) per iteration: 1.408e-06 sec for 100 iterations 0.003 usec (2.9e-09 sec) per iteration: 2.9e-06 sec for 1000 iterations 0.002 usec (2.3004e-09 sec) per iteration: 2.3004e-05 sec for 10000 iterations 0.002 usec (2.29513e-09 sec) per iteration: 0.0997709 sec for 43470700 iterations 0.002 usec (2.28666e-09 sec) per iteration: 0.0994026 sec for 43470700 iterations 0.002 usec (2.28245e-09 sec) per iteration: 0.0992195 sec for 43470700 iterations 0.002 usec (2.27419e-09 sec) per iteration: 0.0988605 sec for 43470700 iterations 0.002 usec (2.30042e-09 sec) per iteration: 0.100001 sec for 43470700 iterations mean: 0.002288 usec stddev: 0.000009 usec relative stddev: 0.40% RESULT TestNull: x86-32_nnacl_newlib_static= {0.002288, 0.000009} us Measuring clock cycles: first runs (cycles): 88 46 24 24 24 26 24 24 22 25 ... slowest runs (cycles): ... 25 25 26 26 26 26 27 27 46 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: 10491 MHz TestNaClSyscall: Measuring real time: 0.384 usec (3.836e-07 sec) per iteration: 3.836e-05 sec for 100 iterations 0.334 usec (3.33818e-07 sec) per iteration: 0.0870224 sec for 260688 iterations 0.346 usec (3.45725e-07 sec) per iteration: 0.0901264 sec for 260688 iterations 0.333 usec (3.32999e-07 sec) per iteration: 0.0868089 sec for 260688 iterations 0.332 usec (3.32175e-07 sec) per iteration: 0.086594 sec for 260688 iterations 0.334 usec (3.33618e-07 sec) per iteration: 0.0869702 sec for 260688 iterations mean: 0.335667 usec stddev: 0.005061 usec relative stddev: 1.51% RESULT TestNaClSyscall: x86-32_nnacl_newlib_static= {0.335667, 0.005061} us Measuring clock cycles: first runs (cycles): 812 757 743 726 724 735 721 731 737 724 ... slowest runs (cycles): ... 737 737 737 743 754 756 757 765 772 812 min: 719 cycles q1: 724 cycles median: 728 cycles q3: 734 cycles max: 812 cycles RESULT TestNaClSyscall_CycleCount: x86-32_nnacl_newlib_static= {728, 10} count Apparent clock speed: 2169 MHz TestSetjmpLongjmp: Measuring real time: 0.027 usec (2.749e-08 sec) per iteration: 2.749e-06 sec for 100 iterations 0.015 usec (1.4809e-08 sec) per iteration: 1.4809e-05 sec for 1000 iterations 0.012 usec (1.22856e-08 sec) per iteration: 0.0829605 sec for 6752650 iterations 0.012 usec (1.23169e-08 sec) per iteration: 0.0831716 sec for 6752650 iterations 0.012 usec (1.2331e-08 sec) per iteration: 0.083267 sec for 6752650 iterations 0.012 usec (1.23825e-08 sec) per iteration: 0.0836147 sec for 6752650 iterations 0.012 usec (1.2259e-08 sec) per iteration: 0.0827806 sec for 6752650 iterations mean: 0.012315 usec stddev: 0.000042 usec relative stddev: 0.34% RESULT TestSetjmpLongjmp: x86-32_nnacl_newlib_static= {0.012315, 0.000042} us Measuring clock cycles: first runs (cycles): 136 86 40 40 37 35 40 37 37 38 ... slowest runs (cycles): ... 40 40 41 42 42 42 79 86 136 150 min: 33 cycles q1: 35 cycles median: 38 cycles q3: 40 cycles max: 150 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-32_nnacl_newlib_static= {38, 5} count Apparent clock speed: 3086 MHz TestClockGetTime: Measuring real time: 0.641 usec (6.4132e-07 sec) per iteration: 6.4132e-05 sec for 100 iterations 0.655 usec (6.55307e-07 sec) per iteration: 0.102181 sec for 155928 iterations 0.652 usec (6.51849e-07 sec) per iteration: 0.101642 sec for 155928 iterations 0.656 usec (6.56122e-07 sec) per iteration: 0.102308 sec for 155928 iterations 0.655 usec (6.54828e-07 sec) per iteration: 0.102106 sec for 155928 iterations 0.653 usec (6.53023e-07 sec) per iteration: 0.101825 sec for 155928 iterations mean: 0.654226 usec stddev: 0.001563 usec relative stddev: 0.24% RESULT TestClockGetTime: x86-32_nnacl_newlib_static= {0.654226, 0.001563} us Measuring clock cycles: first runs (cycles): 1720 1597 1459 1390 1417 1449 1415 1395 1392 1428 ... slowest runs (cycles): ... 1459 1460 1478 1487 1487 1489 1496 1524 1597 1720 min: 1390 cycles q1: 1406 cycles median: 1412 cycles q3: 1419 cycles max: 1720 cycles RESULT TestClockGetTime_CycleCount: x86-32_nnacl_newlib_static= {1412, 13} count Apparent clock speed: 2158 MHz TestTlsVariable: Measuring real time: 0.013 usec (1.344e-08 sec) per iteration: 1.344e-06 sec for 100 iterations 0.004 usec (4.059e-09 sec) per iteration: 4.059e-06 sec for 1000 iterations 0.004 usec (3.8395e-09 sec) per iteration: 3.8395e-05 sec for 10000 iterations 0.003 usec (3.05099e-09 sec) per iteration: 0.0794632 sec for 26045057 iterations 0.003 usec (3.06727e-09 sec) per iteration: 0.0798871 sec for 26045057 iterations 0.003 usec (3.06509e-09 sec) per iteration: 0.0798304 sec for 26045057 iterations 0.003 usec (3.06361e-09 sec) per iteration: 0.0797918 sec for 26045057 iterations 0.003 usec (3.05916e-09 sec) per iteration: 0.0796759 sec for 26045057 iterations mean: 0.003061 usec stddev: 0.000006 usec relative stddev: 0.19% RESULT TestTlsVariable: x86-32_nnacl_newlib_static= {0.003061, 0.000006} us Measuring clock cycles: first runs (cycles): 99 48 29 27 28 26 29 26 28 27 ... slowest runs (cycles): ... 29 29 29 29 29 29 29 29 48 99 min: 26 cycles q1: 27 cycles median: 28 cycles q3: 29 cycles max: 99 cycles RESULT TestTlsVariable_CycleCount: x86-32_nnacl_newlib_static= {28, 2} count Apparent clock speed: 9147 MHz TestMmapAnonymous: Measuring real time: 4.867 usec (4.86695e-06 sec) per iteration: 0.000486695 sec for 100 iterations 3.616 usec (3.61594e-06 sec) per iteration: 0.074293 sec for 20546 iterations 3.602 usec (3.6019e-06 sec) per iteration: 0.0740046 sec for 20546 iterations 3.625 usec (3.625e-06 sec) per iteration: 0.0744792 sec for 20546 iterations 3.645 usec (3.64536e-06 sec) per iteration: 0.0748976 sec for 20546 iterations 3.647 usec (3.64664e-06 sec) per iteration: 0.0749239 sec for 20546 iterations mean: 3.626967 usec stddev: 0.017202 usec relative stddev: 0.47% RESULT TestMmapAnonymous: x86-32_nnacl_newlib_static= {3.626967, 0.017202} us Measuring clock cycles: first runs (cycles): 10665 8047 7893 7961 7840 7737 7810 7747 7838 7964 ... slowest runs (cycles): ... 7902 7917 7921 7942 7961 7964 8013 8047 10665 16875 min: 7668 cycles q1: 7728 cycles median: 7758 cycles q3: 7827 cycles max: 16875 cycles RESULT TestMmapAnonymous_CycleCount: x86-32_nnacl_newlib_static= {7758, 99} count Apparent clock speed: 2139 MHz TestAtomicIncrement: Measuring real time: 0.019 usec (1.93e-08 sec) per iteration: 1.93e-06 sec for 100 iterations 0.010 usec (9.979e-09 sec) per iteration: 9.979e-06 sec for 1000 iterations 0.011 usec (1.08562e-08 sec) per iteration: 0.000108562 sec for 10000 iterations 0.009 usec (9.47768e-09 sec) per iteration: 0.087302 sec for 9211326 iterations 0.009 usec (9.46951e-09 sec) per iteration: 0.0872268 sec for 9211326 iterations 0.009 usec (9.48922e-09 sec) per iteration: 0.0874083 sec for 9211326 iterations 0.009 usec (9.497e-09 sec) per iteration: 0.0874799 sec for 9211326 iterations 0.009 usec (9.47467e-09 sec) per iteration: 0.0872743 sec for 9211326 iterations mean: 0.009482 usec stddev: 0.000010 usec relative stddev: 0.11% RESULT TestAtomicIncrement: x86-32_nnacl_newlib_static= {0.009482, 0.000010} us Measuring clock cycles: first runs (cycles): 117 73 50 51 50 49 59 50 53 50 ... slowest runs (cycles): ... 53 53 53 53 53 53 53 59 73 117 min: 48 cycles q1: 50 cycles median: 51 cycles q3: 51 cycles max: 117 cycles RESULT TestAtomicIncrement_CycleCount: x86-32_nnacl_newlib_static= {51, 1} count Apparent clock speed: 5379 MHz TestUncontendedMutexLock: Measuring real time: 0.032 usec (3.197e-08 sec) per iteration: 3.197e-06 sec for 100 iterations 0.023 usec (2.2573e-08 sec) per iteration: 2.2573e-05 sec for 1000 iterations 0.022 usec (2.24277e-08 sec) per iteration: 0.0993562 sec for 4430071 iterations 0.022 usec (2.24269e-08 sec) per iteration: 0.0993526 sec for 4430071 iterations 0.022 usec (2.23933e-08 sec) per iteration: 0.0992038 sec for 4430071 iterations 0.023 usec (2.3101e-08 sec) per iteration: 0.102339 sec for 4430071 iterations 0.022 usec (2.24706e-08 sec) per iteration: 0.0995462 sec for 4430071 iterations mean: 0.022564 usec stddev: 0.000270 usec relative stddev: 1.20% RESULT TestUncontendedMutexLock: x86-32_nnacl_newlib_static= {0.022564, 0.000270} us Measuring clock cycles: first runs (cycles): 231 147 84 79 81 81 81 81 79 82 ... slowest runs (cycles): ... 82 82 82 82 82 82 82 84 147 231 min: 79 cycles q1: 79 cycles median: 79 cycles q3: 81 cycles max: 231 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-32_nnacl_newlib_static= {79, 2} count Apparent clock speed: 3501 MHz TestCondvarSignalNoOp: Measuring real time: 0.592 usec (5.9203e-07 sec) per iteration: 5.9203e-05 sec for 100 iterations 0.528 usec (5.27517e-07 sec) per iteration: 0.089103 sec for 168910 iterations 0.529 usec (5.29288e-07 sec) per iteration: 0.089402 sec for 168910 iterations 0.526 usec (5.26004e-07 sec) per iteration: 0.0888473 sec for 168910 iterations 0.528 usec (5.27527e-07 sec) per iteration: 0.0891045 sec for 168910 iterations 0.529 usec (5.28648e-07 sec) per iteration: 0.0892939 sec for 168910 iterations mean: 0.527797 usec stddev: 0.001124 usec relative stddev: 0.21% RESULT TestCondvarSignalNoOp: x86-32_nnacl_newlib_static= {0.527797, 0.001124} us Measuring clock cycles: first runs (cycles): 2359 1168 1205 1254 1146 1153 1183 1184 1146 1188 ... slowest runs (cycles): ... 1183 1184 1188 1192 1205 1212 1237 1254 1559 2359 min: 1129 cycles q1: 1138 cycles median: 1143 cycles q3: 1151 cycles max: 2359 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-32_nnacl_newlib_static= {1143, 13} count Apparent clock speed: 2166 MHz TestThreadCreateAndJoin: Measuring real time: 105.637 usec (0.000105637 sec) per iteration: 0.0105637 sec for 100 iterations 89.885 usec (8.98854e-05 sec) per iteration: 0.0850316 sec for 946 iterations 88.533 usec (8.85331e-05 sec) per iteration: 0.0837523 sec for 946 iterations 86.238 usec (8.6238e-05 sec) per iteration: 0.0815811 sec for 946 iterations 89.959 usec (8.99594e-05 sec) per iteration: 0.0851016 sec for 946 iterations 88.753 usec (8.87526e-05 sec) per iteration: 0.0839599 sec for 946 iterations mean: 88.673686 usec stddev: 1.347581 usec relative stddev: 1.52% RESULT TestThreadCreateAndJoin: x86-32_nnacl_newlib_static= {88.673686, 1.347581} us Measuring clock cycles: first runs (cycles): 114512 187258 195879 184453 193510 187324 189080 218155 190833 177837 ... slowest runs (cycles): ... 232205 236485 238530 242703 245519 254293 262702 263438 271100 313405 min: 114512 cycles q1: 185298 cycles median: 194843 cycles q3: 214558 cycles max: 313405 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-32_nnacl_newlib_static= {194843, 29260} count Apparent clock speed: 2197 MHz TestThreadWakeup: Measuring real time: 18.160 usec (1.816e-05 sec) per iteration: 0.001816 sec for 100 iterations 15.169 usec (1.5169e-05 sec) per iteration: 0.0835203 sec for 5506 iterations 16.614 usec (1.66143e-05 sec) per iteration: 0.0914786 sec for 5506 iterations 16.390 usec (1.63901e-05 sec) per iteration: 0.0902437 sec for 5506 iterations 17.431 usec (1.74306e-05 sec) per iteration: 0.095973 sec for 5506 iterations 16.924 usec (1.69242e-05 sec) per iteration: 0.0931844 sec for 5506 iterations mean: 16.505629 usec stddev: 0.754008 usec relative stddev: 4.57% RESULT TestThreadWakeup: x86-32_nnacl_newlib_static= {16.505629, 0.754008} us Measuring clock cycles: first runs (cycles): 37883 34923 35789 35476 35329 36832 35267 35404 35314 40582 ... slowest runs (cycles): ... 42916 43719 43838 43843 45310 48249 48838 52094 52952 62904 min: 32423 cycles q1: 35303 cycles median: 35719 cycles q3: 37351 cycles max: 62904 cycles RESULT TestThreadWakeup_CycleCount: x86-32_nnacl_newlib_static= {35719, 2048} count Apparent clock speed: 2164 MHz TestCatchingFault: Measuring real time: 3.621 usec (3.62135e-06 sec) per iteration: 0.000362135 sec for 100 iterations 2.467 usec (2.46732e-06 sec) per iteration: 0.0681326 sec for 27614 iterations 2.469 usec (2.46934e-06 sec) per iteration: 0.0681884 sec for 27614 iterations 2.477 usec (2.47705e-06 sec) per iteration: 0.0684014 sec for 27614 iterations 2.476 usec (2.47593e-06 sec) per iteration: 0.0683704 sec for 27614 iterations 2.469 usec (2.46927e-06 sec) per iteration: 0.0681864 sec for 27614 iterations mean: 2.471784 usec stddev: 0.003929 usec relative stddev: 0.16% RESULT TestCatchingFault: x86-32_nnacl_newlib_static= {2.471784, 0.003929} us Measuring clock cycles: first runs (cycles): 7158 5499 6355 6602 5528 5403 5323 5324 5409 5317 ... slowest runs (cycles): ... 5407 5409 5413 5456 5499 5528 5576 6355 6602 7158 min: 5273 cycles q1: 5312 cycles median: 5324 cycles q3: 5361 cycles max: 7158 cycles RESULT TestCatchingFault_CycleCount: x86-32_nnacl_newlib_static= {5324, 49} count Apparent clock speed: 2154 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 89.499 usec (8.94988e-05 sec) per iteration: 0.00894988 sec for 100 iterations 91.388 usec (9.13881e-05 sec) per iteration: 0.102081 sec for 1117 iterations 104.549 usec (0.000104549 sec) per iteration: 0.116782 sec for 1117 iterations 92.409 usec (9.24088e-05 sec) per iteration: 0.103221 sec for 1117 iterations 171.220 usec (0.00017122 sec) per iteration: 0.191253 sec for 1117 iterations 195.055 usec (0.000195055 sec) per iteration: 0.217876 sec for 1117 iterations mean: 130.924287 usec stddev: 43.540021 usec relative stddev: 33.26% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-32_nnacl_newlib_static= {130.924287, 43.540021} us Measuring clock cycles: first runs (cycles): 126710 343839 252331 542157 805443 311623 1261605 969284 774009 320579 ... slowest runs (cycles): ... 793638 803616 805443 816043 905399 956962 964402 969284 992859 1261605 min: 126710 cycles q1: 206443 cycles median: 322400 cycles q3: 497537 cycles max: 1261605 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-32_nnacl_newlib_static= {322400, 291094} count Apparent clock speed: 2462 MHz [ OK ] nacl_newlib.run_performance_test (5780 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/dbg-linux-x86-32/test_results/nacl_clock_cputime_test.out /b/s/w/ir/kitchen-workdir/native_client/scons-out/dbg-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/dbg-linux-x86-32/obj/src/shared/platform/nacl_clock_test -c [ OK ] nacl_newlib.run_nacl_clock_cputime_test (8920 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/dbg-linux-x86-32/test_results/nacl_interval_multi_reg_test.out /b/s/w/ir/kitchen-workdir/native_client/scons-out/dbg-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/dbg-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 (1070 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/dbg-linux-x86-32/test_results/performance_test.out /b/s/w/ir/kitchen-workdir/native_client/scons-out/dbg-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/dbg-linux-x86-32/obj/tests/performance/performance_test trusted_linux_x86-32 TestNull: Measuring real time: 0.011 usec (1.126e-08 sec) per iteration: 1.126e-06 sec for 100 iterations 0.005 usec (4.923e-09 sec) per iteration: 4.923e-06 sec for 1000 iterations 0.005 usec (4.5488e-09 sec) per iteration: 4.5488e-05 sec for 10000 iterations 0.003 usec (2.96698e-09 sec) per iteration: 0.0652257 sec for 21983819 iterations 0.003 usec (2.88508e-09 sec) per iteration: 0.063425 sec for 21983819 iterations 0.003 usec (2.87674e-09 sec) per iteration: 0.0632417 sec for 21983819 iterations 0.003 usec (2.87011e-09 sec) per iteration: 0.063096 sec for 21983819 iterations 0.003 usec (3.14474e-09 sec) per iteration: 0.0691334 sec for 21983819 iterations mean: 0.002949 usec stddev: 0.000104 usec relative stddev: 3.53% RESULT TestNull: trusted_linux_x86-32= {0.002949, 0.000104} us Measuring clock cycles: first runs (cycles): 97 26 29 29 20 26 26 26 29 29 ... slowest runs (cycles): ... 29 29 29 29 29 29 29 31 33 97 min: 20 cycles q1: 26 cycles median: 28 cycles q3: 29 cycles max: 97 cycles RESULT TestNull_CycleCount: trusted_linux_x86-32= {28, 3} count Apparent clock speed: 9496 MHz TestHostSyscall: Measuring real time: 0.124 usec (1.2368e-07 sec) per iteration: 1.2368e-05 sec for 100 iterations 0.111 usec (1.11263e-07 sec) per iteration: 0.0899607 sec for 808538 iterations 0.112 usec (1.11557e-07 sec) per iteration: 0.0901984 sec for 808538 iterations 0.111 usec (1.11428e-07 sec) per iteration: 0.0900938 sec for 808538 iterations 0.111 usec (1.11409e-07 sec) per iteration: 0.090078 sec for 808538 iterations 0.112 usec (1.11698e-07 sec) per iteration: 0.0903124 sec for 808538 iterations mean: 0.111471 usec stddev: 0.000147 usec relative stddev: 0.13% RESULT TestHostSyscall: trusted_linux_x86-32= {0.111471, 0.000147} us Measuring clock cycles: first runs (cycles): 627 308 264 269 256 264 268 257 268 257 ... slowest runs (cycles): ... 272 286 292 295 308 337 352 407 440 627 min: 253 cycles q1: 257 cycles median: 262 cycles q3: 264 cycles max: 627 cycles RESULT TestHostSyscall_CycleCount: trusted_linux_x86-32= {262, 7} count Apparent clock speed: 2350 MHz TestSetjmpLongjmp: Measuring real time: 0.104 usec (1.0411e-07 sec) per iteration: 1.0411e-05 sec for 100 iterations 0.035 usec (3.49144e-08 sec) per iteration: 0.033536 sec for 960522 iterations 0.035 usec (3.47968e-08 sec) per iteration: 0.0334231 sec for 960522 iterations 0.035 usec (3.50426e-08 sec) per iteration: 0.0336592 sec for 960522 iterations 0.035 usec (3.49343e-08 sec) per iteration: 0.0335551 sec for 960522 iterations 0.035 usec (3.4693e-08 sec) per iteration: 0.0333234 sec for 960522 iterations mean: 0.034876 usec stddev: 0.000120 usec relative stddev: 0.34% RESULT TestSetjmpLongjmp: trusted_linux_x86-32= {0.034876, 0.000120} us Measuring clock cycles: first runs (cycles): 205 172 103 101 103 105 101 99 103 99 ... slowest runs (cycles): ... 104 104 104 104 105 106 106 143 172 205 min: 94 cycles q1: 97 cycles median: 99 cycles q3: 101 cycles max: 205 cycles RESULT TestSetjmpLongjmp_CycleCount: trusted_linux_x86-32= {99, 4} count Apparent clock speed: 2839 MHz TestClockGetTime: Measuring real time: 0.203 usec (2.0306e-07 sec) per iteration: 2.0306e-05 sec for 100 iterations 0.204 usec (2.0438e-07 sec) per iteration: 0.10065 sec for 492465 iterations 0.208 usec (2.0827e-07 sec) per iteration: 0.102566 sec for 492465 iterations 0.205 usec (2.04784e-07 sec) per iteration: 0.100849 sec for 492465 iterations 0.205 usec (2.04521e-07 sec) per iteration: 0.10072 sec for 492465 iterations 0.204 usec (2.0418e-07 sec) per iteration: 0.100552 sec for 492465 iterations mean: 0.205227 usec stddev: 0.001534 usec relative stddev: 0.75% RESULT TestClockGetTime: trusted_linux_x86-32= {0.205227, 0.001534} us Measuring clock cycles: first runs (cycles): 682 567 475 468 466 468 471 464 468 471 ... slowest runs (cycles): ... 471 471 471 471 475 506 566 567 568 682 min: 457 cycles q1: 460 cycles median: 466 cycles q3: 469 cycles max: 682 cycles RESULT TestClockGetTime_CycleCount: trusted_linux_x86-32= {466, 9} count Apparent clock speed: 2271 MHz TestTlsVariable: Measuring real time: 0.009 usec (9.23e-09 sec) per iteration: 9.23e-07 sec for 100 iterations 0.005 usec (4.506e-09 sec) per iteration: 4.506e-06 sec for 1000 iterations 0.004 usec (4.3315e-09 sec) per iteration: 4.3315e-05 sec for 10000 iterations 0.004 usec (4.40302e-09 sec) per iteration: 0.101651 sec for 23086690 iterations 0.004 usec (4.39541e-09 sec) per iteration: 0.101476 sec for 23086690 iterations 0.004 usec (4.41204e-09 sec) per iteration: 0.10186 sec for 23086690 iterations 0.004 usec (4.40109e-09 sec) per iteration: 0.101607 sec for 23086690 iterations 0.004 usec (4.3962e-09 sec) per iteration: 0.101494 sec for 23086690 iterations mean: 0.004402 usec stddev: 0.000006 usec relative stddev: 0.14% RESULT TestTlsVariable: trusted_linux_x86-32= {0.004402, 0.000006} us Measuring clock cycles: first runs (cycles): 105 33 33 35 29 33 33 35 35 36 ... slowest runs (cycles): ... 35 35 35 35 35 35 36 105 152 227 min: 28 cycles q1: 31 cycles median: 33 cycles q3: 33 cycles max: 227 cycles RESULT TestTlsVariable_CycleCount: trusted_linux_x86-32= {33, 2} count Apparent clock speed: 7497 MHz TestMmapAnonymous: Measuring real time: 1.021 usec (1.02097e-06 sec) per iteration: 0.000102097 sec for 100 iterations 0.811 usec (8.10942e-07 sec) per iteration: 0.0794286 sec for 97946 iterations 0.813 usec (8.12544e-07 sec) per iteration: 0.0795855 sec for 97946 iterations 0.812 usec (8.11765e-07 sec) per iteration: 0.0795091 sec for 97946 iterations 0.813 usec (8.12724e-07 sec) per iteration: 0.0796031 sec for 97946 iterations 0.863 usec (8.63363e-07 sec) per iteration: 0.084563 sec for 97946 iterations mean: 0.822268 usec stddev: 0.020557 usec relative stddev: 2.50% RESULT TestMmapAnonymous: trusted_linux_x86-32= {0.822268, 0.020557} us Measuring clock cycles: first runs (cycles): 2604 1886 1782 1760 1766 1746 1788 1782 1760 1778 ... slowest runs (cycles): ... 1793 1795 1802 1806 1819 1824 1831 1877 1886 2604 min: 1740 cycles q1: 1756 cycles median: 1766 cycles q3: 1775 cycles max: 2604 cycles RESULT TestMmapAnonymous_CycleCount: trusted_linux_x86-32= {1766, 19} count Apparent clock speed: 2148 MHz TestAtomicIncrement: Measuring real time: 0.013 usec (1.329e-08 sec) per iteration: 1.329e-06 sec for 100 iterations 0.009 usec (9.466e-09 sec) per iteration: 9.466e-06 sec for 1000 iterations 0.009 usec (9.2898e-09 sec) per iteration: 9.2898e-05 sec for 10000 iterations 0.010 usec (9.52967e-09 sec) per iteration: 0.102582 sec for 10764494 iterations 0.010 usec (9.53089e-09 sec) per iteration: 0.102595 sec for 10764494 iterations 0.009 usec (9.45436e-09 sec) per iteration: 0.101771 sec for 10764494 iterations 0.009 usec (9.43856e-09 sec) per iteration: 0.101601 sec for 10764494 iterations 0.009 usec (9.43871e-09 sec) per iteration: 0.101603 sec for 10764494 iterations mean: 0.009478 usec stddev: 0.000043 usec relative stddev: 0.45% RESULT TestAtomicIncrement: trusted_linux_x86-32= {0.009478, 0.000043} us Measuring clock cycles: first runs (cycles): 110 222 62 59 59 55 60 55 55 63 ... slowest runs (cycles): ... 60 62 62 62 63 64 110 222 258 275 min: 55 cycles q1: 55 cycles median: 57 cycles q3: 57 cycles max: 275 cycles RESULT TestAtomicIncrement_CycleCount: trusted_linux_x86-32= {57, 2} count Apparent clock speed: 6014 MHz TestUncontendedMutexLock: Measuring real time: 0.040 usec (3.972e-08 sec) per iteration: 3.972e-06 sec for 100 iterations 0.029 usec (2.9195e-08 sec) per iteration: 2.9195e-05 sec for 1000 iterations 0.030 usec (2.97414e-08 sec) per iteration: 0.101871 sec for 3425244 iterations 0.030 usec (2.97348e-08 sec) per iteration: 0.101849 sec for 3425244 iterations 0.030 usec (3.03149e-08 sec) per iteration: 0.103836 sec for 3425244 iterations 0.030 usec (2.99002e-08 sec) per iteration: 0.102416 sec for 3425244 iterations 0.030 usec (2.97408e-08 sec) per iteration: 0.101869 sec for 3425244 iterations mean: 0.029886 usec stddev: 0.000223 usec relative stddev: 0.75% RESULT TestUncontendedMutexLock: trusted_linux_x86-32= {0.029886, 0.000223} us Measuring clock cycles: first runs (cycles): 446 273 104 103 105 104 105 106 104 101 ... slowest runs (cycles): ... 107 107 107 108 108 110 112 273 321 446 min: 99 cycles q1: 103 cycles median: 104 cycles q3: 105 cycles max: 446 cycles RESULT TestUncontendedMutexLock_CycleCount: trusted_linux_x86-32= {104, 2} count Apparent clock speed: 3480 MHz TestCondvarSignalNoOp: Measuring real time: 0.032 usec (3.224e-08 sec) per iteration: 3.224e-06 sec for 100 iterations 0.024 usec (2.3541e-08 sec) per iteration: 2.3541e-05 sec for 1000 iterations 0.024 usec (2.37981e-08 sec) per iteration: 0.101092 sec for 4247907 iterations 0.024 usec (2.37856e-08 sec) per iteration: 0.101039 sec for 4247907 iterations 0.024 usec (2.37446e-08 sec) per iteration: 0.100865 sec for 4247907 iterations 0.024 usec (2.37765e-08 sec) per iteration: 0.101 sec for 4247907 iterations 0.024 usec (2.3759e-08 sec) per iteration: 0.100926 sec for 4247907 iterations mean: 0.023773 usec stddev: 0.000019 usec relative stddev: 0.08% RESULT TestCondvarSignalNoOp: trusted_linux_x86-32= {0.023773, 0.000019} us Measuring clock cycles: first runs (cycles): 352 275 83 86 86 83 84 86 88 90 ... slowest runs (cycles): ... 88 88 90 90 90 93 275 295 303 352 min: 83 cycles q1: 84 cycles median: 86 cycles q3: 86 cycles max: 352 cycles RESULT TestCondvarSignalNoOp_CycleCount: trusted_linux_x86-32= {86, 2} count Apparent clock speed: 3618 MHz TestThreadCreateAndJoin: Measuring real time: 29.762 usec (2.97624e-05 sec) per iteration: 0.00297624 sec for 100 iterations 21.130 usec (2.113e-05 sec) per iteration: 0.0709756 sec for 3359 iterations 22.881 usec (2.28808e-05 sec) per iteration: 0.0768567 sec for 3359 iterations 24.099 usec (2.40989e-05 sec) per iteration: 0.0809484 sec for 3359 iterations 21.717 usec (2.17169e-05 sec) per iteration: 0.0729469 sec for 3359 iterations 22.390 usec (2.23902e-05 sec) per iteration: 0.0752088 sec for 3359 iterations mean: 22.443374 usec stddev: 1.018620 usec relative stddev: 4.54% RESULT TestThreadCreateAndJoin: trusted_linux_x86-32= {22.443374, 1.018620} us Measuring clock cycles: first runs (cycles): 67143 154111 45690 50271 273764 66331 44469 49292 49767 45288 ... slowest runs (cycles): ... 62658 62658 66331 66808 67143 72192 126527 154111 273764 394770 min: 38857 cycles q1: 43959 cycles median: 45035 cycles q3: 48180 cycles max: 394770 cycles RESULT TestThreadCreateAndJoin_CycleCount: trusted_linux_x86-32= {45035, 4221} count Apparent clock speed: 2007 MHz TestThreadWakeup: Measuring real time: 12.544 usec (1.25445e-05 sec) per iteration: 0.00125445 sec for 100 iterations 14.875 usec (1.48754e-05 sec) per iteration: 0.118572 sec for 7971 iterations 14.496 usec (1.44955e-05 sec) per iteration: 0.115544 sec for 7971 iterations 14.571 usec (1.4571e-05 sec) per iteration: 0.116145 sec for 7971 iterations 14.617 usec (1.46172e-05 sec) per iteration: 0.116513 sec for 7971 iterations 14.825 usec (1.48254e-05 sec) per iteration: 0.118173 sec for 7971 iterations mean: 14.676904 usec stddev: 0.147738 usec relative stddev: 1.01% RESULT TestThreadWakeup: trusted_linux_x86-32= {14.676904, 0.147738} us Measuring clock cycles: first runs (cycles): 34467 33595 33028 34559 34406 34146 33887 33316 33512 33782 ... slowest runs (cycles): ... 42648 42910 43190 43644 43950 48018 48251 76756 89443 315277 min: 30524 cycles q1: 36101 cycles median: 37056 cycles q3: 38860 cycles max: 315277 cycles RESULT TestThreadWakeup_CycleCount: trusted_linux_x86-32= {37056, 2759} count Apparent clock speed: 2525 MHz WARNING: should have taken less than 2.000000 secs [ OK ] nacl_newlib.run_trusted_performance_test (4940 ms) scons: done building targets. ********************************************************************** COMMAND EXECUTION REPORT ********************************************************************** 7 command_tester.py 1 doSizeCheck ********************************************************************** ENVIRONMENT USAGE REPORT ********************************************************************** 3 dbg-linux-x86-32 5 nacl-x86-32 Command return code: 0