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-64 --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-64', '--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: 7890324 kB ====================================================================== LOAD: ====================================================================== 1.57 1.42 0.67 1/294 29863 ====================================================================== UPTIME: ====================================================================== 8636.27 65950.56 ====================================================================== 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-64 OBJ_ROOT: /mnt/data/b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-64/obj BUILD_TYPE_DESCRIPTION: NaCl module build BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 64 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 64 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 -m64 -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=64 -I/mnt/data/b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-64/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-64 OBJ_ROOT: /mnt/data/b/s/w/ir/kitchen-workdir/native_client/scons-out/dbg-linux-x86-64/obj BUILD_TYPE_DESCRIPTION: Linux dbg build BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 64 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 64 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 -m64 -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-64 OBJ_ROOT: /mnt/data/b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt-x86-64/obj BUILD_TYPE_DESCRIPTION: NaCl IRT build BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 64 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 64 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 -m64 -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=64 -I/mnt/data/b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt-x86-64/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_vla_test *** BROKEN run_nacl_sync_cond_test SKIPPING test text_overlaps_rodata SKIPPING test text_overlaps_data There are 13 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-64_nnacl_newlib_static --subarch 64 --arch x86 --capture_output 0 --output_stamp /mnt/data/b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-64/test_results/signal_handler_single_step.out /mnt/data/b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-64/obj/tests/signal_handler_single_step/step_test_host /mnt/data/b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-64/obj/tests/signal_handler_single_step/step_test_guest.nexe x86-64_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-64/obj/tests/signal_handler_single_step/step_test_host /mnt/data/b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-64/obj/tests/signal_handler_single_step/step_test_guest.nexe x86-64_nnacl_newlib_static [29871,3492784000:10:24:31.565939] Native Client module will be loaded at base address 0x0000752200000000 Switching to untrusted: since previous switch: 330 instructions, 1466 instruction bytes, 31 jumps Switching to trusted: since previous switch: 11 instructions, 48 instruction bytes, 2 jumps Switching to untrusted: since previous switch: 572 instructions, 1957 instruction bytes, 72 jumps Switching to trusted: since previous switch: 9 instructions, 32 instruction bytes, 3 jumps Switching to untrusted: since previous switch: 572 instructions, 1957 instruction bytes, 72 jumps Switching to trusted: since previous switch: 9 instructions, 32 instruction bytes, 3 jumps Switching to untrusted: since previous switch: 572 instructions, 1957 instruction bytes, 72 jumps Switching to trusted: since previous switch: 9 instructions, 32 instruction bytes, 3 jumps Switching to untrusted: since previous switch: 572 instructions, 1957 instruction bytes, 72 jumps RESULT InstructionsPerSyscall: x86-64_nnacl_newlib_static= 572 count RESULT InstructionBytesPerSyscall: x86-64_nnacl_newlib_static= 1957 count RESULT JumpsPerSyscall: x86-64_nnacl_newlib_static= 72 count Switching to trusted: since previous switch: 9 instructions, 32 instruction bytes, 3 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-64_nnacl_newlib_static --using_nacl_signal_handler True --subarch 64 --arch x86 --capture_output 0 --output_stamp /mnt/data/b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-64/test_results/benchmark_test.out scons-out/dbg-linux-x86-64/staging/nacl_helper_bootstrap scons-out/dbg-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -- scons-out/nacl-x86-64/obj/tests/benchmark/benchmark_test.nexe x86-64_nnacl_newlib_static [ RUN ] nacl_newlib.run_benchmark_test scons-out/dbg-linux-x86-64/staging/nacl_helper_bootstrap scons-out/dbg-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -- scons-out/nacl-x86-64/obj/tests/benchmark/benchmark_test.nexe x86-64_nnacl_newlib_static [29876,3313063808:10:24:31.835680] Native Client module will be loaded at base address 0x00003f0400000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_nnacl_newlib_static= {0.072550, 0.020880} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test (900 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-64_nnacl_newlib_static --using_nacl_signal_handler True --subarch 64 --arch x86 --output_stamp /mnt/data/b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-64/test_results/clock_cputime_test.out scons-out/dbg-linux-x86-64/staging/nacl_helper_bootstrap scons-out/dbg-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -- scons-out/nacl-x86-64/obj/tests/clock/clock_test.nexe -c [ RUN ] nacl_newlib.run_clock_cputime_test scons-out/dbg-linux-x86-64/staging/nacl_helper_bootstrap scons-out/dbg-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -- scons-out/nacl-x86-64/obj/tests/clock/clock_test.nexe -c WARNING: should have taken less than 60.000000 secs [ OK ] nacl_newlib.run_clock_cputime_test (61680 ms) doSizeCheck(["scons-out/nacl-x86-64/obj/tests/hello_world/hello_world_size.out"], ["scons-out/nacl-x86-64/obj/tests/hello_world/hello_world.nexe"]) RESULT hello_world_size.out: x86-64_nnacl_newlib_static= 528.348 KB RESULT hello_world_size.out: ZIPPED_x86-64_nnacl_newlib_static= 187.912 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-64_nnacl_newlib_static --using_nacl_signal_handler True --subarch 64 --arch x86 --capture_output 0 --output_stamp /mnt/data/b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-64/test_results/performance_test.out scons-out/dbg-linux-x86-64/staging/nacl_helper_bootstrap scons-out/dbg-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -e -- scons-out/nacl-x86-64/obj/tests/performance/performance_test.nexe x86-64_nnacl_newlib_static [ RUN ] nacl_newlib.run_performance_test scons-out/dbg-linux-x86-64/staging/nacl_helper_bootstrap scons-out/dbg-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -e -- scons-out/nacl-x86-64/obj/tests/performance/performance_test.nexe x86-64_nnacl_newlib_static [29910,3751282560:10:24:44.912224] Native Client module will be loaded at base address 0x0000004d00000000 TestNull: Measuring real time: 0.007 usec (7.07e-09 sec) per iteration: 7.07e-07 sec for 100 iterations 0.002 usec (2.323e-09 sec) per iteration: 2.323e-06 sec for 1000 iterations 0.002 usec (2.035e-09 sec) per iteration: 2.035e-05 sec for 10000 iterations 0.002 usec (2.01099e-09 sec) per iteration: 0.0988204 sec for 49140049 iterations 0.002 usec (2.01302e-09 sec) per iteration: 0.0989201 sec for 49140049 iterations 0.002 usec (2.00945e-09 sec) per iteration: 0.0987445 sec for 49140049 iterations 0.002 usec (2.0082e-09 sec) per iteration: 0.0986831 sec for 49140049 iterations 0.002 usec (2.00837e-09 sec) per iteration: 0.0986913 sec for 49140049 iterations mean: 0.002010 usec stddev: 0.000002 usec relative stddev: 0.09% RESULT TestNull: x86-64_nnacl_newlib_static= {0.002010, 0.000002} us Measuring clock cycles: first runs (cycles): 468 25 28 25 21 21 21 21 21 21 ... slowest runs (cycles): ... 28 28 28 28 28 28 28 28 28 468 min: 18 cycles q1: 18 cycles median: 21 cycles q3: 25 cycles max: 468 cycles RESULT TestNull_CycleCount: x86-64_nnacl_newlib_static= {21, 7} count Apparent clock speed: 10448 MHz TestNaClSyscall: Measuring real time: 0.166 usec (1.6552e-07 sec) per iteration: 1.6552e-05 sec for 100 iterations 0.156 usec (1.5569e-07 sec) per iteration: 0.0940611 sec for 604156 iterations 0.156 usec (1.55584e-07 sec) per iteration: 0.0939972 sec for 604156 iterations 0.155 usec (1.55349e-07 sec) per iteration: 0.0938548 sec for 604156 iterations 0.154 usec (1.53745e-07 sec) per iteration: 0.0928857 sec for 604156 iterations 0.159 usec (1.59012e-07 sec) per iteration: 0.0960679 sec for 604156 iterations mean: 0.155876 usec stddev: 0.001719 usec relative stddev: 1.10% RESULT TestNaClSyscall: x86-64_nnacl_newlib_static= {0.155876, 0.001719} us Measuring clock cycles: first runs (cycles): 1554 441 520 465 416 426 416 429 416 419 ... slowest runs (cycles): ... 484 486 487 489 499 514 520 581 600 1554 min: 406 cycles q1: 416 cycles median: 422 cycles q3: 452 cycles max: 1554 cycles RESULT TestNaClSyscall_CycleCount: x86-64_nnacl_newlib_static= {422, 36} count Apparent clock speed: 2707 MHz TestSetjmpLongjmp: Measuring real time: 0.020 usec (1.984e-08 sec) per iteration: 1.984e-06 sec for 100 iterations 0.013 usec (1.3303e-08 sec) per iteration: 1.3303e-05 sec for 1000 iterations 0.013 usec (1.26733e-08 sec) per iteration: 0.0952662 sec for 7517101 iterations 0.013 usec (1.26734e-08 sec) per iteration: 0.0952675 sec for 7517101 iterations 0.013 usec (1.26892e-08 sec) per iteration: 0.0953857 sec for 7517101 iterations 0.013 usec (1.26694e-08 sec) per iteration: 0.0952374 sec for 7517101 iterations 0.013 usec (1.26652e-08 sec) per iteration: 0.0952058 sec for 7517101 iterations mean: 0.012674 usec stddev: 0.000008 usec relative stddev: 0.06% RESULT TestSetjmpLongjmp: x86-64_nnacl_newlib_static= {0.012674, 0.000008} us Measuring clock cycles: first runs (cycles): 697 61 52 40 40 40 40 27 37 37 ... slowest runs (cycles): ... 40 40 40 43 43 43 43 52 61 697 min: 27 cycles q1: 33 cycles median: 40 cycles q3: 40 cycles max: 697 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_nnacl_newlib_static= {40, 7} count Apparent clock speed: 3156 MHz TestClockGetTime: Measuring real time: 0.262 usec (2.6165e-07 sec) per iteration: 2.6165e-05 sec for 100 iterations 0.252 usec (2.52246e-07 sec) per iteration: 0.0964056 sec for 382189 iterations 0.254 usec (2.54016e-07 sec) per iteration: 0.0970822 sec for 382189 iterations 0.251 usec (2.50881e-07 sec) per iteration: 0.0958841 sec for 382189 iterations 0.253 usec (2.526e-07 sec) per iteration: 0.0965408 sec for 382189 iterations 0.250 usec (2.49787e-07 sec) per iteration: 0.0954658 sec for 382189 iterations mean: 0.251906 usec stddev: 0.001455 usec relative stddev: 0.58% RESULT TestClockGetTime: x86-64_nnacl_newlib_static= {0.251906, 0.001455} us Measuring clock cycles: first runs (cycles): 3628 835 792 710 679 670 673 676 685 660 ... slowest runs (cycles): ... 710 710 722 722 731 749 758 792 835 3628 min: 642 cycles q1: 660 cycles median: 664 cycles q3: 670 cycles max: 3628 cycles RESULT TestClockGetTime_CycleCount: x86-64_nnacl_newlib_static= {664, 10} count Apparent clock speed: 2636 MHz TestTlsVariable: Measuring real time: 0.013 usec (1.299e-08 sec) per iteration: 1.299e-06 sec for 100 iterations 0.007 usec (6.615e-09 sec) per iteration: 6.615e-06 sec for 1000 iterations 0.006 usec (6.061e-09 sec) per iteration: 6.061e-05 sec for 10000 iterations 0.006 usec (6.01996e-09 sec) per iteration: 0.0993229 sec for 16498927 iterations 0.006 usec (6.02363e-09 sec) per iteration: 0.0993834 sec for 16498927 iterations 0.006 usec (6.02749e-09 sec) per iteration: 0.0994471 sec for 16498927 iterations 0.006 usec (6.02773e-09 sec) per iteration: 0.099451 sec for 16498927 iterations 0.006 usec (6.02933e-09 sec) per iteration: 0.0994775 sec for 16498927 iterations mean: 0.006026 usec stddev: 0.000003 usec relative stddev: 0.06% RESULT TestTlsVariable: x86-64_nnacl_newlib_static= {0.006026, 0.000003} us Measuring clock cycles: first runs (cycles): 557 74 31 28 28 21 21 21 21 21 ... slowest runs (cycles): ... 28 28 28 28 28 28 28 31 74 557 min: 21 cycles q1: 21 cycles median: 21 cycles q3: 28 cycles max: 557 cycles RESULT TestTlsVariable_CycleCount: x86-64_nnacl_newlib_static= {21, 7} count Apparent clock speed: 3485 MHz TestMmapAnonymous: Measuring real time: 3.207 usec (3.20663e-06 sec) per iteration: 0.000320663 sec for 100 iterations 3.005 usec (3.00527e-06 sec) per iteration: 0.0937195 sec for 31185 iterations 3.001 usec (3.00057e-06 sec) per iteration: 0.0935727 sec for 31185 iterations 3.004 usec (3.0042e-06 sec) per iteration: 0.0936859 sec for 31185 iterations 3.002 usec (3.00202e-06 sec) per iteration: 0.0936181 sec for 31185 iterations 3.002 usec (3.00197e-06 sec) per iteration: 0.0936163 sec for 31185 iterations mean: 3.002805 usec stddev: 0.001694 usec relative stddev: 0.06% RESULT TestMmapAnonymous: x86-64_nnacl_newlib_static= {3.002805, 0.001694} us Measuring clock cycles: first runs (cycles): 24562 9097 8537 8375 8385 8400 8314 8137 8118 8173 ... slowest runs (cycles): ... 8173 8222 8244 8314 8375 8385 8400 8537 9097 24562 min: 7693 cycles q1: 7907 cycles median: 7959 cycles q3: 8054 cycles max: 24562 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_nnacl_newlib_static= {7959, 147} count Apparent clock speed: 2651 MHz TestAtomicIncrement: Measuring real time: 0.017 usec (1.721e-08 sec) per iteration: 1.721e-06 sec for 100 iterations 0.011 usec (1.0538e-08 sec) per iteration: 1.0538e-05 sec for 1000 iterations 0.010 usec (1.00265e-08 sec) per iteration: 0.0951459 sec for 9489466 iterations 0.010 usec (1.00282e-08 sec) per iteration: 0.0951627 sec for 9489466 iterations 0.010 usec (1.00474e-08 sec) per iteration: 0.0953447 sec for 9489466 iterations 0.010 usec (1.00318e-08 sec) per iteration: 0.095196 sec for 9489466 iterations 0.010 usec (1.00292e-08 sec) per iteration: 0.0951715 sec for 9489466 iterations mean: 0.010033 usec stddev: 0.000008 usec relative stddev: 0.08% RESULT TestAtomicIncrement: x86-64_nnacl_newlib_static= {0.010033, 0.000008} us Measuring clock cycles: first runs (cycles): 432 58 52 55 49 49 49 49 49 67 ... slowest runs (cycles): ... 49 52 55 55 58 61 67 67 74 432 min: 42 cycles q1: 49 cycles median: 49 cycles q3: 49 cycles max: 432 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_nnacl_newlib_static= {49, 0} count Apparent clock speed: 4884 MHz TestUncontendedMutexLock: Measuring real time: 0.031 usec (3.069e-08 sec) per iteration: 3.069e-06 sec for 100 iterations 0.024 usec (2.3924e-08 sec) per iteration: 2.3924e-05 sec for 1000 iterations 0.023 usec (2.34057e-08 sec) per iteration: 0.0978336 sec for 4179903 iterations 0.023 usec (2.3407e-08 sec) per iteration: 0.0978388 sec for 4179903 iterations 0.023 usec (2.34067e-08 sec) per iteration: 0.0978379 sec for 4179903 iterations 0.023 usec (2.34171e-08 sec) per iteration: 0.097881 sec for 4179903 iterations 0.023 usec (2.34038e-08 sec) per iteration: 0.0978258 sec for 4179903 iterations mean: 0.023408 usec stddev: 0.000005 usec relative stddev: 0.02% RESULT TestUncontendedMutexLock: x86-64_nnacl_newlib_static= {0.023408, 0.000005} us Measuring clock cycles: first runs (cycles): 642 116 88 82 82 89 86 86 89 89 ... slowest runs (cycles): ... 89 89 89 89 101 104 107 110 116 642 min: 79 cycles q1: 82 cycles median: 86 cycles q3: 89 cycles max: 642 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_nnacl_newlib_static= {86, 7} count Apparent clock speed: 3674 MHz TestCondvarSignalNoOp: Measuring real time: 0.381 usec (3.8057e-07 sec) per iteration: 3.8057e-05 sec for 100 iterations 0.325 usec (3.24526e-07 sec) per iteration: 0.0852733 sec for 262763 iterations 0.324 usec (3.24279e-07 sec) per iteration: 0.0852084 sec for 262763 iterations 0.325 usec (3.24515e-07 sec) per iteration: 0.0852704 sec for 262763 iterations 0.325 usec (3.24775e-07 sec) per iteration: 0.0853389 sec for 262763 iterations 0.324 usec (3.24365e-07 sec) per iteration: 0.085231 sec for 262763 iterations mean: 0.324492 usec stddev: 0.000169 usec relative stddev: 0.05% RESULT TestCondvarSignalNoOp: x86-64_nnacl_newlib_static= {0.324492, 0.000169} us Measuring clock cycles: first runs (cycles): 3514 896 838 912 1037 985 869 847 844 869 ... slowest runs (cycles): ... 909 909 911 912 912 921 945 985 1037 3514 min: 829 cycles q1: 835 cycles median: 841 cycles q3: 869 cycles max: 3514 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_nnacl_newlib_static= {841, 34} count Apparent clock speed: 2592 MHz TestThreadCreateAndJoin: Measuring real time: 50.983 usec (5.09835e-05 sec) per iteration: 0.00509835 sec for 100 iterations 52.922 usec (5.29222e-05 sec) per iteration: 0.10378 sec for 1961 iterations 52.297 usec (5.22971e-05 sec) per iteration: 0.102555 sec for 1961 iterations 52.508 usec (5.25076e-05 sec) per iteration: 0.102967 sec for 1961 iterations 51.477 usec (5.14771e-05 sec) per iteration: 0.100947 sec for 1961 iterations 50.877 usec (5.08768e-05 sec) per iteration: 0.0997693 sec for 1961 iterations mean: 52.016155 usec stddev: 0.738953 usec relative stddev: 1.42% RESULT TestThreadCreateAndJoin: x86-64_nnacl_newlib_static= {52.016155, 0.738953} us Measuring clock cycles: first runs (cycles): 95013 129991 130939 126214 128853 130407 130917 127941 136044 129651 ... slowest runs (cycles): ... 133799 134512 135794 135974 136044 136744 137555 146897 151986 153284 min: 95013 cycles q1: 129211 cycles median: 130061 cycles q3: 131334 cycles max: 153284 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_nnacl_newlib_static= {130061, 2123} count Apparent clock speed: 2500 MHz TestThreadWakeup: Measuring real time: 18.669 usec (1.86694e-05 sec) per iteration: 0.00186694 sec for 100 iterations 18.290 usec (1.82903e-05 sec) per iteration: 0.0979629 sec for 5356 iterations 17.862 usec (1.7862e-05 sec) per iteration: 0.0956687 sec for 5356 iterations 17.698 usec (1.76985e-05 sec) per iteration: 0.0947931 sec for 5356 iterations 17.713 usec (1.77127e-05 sec) per iteration: 0.094869 sec for 5356 iterations 17.823 usec (1.78234e-05 sec) per iteration: 0.0954623 sec for 5356 iterations mean: 17.877371 usec stddev: 0.215778 usec relative stddev: 1.21% RESULT TestThreadWakeup: x86-64_nnacl_newlib_static= {17.877371, 0.215778} us Measuring clock cycles: first runs (cycles): 64254 48736 46772 46237 45831 46898 47754 47733 47987 47724 ... slowest runs (cycles): ... 47338 47449 47703 47724 47733 47754 47987 48736 55897 64254 min: 45047 cycles q1: 45479 cycles median: 45775 cycles q3: 46210 cycles max: 64254 cycles RESULT TestThreadWakeup_CycleCount: x86-64_nnacl_newlib_static= {45775, 731} count Apparent clock speed: 2560 MHz TestCatchingFault: Measuring real time: 1.986 usec (1.98611e-06 sec) per iteration: 0.000198611 sec for 100 iterations 1.876 usec (1.87594e-06 sec) per iteration: 0.0944515 sec for 50349 iterations 1.860 usec (1.8603e-06 sec) per iteration: 0.0936641 sec for 50349 iterations 1.868 usec (1.86835e-06 sec) per iteration: 0.0940693 sec for 50349 iterations 1.868 usec (1.86831e-06 sec) per iteration: 0.0940678 sec for 50349 iterations 1.858 usec (1.85803e-06 sec) per iteration: 0.0935501 sec for 50349 iterations mean: 1.866185 usec stddev: 0.006410 usec relative stddev: 0.34% RESULT TestCatchingFault: x86-64_nnacl_newlib_static= {1.866185, 0.006410} us Measuring clock cycles: first runs (cycles): 13814 5454 5258 5005 5142 5035 5059 5008 5007 4995 ... slowest runs (cycles): ... 5035 5056 5059 5096 5123 5142 5160 5258 5454 13814 min: 4925 cycles q1: 4958 cycles median: 4967 cycles q3: 4974 cycles max: 13814 cycles RESULT TestCatchingFault_CycleCount: x86-64_nnacl_newlib_static= {4967, 16} count Apparent clock speed: 2662 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 49.023 usec (4.90225e-05 sec) per iteration: 0.00490225 sec for 100 iterations 48.967 usec (4.89669e-05 sec) per iteration: 0.0998434 sec for 2039 iterations 48.064 usec (4.8064e-05 sec) per iteration: 0.0980025 sec for 2039 iterations 48.598 usec (4.85976e-05 sec) per iteration: 0.0990905 sec for 2039 iterations 47.917 usec (4.79166e-05 sec) per iteration: 0.0977019 sec for 2039 iterations 48.012 usec (4.80117e-05 sec) per iteration: 0.0978958 sec for 2039 iterations mean: 48.311346 usec stddev: 0.404590 usec relative stddev: 0.84% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_nnacl_newlib_static= {48.311346, 0.404590} us Measuring clock cycles: first runs (cycles): 105031 143682 130918 88728 127143 125599 124574 123470 126014 126290 ... slowest runs (cycles): ... 127666 127984 129082 129866 130918 134934 143682 152477 154363 182906 min: 83546 cycles q1: 123589 cycles median: 124659 cycles q3: 126057 cycles max: 182906 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_nnacl_newlib_static= {124659, 2468} count Apparent clock speed: 2580 MHz [ OK ] nacl_newlib.run_performance_test (6380 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-64 --subarch 64 --arch x86 --output_stamp /mnt/data/b/s/w/ir/kitchen-workdir/native_client/scons-out/dbg-linux-x86-64/test_results/nacl_clock_cputime_test.out /mnt/data/b/s/w/ir/kitchen-workdir/native_client/scons-out/dbg-linux-x86-64/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-64/obj/src/shared/platform/nacl_clock_test -c [ OK ] nacl_newlib.run_nacl_clock_cputime_test (9230 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-64 --subarch 64 --arch x86 --output_stamp /mnt/data/b/s/w/ir/kitchen-workdir/native_client/scons-out/dbg-linux-x86-64/test_results/nacl_interval_multi_reg_test.out /mnt/data/b/s/w/ir/kitchen-workdir/native_client/scons-out/dbg-linux-x86-64/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-64/obj/src/trusted/interval_multiset/nacl_interval_test -k NaClIntervalListMultiset -k NaClIntervalRangeTree -c 1000000 [ OK ] nacl_newlib.run_nacl_interval_multi_reg_test (1110 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-64 --subarch 64 --arch x86 --capture_output 0 --output_stamp /mnt/data/b/s/w/ir/kitchen-workdir/native_client/scons-out/dbg-linux-x86-64/test_results/performance_test.out /mnt/data/b/s/w/ir/kitchen-workdir/native_client/scons-out/dbg-linux-x86-64/obj/tests/performance/performance_test trusted_linux_x86-64 [ RUN ] nacl_newlib.run_trusted_performance_test /mnt/data/b/s/w/ir/kitchen-workdir/native_client/scons-out/dbg-linux-x86-64/obj/tests/performance/performance_test trusted_linux_x86-64 TestNull: Measuring real time: 0.009 usec (8.81e-09 sec) per iteration: 8.81e-07 sec for 100 iterations 0.003 usec (2.981e-09 sec) per iteration: 2.981e-06 sec for 1000 iterations 0.003 usec (2.8599e-09 sec) per iteration: 2.8599e-05 sec for 10000 iterations 0.003 usec (2.87834e-09 sec) per iteration: 0.100645 sec for 34966257 iterations 0.003 usec (2.86028e-09 sec) per iteration: 0.100013 sec for 34966257 iterations 0.003 usec (2.8565e-09 sec) per iteration: 0.099881 sec for 34966257 iterations 0.003 usec (2.8585e-09 sec) per iteration: 0.0999509 sec for 34966257 iterations 0.003 usec (2.85715e-09 sec) per iteration: 0.0999038 sec for 34966257 iterations mean: 0.002862 usec stddev: 0.000008 usec relative stddev: 0.29% RESULT TestNull: trusted_linux_x86-64= {0.002862, 0.000008} us Measuring clock cycles: first runs (cycles): 85 24 21 21 21 21 21 21 21 21 ... slowest runs (cycles): ... 28 28 28 28 28 28 28 28 28 85 min: 21 cycles q1: 21 cycles median: 21 cycles q3: 28 cycles max: 85 cycles RESULT TestNull_CycleCount: trusted_linux_x86-64= {21, 7} count Apparent clock speed: 7337 MHz TestHostSyscall: Measuring real time: 0.067 usec (6.659e-08 sec) per iteration: 6.659e-06 sec for 100 iterations 0.055 usec (5.5059e-08 sec) per iteration: 5.5059e-05 sec for 1000 iterations 0.055 usec (5.53595e-08 sec) per iteration: 0.100546 sec for 1816233 iterations 0.055 usec (5.53733e-08 sec) per iteration: 0.100571 sec for 1816233 iterations 0.055 usec (5.53654e-08 sec) per iteration: 0.100556 sec for 1816233 iterations 0.055 usec (5.53175e-08 sec) per iteration: 0.100469 sec for 1816233 iterations 0.055 usec (5.53973e-08 sec) per iteration: 0.100614 sec for 1816233 iterations mean: 0.055363 usec stddev: 0.000026 usec relative stddev: 0.05% RESULT TestHostSyscall: trusted_linux_x86-64= {0.055363, 0.000026} us Measuring clock cycles: first runs (cycles): 1119 183 180 159 180 183 159 156 162 159 ... slowest runs (cycles): ... 166 166 166 169 169 180 180 183 183 1119 min: 156 cycles q1: 159 cycles median: 159 cycles q3: 162 cycles max: 1119 cycles RESULT TestHostSyscall_CycleCount: trusted_linux_x86-64= {159, 3} count Apparent clock speed: 2872 MHz TestSetjmpLongjmp: Measuring real time: 0.053 usec (5.303e-08 sec) per iteration: 5.303e-06 sec for 100 iterations 0.028 usec (2.8125e-08 sec) per iteration: 2.8125e-05 sec for 1000 iterations 0.027 usec (2.72227e-08 sec) per iteration: 0.0967917 sec for 3555555 iterations 0.027 usec (2.72094e-08 sec) per iteration: 0.0967447 sec for 3555555 iterations 0.027 usec (2.71962e-08 sec) per iteration: 0.0966976 sec for 3555555 iterations 0.027 usec (2.72077e-08 sec) per iteration: 0.0967386 sec for 3555555 iterations 0.027 usec (2.71973e-08 sec) per iteration: 0.0967013 sec for 3555555 iterations mean: 0.027207 usec stddev: 0.000010 usec relative stddev: 0.04% RESULT TestSetjmpLongjmp: trusted_linux_x86-64= {0.027207, 0.000010} us Measuring clock cycles: first runs (cycles): 1386 107 82 76 73 73 70 76 70 76 ... slowest runs (cycles): ... 83 83 83 83 83 83 83 83 107 1386 min: 70 cycles q1: 73 cycles median: 77 cycles q3: 80 cycles max: 1386 cycles RESULT TestSetjmpLongjmp_CycleCount: trusted_linux_x86-64= {77, 7} count Apparent clock speed: 2830 MHz TestClockGetTime: Measuring real time: 0.026 usec (2.601e-08 sec) per iteration: 2.601e-06 sec for 100 iterations 0.024 usec (2.3732e-08 sec) per iteration: 2.3732e-05 sec for 1000 iterations 0.023 usec (2.31274e-08 sec) per iteration: 0.0974525 sec for 4213719 iterations 0.023 usec (2.31844e-08 sec) per iteration: 0.0976927 sec for 4213719 iterations 0.023 usec (2.32292e-08 sec) per iteration: 0.0978813 sec for 4213719 iterations 0.023 usec (2.32648e-08 sec) per iteration: 0.0980314 sec for 4213719 iterations 0.023 usec (2.3202e-08 sec) per iteration: 0.0977669 sec for 4213719 iterations mean: 0.023202 usec stddev: 0.000046 usec relative stddev: 0.20% RESULT TestClockGetTime: trusted_linux_x86-64= {0.023202, 0.000046} us Measuring clock cycles: first runs (cycles): 1217 95 95 83 80 77 77 74 70 73 ... slowest runs (cycles): ... 77 77 77 77 77 80 83 95 95 1217 min: 67 cycles q1: 70 cycles median: 70 cycles q3: 77 cycles max: 1217 cycles RESULT TestClockGetTime_CycleCount: trusted_linux_x86-64= {70, 7} count Apparent clock speed: 3017 MHz TestTlsVariable: Measuring real time: 0.006 usec (6.4e-09 sec) per iteration: 6.4e-07 sec for 100 iterations 0.004 usec (4.228e-09 sec) per iteration: 4.228e-06 sec for 1000 iterations 0.004 usec (4.0213e-09 sec) per iteration: 4.0213e-05 sec for 10000 iterations 0.004 usec (4.02283e-09 sec) per iteration: 0.100038 sec for 24867580 iterations 0.004 usec (4.01923e-09 sec) per iteration: 0.0999484 sec for 24867580 iterations 0.004 usec (4.01638e-09 sec) per iteration: 0.0998777 sec for 24867580 iterations 0.004 usec (4.01799e-09 sec) per iteration: 0.0999176 sec for 24867580 iterations 0.004 usec (4.01624e-09 sec) per iteration: 0.0998742 sec for 24867580 iterations mean: 0.004019 usec stddev: 0.000002 usec relative stddev: 0.06% RESULT TestTlsVariable: trusted_linux_x86-64= {0.004019, 0.000002} us Measuring clock cycles: first runs (cycles): 523 46 28 28 28 28 28 28 28 28 ... slowest runs (cycles): ... 28 28 28 28 28 28 28 28 46 523 min: 21 cycles q1: 21 cycles median: 21 cycles q3: 28 cycles max: 523 cycles RESULT TestTlsVariable_CycleCount: trusted_linux_x86-64= {21, 7} count Apparent clock speed: 5226 MHz TestMmapAnonymous: Measuring real time: 0.771 usec (7.7101e-07 sec) per iteration: 7.7101e-05 sec for 100 iterations 0.690 usec (6.89686e-07 sec) per iteration: 0.0894523 sec for 129700 iterations 0.689 usec (6.89181e-07 sec) per iteration: 0.0893868 sec for 129700 iterations 0.690 usec (6.89528e-07 sec) per iteration: 0.0894318 sec for 129700 iterations 0.690 usec (6.90046e-07 sec) per iteration: 0.0894989 sec for 129700 iterations 0.689 usec (6.89053e-07 sec) per iteration: 0.0893701 sec for 129700 iterations mean: 0.689499 usec stddev: 0.000356 usec relative stddev: 0.05% RESULT TestMmapAnonymous: trusted_linux_x86-64= {0.689499, 0.000356} us Measuring clock cycles: first runs (cycles): 8806 2122 1866 1927 1832 1777 1762 1759 1750 1777 ... slowest runs (cycles): ... 1893 1893 1893 1905 1905 1909 1918 1927 2122 8806 min: 1731 cycles q1: 1759 cycles median: 1774 cycles q3: 1850 cycles max: 8806 cycles RESULT TestMmapAnonymous_CycleCount: trusted_linux_x86-64= {1774, 91} count Apparent clock speed: 2573 MHz TestAtomicIncrement: Measuring real time: 0.013 usec (1.254e-08 sec) per iteration: 1.254e-06 sec for 100 iterations 0.010 usec (1.0201e-08 sec) per iteration: 1.0201e-05 sec for 1000 iterations 0.010 usec (1.00379e-08 sec) per iteration: 0.0984011 sec for 9802960 iterations 0.010 usec (1.00717e-08 sec) per iteration: 0.0987328 sec for 9802960 iterations 0.010 usec (1.00375e-08 sec) per iteration: 0.098397 sec for 9802960 iterations 0.010 usec (1.00361e-08 sec) per iteration: 0.0983832 sec for 9802960 iterations 0.010 usec (1.00359e-08 sec) per iteration: 0.0983811 sec for 9802960 iterations mean: 0.010044 usec stddev: 0.000014 usec relative stddev: 0.14% RESULT TestAtomicIncrement: trusted_linux_x86-64= {0.010044, 0.000014} us Measuring clock cycles: first runs (cycles): 593 79 58 65 61 61 68 61 61 83 ... slowest runs (cycles): ... 68 68 79 79 82 83 89 92 95 593 min: 58 cycles q1: 61 cycles median: 61 cycles q3: 68 cycles max: 593 cycles RESULT TestAtomicIncrement_CycleCount: trusted_linux_x86-64= {61, 7} count Apparent clock speed: 6073 MHz TestUncontendedMutexLock: Measuring real time: 0.031 usec (3.122e-08 sec) per iteration: 3.122e-06 sec for 100 iterations 0.023 usec (2.3342e-08 sec) per iteration: 2.3342e-05 sec for 1000 iterations 0.023 usec (2.31178e-08 sec) per iteration: 0.0990395 sec for 4284123 iterations 0.023 usec (2.31237e-08 sec) per iteration: 0.0990648 sec for 4284123 iterations 0.023 usec (2.31108e-08 sec) per iteration: 0.0990094 sec for 4284123 iterations 0.023 usec (2.31476e-08 sec) per iteration: 0.0991671 sec for 4284123 iterations 0.023 usec (2.3126e-08 sec) per iteration: 0.0990744 sec for 4284123 iterations mean: 0.023125 usec stddev: 0.000012 usec relative stddev: 0.05% RESULT TestUncontendedMutexLock: trusted_linux_x86-64= {0.023125, 0.000012} us Measuring clock cycles: first runs (cycles): 1388 116 88 95 92 92 85 92 92 123 ... slowest runs (cycles): ... 95 113 113 116 119 122 123 126 129 1388 min: 85 cycles q1: 92 cycles median: 95 cycles q3: 95 cycles max: 1388 cycles RESULT TestUncontendedMutexLock_CycleCount: trusted_linux_x86-64= {95, 3} count Apparent clock speed: 4108 MHz TestCondvarSignalNoOp: Measuring real time: 0.021 usec (2.07e-08 sec) per iteration: 2.07e-06 sec for 100 iterations 0.018 usec (1.7972e-08 sec) per iteration: 1.7972e-05 sec for 1000 iterations 0.018 usec (1.77486e-08 sec) per iteration: 0.0987568 sec for 5564210 iterations 0.018 usec (1.77436e-08 sec) per iteration: 0.0987292 sec for 5564210 iterations 0.018 usec (1.77489e-08 sec) per iteration: 0.0987584 sec for 5564210 iterations 0.018 usec (1.77452e-08 sec) per iteration: 0.098738 sec for 5564210 iterations 0.018 usec (1.77453e-08 sec) per iteration: 0.0987388 sec for 5564210 iterations mean: 0.017746 usec stddev: 0.000002 usec relative stddev: 0.01% RESULT TestCondvarSignalNoOp: trusted_linux_x86-64= {0.017746, 0.000002} us Measuring clock cycles: first runs (cycles): 982 101 86 86 83 83 80 80 83 104 ... slowest runs (cycles): ... 86 89 89 101 104 104 107 107 110 982 min: 73 cycles q1: 76 cycles median: 80 cycles q3: 83 cycles max: 982 cycles RESULT TestCondvarSignalNoOp_CycleCount: trusted_linux_x86-64= {80, 7} count Apparent clock speed: 4508 MHz TestThreadCreateAndJoin: Measuring real time: 24.174 usec (2.41737e-05 sec) per iteration: 0.00241737 sec for 100 iterations 22.711 usec (2.27106e-05 sec) per iteration: 0.0939311 sec for 4136 iterations 22.623 usec (2.26233e-05 sec) per iteration: 0.09357 sec for 4136 iterations 22.310 usec (2.23105e-05 sec) per iteration: 0.0922761 sec for 4136 iterations 23.635 usec (2.36354e-05 sec) per iteration: 0.0977561 sec for 4136 iterations 23.792 usec (2.37919e-05 sec) per iteration: 0.0984033 sec for 4136 iterations mean: 23.014347 usec stddev: 0.588375 usec relative stddev: 2.56% RESULT TestThreadCreateAndJoin: trusted_linux_x86-64= {23.014347, 0.588375} us Measuring clock cycles: first runs (cycles): 84702 65162 62589 63541 61994 62192 63996 61485 63547 62409 ... slowest runs (cycles): ... 63908 63970 63996 64177 64599 64930 65162 65994 66976 84702 min: 59601 cycles q1: 60810 cycles median: 61806 cycles q3: 62547 cycles max: 84702 cycles RESULT TestThreadCreateAndJoin_CycleCount: trusted_linux_x86-64= {61806, 1737} count Apparent clock speed: 2686 MHz TestThreadWakeup: Measuring real time: 2.722 usec (2.72207e-06 sec) per iteration: 0.000272207 sec for 100 iterations 2.399 usec (2.39895e-06 sec) per iteration: 0.0881278 sec for 36736 iterations 2.394 usec (2.39387e-06 sec) per iteration: 0.0879412 sec for 36736 iterations 2.394 usec (2.39388e-06 sec) per iteration: 0.0879415 sec for 36736 iterations 15.514 usec (1.55144e-05 sec) per iteration: 0.569937 sec for 36736 iterations 16.023 usec (1.60226e-05 sec) per iteration: 0.588605 sec for 36736 iterations mean: 7.744731 usec stddev: 6.553333 usec relative stddev: 84.62% RESULT TestThreadWakeup: trusted_linux_x86-64= {7.744731, 6.553333} us Measuring clock cycles: first runs (cycles): 63155 46359 43983 43549 43705 43157 42973 43735 43191 42695 ... slowest runs (cycles): ... 43191 43245 43454 43549 43705 43735 43983 46359 58879 63155 min: 41337 cycles q1: 41720 cycles median: 41998 cycles q3: 42732 cycles max: 63155 cycles RESULT TestThreadWakeup_CycleCount: trusted_linux_x86-64= {41998, 1012} count Apparent clock speed: 5423 MHz WARNING: should have taken less than 2.000000 secs [ OK ] nacl_newlib.run_trusted_performance_test (6049 ms) scons: done building targets. ********************************************************************** COMMAND EXECUTION REPORT ********************************************************************** 7 command_tester.py 1 doSizeCheck ********************************************************************** ENVIRONMENT USAGE REPORT ********************************************************************** 3 dbg-linux-x86-64 5 nacl-x86-64 Command return code: 0