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

Running command: /b/s/w/ir/cache/vpython/b574a6/bin/python scons.py --verbose -k -j1 --mode=nacl,opt-host platform=x86-32 --clang breakpad_tools_dir=breakpad-out large_tests scons: Reading SConscript files ... ====================================================================== SCONS ARGS:['scons.py', '--verbose', '-k', '-j1', '--mode=nacl,opt-host', 'platform=x86-32', '--clang', 'breakpad_tools_dir=breakpad-out', 'large_tests'] ====================================================================== ====================================================================== Python Info: ====================================================================== linux2 2.7.14+chromium14 (df075345c7-dirty:df075345c7, Oct 3 2017, 17:29:00) [GCC 4.8.4] ====================================================================== ENV: ====================================================================== PATH /b/s/w/ir/cache/vpython/b574a6/bin:/b/s/w/ir/cipd_bin_packages:/b/s/w/ir/cipd_bin_packages/bin:/b/s/cipd_cache/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin:/b/s/w/ir/kitchen-checkout/depot_tools ====================================================================== OS: ====================================================================== Linux gce-trusty-e833d7b0-us-east1-b-x77q 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: 12907424 kB MemAvailable: 29428048 kB ====================================================================== LOAD: ====================================================================== 1.11 1.74 0.93 1/438 19014 ====================================================================== UPTIME: ====================================================================== 21284.05 153419.04 ====================================================================== 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 [19024,4148516608:19:34:57.631896] Native Client module will be loaded at base address 0x00000000b7454000 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 [19029,4147726080:19:34:57.945193] 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.117610, 0.001250} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test (2009 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 (70190 ms) doSizeCheck(["scons-out/nacl-x86-32/obj/tests/hello_world/hello_world_size.out"], ["scons-out/nacl-x86-32/obj/tests/hello_world/hello_world.nexe"]) RESULT hello_world_size.out: x86-32_nnacl_newlib_static= 509.324 KB RESULT hello_world_size.out: ZIPPED_x86-32_nnacl_newlib_static= 171.073 KB /b/s/w/ir/cache/vpython/b574a6/bin/python tools/command_tester.py --name nacl_newlib.run_performance_test --time_warning 10 --time_error 100 --perf_env_description x86-32_nnacl_newlib_static --using_nacl_signal_handler True --subarch 32 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-32/test_results/performance_test.out scons-out/opt-linux-x86-32/staging/nacl_helper_bootstrap scons-out/opt-linux-x86-32/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -e -- scons-out/nacl-x86-32/obj/tests/performance/performance_test.nexe x86-32_nnacl_newlib_static [ RUN ] nacl_newlib.run_performance_test scons-out/opt-linux-x86-32/staging/nacl_helper_bootstrap scons-out/opt-linux-x86-32/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -e -- scons-out/nacl-x86-32/obj/tests/performance/performance_test.nexe x86-32_nnacl_newlib_static [19060,4147246848:19:35:12.840744] Native Client module will be loaded at base address 0x0000000000000000 TestNull: Measuring real time: 0.013 usec (1.272e-08 sec) per iteration: 1.272e-06 sec for 100 iterations 0.004 usec (3.73e-09 sec) per iteration: 3.73e-06 sec for 1000 iterations 0.003 usec (3.1117e-09 sec) per iteration: 3.1117e-05 sec for 10000 iterations 0.002 usec (2.29292e-09 sec) per iteration: 0.073687 sec for 32136774 iterations 0.002 usec (2.27289e-09 sec) per iteration: 0.0730433 sec for 32136774 iterations 0.002 usec (2.27177e-09 sec) per iteration: 0.0730075 sec for 32136774 iterations 0.002 usec (2.26823e-09 sec) per iteration: 0.0728937 sec for 32136774 iterations 0.002 usec (2.26471e-09 sec) per iteration: 0.0727804 sec for 32136774 iterations mean: 0.002274 usec stddev: 0.000010 usec relative stddev: 0.43% RESULT TestNull: x86-32_nnacl_newlib_static= {0.002274, 0.000010} us Measuring clock cycles: first runs (cycles): 74 46 22 24 22 24 50 24 22 25 ... slowest runs (cycles): ... 25 26 26 26 27 27 27 46 50 74 min: 22 cycles q1: 22 cycles median: 24 cycles q3: 24 cycles max: 74 cycles RESULT TestNull_CycleCount: x86-32_nnacl_newlib_static= {24, 2} count Apparent clock speed: 10554 MHz TestNaClSyscall: Measuring real time: 0.274 usec (2.7418e-07 sec) per iteration: 2.7418e-05 sec for 100 iterations 0.240 usec (2.39647e-07 sec) per iteration: 0.0874048 sec for 364723 iterations 0.239 usec (2.38926e-07 sec) per iteration: 0.0871419 sec for 364723 iterations 0.240 usec (2.39821e-07 sec) per iteration: 0.0874684 sec for 364723 iterations 0.239 usec (2.39305e-07 sec) per iteration: 0.0872802 sec for 364723 iterations 0.239 usec (2.39109e-07 sec) per iteration: 0.0872086 sec for 364723 iterations mean: 0.239362 usec stddev: 0.000331 usec relative stddev: 0.14% RESULT TestNaClSyscall: x86-32_nnacl_newlib_static= {0.239362, 0.000331} us Measuring clock cycles: first runs (cycles): 622 559 530 528 528 528 528 531 527 528 ... slowest runs (cycles): ... 531 531 531 531 531 532 533 533 559 622 min: 526 cycles q1: 528 cycles median: 528 cycles q3: 530 cycles max: 622 cycles RESULT TestNaClSyscall_CycleCount: x86-32_nnacl_newlib_static= {528, 2} count Apparent clock speed: 2206 MHz TestSetjmpLongjmp: Measuring real time: 0.025 usec (2.475e-08 sec) per iteration: 2.475e-06 sec for 100 iterations 0.017 usec (1.6605e-08 sec) per iteration: 1.6605e-05 sec for 1000 iterations 0.012 usec (1.22329e-08 sec) per iteration: 0.0736702 sec for 6022282 iterations 0.012 usec (1.22156e-08 sec) per iteration: 0.0735658 sec for 6022282 iterations 0.012 usec (1.22087e-08 sec) per iteration: 0.073524 sec for 6022282 iterations 0.013 usec (1.26331e-08 sec) per iteration: 0.0760799 sec for 6022282 iterations 0.012 usec (1.21939e-08 sec) per iteration: 0.0734349 sec for 6022282 iterations mean: 0.012297 usec stddev: 0.000169 usec relative stddev: 1.37% RESULT TestSetjmpLongjmp: x86-32_nnacl_newlib_static= {0.012297, 0.000169} us Measuring clock cycles: first runs (cycles): 130 85 42 35 36 37 35 40 39 40 ... slowest runs (cycles): ... 42 42 42 42 42 42 42 44 85 130 min: 33 cycles q1: 37 cycles median: 38 cycles q3: 40 cycles max: 130 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-32_nnacl_newlib_static= {38, 3} count Apparent clock speed: 3090 MHz TestClockGetTime: Measuring real time: 0.528 usec (5.2782e-07 sec) per iteration: 5.2782e-05 sec for 100 iterations 0.460 usec (4.60336e-07 sec) per iteration: 0.0872143 sec for 189458 iterations 0.462 usec (4.61506e-07 sec) per iteration: 0.0874359 sec for 189458 iterations 0.461 usec (4.61205e-07 sec) per iteration: 0.087379 sec for 189458 iterations 0.460 usec (4.59731e-07 sec) per iteration: 0.0870997 sec for 189458 iterations 0.461 usec (4.60628e-07 sec) per iteration: 0.0872697 sec for 189458 iterations mean: 0.460681 usec stddev: 0.000629 usec relative stddev: 0.14% RESULT TestClockGetTime: x86-32_nnacl_newlib_static= {0.460681, 0.000629} us Measuring clock cycles: first runs (cycles): 1285 1126 1008 1011 1012 1006 1009 1008 1007 1006 ... slowest runs (cycles): ... 1017 1017 1017 1018 1019 1019 1053 1054 1126 1285 min: 1005 cycles q1: 1009 cycles median: 1011 cycles q3: 1012 cycles max: 1285 cycles RESULT TestClockGetTime_CycleCount: x86-32_nnacl_newlib_static= {1011, 3} count Apparent clock speed: 2195 MHz TestTlsVariable: Measuring real time: 0.009 usec (8.68e-09 sec) per iteration: 8.68e-07 sec for 100 iterations 0.003 usec (3.464e-09 sec) per iteration: 3.464e-06 sec for 1000 iterations 0.003 usec (3.4869e-09 sec) per iteration: 3.4869e-05 sec for 10000 iterations 0.003 usec (3.02696e-09 sec) per iteration: 0.0868096 sec for 28678769 iterations 0.003 usec (3.01954e-09 sec) per iteration: 0.0865967 sec for 28678769 iterations 0.003 usec (3.03014e-09 sec) per iteration: 0.0869006 sec for 28678769 iterations 0.003 usec (3.13476e-09 sec) per iteration: 0.0899011 sec for 28678769 iterations 0.003 usec (3.02651e-09 sec) per iteration: 0.0867966 sec for 28678769 iterations mean: 0.003048 usec stddev: 0.000044 usec relative stddev: 1.43% RESULT TestTlsVariable: x86-32_nnacl_newlib_static= {0.003048, 0.000044} us Measuring clock cycles: first runs (cycles): 119 49 27 26 26 27 26 28 27 29 ... slowest runs (cycles): ... 29 29 29 29 29 29 29 29 49 119 min: 24 cycles q1: 26 cycles median: 26 cycles q3: 27 cycles max: 119 cycles RESULT TestTlsVariable_CycleCount: x86-32_nnacl_newlib_static= {26, 1} count Apparent clock speed: 8531 MHz TestMmapAnonymous: Measuring real time: 3.339 usec (3.33927e-06 sec) per iteration: 0.000333927 sec for 100 iterations 2.730 usec (2.72992e-06 sec) per iteration: 0.0817502 sec for 29946 iterations 2.694 usec (2.69438e-06 sec) per iteration: 0.080686 sec for 29946 iterations 2.695 usec (2.69484e-06 sec) per iteration: 0.0806998 sec for 29946 iterations 2.706 usec (2.70586e-06 sec) per iteration: 0.0810296 sec for 29946 iterations 2.707 usec (2.70653e-06 sec) per iteration: 0.0810496 sec for 29946 iterations mean: 2.706306 usec stddev: 0.012895 usec relative stddev: 0.48% RESULT TestMmapAnonymous: x86-32_nnacl_newlib_static= {2.706306, 0.012895} us Measuring clock cycles: first runs (cycles): 7506 5913 5792 5825 5911 5781 5788 5814 5770 5766 ... slowest runs (cycles): ... 5865 5867 5887 5889 5895 5911 5913 5933 6034 7506 min: 5742 cycles q1: 5781 cycles median: 5795 cycles q3: 5819 cycles max: 7506 cycles RESULT TestMmapAnonymous_CycleCount: x86-32_nnacl_newlib_static= {5795, 38} count Apparent clock speed: 2141 MHz TestAtomicIncrement: Measuring real time: 0.019 usec (1.872e-08 sec) per iteration: 1.872e-06 sec for 100 iterations 0.011 usec (1.082e-08 sec) per iteration: 1.082e-05 sec for 1000 iterations 0.009 usec (9.43763e-09 sec) per iteration: 0.0872239 sec for 9242144 iterations 0.009 usec (9.45644e-09 sec) per iteration: 0.0873978 sec for 9242144 iterations 0.009 usec (9.42992e-09 sec) per iteration: 0.0871527 sec for 9242144 iterations 0.009 usec (9.44817e-09 sec) per iteration: 0.0873213 sec for 9242144 iterations 0.009 usec (9.44939e-09 sec) per iteration: 0.0873326 sec for 9242144 iterations mean: 0.009444 usec stddev: 0.000009 usec relative stddev: 0.10% RESULT TestAtomicIncrement: x86-32_nnacl_newlib_static= {0.009444, 0.000009} us Measuring clock cycles: first runs (cycles): 86 68 51 51 50 51 48 51 49 50 ... slowest runs (cycles): ... 51 51 51 51 51 51 51 51 68 86 min: 48 cycles q1: 49 cycles median: 50 cycles q3: 51 cycles max: 86 cycles RESULT TestAtomicIncrement_CycleCount: x86-32_nnacl_newlib_static= {50, 2} count Apparent clock speed: 5294 MHz TestUncontendedMutexLock: Measuring real time: 0.030 usec (2.95e-08 sec) per iteration: 2.95e-06 sec for 100 iterations 0.027 usec (2.677e-08 sec) per iteration: 2.677e-05 sec for 1000 iterations 0.022 usec (2.23165e-08 sec) per iteration: 0.0833639 sec for 3735524 iterations 0.022 usec (2.22366e-08 sec) per iteration: 0.0830653 sec for 3735524 iterations 0.023 usec (2.29881e-08 sec) per iteration: 0.0858727 sec for 3735524 iterations 0.022 usec (2.22141e-08 sec) per iteration: 0.0829812 sec for 3735524 iterations 0.022 usec (2.2234e-08 sec) per iteration: 0.0830558 sec for 3735524 iterations mean: 0.022398 usec stddev: 0.000297 usec relative stddev: 1.33% RESULT TestUncontendedMutexLock: x86-32_nnacl_newlib_static= {0.022398, 0.000297} us Measuring clock cycles: first runs (cycles): 226 121 81 79 79 80 82 81 79 79 ... slowest runs (cycles): ... 82 82 82 82 82 82 88 90 121 226 min: 79 cycles q1: 79 cycles median: 79 cycles q3: 81 cycles max: 226 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-32_nnacl_newlib_static= {79, 2} count Apparent clock speed: 3527 MHz TestCondvarSignalNoOp: Measuring real time: 0.437 usec (4.3672e-07 sec) per iteration: 4.3672e-05 sec for 100 iterations 0.410 usec (4.10422e-07 sec) per iteration: 0.0939779 sec for 228979 iterations 0.410 usec (4.09823e-07 sec) per iteration: 0.093841 sec for 228979 iterations 0.410 usec (4.10124e-07 sec) per iteration: 0.0939099 sec for 228979 iterations 0.410 usec (4.0951e-07 sec) per iteration: 0.0937692 sec for 228979 iterations 0.409 usec (4.09438e-07 sec) per iteration: 0.0937526 sec for 228979 iterations mean: 0.409863 usec stddev: 0.000371 usec relative stddev: 0.09% RESULT TestCondvarSignalNoOp: x86-32_nnacl_newlib_static= {0.409863, 0.000371} us Measuring clock cycles: first runs (cycles): 1062 924 899 900 893 898 896 891 898 897 ... slowest runs (cycles): ... 900 900 900 900 900 902 904 924 933 1062 min: 891 cycles q1: 895 cycles median: 897 cycles q3: 900 cycles max: 1062 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-32_nnacl_newlib_static= {897, 5} count Apparent clock speed: 2189 MHz TestThreadCreateAndJoin: Measuring real time: 100.023 usec (0.000100023 sec) per iteration: 0.0100023 sec for 100 iterations 90.509 usec (9.05086e-05 sec) per iteration: 0.0904181 sec for 999 iterations 94.059 usec (9.4059e-05 sec) per iteration: 0.093965 sec for 999 iterations 91.495 usec (9.14953e-05 sec) per iteration: 0.0914038 sec for 999 iterations 97.573 usec (9.75728e-05 sec) per iteration: 0.0974752 sec for 999 iterations 86.909 usec (8.69089e-05 sec) per iteration: 0.086822 sec for 999 iterations mean: 92.108910 usec stddev: 3.567490 usec relative stddev: 3.87% RESULT TestThreadCreateAndJoin: x86-32_nnacl_newlib_static= {92.108910, 3.567490} us Measuring clock cycles: first runs (cycles): 123841 177943 182662 215703 177125 178735 199392 187847 187229 185022 ... slowest runs (cycles): ... 199392 206011 208160 208596 209865 214218 215703 227552 243153 357008 min: 123841 cycles q1: 172314 cycles median: 175075 cycles q3: 185022 cycles max: 357008 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-32_nnacl_newlib_static= {175075, 12708} count Apparent clock speed: 1901 MHz TestThreadWakeup: Measuring real time: 14.345 usec (1.43452e-05 sec) per iteration: 0.00143452 sec for 100 iterations 15.317 usec (1.53174e-05 sec) per iteration: 0.106762 sec for 6970 iterations 14.988 usec (1.49877e-05 sec) per iteration: 0.104464 sec for 6970 iterations 15.235 usec (1.52353e-05 sec) per iteration: 0.10619 sec for 6970 iterations 17.139 usec (1.7139e-05 sec) per iteration: 0.119459 sec for 6970 iterations 16.093 usec (1.60927e-05 sec) per iteration: 0.112166 sec for 6970 iterations mean: 15.754411 usec stddev: 0.784823 usec relative stddev: 4.98% RESULT TestThreadWakeup: x86-32_nnacl_newlib_static= {15.754411, 0.784823} us Measuring clock cycles: first runs (cycles): 305996 102721 42228 43260 43056 43891 41815 41709 41096 42008 ... slowest runs (cycles): ... 43056 43260 43891 46241 48401 52004 53652 102721 145174 305996 min: 28569 cycles q1: 34749 cycles median: 35255 cycles q3: 38754 cycles max: 305996 cycles RESULT TestThreadWakeup_CycleCount: x86-32_nnacl_newlib_static= {35255, 4005} count Apparent clock speed: 2238 MHz TestCatchingFault: Measuring real time: 3.583 usec (3.58331e-06 sec) per iteration: 0.000358331 sec for 100 iterations 2.291 usec (2.2913e-06 sec) per iteration: 0.0639433 sec for 27907 iterations 2.284 usec (2.28439e-06 sec) per iteration: 0.0637506 sec for 27907 iterations 2.287 usec (2.28717e-06 sec) per iteration: 0.0638279 sec for 27907 iterations 2.291 usec (2.2908e-06 sec) per iteration: 0.0639293 sec for 27907 iterations 2.292 usec (2.29216e-06 sec) per iteration: 0.0639674 sec for 27907 iterations mean: 2.289164 usec stddev: 0.002931 usec relative stddev: 0.13% RESULT TestCatchingFault: x86-32_nnacl_newlib_static= {2.289164, 0.002931} us Measuring clock cycles: first runs (cycles): 6283 5045 4969 4954 4943 4934 4972 4949 4952 4956 ... slowest runs (cycles): ... 4994 4998 4999 5000 5002 5012 5038 5045 5090 6283 min: 4934 cycles q1: 4951 cycles median: 4960 cycles q3: 4974 cycles max: 6283 cycles RESULT TestCatchingFault_CycleCount: x86-32_nnacl_newlib_static= {4960, 23} count Apparent clock speed: 2167 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 91.096 usec (9.10963e-05 sec) per iteration: 0.00910963 sec for 100 iterations 94.847 usec (9.48465e-05 sec) per iteration: 0.104047 sec for 1097 iterations 92.443 usec (9.24429e-05 sec) per iteration: 0.10141 sec for 1097 iterations 91.054 usec (9.10541e-05 sec) per iteration: 0.0998864 sec for 1097 iterations 91.310 usec (9.13104e-05 sec) per iteration: 0.100168 sec for 1097 iterations 109.863 usec (0.000109863 sec) per iteration: 0.120519 sec for 1097 iterations mean: 95.903352 usec stddev: 7.107292 usec relative stddev: 7.41% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-32_nnacl_newlib_static= {95.903352, 7.107292} us Measuring clock cycles: first runs (cycles): 210527 301894 372275 397740 292076 247241 231520 392362 268452 317118 ... slowest runs (cycles): ... 479333 501180 513519 525461 562144 587081 743655 751243 761149 786852 min: 162778 cycles q1: 237524 cycles median: 269488 cycles q3: 365649 cycles max: 786852 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-32_nnacl_newlib_static= {269488, 128125} count Apparent clock speed: 2810 MHz [ OK ] nacl_newlib.run_performance_test (5540 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 (2130 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 (1020 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.26e-09 sec) per iteration: 9.26e-07 sec for 100 iterations 0.003 usec (3.08e-09 sec) per iteration: 3.08e-06 sec for 1000 iterations 0.002 usec (2.2662e-09 sec) per iteration: 2.2662e-05 sec for 10000 iterations 0.002 usec (1.89091e-09 sec) per iteration: 0.0834397 sec for 44126731 iterations 0.002 usec (1.89021e-09 sec) per iteration: 0.0834089 sec for 44126731 iterations 0.002 usec (1.89112e-09 sec) per iteration: 0.083449 sec for 44126731 iterations 0.002 usec (1.88962e-09 sec) per iteration: 0.0833828 sec for 44126731 iterations 0.002 usec (1.89628e-09 sec) per iteration: 0.0836769 sec for 44126731 iterations mean: 0.001892 usec stddev: 0.000002 usec relative stddev: 0.13% RESULT TestNull: trusted_linux_x86-32= {0.001892, 0.000002} us Measuring clock cycles: first runs (cycles): 108 22 24 24 24 25 22 24 24 35 ... slowest runs (cycles): ... 35 35 35 37 37 37 37 38 50 108 min: 20 cycles q1: 26 cycles median: 27 cycles q3: 29 cycles max: 108 cycles RESULT TestNull_CycleCount: trusted_linux_x86-32= {27, 3} count Apparent clock speed: 14273 MHz TestHostSyscall: Measuring real time: 0.123 usec (1.23e-07 sec) per iteration: 1.23e-05 sec for 100 iterations 0.104 usec (1.04273e-07 sec) per iteration: 0.0847745 sec for 813008 iterations 0.105 usec (1.04565e-07 sec) per iteration: 0.0850124 sec for 813008 iterations 0.105 usec (1.04932e-07 sec) per iteration: 0.0853105 sec for 813008 iterations 0.105 usec (1.04835e-07 sec) per iteration: 0.0852319 sec for 813008 iterations 0.105 usec (1.04973e-07 sec) per iteration: 0.0853441 sec for 813008 iterations mean: 0.104716 usec stddev: 0.000263 usec relative stddev: 0.25% RESULT TestHostSyscall: trusted_linux_x86-32= {0.104716, 0.000263} us Measuring clock cycles: first runs (cycles): 462 482 253 247 253 251 257 255 251 508 ... slowest runs (cycles): ... 260 260 264 462 475 479 482 491 506 508 min: 247 cycles q1: 252 cycles median: 255 cycles q3: 256 cycles max: 508 cycles RESULT TestHostSyscall_CycleCount: trusted_linux_x86-32= {255, 4} count Apparent clock speed: 2435 MHz TestSetjmpLongjmp: Measuring real time: 0.049 usec (4.921e-08 sec) per iteration: 4.921e-06 sec for 100 iterations 0.048 usec (4.7851e-08 sec) per iteration: 4.7851e-05 sec for 1000 iterations 0.032 usec (3.20132e-08 sec) per iteration: 0.0669018 sec for 2089820 iterations 0.032 usec (3.19075e-08 sec) per iteration: 0.0666809 sec for 2089820 iterations 0.032 usec (3.19883e-08 sec) per iteration: 0.0668498 sec for 2089820 iterations 0.032 usec (3.22752e-08 sec) per iteration: 0.0674495 sec for 2089820 iterations 0.032 usec (3.24128e-08 sec) per iteration: 0.0677368 sec for 2089820 iterations mean: 0.032119 usec stddev: 0.000192 usec relative stddev: 0.60% RESULT TestSetjmpLongjmp: trusted_linux_x86-32= {0.032119, 0.000192} us Measuring clock cycles: first runs (cycles): 248 380 101 101 99 99 101 99 96 402 ... slowest runs (cycles): ... 125 126 128 132 132 139 248 380 402 429 min: 90 cycles q1: 99 cycles median: 106 cycles q3: 116 cycles max: 429 cycles RESULT TestSetjmpLongjmp_CycleCount: trusted_linux_x86-32= {106, 17} count Apparent clock speed: 3300 MHz TestClockGetTime: Measuring real time: 0.199 usec (1.9935e-07 sec) per iteration: 1.9935e-05 sec for 100 iterations 0.197 usec (1.96873e-07 sec) per iteration: 0.0987576 sec for 501630 iterations 0.197 usec (1.9673e-07 sec) per iteration: 0.0986856 sec for 501630 iterations 0.196 usec (1.96226e-07 sec) per iteration: 0.0984329 sec for 501630 iterations 0.199 usec (1.98861e-07 sec) per iteration: 0.0997548 sec for 501630 iterations 0.196 usec (1.95917e-07 sec) per iteration: 0.0982777 sec for 501630 iterations mean: 0.196921 usec stddev: 0.001029 usec relative stddev: 0.52% RESULT TestClockGetTime: trusted_linux_x86-32= {0.196921, 0.001029} us Measuring clock cycles: first runs (cycles): 607 643 444 447 447 446 449 444 449 695 ... slowest runs (cycles): ... 447 449 449 449 449 451 607 616 643 695 min: 444 cycles q1: 445 cycles median: 446 cycles q3: 447 cycles max: 695 cycles RESULT TestClockGetTime_CycleCount: trusted_linux_x86-32= {446, 2} count Apparent clock speed: 2265 MHz TestTlsVariable: Measuring real time: 0.005 usec (4.85e-09 sec) per iteration: 4.85e-07 sec for 100 iterations 0.003 usec (2.593e-09 sec) per iteration: 2.593e-06 sec for 1000 iterations 0.003 usec (3.0756e-09 sec) per iteration: 3.0756e-05 sec for 10000 iterations 0.002 usec (2.43629e-09 sec) per iteration: 0.0792136 sec for 32513981 iterations 0.002 usec (2.4485e-09 sec) per iteration: 0.0796105 sec for 32513981 iterations 0.002 usec (2.46577e-09 sec) per iteration: 0.0801719 sec for 32513981 iterations 0.002 usec (2.46846e-09 sec) per iteration: 0.0802593 sec for 32513981 iterations 0.002 usec (2.46111e-09 sec) per iteration: 0.0800204 sec for 32513981 iterations mean: 0.002456 usec stddev: 0.000012 usec relative stddev: 0.49% RESULT TestTlsVariable: trusted_linux_x86-32= {0.002456, 0.000012} us Measuring clock cycles: first runs (cycles): 75 28 26 24 29 24 24 26 51 26 ... slowest runs (cycles): ... 29 29 29 29 29 29 48 51 51 75 min: 19 cycles q1: 24 cycles median: 26 cycles q3: 27 cycles max: 75 cycles RESULT TestTlsVariable_CycleCount: trusted_linux_x86-32= {26, 3} count Apparent clock speed: 10586 MHz TestMmapAnonymous: Measuring real time: 1.019 usec (1.01938e-06 sec) per iteration: 0.000101938 sec for 100 iterations 0.793 usec (7.933e-07 sec) per iteration: 0.0778211 sec for 98098 iterations 0.796 usec (7.96014e-07 sec) per iteration: 0.0780874 sec for 98098 iterations 0.802 usec (8.01549e-07 sec) per iteration: 0.0786303 sec for 98098 iterations 0.796 usec (7.96344e-07 sec) per iteration: 0.0781198 sec for 98098 iterations 0.798 usec (7.98305e-07 sec) per iteration: 0.0783121 sec for 98098 iterations mean: 0.797102 usec stddev: 0.002736 usec relative stddev: 0.34% RESULT TestMmapAnonymous: trusted_linux_x86-32= {0.797102, 0.002736} us Measuring clock cycles: first runs (cycles): 2560 1982 1727 1733 1800 1715 1723 1734 1722 1921 ... slowest runs (cycles): ... 1773 1777 1789 1795 1797 1800 1921 1929 1982 2560 min: 1710 cycles q1: 1725 cycles median: 1733 cycles q3: 1740 cycles max: 2560 cycles RESULT TestMmapAnonymous_CycleCount: trusted_linux_x86-32= {1733, 15} count Apparent clock speed: 2174 MHz TestAtomicIncrement: Measuring real time: 0.013 usec (1.345e-08 sec) per iteration: 1.345e-06 sec for 100 iterations 0.010 usec (9.522e-09 sec) per iteration: 9.522e-06 sec for 1000 iterations 0.010 usec (1.00346e-08 sec) per iteration: 0.000100346 sec for 10000 iterations 0.009 usec (9.43257e-09 sec) per iteration: 0.0940005 sec for 9965519 iterations 0.010 usec (9.50877e-09 sec) per iteration: 0.0947599 sec for 9965519 iterations 0.009 usec (9.47418e-09 sec) per iteration: 0.0944151 sec for 9965519 iterations 0.009 usec (9.48261e-09 sec) per iteration: 0.0944991 sec for 9965519 iterations 0.010 usec (9.50037e-09 sec) per iteration: 0.0946762 sec for 9965519 iterations mean: 0.009480 usec stddev: 0.000027 usec relative stddev: 0.28% RESULT TestAtomicIncrement: trusted_linux_x86-32= {0.009480, 0.000027} us Measuring clock cycles: first runs (cycles): 103 336 55 55 55 51 57 55 50 378 ... slowest runs (cycles): ... 55 55 55 57 57 57 103 328 336 378 min: 48 cycles q1: 50 cycles median: 51 cycles q3: 51 cycles max: 378 cycles RESULT TestAtomicIncrement_CycleCount: trusted_linux_x86-32= {51, 1} count Apparent clock speed: 5380 MHz TestUncontendedMutexLock: Measuring real time: 0.039 usec (3.857e-08 sec) per iteration: 3.857e-06 sec for 100 iterations 0.026 usec (2.6155e-08 sec) per iteration: 2.6155e-05 sec for 1000 iterations 0.026 usec (2.6383e-08 sec) per iteration: 0.100872 sec for 3823360 iterations 0.026 usec (2.64353e-08 sec) per iteration: 0.101072 sec for 3823360 iterations 0.026 usec (2.64358e-08 sec) per iteration: 0.101074 sec for 3823360 iterations 0.026 usec (2.64072e-08 sec) per iteration: 0.100964 sec for 3823360 iterations 0.026 usec (2.64892e-08 sec) per iteration: 0.101278 sec for 3823360 iterations mean: 0.026430 usec stddev: 0.000035 usec relative stddev: 0.13% RESULT TestUncontendedMutexLock: trusted_linux_x86-32= {0.026430, 0.000035} us Measuring clock cycles: first runs (cycles): 207 401 93 94 92 95 90 90 92 438 ... slowest runs (cycles): ... 95 95 95 95 97 207 380 401 438 440 min: 88 cycles q1: 92 cycles median: 94 cycles q3: 95 cycles max: 440 cycles RESULT TestUncontendedMutexLock_CycleCount: trusted_linux_x86-32= {94, 3} count Apparent clock speed: 3557 MHz TestCondvarSignalNoOp: Measuring real time: 0.029 usec (2.918e-08 sec) per iteration: 2.918e-06 sec for 100 iterations 0.021 usec (2.1067e-08 sec) per iteration: 2.1067e-05 sec for 1000 iterations 0.021 usec (2.11844e-08 sec) per iteration: 0.100557 sec for 4746760 iterations 0.021 usec (2.12174e-08 sec) per iteration: 0.100714 sec for 4746760 iterations 0.021 usec (2.11448e-08 sec) per iteration: 0.100369 sec for 4746760 iterations 0.021 usec (2.11554e-08 sec) per iteration: 0.10042 sec for 4746760 iterations 0.021 usec (2.11473e-08 sec) per iteration: 0.100381 sec for 4746760 iterations mean: 0.021170 usec stddev: 0.000028 usec relative stddev: 0.13% RESULT TestCondvarSignalNoOp: trusted_linux_x86-32= {0.021170, 0.000028} us Measuring clock cycles: first runs (cycles): 134 377 79 79 80 79 81 79 79 405 ... slowest runs (cycles): ... 83 84 84 84 134 377 385 394 405 433 min: 77 cycles q1: 79 cycles median: 81 cycles q3: 82 cycles max: 433 cycles RESULT TestCondvarSignalNoOp_CycleCount: trusted_linux_x86-32= {81, 3} count Apparent clock speed: 3826 MHz TestThreadCreateAndJoin: Measuring real time: 31.714 usec (3.17138e-05 sec) per iteration: 0.00317138 sec for 100 iterations 24.729 usec (2.47292e-05 sec) per iteration: 0.0779711 sec for 3153 iterations 25.235 usec (2.52352e-05 sec) per iteration: 0.0795665 sec for 3153 iterations 33.537 usec (3.35368e-05 sec) per iteration: 0.105741 sec for 3153 iterations 33.588 usec (3.35884e-05 sec) per iteration: 0.105904 sec for 3153 iterations 34.977 usec (3.49766e-05 sec) per iteration: 0.110281 sec for 3153 iterations mean: 30.413232 usec stddev: 4.467294 usec relative stddev: 14.69% RESULT TestThreadCreateAndJoin: trusted_linux_x86-32= {30.413232, 4.467294} us Measuring clock cycles: first runs (cycles): 767532 506957 178192 249183 59127 57297 58901 57067 55135 58533 ... slowest runs (cycles): ... 159968 178192 223694 249183 262626 369431 428585 496568 506957 767532 min: 42134 cycles q1: 57020 cycles median: 67064 cycles q3: 117406 cycles max: 767532 cycles RESULT TestThreadCreateAndJoin_CycleCount: trusted_linux_x86-32= {67064, 60386} count Apparent clock speed: 2205 MHz TestThreadWakeup: Measuring real time: 13.903 usec (1.39032e-05 sec) per iteration: 0.00139032 sec for 100 iterations 17.191 usec (1.7191e-05 sec) per iteration: 0.123638 sec for 7192 iterations 18.190 usec (1.81903e-05 sec) per iteration: 0.130825 sec for 7192 iterations 19.166 usec (1.91663e-05 sec) per iteration: 0.137844 sec for 7192 iterations 16.091 usec (1.60907e-05 sec) per iteration: 0.115724 sec for 7192 iterations 17.345 usec (1.7345e-05 sec) per iteration: 0.124745 sec for 7192 iterations mean: 17.596657 usec stddev: 1.030734 usec relative stddev: 5.86% RESULT TestThreadWakeup: trusted_linux_x86-32= {17.596657, 1.030734} us Measuring clock cycles: first runs (cycles): 43540 37921 38622 37743 39080 39135 38178 39320 38236 38240 ... slowest runs (cycles): ... 43528 43540 43607 43900 44732 45552 47451 50232 52996 53880 min: 36898 cycles q1: 37859 cycles median: 38240 cycles q3: 39852 cycles max: 53880 cycles RESULT TestThreadWakeup_CycleCount: trusted_linux_x86-32= {38240, 1993} count Apparent clock speed: 2173 MHz WARNING: should have taken less than 2.000000 secs [ OK ] nacl_newlib.run_trusted_performance_test (5100 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