Back to build
Raw log | Switch to full mode | Login

Running command: /b/s/w/ir/cache/vpython/b50d97/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/b50d97/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/bin:/usr/sbin:/sbin:/bin:/mnt/data/b/s/w/ir/kitchen-checkout/depot_tools ====================================================================== OS: ====================================================================== Linux vm316-m1.golo.chromium.org 3.13.0-65-generic #106-Ubuntu SMP Fri Oct 2 22:08:27 UTC 2015 x86_64 x86_64 x86_64 GNU/Linux ====================================================================== CPU: ====================================================================== model name : Intel(R) Xeon(R) CPU E5-2670 0 @ 2.60GHz stepping : 7 cpu MHz : 2600.000 model name : Intel(R) Xeon(R) CPU E5-2670 0 @ 2.60GHz stepping : 7 cpu MHz : 2600.000 model name : Intel(R) Xeon(R) CPU E5-2670 0 @ 2.60GHz stepping : 7 cpu MHz : 2600.000 model name : Intel(R) Xeon(R) CPU E5-2670 0 @ 2.60GHz stepping : 7 cpu MHz : 2600.000 model name : Intel(R) Xeon(R) CPU E5-2670 0 @ 2.60GHz stepping : 7 cpu MHz : 2600.000 model name : Intel(R) Xeon(R) CPU E5-2670 0 @ 2.60GHz stepping : 7 cpu MHz : 2600.000 model name : Intel(R) Xeon(R) CPU E5-2670 0 @ 2.60GHz stepping : 7 cpu MHz : 2600.000 model name : Intel(R) Xeon(R) CPU E5-2670 0 @ 2.60GHz stepping : 7 cpu MHz : 2600.000 ====================================================================== RAM: ====================================================================== MemTotal: 16433828 kB MemFree: 3083940 kB ====================================================================== LOAD: ====================================================================== 1.21 1.94 2.25 1/291 10695 ====================================================================== UPTIME: ====================================================================== 13869.13 89512.46 ====================================================================== The following environments have been configured ====================================================================== NACL_BUILD_FAMILY: UNTRUSTED BUILD_TYPE: nacl TARGET_ROOT: /mnt/data/b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-32 OBJ_ROOT: /mnt/data/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: /mnt/data/b/s/w/ir/kitchen-workdir/native_client/toolchain/linux_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /mnt/data/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/mnt/data/b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-32/include -I/mnt/data/b/s/w/ir/kitchen-workdir -c -o UNKNOWN COMPILER NACL_BUILD_FAMILY: TRUSTED BUILD_TYPE: dbg-linux TARGET_ROOT: /mnt/data/b/s/w/ir/kitchen-workdir/native_client/scons-out/dbg-linux-x86-32 OBJ_ROOT: /mnt/data/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: /mnt/data/b/s/w/ir/kitchen-workdir/third_party/llvm-build/Release+Asserts/bin/clang ASPPCOM: /mnt/data/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/mnt/data/b/s/w/ir/kitchen-workdir -c -o UNKNOWN COMPILER NACL_BUILD_FAMILY: UNTRUSTED_IRT BUILD_TYPE: nacl_irt TARGET_ROOT: /mnt/data/b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt-x86-32 OBJ_ROOT: /mnt/data/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: /mnt/data/b/s/w/ir/kitchen-workdir/native_client/toolchain/linux_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /mnt/data/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/mnt/data/b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt-x86-32/include -I/mnt/data/b/s/w/ir/kitchen-workdir -I/mnt/data/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/b50d97/bin/python /mnt/data/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 /mnt/data/b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-32/test_results/signal_handler_single_step.out /mnt/data/b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-32/obj/tests/signal_handler_single_step/step_test_host /mnt/data/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 /mnt/data/b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-32/obj/tests/signal_handler_single_step/step_test_host /mnt/data/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 [10703,4147123968:13:34:17.819145] Native Client module will be loaded at base address 0x000000007a320000 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 (10 ms) /b/s/w/ir/cache/vpython/b50d97/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 /mnt/data/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 [10708,4147803904:13:34:18.151274] 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.062920, 0.038100} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test (850 ms) /b/s/w/ir/cache/vpython/b50d97/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 /mnt/data/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 [ OK ] nacl_newlib.run_clock_cputime_test (58519 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.448 KB RESULT hello_world_size.out: ZIPPED_x86-32_nnacl_newlib_static= 171.100 KB /b/s/w/ir/cache/vpython/b50d97/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 /mnt/data/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 [10742,4147795712:13:34:30.696385] Native Client module will be loaded at base address 0x0000000000000000 TestNull: Measuring real time: 0.013 usec (1.35e-08 sec) per iteration: 1.35e-06 sec for 100 iterations 0.003 usec (3.258e-09 sec) per iteration: 3.258e-06 sec for 1000 iterations 0.003 usec (2.7238e-09 sec) per iteration: 2.7238e-05 sec for 10000 iterations 0.003 usec (2.68306e-09 sec) per iteration: 0.0985044 sec for 36713415 iterations 0.003 usec (2.67614e-09 sec) per iteration: 0.0982501 sec for 36713415 iterations 0.003 usec (2.67722e-09 sec) per iteration: 0.0982899 sec for 36713415 iterations 0.003 usec (2.67597e-09 sec) per iteration: 0.0982439 sec for 36713415 iterations 0.003 usec (2.70631e-09 sec) per iteration: 0.0993578 sec for 36713415 iterations mean: 0.002684 usec stddev: 0.000012 usec relative stddev: 0.43% RESULT TestNull: x86-32_nnacl_newlib_static= {0.002684, 0.000012} us Measuring clock cycles: first runs (cycles): 107 21 21 18 21 18 21 25 28 25 ... slowest runs (cycles): ... 28 28 28 28 28 28 28 28 28 107 min: 18 cycles q1: 18 cycles median: 21 cycles q3: 25 cycles max: 107 cycles RESULT TestNull_CycleCount: x86-32_nnacl_newlib_static= {21, 7} count Apparent clock speed: 7825 MHz TestNaClSyscall: Measuring real time: 0.308 usec (3.0821e-07 sec) per iteration: 3.0821e-05 sec for 100 iterations 0.301 usec (3.01157e-07 sec) per iteration: 0.0977115 sec for 324454 iterations 0.301 usec (3.00948e-07 sec) per iteration: 0.0976438 sec for 324454 iterations 0.301 usec (3.00934e-07 sec) per iteration: 0.0976392 sec for 324454 iterations 0.301 usec (3.01105e-07 sec) per iteration: 0.0976947 sec for 324454 iterations 0.301 usec (3.00894e-07 sec) per iteration: 0.0976262 sec for 324454 iterations mean: 0.301007 usec stddev: 0.000104 usec relative stddev: 0.03% RESULT TestNaClSyscall: x86-32_nnacl_newlib_static= {0.301007, 0.000104} us Measuring clock cycles: first runs (cycles): 2438 792 884 783 786 786 802 838 783 786 ... slowest runs (cycles): ... 838 841 884 1022 1034 1236 1435 1578 2438 13954 min: 780 cycles q1: 786 cycles median: 792 cycles q3: 796 cycles max: 13954 cycles RESULT TestNaClSyscall_CycleCount: x86-32_nnacl_newlib_static= {792, 10} count Apparent clock speed: 2631 MHz TestSetjmpLongjmp: Measuring real time: 0.023 usec (2.32e-08 sec) per iteration: 2.32e-06 sec for 100 iterations 0.013 usec (1.3032e-08 sec) per iteration: 1.3032e-05 sec for 1000 iterations 0.012 usec (1.1849e-08 sec) per iteration: 0.090922 sec for 7673419 iterations 0.012 usec (1.18492e-08 sec) per iteration: 0.090924 sec for 7673419 iterations 0.012 usec (1.18514e-08 sec) per iteration: 0.0909407 sec for 7673419 iterations 0.012 usec (1.18449e-08 sec) per iteration: 0.0908911 sec for 7673419 iterations 0.012 usec (1.18377e-08 sec) per iteration: 0.0908353 sec for 7673419 iterations mean: 0.011846 usec stddev: 0.000005 usec relative stddev: 0.04% RESULT TestSetjmpLongjmp: x86-32_nnacl_newlib_static= {0.011846, 0.000005} us Measuring clock cycles: first runs (cycles): 749 58 52 24 30 79 34 31 34 31 ... slowest runs (cycles): ... 34 34 34 34 34 34 52 58 79 749 min: 24 cycles q1: 27 cycles median: 31 cycles q3: 34 cycles max: 749 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-32_nnacl_newlib_static= {31, 7} count Apparent clock speed: 2617 MHz TestClockGetTime: Measuring real time: 0.530 usec (5.3049e-07 sec) per iteration: 5.3049e-05 sec for 100 iterations 0.512 usec (5.11829e-07 sec) per iteration: 0.0964818 sec for 188504 iterations 0.511 usec (5.11161e-07 sec) per iteration: 0.0963558 sec for 188504 iterations 0.511 usec (5.11423e-07 sec) per iteration: 0.0964052 sec for 188504 iterations 0.513 usec (5.13021e-07 sec) per iteration: 0.0967065 sec for 188504 iterations 0.512 usec (5.11543e-07 sec) per iteration: 0.0964279 sec for 188504 iterations mean: 0.511795 usec stddev: 0.000649 usec relative stddev: 0.13% RESULT TestClockGetTime: x86-32_nnacl_newlib_static= {0.511795, 0.000649} us Measuring clock cycles: first runs (cycles): 5512 1401 1358 1315 1453 1309 1328 1313 1315 1327 ... slowest runs (cycles): ... 1376 1380 1380 1383 1383 1398 1401 1410 1453 5512 min: 1306 cycles q1: 1321 cycles median: 1328 cycles q3: 1337 cycles max: 5512 cycles RESULT TestClockGetTime_CycleCount: x86-32_nnacl_newlib_static= {1328, 16} count Apparent clock speed: 2595 MHz TestTlsVariable: Measuring real time: 0.013 usec (1.257e-08 sec) per iteration: 1.257e-06 sec for 100 iterations 0.004 usec (3.787e-09 sec) per iteration: 3.787e-06 sec for 1000 iterations 0.003 usec (3.0831e-09 sec) per iteration: 3.0831e-05 sec for 10000 iterations 0.003 usec (3.01202e-09 sec) per iteration: 0.0976944 sec for 32434886 iterations 0.003 usec (3.03396e-09 sec) per iteration: 0.0984062 sec for 32434886 iterations 0.003 usec (3.01328e-09 sec) per iteration: 0.0977353 sec for 32434886 iterations 0.003 usec (3.01196e-09 sec) per iteration: 0.0976926 sec for 32434886 iterations 0.003 usec (3.01978e-09 sec) per iteration: 0.0979461 sec for 32434886 iterations mean: 0.003018 usec stddev: 0.000008 usec relative stddev: 0.28% RESULT TestTlsVariable: x86-32_nnacl_newlib_static= {0.003018, 0.000008} us Measuring clock cycles: first runs (cycles): 511 28 28 21 21 21 21 21 21 28 ... slowest runs (cycles): ... 28 28 28 28 28 28 28 28 28 511 min: 21 cycles q1: 21 cycles median: 21 cycles q3: 28 cycles max: 511 cycles RESULT TestTlsVariable_CycleCount: x86-32_nnacl_newlib_static= {21, 7} count Apparent clock speed: 6958 MHz TestMmapAnonymous: Measuring real time: 4.338 usec (4.33778e-06 sec) per iteration: 0.000433778 sec for 100 iterations 4.074 usec (4.0743e-06 sec) per iteration: 0.0939249 sec for 23053 iterations 4.072 usec (4.07191e-06 sec) per iteration: 0.0938698 sec for 23053 iterations 4.071 usec (4.07088e-06 sec) per iteration: 0.093846 sec for 23053 iterations 4.083 usec (4.08341e-06 sec) per iteration: 0.0941348 sec for 23053 iterations 4.068 usec (4.06847e-06 sec) per iteration: 0.0937903 sec for 23053 iterations mean: 4.073794 usec stddev: 0.005160 usec relative stddev: 0.13% RESULT TestMmapAnonymous: x86-32_nnacl_newlib_static= {4.073794, 0.005160} us Measuring clock cycles: first runs (cycles): 27147 11730 11189 10887 10712 10681 10748 10773 10632 10574 ... slowest runs (cycles): ... 10712 10712 10715 10748 10755 10773 10887 11189 11730 27147 min: 10180 cycles q1: 10427 cycles median: 10532 cycles q3: 10614 cycles max: 27147 cycles RESULT TestMmapAnonymous_CycleCount: x86-32_nnacl_newlib_static= {10532, 187} count Apparent clock speed: 2585 MHz TestAtomicIncrement: Measuring real time: 0.018 usec (1.794e-08 sec) per iteration: 1.794e-06 sec for 100 iterations 0.009 usec (8.524e-09 sec) per iteration: 8.524e-06 sec for 1000 iterations 0.008 usec (7.7547e-09 sec) per iteration: 7.7547e-05 sec for 10000 iterations 0.008 usec (7.69708e-09 sec) per iteration: 0.0992569 sec for 12895405 iterations 0.008 usec (7.69596e-09 sec) per iteration: 0.0992426 sec for 12895405 iterations 0.008 usec (7.69588e-09 sec) per iteration: 0.0992414 sec for 12895405 iterations 0.008 usec (7.69683e-09 sec) per iteration: 0.0992538 sec for 12895405 iterations 0.008 usec (7.69484e-09 sec) per iteration: 0.0992281 sec for 12895405 iterations mean: 0.007696 usec stddev: 0.000001 usec relative stddev: 0.01% RESULT TestAtomicIncrement: x86-32_nnacl_newlib_static= {0.007696, 0.000001} us Measuring clock cycles: first runs (cycles): 514 55 62 52 62 67 52 55 52 55 ... slowest runs (cycles): ... 55 55 58 58 58 62 62 67 68 514 min: 52 cycles q1: 52 cycles median: 55 cycles q3: 55 cycles max: 514 cycles RESULT TestAtomicIncrement_CycleCount: x86-32_nnacl_newlib_static= {55, 3} count Apparent clock speed: 7146 MHz TestUncontendedMutexLock: Measuring real time: 0.034 usec (3.404e-08 sec) per iteration: 3.404e-06 sec for 100 iterations 0.158 usec (1.57896e-07 sec) per iteration: 0.000157896 sec for 1000 iterations 0.024 usec (2.35228e-08 sec) per iteration: 0.0148976 sec for 633328 iterations 0.023 usec (2.33756e-08 sec) per iteration: 0.0148044 sec for 633328 iterations 0.023 usec (2.33811e-08 sec) per iteration: 0.0148079 sec for 633328 iterations 0.023 usec (2.33943e-08 sec) per iteration: 0.0148163 sec for 633328 iterations 0.023 usec (2.33788e-08 sec) per iteration: 0.0148065 sec for 633328 iterations mean: 0.023411 usec stddev: 0.000056 usec relative stddev: 0.24% RESULT TestUncontendedMutexLock: x86-32_nnacl_newlib_static= {0.023411, 0.000056} us Measuring clock cycles: first runs (cycles): 177 79 89 86 86 86 89 86 86 86 ... slowest runs (cycles): ... 89 89 89 89 89 89 89 89 101 177 min: 79 cycles q1: 79 cycles median: 86 cycles q3: 86 cycles max: 177 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-32_nnacl_newlib_static= {86, 7} count Apparent clock speed: 3674 MHz TestCondvarSignalNoOp: Measuring real time: 0.549 usec (5.4885e-07 sec) per iteration: 5.4885e-05 sec for 100 iterations 0.483 usec (4.82526e-07 sec) per iteration: 0.0879158 sec for 182199 iterations 0.482 usec (4.81554e-07 sec) per iteration: 0.0877386 sec for 182199 iterations 0.482 usec (4.81593e-07 sec) per iteration: 0.0877458 sec for 182199 iterations 0.482 usec (4.81681e-07 sec) per iteration: 0.0877618 sec for 182199 iterations 0.482 usec (4.81605e-07 sec) per iteration: 0.0877479 sec for 182199 iterations mean: 0.481792 usec stddev: 0.000370 usec relative stddev: 0.08% RESULT TestCondvarSignalNoOp: x86-32_nnacl_newlib_static= {0.481792, 0.000370} us Measuring clock cycles: first runs (cycles): 5133 1364 1294 1266 1333 1266 1303 1251 1251 1254 ... slowest runs (cycles): ... 1294 1300 1303 1309 1322 1325 1333 1364 1389 5133 min: 1248 cycles q1: 1254 cycles median: 1260 cycles q3: 1269 cycles max: 5133 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-32_nnacl_newlib_static= {1260, 15} count Apparent clock speed: 2615 MHz TestThreadCreateAndJoin: Measuring real time: 47.751 usec (4.77515e-05 sec) per iteration: 0.00477515 sec for 100 iterations 45.119 usec (4.51195e-05 sec) per iteration: 0.0944801 sec for 2094 iterations 44.543 usec (4.45426e-05 sec) per iteration: 0.0932722 sec for 2094 iterations 44.325 usec (4.4325e-05 sec) per iteration: 0.0928165 sec for 2094 iterations 44.718 usec (4.47184e-05 sec) per iteration: 0.0936403 sec for 2094 iterations 44.697 usec (4.46974e-05 sec) per iteration: 0.0935964 sec for 2094 iterations mean: 44.680576 usec stddev: 0.260692 usec relative stddev: 0.58% RESULT TestThreadCreateAndJoin: x86-32_nnacl_newlib_static= {44.680576, 0.260692} us Measuring clock cycles: first runs (cycles): 138745 147548 137833 114070 114324 137531 116373 114926 113559 117226 ... slowest runs (cycles): ... 119505 119579 119989 121016 121466 135524 137531 137833 138745 147548 min: 108870 cycles q1: 113165 cycles median: 114244 cycles q3: 115400 cycles max: 147548 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-32_nnacl_newlib_static= {114244, 2235} count Apparent clock speed: 2557 MHz TestThreadWakeup: Measuring real time: 18.974 usec (1.89738e-05 sec) per iteration: 0.00189738 sec for 100 iterations 17.027 usec (1.70266e-05 sec) per iteration: 0.0897304 sec for 5270 iterations 13.144 usec (1.31438e-05 sec) per iteration: 0.0692676 sec for 5270 iterations 17.363 usec (1.73631e-05 sec) per iteration: 0.0915036 sec for 5270 iterations 7.164 usec (7.16378e-06 sec) per iteration: 0.0377531 sec for 5270 iterations 14.938 usec (1.49376e-05 sec) per iteration: 0.0787211 sec for 5270 iterations mean: 13.926975 usec stddev: 3.709193 usec relative stddev: 26.63% RESULT TestThreadWakeup: x86-32_nnacl_newlib_static= {13.926975, 3.709193} us Measuring clock cycles: first runs (cycles): 77700 53554 51211 50330 50461 50400 50067 49858 49905 49290 ... slowest runs (cycles): ... 50715 50853 50856 50862 50942 51211 51229 51468 53554 77700 min: 48996 cycles q1: 49418 cycles median: 49712 cycles q3: 50330 cycles max: 77700 cycles RESULT TestThreadWakeup_CycleCount: x86-32_nnacl_newlib_static= {49712, 912} count Apparent clock speed: 3569 MHz TestCatchingFault: Measuring real time: 2.997 usec (2.99661e-06 sec) per iteration: 0.000299661 sec for 100 iterations 2.924 usec (2.92358e-06 sec) per iteration: 0.0975626 sec for 33371 iterations 2.870 usec (2.86951e-06 sec) per iteration: 0.0957583 sec for 33371 iterations 2.884 usec (2.88406e-06 sec) per iteration: 0.0962441 sec for 33371 iterations 2.870 usec (2.87014e-06 sec) per iteration: 0.0957796 sec for 33371 iterations 2.861 usec (2.86071e-06 sec) per iteration: 0.0954646 sec for 33371 iterations mean: 2.881599 usec stddev: 0.022280 usec relative stddev: 0.77% RESULT TestCatchingFault: x86-32_nnacl_newlib_static= {2.881599, 0.022280} us Measuring clock cycles: first runs (cycles): 18372 8103 7751 7644 7720 7469 7448 7445 7452 7436 ... slowest runs (cycles): ... 7452 7452 7452 7455 7469 7644 7720 7751 8103 18372 min: 7399 cycles q1: 7439 cycles median: 7439 cycles q3: 7445 cycles max: 18372 cycles RESULT TestCatchingFault_CycleCount: x86-32_nnacl_newlib_static= {7439, 6} count Apparent clock speed: 2582 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 45.460 usec (4.54596e-05 sec) per iteration: 0.00454596 sec for 100 iterations 44.335 usec (4.43351e-05 sec) per iteration: 0.0974929 sec for 2199 iterations 44.255 usec (4.42546e-05 sec) per iteration: 0.0973158 sec for 2199 iterations 44.664 usec (4.46638e-05 sec) per iteration: 0.0982157 sec for 2199 iterations 43.560 usec (4.35598e-05 sec) per iteration: 0.095788 sec for 2199 iterations 43.551 usec (4.35507e-05 sec) per iteration: 0.095768 sec for 2199 iterations mean: 44.072801 usec stddev: 0.444275 usec relative stddev: 1.01% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-32_nnacl_newlib_static= {44.072801, 0.444275} us Measuring clock cycles: first runs (cycles): 132896 120056 115238 116180 118101 112932 114072 111959 112240 117247 ... slowest runs (cycles): ... 120041 120056 120086 120677 123506 123922 125663 130218 132896 147940 min: 110601 cycles q1: 112914 cycles median: 114247 cycles q3: 116149 cycles max: 147940 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-32_nnacl_newlib_static= {114247, 3235} count Apparent clock speed: 2592 MHz [ OK ] nacl_newlib.run_performance_test (6010 ms) /b/s/w/ir/cache/vpython/b50d97/bin/python /mnt/data/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 /mnt/data/b/s/w/ir/kitchen-workdir/native_client/scons-out/dbg-linux-x86-32/test_results/nacl_clock_cputime_test.out /mnt/data/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 /mnt/data/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 (9400 ms) /b/s/w/ir/cache/vpython/b50d97/bin/python /mnt/data/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 /mnt/data/b/s/w/ir/kitchen-workdir/native_client/scons-out/dbg-linux-x86-32/test_results/nacl_interval_multi_reg_test.out /mnt/data/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 /mnt/data/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 (1720 ms) /b/s/w/ir/cache/vpython/b50d97/bin/python /mnt/data/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 /mnt/data/b/s/w/ir/kitchen-workdir/native_client/scons-out/dbg-linux-x86-32/test_results/performance_test.out /mnt/data/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 /mnt/data/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.008 usec (7.91e-09 sec) per iteration: 7.91e-07 sec for 100 iterations 0.004 usec (3.726e-09 sec) per iteration: 3.726e-06 sec for 1000 iterations 0.004 usec (3.7328e-09 sec) per iteration: 3.7328e-05 sec for 10000 iterations 0.004 usec (3.67512e-09 sec) per iteration: 0.0984547 sec for 26789541 iterations 0.004 usec (3.69066e-09 sec) per iteration: 0.0988712 sec for 26789541 iterations 0.004 usec (3.70762e-09 sec) per iteration: 0.0993255 sec for 26789541 iterations 0.004 usec (3.67912e-09 sec) per iteration: 0.098562 sec for 26789541 iterations 0.004 usec (3.6743e-09 sec) per iteration: 0.0984328 sec for 26789541 iterations mean: 0.003685 usec stddev: 0.000013 usec relative stddev: 0.34% RESULT TestNull: trusted_linux_x86-32= {0.003685, 0.000013} us Measuring clock cycles: first runs (cycles): 153 24 21 21 21 21 21 21 21 28 ... slowest runs (cycles): ... 28 28 31 31 31 31 31 31 31 153 min: 18 cycles q1: 21 cycles median: 24 cycles q3: 28 cycles max: 153 cycles RESULT TestNull_CycleCount: trusted_linux_x86-32= {24, 7} count Apparent clock speed: 6512 MHz TestHostSyscall: Measuring real time: 0.094 usec (9.407e-08 sec) per iteration: 9.407e-06 sec for 100 iterations 0.077 usec (7.7167e-08 sec) per iteration: 7.7167e-05 sec for 1000 iterations 0.078 usec (7.82636e-08 sec) per iteration: 0.101421 sec for 1295890 iterations 0.078 usec (7.82542e-08 sec) per iteration: 0.101409 sec for 1295890 iterations 0.078 usec (7.83444e-08 sec) per iteration: 0.101526 sec for 1295890 iterations 0.078 usec (7.82228e-08 sec) per iteration: 0.101368 sec for 1295890 iterations 0.078 usec (7.82232e-08 sec) per iteration: 0.101369 sec for 1295890 iterations mean: 0.078262 usec stddev: 0.000044 usec relative stddev: 0.06% RESULT TestHostSyscall: trusted_linux_x86-32= {0.078262, 0.000044} us Measuring clock cycles: first runs (cycles): 1373 220 220 224 214 214 221 214 217 224 ... slowest runs (cycles): ... 221 221 221 221 221 221 221 224 224 1373 min: 211 cycles q1: 214 cycles median: 214 cycles q3: 214 cycles max: 1373 cycles RESULT TestHostSyscall_CycleCount: trusted_linux_x86-32= {214, 0} count Apparent clock speed: 2734 MHz TestSetjmpLongjmp: Measuring real time: 0.080 usec (8.045e-08 sec) per iteration: 8.045e-06 sec for 100 iterations 0.033 usec (3.3261e-08 sec) per iteration: 3.3261e-05 sec for 1000 iterations 0.033 usec (3.25576e-08 sec) per iteration: 0.0978853 sec for 3006524 iterations 0.033 usec (3.26257e-08 sec) per iteration: 0.0980901 sec for 3006524 iterations 0.033 usec (3.25493e-08 sec) per iteration: 0.0978603 sec for 3006524 iterations 0.033 usec (3.26586e-08 sec) per iteration: 0.0981887 sec for 3006524 iterations 0.033 usec (3.25887e-08 sec) per iteration: 0.0979787 sec for 3006524 iterations mean: 0.032596 usec stddev: 0.000041 usec relative stddev: 0.13% RESULT TestSetjmpLongjmp: trusted_linux_x86-32= {0.032596, 0.000041} us Measuring clock cycles: first runs (cycles): 1725 150 162 104 101 101 101 116 101 101 ... slowest runs (cycles): ... 119 122 122 129 132 150 162 169 1725 18729 min: 88 cycles q1: 98 cycles median: 101 cycles q3: 101 cycles max: 18729 cycles RESULT TestSetjmpLongjmp_CycleCount: trusted_linux_x86-32= {101, 3} count Apparent clock speed: 3099 MHz TestClockGetTime: Measuring real time: 0.104 usec (1.0393e-07 sec) per iteration: 1.0393e-05 sec for 100 iterations 0.095 usec (9.53911e-08 sec) per iteration: 0.091784 sec for 962186 iterations 0.096 usec (9.57701e-08 sec) per iteration: 0.0921486 sec for 962186 iterations 0.096 usec (9.59617e-08 sec) per iteration: 0.092333 sec for 962186 iterations 0.096 usec (9.56155e-08 sec) per iteration: 0.0919999 sec for 962186 iterations 0.096 usec (9.55443e-08 sec) per iteration: 0.0919314 sec for 962186 iterations mean: 0.095657 usec stddev: 0.000195 usec relative stddev: 0.20% RESULT TestClockGetTime: trusted_linux_x86-32= {0.095657, 0.000195} us Measuring clock cycles: first runs (cycles): 1762 269 275 266 276 269 266 263 263 260 ... slowest runs (cycles): ... 263 266 266 266 269 269 270 275 276 1762 min: 260 cycles q1: 260 cycles median: 260 cycles q3: 263 cycles max: 1762 cycles RESULT TestClockGetTime_CycleCount: trusted_linux_x86-32= {260, 3} count Apparent clock speed: 2718 MHz TestTlsVariable: Measuring real time: 0.009 usec (9.36e-09 sec) per iteration: 9.36e-07 sec for 100 iterations 0.005 usec (4.951e-09 sec) per iteration: 4.951e-06 sec for 1000 iterations 0.005 usec (4.6039e-09 sec) per iteration: 4.6039e-05 sec for 10000 iterations 0.005 usec (4.80541e-09 sec) per iteration: 0.104377 sec for 21720715 iterations 0.005 usec (4.80557e-09 sec) per iteration: 0.10438 sec for 21720715 iterations 0.005 usec (4.80148e-09 sec) per iteration: 0.104292 sec for 21720715 iterations 0.005 usec (4.80416e-09 sec) per iteration: 0.10435 sec for 21720715 iterations 0.005 usec (4.80437e-09 sec) per iteration: 0.104354 sec for 21720715 iterations mean: 0.004804 usec stddev: 0.000001 usec relative stddev: 0.03% RESULT TestTlsVariable: trusted_linux_x86-32= {0.004804, 0.000001} us Measuring clock cycles: first runs (cycles): 596 21 21 21 21 21 21 21 31 28 ... slowest runs (cycles): ... 31 31 31 31 31 31 31 31 31 596 min: 21 cycles q1: 21 cycles median: 28 cycles q3: 28 cycles max: 596 cycles RESULT TestTlsVariable_CycleCount: trusted_linux_x86-32= {28, 7} count Apparent clock speed: 5828 MHz TestMmapAnonymous: Measuring real time: 0.837 usec (8.3652e-07 sec) per iteration: 8.3652e-05 sec for 100 iterations 0.738 usec (7.37643e-07 sec) per iteration: 0.0881793 sec for 119542 iterations 0.738 usec (7.38359e-07 sec) per iteration: 0.0882649 sec for 119542 iterations 0.739 usec (7.38596e-07 sec) per iteration: 0.0882932 sec for 119542 iterations 0.738 usec (7.37854e-07 sec) per iteration: 0.0882045 sec for 119542 iterations 0.738 usec (7.37639e-07 sec) per iteration: 0.0881788 sec for 119542 iterations mean: 0.738018 usec stddev: 0.000390 usec relative stddev: 0.05% RESULT TestMmapAnonymous: trusted_linux_x86-32= {0.738018, 0.000390} us Measuring clock cycles: first runs (cycles): 10314 2624 2019 2040 2105 2108 2010 2016 2080 2037 ... slowest runs (cycles): ... 2138 2141 2141 2160 2166 2193 2218 2258 2624 10314 min: 1924 cycles q1: 1991 cycles median: 2025 cycles q3: 2080 cycles max: 10314 cycles RESULT TestMmapAnonymous_CycleCount: trusted_linux_x86-32= {2025, 89} count Apparent clock speed: 2744 MHz TestAtomicIncrement: Measuring real time: 0.014 usec (1.395e-08 sec) per iteration: 1.395e-06 sec for 100 iterations 0.010 usec (1.0314e-08 sec) per iteration: 1.0314e-05 sec for 1000 iterations 0.010 usec (1.04498e-08 sec) per iteration: 0.101316 sec for 9695559 iterations 0.010 usec (1.04359e-08 sec) per iteration: 0.101182 sec for 9695559 iterations 0.010 usec (1.0466e-08 sec) per iteration: 0.101474 sec for 9695559 iterations 0.010 usec (1.04581e-08 sec) per iteration: 0.101397 sec for 9695559 iterations 0.010 usec (1.04397e-08 sec) per iteration: 0.101219 sec for 9695559 iterations mean: 0.010450 usec stddev: 0.000011 usec relative stddev: 0.11% RESULT TestAtomicIncrement: trusted_linux_x86-32= {0.010450, 0.000011} us Measuring clock cycles: first runs (cycles): 526 65 58 58 58 58 58 80 61 58 ... slowest runs (cycles): ... 76 76 76 79 80 80 86 89 92 526 min: 58 cycles q1: 58 cycles median: 58 cycles q3: 65 cycles max: 526 cycles RESULT TestAtomicIncrement_CycleCount: trusted_linux_x86-32= {58, 7} count Apparent clock speed: 5550 MHz TestUncontendedMutexLock: Measuring real time: 0.043 usec (4.35e-08 sec) per iteration: 4.35e-06 sec for 100 iterations 0.032 usec (3.1713e-08 sec) per iteration: 3.1713e-05 sec for 1000 iterations 0.037 usec (3.6525e-08 sec) per iteration: 0.115174 sec for 3153280 iterations 0.037 usec (3.6531e-08 sec) per iteration: 0.115192 sec for 3153280 iterations 0.037 usec (3.65212e-08 sec) per iteration: 0.115162 sec for 3153280 iterations 0.037 usec (3.65337e-08 sec) per iteration: 0.115201 sec for 3153280 iterations 0.037 usec (3.65256e-08 sec) per iteration: 0.115175 sec for 3153280 iterations mean: 0.036527 usec stddev: 0.000004 usec relative stddev: 0.01% RESULT TestUncontendedMutexLock: trusted_linux_x86-32= {0.036527, 0.000004} us Measuring clock cycles: first runs (cycles): 1398 107 107 104 150 104 104 125 107 104 ... slowest runs (cycles): ... 120 120 125 131 132 134 135 141 150 1398 min: 101 cycles q1: 104 cycles median: 104 cycles q3: 107 cycles max: 1398 cycles RESULT TestUncontendedMutexLock_CycleCount: trusted_linux_x86-32= {104, 3} count Apparent clock speed: 2847 MHz TestCondvarSignalNoOp: Measuring real time: 0.071 usec (7.06e-08 sec) per iteration: 7.06e-06 sec for 100 iterations 0.023 usec (2.2766e-08 sec) per iteration: 2.2766e-05 sec for 1000 iterations 0.024 usec (2.40519e-08 sec) per iteration: 0.105648 sec for 4392515 iterations 0.024 usec (2.40457e-08 sec) per iteration: 0.105621 sec for 4392515 iterations 0.024 usec (2.40463e-08 sec) per iteration: 0.105624 sec for 4392515 iterations 0.024 usec (2.40537e-08 sec) per iteration: 0.105656 sec for 4392515 iterations 0.024 usec (2.40491e-08 sec) per iteration: 0.105636 sec for 4392515 iterations mean: 0.024049 usec stddev: 0.000003 usec relative stddev: 0.01% RESULT TestCondvarSignalNoOp: trusted_linux_x86-32= {0.024049, 0.000003} us Measuring clock cycles: first runs (cycles): 890 85 82 92 89 89 89 110 89 89 ... slowest runs (cycles): ... 92 92 92 104 107 110 110 110 113 890 min: 79 cycles q1: 82 cycles median: 89 cycles q3: 89 cycles max: 890 cycles RESULT TestCondvarSignalNoOp_CycleCount: trusted_linux_x86-32= {89, 7} count Apparent clock speed: 3701 MHz TestThreadCreateAndJoin: Measuring real time: 25.887 usec (2.58873e-05 sec) per iteration: 0.00258873 sec for 100 iterations 24.552 usec (2.45519e-05 sec) per iteration: 0.0948195 sec for 3862 iterations 24.318 usec (2.43178e-05 sec) per iteration: 0.0939152 sec for 3862 iterations 24.174 usec (2.41745e-05 sec) per iteration: 0.0933619 sec for 3862 iterations 24.039 usec (2.40393e-05 sec) per iteration: 0.0928397 sec for 3862 iterations 23.984 usec (2.39844e-05 sec) per iteration: 0.0926279 sec for 3862 iterations mean: 24.213579 usec stddev: 0.204809 usec relative stddev: 0.85% RESULT TestThreadCreateAndJoin: trusted_linux_x86-32= {24.213579, 0.204809} us Measuring clock cycles: first runs (cycles): 91514 67092 64508 64330 63247 62575 63541 62599 62173 62786 ... slowest runs (cycles): ... 63743 64168 64330 64508 64550 64930 65847 67092 75305 91514 min: 59448 cycles q1: 61256 cycles median: 61895 cycles q3: 62727 cycles max: 91514 cycles RESULT TestThreadCreateAndJoin_CycleCount: trusted_linux_x86-32= {61895, 1471} count Apparent clock speed: 2556 MHz TestThreadWakeup: Measuring real time: 18.013 usec (1.8013e-05 sec) per iteration: 0.0018013 sec for 100 iterations 17.749 usec (1.77487e-05 sec) per iteration: 0.098523 sec for 5551 iterations 17.796 usec (1.77962e-05 sec) per iteration: 0.0987868 sec for 5551 iterations 17.838 usec (1.78382e-05 sec) per iteration: 0.09902 sec for 5551 iterations 17.720 usec (1.77198e-05 sec) per iteration: 0.0983629 sec for 5551 iterations 18.939 usec (1.89394e-05 sec) per iteration: 0.105132 sec for 5551 iterations mean: 18.008465 usec stddev: 0.467197 usec relative stddev: 2.59% RESULT TestThreadWakeup: trusted_linux_x86-32= {18.008465, 0.467197} us Measuring clock cycles: first runs (cycles): 50871 51067 48596 47360 47544 47045 47200 46555 46476 46237 ... slowest runs (cycles): ... 47265 47360 47369 47375 47427 47470 47544 48596 50871 51067 min: 45454 cycles q1: 45867 cycles median: 46326 cycles q3: 46916 cycles max: 51067 cycles RESULT TestThreadWakeup_CycleCount: trusted_linux_x86-32= {46326, 1049} count Apparent clock speed: 2572 MHz WARNING: should have taken less than 2.000000 secs [ OK ] nacl_newlib.run_trusted_performance_test (5430 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