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-west1-b-zjqr 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: 17670872 kB MemAvailable: 29462220 kB ====================================================================== LOAD: ====================================================================== 1.46 2.52 1.92 1/441 21562 ====================================================================== UPTIME: ====================================================================== 6227.99 46416.16 ====================================================================== 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 [21570,4148512512:12:18:40.864143] Native Client module will be loaded at base address 0x00000000b7453000 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 [21575,4147013376:12:18:41.153993] 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.082250, 0.004700} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test (1370 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 (71280 ms) doSizeCheck(["scons-out/nacl-x86-32/obj/tests/hello_world/hello_world_size.out"], ["scons-out/nacl-x86-32/obj/tests/hello_world/hello_world.nexe"]) RESULT hello_world_size.out: x86-32_nnacl_newlib_static= 512.216 KB RESULT hello_world_size.out: ZIPPED_x86-32_nnacl_newlib_static= 171.095 KB /b/s/w/ir/cache/vpython/b574a6/bin/python tools/command_tester.py --name nacl_newlib.run_performance_test --time_warning 10 --time_error 100 --perf_env_description x86-32_nnacl_newlib_static --using_nacl_signal_handler True --subarch 32 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-32/test_results/performance_test.out scons-out/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 [21603,4147431168:12:18:55.801925] Native Client module will be loaded at base address 0x0000000000000000 TestNull: Measuring real time: 0.012 usec (1.228e-08 sec) per iteration: 1.228e-06 sec for 100 iterations 0.003 usec (3.315e-09 sec) per iteration: 3.315e-06 sec for 1000 iterations 0.002 usec (2.2959e-09 sec) per iteration: 2.2959e-05 sec for 10000 iterations 0.002 usec (2.26976e-09 sec) per iteration: 0.0988613 sec for 43555904 iterations 0.002 usec (2.26424e-09 sec) per iteration: 0.0986209 sec for 43555904 iterations 0.002 usec (2.26689e-09 sec) per iteration: 0.0987362 sec for 43555904 iterations 0.002 usec (2.26909e-09 sec) per iteration: 0.0988323 sec for 43555904 iterations 0.002 usec (2.26613e-09 sec) per iteration: 0.0987031 sec for 43555904 iterations mean: 0.002267 usec stddev: 0.000002 usec relative stddev: 0.09% RESULT TestNull: x86-32_nnacl_newlib_static= {0.002267, 0.000002} us Measuring clock cycles: first runs (cycles): 88 46 24 25 25 25 22 24 22 24 ... 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: 10586 MHz TestNaClSyscall: Measuring real time: 0.337 usec (3.3747e-07 sec) per iteration: 3.3747e-05 sec for 100 iterations 0.333 usec (3.33374e-07 sec) per iteration: 0.0987861 sec for 296322 iterations 0.331 usec (3.31467e-07 sec) per iteration: 0.098221 sec for 296322 iterations 0.336 usec (3.36312e-07 sec) per iteration: 0.0996567 sec for 296322 iterations 0.332 usec (3.32102e-07 sec) per iteration: 0.0984091 sec for 296322 iterations 0.332 usec (3.31685e-07 sec) per iteration: 0.0982855 sec for 296322 iterations mean: 0.332988 usec stddev: 0.001789 usec relative stddev: 0.54% RESULT TestNaClSyscall: x86-32_nnacl_newlib_static= {0.332988, 0.001789} us Measuring clock cycles: first runs (cycles): 953 796 730 739 726 728 724 724 726 734 ... slowest runs (cycles): ... 737 739 740 763 763 770 772 772 796 953 min: 717 cycles q1: 726 cycles median: 728 cycles q3: 733 cycles max: 953 cycles RESULT TestNaClSyscall_CycleCount: x86-32_nnacl_newlib_static= {728, 7} count Apparent clock speed: 2186 MHz TestSetjmpLongjmp: Measuring real time: 0.021 usec (2.111e-08 sec) per iteration: 2.111e-06 sec for 100 iterations 0.013 usec (1.268e-08 sec) per iteration: 1.268e-05 sec for 1000 iterations 0.012 usec (1.21907e-08 sec) per iteration: 0.0961412 sec for 7886435 iterations 0.012 usec (1.21973e-08 sec) per iteration: 0.0961929 sec for 7886435 iterations 0.012 usec (1.21944e-08 sec) per iteration: 0.0961707 sec for 7886435 iterations 0.012 usec (1.22017e-08 sec) per iteration: 0.0962275 sec for 7886435 iterations 0.012 usec (1.22174e-08 sec) per iteration: 0.096352 sec for 7886435 iterations mean: 0.012200 usec stddev: 0.000009 usec relative stddev: 0.08% RESULT TestSetjmpLongjmp: x86-32_nnacl_newlib_static= {0.012200, 0.000009} us Measuring clock cycles: first runs (cycles): 118 95 42 37 33 38 35 42 40 40 ... slowest runs (cycles): ... 42 42 42 42 42 44 44 95 118 136 min: 31 cycles q1: 36 cycles median: 38 cycles q3: 40 cycles max: 136 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-32_nnacl_newlib_static= {38, 4} count Apparent clock speed: 3115 MHz TestClockGetTime: Measuring real time: 0.633 usec (6.3347e-07 sec) per iteration: 6.3347e-05 sec for 100 iterations 0.634 usec (6.3381e-07 sec) per iteration: 0.100053 sec for 157860 iterations 0.639 usec (6.39077e-07 sec) per iteration: 0.100885 sec for 157860 iterations 0.637 usec (6.36658e-07 sec) per iteration: 0.100503 sec for 157860 iterations 0.638 usec (6.3753e-07 sec) per iteration: 0.10064 sec for 157860 iterations 0.637 usec (6.37182e-07 sec) per iteration: 0.100586 sec for 157860 iterations mean: 0.636851 usec stddev: 0.001721 usec relative stddev: 0.27% RESULT TestClockGetTime: x86-32_nnacl_newlib_static= {0.636851, 0.001721} us Measuring clock cycles: first runs (cycles): 1718 1476 1476 1424 1388 1375 1432 1430 1432 1430 ... slowest runs (cycles): ... 1441 1441 1445 1456 1458 1465 1476 1476 1509 1718 min: 1366 cycles q1: 1386 cycles median: 1392 cycles q3: 1418 cycles max: 1718 cycles RESULT TestClockGetTime_CycleCount: x86-32_nnacl_newlib_static= {1392, 32} count Apparent clock speed: 2186 MHz TestTlsVariable: Measuring real time: 0.016 usec (1.636e-08 sec) per iteration: 1.636e-06 sec for 100 iterations 0.004 usec (3.654e-09 sec) per iteration: 3.654e-06 sec for 1000 iterations 0.003 usec (3.0357e-09 sec) per iteration: 3.0357e-05 sec for 10000 iterations 0.003 usec (3.01918e-09 sec) per iteration: 0.0994559 sec for 32941331 iterations 0.003 usec (3.02632e-09 sec) per iteration: 0.0996911 sec for 32941331 iterations 0.003 usec (3.02116e-09 sec) per iteration: 0.0995211 sec for 32941331 iterations 0.003 usec (3.02088e-09 sec) per iteration: 0.0995119 sec for 32941331 iterations 0.003 usec (3.02036e-09 sec) per iteration: 0.0994948 sec for 32941331 iterations mean: 0.003022 usec stddev: 0.000002 usec relative stddev: 0.08% RESULT TestTlsVariable: x86-32_nnacl_newlib_static= {0.003022, 0.000002} us Measuring clock cycles: first runs (cycles): 101 49 26 26 27 29 26 29 24 26 ... slowest runs (cycles): ... 29 29 29 29 29 29 29 29 49 101 min: 24 cycles q1: 26 cycles median: 27 cycles q3: 28 cycles max: 101 cycles RESULT TestTlsVariable_CycleCount: x86-32_nnacl_newlib_static= {27, 2} count Apparent clock speed: 8936 MHz TestMmapAnonymous: Measuring real time: 3.690 usec (3.69037e-06 sec) per iteration: 0.000369037 sec for 100 iterations 3.416 usec (3.4159e-06 sec) per iteration: 0.0925606 sec for 27097 iterations 3.410 usec (3.40977e-06 sec) per iteration: 0.0923947 sec for 27097 iterations 3.423 usec (3.42251e-06 sec) per iteration: 0.0927397 sec for 27097 iterations 3.396 usec (3.39577e-06 sec) per iteration: 0.0920152 sec for 27097 iterations 3.391 usec (3.39125e-06 sec) per iteration: 0.0918928 sec for 27097 iterations mean: 3.407041 usec stddev: 0.011844 usec relative stddev: 0.35% RESULT TestMmapAnonymous: x86-32_nnacl_newlib_static= {3.407041, 0.011844} us Measuring clock cycles: first runs (cycles): 10667 7686 7404 7273 7422 7459 7462 7334 7429 7318 ... slowest runs (cycles): ... 7459 7462 7462 7477 7497 7552 7686 10667 16093 21230 min: 7215 cycles q1: 7282 cycles median: 7321 cycles q3: 7385 cycles max: 21230 cycles RESULT TestMmapAnonymous_CycleCount: x86-32_nnacl_newlib_static= {7321, 103} count Apparent clock speed: 2149 MHz TestAtomicIncrement: Measuring real time: 0.023 usec (2.34e-08 sec) per iteration: 2.34e-06 sec for 100 iterations 0.012 usec (1.1659e-08 sec) per iteration: 1.1659e-05 sec for 1000 iterations 0.009 usec (9.44651e-09 sec) per iteration: 0.0810233 sec for 8577064 iterations 0.009 usec (9.44695e-09 sec) per iteration: 0.0810271 sec for 8577064 iterations 0.009 usec (9.39505e-09 sec) per iteration: 0.0805819 sec for 8577064 iterations 0.009 usec (9.44673e-09 sec) per iteration: 0.0810252 sec for 8577064 iterations 0.009 usec (9.4441e-09 sec) per iteration: 0.0810027 sec for 8577064 iterations mean: 0.009436 usec stddev: 0.000020 usec relative stddev: 0.22% RESULT TestAtomicIncrement: x86-32_nnacl_newlib_static= {0.009436, 0.000020} us Measuring clock cycles: first runs (cycles): 132 66 53 51 52 51 48 51 50 55 ... slowest runs (cycles): ... 57 57 58 59 59 59 62 66 132 242 min: 48 cycles q1: 50 cycles median: 51 cycles q3: 53 cycles max: 242 cycles RESULT TestAtomicIncrement_CycleCount: x86-32_nnacl_newlib_static= {51, 3} count Apparent clock speed: 5405 MHz TestUncontendedMutexLock: Measuring real time: 0.039 usec (3.891e-08 sec) per iteration: 3.891e-06 sec for 100 iterations 0.027 usec (2.6586e-08 sec) per iteration: 2.6586e-05 sec for 1000 iterations 0.022 usec (2.21827e-08 sec) per iteration: 0.0834374 sec for 3761378 iterations 0.022 usec (2.22648e-08 sec) per iteration: 0.0837462 sec for 3761378 iterations 0.022 usec (2.23842e-08 sec) per iteration: 0.0841954 sec for 3761378 iterations 0.022 usec (2.22459e-08 sec) per iteration: 0.0836752 sec for 3761378 iterations 0.022 usec (2.22658e-08 sec) per iteration: 0.0837502 sec for 3761378 iterations mean: 0.022269 usec stddev: 0.000065 usec relative stddev: 0.29% RESULT TestUncontendedMutexLock: x86-32_nnacl_newlib_static= {0.022269, 0.000065} us Measuring clock cycles: first runs (cycles): 255 141 90 79 83 84 83 81 92 88 ... slowest runs (cycles): ... 90 90 91 92 92 92 95 97 141 255 min: 79 cycles q1: 83 cycles median: 84 cycles q3: 86 cycles max: 255 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-32_nnacl_newlib_static= {84, 3} count Apparent clock speed: 3772 MHz TestCondvarSignalNoOp: Measuring real time: 0.718 usec (7.1813e-07 sec) per iteration: 7.1813e-05 sec for 100 iterations 0.520 usec (5.20076e-07 sec) per iteration: 0.0724207 sec for 139250 iterations 0.524 usec (5.23792e-07 sec) per iteration: 0.072938 sec for 139250 iterations 0.522 usec (5.21934e-07 sec) per iteration: 0.0726793 sec for 139250 iterations 0.520 usec (5.20139e-07 sec) per iteration: 0.0724293 sec for 139250 iterations 0.522 usec (5.22288e-07 sec) per iteration: 0.0727286 sec for 139250 iterations mean: 0.521646 usec stddev: 0.001402 usec relative stddev: 0.27% RESULT TestCondvarSignalNoOp: x86-32_nnacl_newlib_static= {0.521646, 0.001402} us Measuring clock cycles: first runs (cycles): 2723 1351 1362 1302 1212 1293 1215 1236 1251 1225 ... slowest runs (cycles): ... 1419 1419 1430 1470 1507 1514 1535 1562 1586 2723 min: 1212 cycles q1: 1251 cycles median: 1289 cycles q3: 1342 cycles max: 2723 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-32_nnacl_newlib_static= {1289, 91} count Apparent clock speed: 2471 MHz TestThreadCreateAndJoin: Measuring real time: 92.044 usec (9.20439e-05 sec) per iteration: 0.00920439 sec for 100 iterations 79.097 usec (7.90968e-05 sec) per iteration: 0.0858991 sec for 1086 iterations 82.834 usec (8.28338e-05 sec) per iteration: 0.0899575 sec for 1086 iterations 84.387 usec (8.43872e-05 sec) per iteration: 0.0916445 sec for 1086 iterations 79.901 usec (7.9901e-05 sec) per iteration: 0.0867725 sec for 1086 iterations 80.397 usec (8.03975e-05 sec) per iteration: 0.0873117 sec for 1086 iterations mean: 81.323255 usec stddev: 1.975181 usec relative stddev: 2.43% RESULT TestThreadCreateAndJoin: x86-32_nnacl_newlib_static= {81.323255, 1.975181} us Measuring clock cycles: first runs (cycles): 112631 118368 207959 117809 174399 185411 175930 178982 168802 166889 ... slowest runs (cycles): ... 232107 233777 234737 262996 288691 325294 342153 420651 526215 1280791 min: 107751 cycles q1: 128471 cycles median: 173442 cycles q3: 186452 cycles max: 1280791 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-32_nnacl_newlib_static= {173442, 57981} count Apparent clock speed: 2133 MHz TestThreadWakeup: Measuring real time: 20.672 usec (2.06717e-05 sec) per iteration: 0.00206717 sec for 100 iterations 19.598 usec (1.95979e-05 sec) per iteration: 0.0947949 sec for 4837 iterations 14.947 usec (1.49472e-05 sec) per iteration: 0.0722996 sec for 4837 iterations 14.905 usec (1.49054e-05 sec) per iteration: 0.0720976 sec for 4837 iterations 15.421 usec (1.54206e-05 sec) per iteration: 0.0745895 sec for 4837 iterations 15.771 usec (1.57711e-05 sec) per iteration: 0.0762848 sec for 4837 iterations mean: 16.128442 usec stddev: 1.763902 usec relative stddev: 10.94% RESULT TestThreadWakeup: x86-32_nnacl_newlib_static= {16.128442, 1.763902} us Measuring clock cycles: first runs (cycles): 41704 43259 44975 35828 39071 40037 38901 37787 34839 32203 ... slowest runs (cycles): ... 39071 39308 39790 40037 41704 42643 43259 44975 50059 59358 min: 28945 cycles q1: 35125 cycles median: 35653 cycles q3: 37177 cycles max: 59358 cycles RESULT TestThreadWakeup_CycleCount: x86-32_nnacl_newlib_static= {35653, 2052} count Apparent clock speed: 2211 MHz TestCatchingFault: Measuring real time: 4.083 usec (4.08337e-06 sec) per iteration: 0.000408337 sec for 100 iterations 2.481 usec (2.48123e-06 sec) per iteration: 0.0607628 sec for 24489 iterations 2.506 usec (2.5059e-06 sec) per iteration: 0.0613669 sec for 24489 iterations 2.504 usec (2.50411e-06 sec) per iteration: 0.0613231 sec for 24489 iterations 2.469 usec (2.46889e-06 sec) per iteration: 0.0604607 sec for 24489 iterations 2.473 usec (2.47292e-06 sec) per iteration: 0.0605593 sec for 24489 iterations mean: 2.486608 usec stddev: 0.015547 usec relative stddev: 0.63% RESULT TestCatchingFault: x86-32_nnacl_newlib_static= {2.486608, 0.015547} us Measuring clock cycles: first runs (cycles): 6103 5487 5440 5372 5383 5519 5524 5388 5400 5370 ... slowest runs (cycles): ... 5464 5471 5487 5491 5519 5524 5576 5702 6103 12301 min: 5357 cycles q1: 5389 cycles median: 5401 cycles q3: 5429 cycles max: 12301 cycles RESULT TestCatchingFault_CycleCount: x86-32_nnacl_newlib_static= {5401, 40} count Apparent clock speed: 2172 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 85.619 usec (8.56188e-05 sec) per iteration: 0.00856188 sec for 100 iterations 82.388 usec (8.23879e-05 sec) per iteration: 0.0961467 sec for 1167 iterations 83.467 usec (8.34669e-05 sec) per iteration: 0.0974058 sec for 1167 iterations 85.337 usec (8.53373e-05 sec) per iteration: 0.0995886 sec for 1167 iterations 80.896 usec (8.08958e-05 sec) per iteration: 0.0944054 sec for 1167 iterations 79.696 usec (7.96958e-05 sec) per iteration: 0.093005 sec for 1167 iterations mean: 82.356725 usec stddev: 1.966298 usec relative stddev: 2.39% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-32_nnacl_newlib_static= {82.356725, 1.966298} us Measuring clock cycles: first runs (cycles): 97764 183806 178566 155771 278276 387216 305821 236034 238213 218224 ... slowest runs (cycles): ... 278276 291308 302141 305821 324924 387216 392992 408933 436333 527496 min: 97764 cycles q1: 176294 cycles median: 183381 cycles q3: 203582 cycles max: 527496 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-32_nnacl_newlib_static= {183381, 27288} count Apparent clock speed: 2227 MHz [ OK ] nacl_newlib.run_performance_test (5670 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 (10220 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 (1010 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.008 usec (8.01e-09 sec) per iteration: 8.01e-07 sec for 100 iterations 0.003 usec (3.268e-09 sec) per iteration: 3.268e-06 sec for 1000 iterations 0.003 usec (3.0337e-09 sec) per iteration: 3.0337e-05 sec for 10000 iterations 0.003 usec (2.86877e-09 sec) per iteration: 0.0945634 sec for 32963048 iterations 0.003 usec (2.87089e-09 sec) per iteration: 0.0946333 sec for 32963048 iterations 0.003 usec (2.85504e-09 sec) per iteration: 0.0941108 sec for 32963048 iterations 0.003 usec (2.86096e-09 sec) per iteration: 0.094306 sec for 32963048 iterations 0.003 usec (2.88526e-09 sec) per iteration: 0.0951071 sec for 32963048 iterations mean: 0.002868 usec stddev: 0.000010 usec relative stddev: 0.36% RESULT TestNull: trusted_linux_x86-32= {0.002868, 0.000010} us Measuring clock cycles: first runs (cycles): 95 28 29 27 29 29 29 27 26 26 ... slowest runs (cycles): ... 31 31 31 31 31 31 31 31 31 95 min: 24 cycles q1: 27 cycles median: 28 cycles q3: 29 cycles max: 95 cycles RESULT TestNull_CycleCount: trusted_linux_x86-32= {28, 2} count Apparent clock speed: 9762 MHz TestHostSyscall: Measuring real time: 0.117 usec (1.1734e-07 sec) per iteration: 1.1734e-05 sec for 100 iterations 0.105 usec (1.05333e-07 sec) per iteration: 0.0897674 sec for 852224 iterations 0.105 usec (1.05415e-07 sec) per iteration: 0.0898373 sec for 852224 iterations 0.105 usec (1.05321e-07 sec) per iteration: 0.0897575 sec for 852224 iterations 0.105 usec (1.05417e-07 sec) per iteration: 0.0898389 sec for 852224 iterations 0.105 usec (1.05379e-07 sec) per iteration: 0.0898068 sec for 852224 iterations mean: 0.105373 usec stddev: 0.000040 usec relative stddev: 0.04% RESULT TestHostSyscall: trusted_linux_x86-32= {0.105373, 0.000040} us Measuring clock cycles: first runs (cycles): 435 255 255 251 250 248 249 253 253 253 ... slowest runs (cycles): ... 303 304 310 310 316 326 335 431 435 570 min: 246 cycles q1: 249 cycles median: 251 cycles q3: 272 cycles max: 570 cycles RESULT TestHostSyscall_CycleCount: trusted_linux_x86-32= {251, 23} count Apparent clock speed: 2382 MHz TestSetjmpLongjmp: Measuring real time: 0.094 usec (9.394e-08 sec) per iteration: 9.394e-06 sec for 100 iterations 0.034 usec (3.3752e-08 sec) per iteration: 3.3752e-05 sec for 1000 iterations 0.035 usec (3.50021e-08 sec) per iteration: 0.103704 sec for 2962787 iterations 0.035 usec (3.50699e-08 sec) per iteration: 0.103904 sec for 2962787 iterations 0.035 usec (3.50546e-08 sec) per iteration: 0.103859 sec for 2962787 iterations 0.035 usec (3.51969e-08 sec) per iteration: 0.104281 sec for 2962787 iterations 0.035 usec (3.50514e-08 sec) per iteration: 0.10385 sec for 2962787 iterations mean: 0.035075 usec stddev: 0.000065 usec relative stddev: 0.19% RESULT TestSetjmpLongjmp: trusted_linux_x86-32= {0.035075, 0.000065} us Measuring clock cycles: first runs (cycles): 178 605 106 104 104 106 99 102 97 103 ... slowest runs (cycles): ... 106 106 106 106 106 107 132 145 178 605 min: 94 cycles q1: 97 cycles median: 99 cycles q3: 101 cycles max: 605 cycles RESULT TestSetjmpLongjmp_CycleCount: trusted_linux_x86-32= {99, 4} count Apparent clock speed: 2823 MHz TestClockGetTime: Measuring real time: 0.199 usec (1.9895e-07 sec) per iteration: 1.9895e-05 sec for 100 iterations 0.200 usec (2.00237e-07 sec) per iteration: 0.100647 sec for 502638 iterations 0.200 usec (1.99882e-07 sec) per iteration: 0.100468 sec for 502638 iterations 0.200 usec (2.00444e-07 sec) per iteration: 0.100751 sec for 502638 iterations 0.199 usec (1.99249e-07 sec) per iteration: 0.10015 sec for 502638 iterations 0.200 usec (2.00113e-07 sec) per iteration: 0.100584 sec for 502638 iterations mean: 0.199985 usec stddev: 0.000411 usec relative stddev: 0.21% RESULT TestClockGetTime: trusted_linux_x86-32= {0.199985, 0.000411} us Measuring clock cycles: first runs (cycles): 616 473 473 478 477 475 476 480 475 475 ... slowest runs (cycles): ... 477 477 478 478 478 478 480 481 484 616 min: 467 cycles q1: 469 cycles median: 471 cycles q3: 473 cycles max: 616 cycles RESULT TestClockGetTime_CycleCount: trusted_linux_x86-32= {471, 4} count Apparent clock speed: 2355 MHz TestTlsVariable: Measuring real time: 0.019 usec (1.909e-08 sec) per iteration: 1.909e-06 sec for 100 iterations 0.015 usec (1.5007e-08 sec) per iteration: 1.5007e-05 sec for 1000 iterations 0.004 usec (4.39821e-09 sec) per iteration: 0.0293077 sec for 6663557 iterations 0.004 usec (4.37039e-09 sec) per iteration: 0.0291223 sec for 6663557 iterations 0.004 usec (4.362e-09 sec) per iteration: 0.0290664 sec for 6663557 iterations 0.004 usec (4.37166e-09 sec) per iteration: 0.0291308 sec for 6663557 iterations 0.004 usec (4.36376e-09 sec) per iteration: 0.0290782 sec for 6663557 iterations mean: 0.004373 usec stddev: 0.000013 usec relative stddev: 0.30% RESULT TestTlsVariable: trusted_linux_x86-32= {0.004373, 0.000013} us Measuring clock cycles: first runs (cycles): 90 33 31 33 35 35 33 33 31 31 ... slowest runs (cycles): ... 33 33 33 33 33 33 35 35 35 90 min: 26 cycles q1: 30 cycles median: 31 cycles q3: 31 cycles max: 90 cycles RESULT TestTlsVariable_CycleCount: trusted_linux_x86-32= {31, 1} count Apparent clock speed: 7089 MHz TestMmapAnonymous: Measuring real time: 0.954 usec (9.5379e-07 sec) per iteration: 9.5379e-05 sec for 100 iterations 0.799 usec (7.99279e-07 sec) per iteration: 0.0837996 sec for 104844 iterations 0.797 usec (7.96943e-07 sec) per iteration: 0.0835547 sec for 104844 iterations 0.796 usec (7.96415e-07 sec) per iteration: 0.0834994 sec for 104844 iterations 0.796 usec (7.96499e-07 sec) per iteration: 0.0835081 sec for 104844 iterations 0.802 usec (8.02308e-07 sec) per iteration: 0.0841172 sec for 104844 iterations mean: 0.798289 usec stddev: 0.002265 usec relative stddev: 0.28% RESULT TestMmapAnonymous: trusted_linux_x86-32= {0.798289, 0.002265} us Measuring clock cycles: first runs (cycles): 2448 1811 1736 1751 1733 1742 1749 1767 1749 1746 ... slowest runs (cycles): ... 1767 1770 1771 1771 1775 1777 1777 1798 1811 2448 min: 1722 cycles q1: 1734 cycles median: 1740 cycles q3: 1749 cycles max: 2448 cycles RESULT TestMmapAnonymous_CycleCount: trusted_linux_x86-32= {1740, 15} count Apparent clock speed: 2180 MHz TestAtomicIncrement: Measuring real time: 0.013 usec (1.336e-08 sec) per iteration: 1.336e-06 sec for 100 iterations 0.009 usec (9.463e-09 sec) per iteration: 9.463e-06 sec for 1000 iterations 0.009 usec (9.2903e-09 sec) per iteration: 9.2903e-05 sec for 10000 iterations 0.009 usec (9.39552e-09 sec) per iteration: 0.101133 sec for 10763915 iterations 0.009 usec (9.41224e-09 sec) per iteration: 0.101313 sec for 10763915 iterations 0.009 usec (9.3989e-09 sec) per iteration: 0.101169 sec for 10763915 iterations 0.009 usec (9.3935e-09 sec) per iteration: 0.101111 sec for 10763915 iterations 0.009 usec (9.40518e-09 sec) per iteration: 0.101237 sec for 10763915 iterations mean: 0.009401 usec stddev: 0.000007 usec relative stddev: 0.07% RESULT TestAtomicIncrement: trusted_linux_x86-32= {0.009401, 0.000007} us Measuring clock cycles: first runs (cycles): 105 57 60 59 62 61 60 55 55 55 ... slowest runs (cycles): ... 60 60 61 61 62 62 62 64 64 105 min: 55 cycles q1: 55 cycles median: 57 cycles q3: 57 cycles max: 105 cycles RESULT TestAtomicIncrement_CycleCount: trusted_linux_x86-32= {57, 2} count Apparent clock speed: 6063 MHz TestUncontendedMutexLock: Measuring real time: 0.073 usec (7.334e-08 sec) per iteration: 7.334e-06 sec for 100 iterations 0.061 usec (6.1052e-08 sec) per iteration: 6.1052e-05 sec for 1000 iterations 0.030 usec (2.9804e-08 sec) per iteration: 0.0488173 sec for 1637947 iterations 0.030 usec (2.97468e-08 sec) per iteration: 0.0487237 sec for 1637947 iterations 0.030 usec (2.96876e-08 sec) per iteration: 0.0486267 sec for 1637947 iterations 0.030 usec (2.96641e-08 sec) per iteration: 0.0485882 sec for 1637947 iterations 0.030 usec (2.96806e-08 sec) per iteration: 0.0486152 sec for 1637947 iterations mean: 0.029717 usec stddev: 0.000052 usec relative stddev: 0.17% RESULT TestUncontendedMutexLock: trusted_linux_x86-32= {0.029717, 0.000052} us Measuring clock cycles: first runs (cycles): 185 97 103 101 104 106 97 103 101 104 ... slowest runs (cycles): ... 106 106 106 106 107 107 108 108 110 185 min: 97 cycles q1: 102 cycles median: 103 cycles q3: 104 cycles max: 185 cycles RESULT TestUncontendedMutexLock_CycleCount: trusted_linux_x86-32= {103, 2} count Apparent clock speed: 3466 MHz TestCondvarSignalNoOp: Measuring real time: 0.030 usec (2.984e-08 sec) per iteration: 2.984e-06 sec for 100 iterations 0.024 usec (2.3582e-08 sec) per iteration: 2.3582e-05 sec for 1000 iterations 0.024 usec (2.37553e-08 sec) per iteration: 0.100735 sec for 4240522 iterations 0.024 usec (2.36963e-08 sec) per iteration: 0.100485 sec for 4240522 iterations 0.024 usec (2.36573e-08 sec) per iteration: 0.100319 sec for 4240522 iterations 0.024 usec (2.36566e-08 sec) per iteration: 0.100317 sec for 4240522 iterations 0.024 usec (2.36806e-08 sec) per iteration: 0.100418 sec for 4240522 iterations mean: 0.023689 usec stddev: 0.000036 usec relative stddev: 0.15% RESULT TestCondvarSignalNoOp: trusted_linux_x86-32= {0.023689, 0.000036} us Measuring clock cycles: first runs (cycles): 158 88 88 85 86 93 85 84 90 86 ... slowest runs (cycles): ... 88 90 90 90 92 93 93 95 97 158 min: 84 cycles q1: 86 cycles median: 86 cycles q3: 88 cycles max: 158 cycles RESULT TestCondvarSignalNoOp_CycleCount: trusted_linux_x86-32= {86, 2} count Apparent clock speed: 3630 MHz TestThreadCreateAndJoin: Measuring real time: 35.876 usec (3.58759e-05 sec) per iteration: 0.00358759 sec for 100 iterations 21.357 usec (2.13574e-05 sec) per iteration: 0.0595232 sec for 2787 iterations 21.646 usec (2.16456e-05 sec) per iteration: 0.0603264 sec for 2787 iterations 20.808 usec (2.08076e-05 sec) per iteration: 0.0579909 sec for 2787 iterations 22.038 usec (2.20382e-05 sec) per iteration: 0.0614206 sec for 2787 iterations 21.531 usec (2.15306e-05 sec) per iteration: 0.0600057 sec for 2787 iterations mean: 21.475904 usec stddev: 0.402150 usec relative stddev: 1.87% RESULT TestThreadCreateAndJoin: trusted_linux_x86-32= {21.475904, 0.402150} us Measuring clock cycles: first runs (cycles): 62525 63395 62287 46720 43833 45209 304544 85032 42010 43301 ... slowest runs (cycles): ... 63839 63890 66507 66566 75187 75326 83494 85032 85941 304544 min: 40322 cycles q1: 42335 cycles median: 44679 cycles q3: 46651 cycles max: 304544 cycles RESULT TestThreadCreateAndJoin_CycleCount: trusted_linux_x86-32= {44679, 4316} count Apparent clock speed: 2080 MHz TestThreadWakeup: Measuring real time: 14.165 usec (1.41654e-05 sec) per iteration: 0.00141654 sec for 100 iterations 13.293 usec (1.32929e-05 sec) per iteration: 0.0938346 sec for 7059 iterations 15.397 usec (1.5397e-05 sec) per iteration: 0.108688 sec for 7059 iterations 18.460 usec (1.84604e-05 sec) per iteration: 0.130312 sec for 7059 iterations 16.452 usec (1.6452e-05 sec) per iteration: 0.116135 sec for 7059 iterations 15.694 usec (1.56937e-05 sec) per iteration: 0.110782 sec for 7059 iterations mean: 15.859200 usec stddev: 1.669996 usec relative stddev: 10.53% RESULT TestThreadWakeup: trusted_linux_x86-32= {15.859200, 1.669996} us Measuring clock cycles: first runs (cycles): 26846 25597 25610 25386 24990 25397 25762 25487 25601 25601 ... slowest runs (cycles): ... 31629 31882 31910 31954 32056 33224 33475 33890 36398 40226 min: 24640 cycles q1: 25331 cycles median: 25549 cycles q3: 26265 cycles max: 40226 cycles RESULT TestThreadWakeup_CycleCount: trusted_linux_x86-32= {25549, 934} count Apparent clock speed: 1611 MHz WARNING: should have taken less than 2.000000 secs [ OK ] nacl_newlib.run_trusted_performance_test (4720 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