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-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/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 luci-flex-try-trusty-3-oss1 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: 5009124 kB MemAvailable: 29372184 kB ====================================================================== LOAD: ====================================================================== 1.21 1.66 0.94 1/416 16567 ====================================================================== UPTIME: ====================================================================== 35318.97 266663.07 ====================================================================== The following environments have been configured ====================================================================== NACL_BUILD_FAMILY: UNTRUSTED BUILD_TYPE: nacl TARGET_ROOT: /b/s/w/ir/k/native_client/scons-out/nacl-x86-64 OBJ_ROOT: /b/s/w/ir/k/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: /b/s/w/ir/k/native_client/toolchain/linux_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /b/s/w/ir/k/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/b/s/w/ir/k/native_client/scons-out/nacl-x86-64/include -I/b/s/w/ir/k -c -o UNKNOWN COMPILER NACL_BUILD_FAMILY: TRUSTED BUILD_TYPE: dbg-linux TARGET_ROOT: /b/s/w/ir/k/native_client/scons-out/dbg-linux-x86-64 OBJ_ROOT: /b/s/w/ir/k/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: /b/s/w/ir/k/third_party/llvm-build/Release+Asserts/bin/clang ASPPCOM: /b/s/w/ir/k/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/b/s/w/ir/k -c -o UNKNOWN COMPILER NACL_BUILD_FAMILY: UNTRUSTED_IRT BUILD_TYPE: nacl_irt TARGET_ROOT: /b/s/w/ir/k/native_client/scons-out/nacl_irt-x86-64 OBJ_ROOT: /b/s/w/ir/k/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: /b/s/w/ir/k/native_client/toolchain/linux_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /b/s/w/ir/k/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/b/s/w/ir/k/native_client/scons-out/nacl_irt-x86-64/include -I/b/s/w/ir/k -I/b/s/w/ir/k/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/b574a6/bin/python /b/s/w/ir/k/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 /b/s/w/ir/k/native_client/scons-out/nacl-x86-64/test_results/signal_handler_single_step.out /b/s/w/ir/k/native_client/scons-out/nacl-x86-64/obj/tests/signal_handler_single_step/step_test_host /b/s/w/ir/k/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 /b/s/w/ir/k/native_client/scons-out/nacl-x86-64/obj/tests/signal_handler_single_step/step_test_host /b/s/w/ir/k/native_client/scons-out/nacl-x86-64/obj/tests/signal_handler_single_step/step_test_guest.nexe x86-64_nnacl_newlib_static [16577,2869962624:23:21:46.936723] Native Client module will be loaded at base address 0x00007ec700000000 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/b574a6/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 /b/s/w/ir/k/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 [16582,3891316608:23:21:47.425796] Native Client module will be loaded at base address 0x0000114000000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_nnacl_newlib_static= {0.107430, 0.005230} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test (1670 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-64_nnacl_newlib_static --using_nacl_signal_handler True --subarch 64 --arch x86 --output_stamp /b/s/w/ir/k/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 (75899 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= 524.920 KB RESULT hello_world_size.out: ZIPPED_x86-64_nnacl_newlib_static= 187.832 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-64_nnacl_newlib_static --using_nacl_signal_handler True --subarch 64 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/k/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 [16610,2512287616:23:22:03.407371] Native Client module will be loaded at base address 0x0000716400000000 TestNull: Measuring real time: 0.011 usec (1.145e-08 sec) per iteration: 1.145e-06 sec for 100 iterations 0.004 usec (3.85e-09 sec) per iteration: 3.85e-06 sec for 1000 iterations 0.004 usec (3.7964e-09 sec) per iteration: 3.7964e-05 sec for 10000 iterations 0.002 usec (1.91536e-09 sec) per iteration: 0.0504521 sec for 26340743 iterations 0.002 usec (1.90906e-09 sec) per iteration: 0.0502859 sec for 26340743 iterations 0.002 usec (1.90619e-09 sec) per iteration: 0.0502103 sec for 26340743 iterations 0.002 usec (1.90827e-09 sec) per iteration: 0.0502652 sec for 26340743 iterations 0.002 usec (1.90065e-09 sec) per iteration: 0.0500646 sec for 26340743 iterations mean: 0.001908 usec stddev: 0.000005 usec relative stddev: 0.25% RESULT TestNull: x86-64_nnacl_newlib_static= {0.001908, 0.000005} us Measuring clock cycles: first runs (cycles): 92 22 26 22 27 25 24 24 20 24 ... slowest runs (cycles): ... 27 27 28 28 28 28 29 29 29 92 min: 20 cycles q1: 24 cycles median: 25 cycles q3: 26 cycles max: 92 cycles RESULT TestNull_CycleCount: x86-64_nnacl_newlib_static= {25, 2} count Apparent clock speed: 13103 MHz TestNaClSyscall: Measuring real time: 0.227 usec (2.267e-07 sec) per iteration: 2.267e-05 sec for 100 iterations 0.213 usec (2.13496e-07 sec) per iteration: 0.0941754 sec for 441111 iterations 0.215 usec (2.15176e-07 sec) per iteration: 0.0949166 sec for 441111 iterations 0.214 usec (2.14276e-07 sec) per iteration: 0.0945193 sec for 441111 iterations 0.214 usec (2.13623e-07 sec) per iteration: 0.0942315 sec for 441111 iterations 0.214 usec (2.14434e-07 sec) per iteration: 0.0945893 sec for 441111 iterations mean: 0.214201 usec stddev: 0.000607 usec relative stddev: 0.28% RESULT TestNaClSyscall: x86-64_nnacl_newlib_static= {0.214201, 0.000607} us Measuring clock cycles: first runs (cycles): 466 436 429 372 359 350 382 352 407 367 ... slowest runs (cycles): ... 367 370 372 382 407 407 429 436 466 502 min: 341 cycles q1: 347 cycles median: 350 cycles q3: 354 cycles max: 502 cycles RESULT TestNaClSyscall_CycleCount: x86-64_nnacl_newlib_static= {350, 7} count Apparent clock speed: 1634 MHz TestSetjmpLongjmp: Measuring real time: 0.018 usec (1.786e-08 sec) per iteration: 1.786e-06 sec for 100 iterations 0.013 usec (1.3066e-08 sec) per iteration: 1.3066e-05 sec for 1000 iterations 0.013 usec (1.28784e-08 sec) per iteration: 0.098564 sec for 7653451 iterations 0.013 usec (1.32413e-08 sec) per iteration: 0.101341 sec for 7653451 iterations 0.013 usec (1.2891e-08 sec) per iteration: 0.0986603 sec for 7653451 iterations 0.013 usec (1.3277e-08 sec) per iteration: 0.101615 sec for 7653451 iterations 0.013 usec (1.2891e-08 sec) per iteration: 0.0986609 sec for 7653451 iterations mean: 0.013036 usec stddev: 0.000183 usec relative stddev: 1.40% RESULT TestSetjmpLongjmp: x86-64_nnacl_newlib_static= {0.013036, 0.000183} us Measuring clock cycles: first runs (cycles): 167 59 42 35 35 31 24 24 27 24 ... slowest runs (cycles): ... 29 29 29 29 31 35 35 42 59 167 min: 24 cycles q1: 26 cycles median: 28 cycles q3: 29 cycles max: 167 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_nnacl_newlib_static= {28, 3} count Apparent clock speed: 2148 MHz TestClockGetTime: Measuring real time: 0.352 usec (3.5174e-07 sec) per iteration: 3.5174e-05 sec for 100 iterations 0.274 usec (2.743e-07 sec) per iteration: 0.0779836 sec for 284300 iterations 0.275 usec (2.74741e-07 sec) per iteration: 0.0781088 sec for 284300 iterations 0.275 usec (2.74516e-07 sec) per iteration: 0.0780448 sec for 284300 iterations 0.274 usec (2.74272e-07 sec) per iteration: 0.0779756 sec for 284300 iterations 0.274 usec (2.74478e-07 sec) per iteration: 0.078034 sec for 284300 iterations mean: 0.274461 usec stddev: 0.000169 usec relative stddev: 0.06% RESULT TestClockGetTime: x86-64_nnacl_newlib_static= {0.274461, 0.000169} us Measuring clock cycles: first runs (cycles): 979 679 647 616 602 605 596 607 649 686 ... slowest runs (cycles): ... 647 649 649 660 678 679 682 686 688 979 min: 592 cycles q1: 601 cycles median: 607 cycles q3: 611 cycles max: 979 cycles RESULT TestClockGetTime_CycleCount: x86-64_nnacl_newlib_static= {607, 10} count Apparent clock speed: 2212 MHz TestTlsVariable: Measuring real time: 0.011 usec (1.089e-08 sec) per iteration: 1.089e-06 sec for 100 iterations 0.006 usec (5.887e-09 sec) per iteration: 5.887e-06 sec for 1000 iterations 0.006 usec (5.5997e-09 sec) per iteration: 5.5997e-05 sec for 10000 iterations 0.006 usec (5.71017e-09 sec) per iteration: 0.101973 sec for 17858099 iterations 0.006 usec (5.71731e-09 sec) per iteration: 0.1021 sec for 17858099 iterations 0.006 usec (5.69657e-09 sec) per iteration: 0.10173 sec for 17858099 iterations 0.006 usec (5.73222e-09 sec) per iteration: 0.102367 sec for 17858099 iterations 0.006 usec (5.70526e-09 sec) per iteration: 0.101885 sec for 17858099 iterations mean: 0.005712 usec stddev: 0.000012 usec relative stddev: 0.21% RESULT TestTlsVariable: x86-64_nnacl_newlib_static= {0.005712, 0.000012} us Measuring clock cycles: first runs (cycles): 148 77 57 31 31 29 31 26 29 33 ... slowest runs (cycles): ... 31 31 33 33 33 33 33 57 77 148 min: 22 cycles q1: 26 cycles median: 28 cycles q3: 29 cycles max: 148 cycles RESULT TestTlsVariable_CycleCount: x86-64_nnacl_newlib_static= {28, 3} count Apparent clock speed: 4902 MHz TestMmapAnonymous: Measuring real time: 2.837 usec (2.83734e-06 sec) per iteration: 0.000283734 sec for 100 iterations 2.560 usec (2.56031e-06 sec) per iteration: 0.0902354 sec for 35244 iterations 2.547 usec (2.54702e-06 sec) per iteration: 0.0897673 sec for 35244 iterations 2.550 usec (2.55034e-06 sec) per iteration: 0.0898841 sec for 35244 iterations 2.560 usec (2.55976e-06 sec) per iteration: 0.0902162 sec for 35244 iterations 2.538 usec (2.53849e-06 sec) per iteration: 0.0894665 sec for 35244 iterations mean: 2.551183 usec stddev: 0.008197 usec relative stddev: 0.32% RESULT TestMmapAnonymous: x86-64_nnacl_newlib_static= {2.551183, 0.008197} us Measuring clock cycles: first runs (cycles): 7425 5682 5511 5460 5416 5359 5372 5412 5367 5473 ... slowest runs (cycles): ... 5621 5625 5634 5645 5660 5663 5667 5682 5713 7425 min: 5308 cycles q1: 5361 cycles median: 5399 cycles q3: 5449 cycles max: 7425 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_nnacl_newlib_static= {5399, 88} count Apparent clock speed: 2116 MHz TestAtomicIncrement: Measuring real time: 0.014 usec (1.417e-08 sec) per iteration: 1.417e-06 sec for 100 iterations 0.010 usec (9.693e-09 sec) per iteration: 9.693e-06 sec for 1000 iterations 0.009 usec (9.4006e-09 sec) per iteration: 9.4006e-05 sec for 10000 iterations 0.009 usec (9.45899e-09 sec) per iteration: 0.100621 sec for 10637618 iterations 0.009 usec (9.46928e-09 sec) per iteration: 0.100731 sec for 10637618 iterations 0.010 usec (9.73075e-09 sec) per iteration: 0.103512 sec for 10637618 iterations 0.009 usec (9.4737e-09 sec) per iteration: 0.100778 sec for 10637618 iterations 0.010 usec (9.76886e-09 sec) per iteration: 0.103917 sec for 10637618 iterations mean: 0.009580 usec stddev: 0.000139 usec relative stddev: 1.45% RESULT TestAtomicIncrement: x86-64_nnacl_newlib_static= {0.009580, 0.000139} us Measuring clock cycles: first runs (cycles): 112 53 53 50 51 50 51 50 49 50 ... slowest runs (cycles): ... 53 53 53 53 53 53 53 53 53 112 min: 49 cycles q1: 50 cycles median: 51 cycles q3: 53 cycles max: 112 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_nnacl_newlib_static= {51, 3} count Apparent clock speed: 5323 MHz TestUncontendedMutexLock: Measuring real time: 0.032 usec (3.229e-08 sec) per iteration: 3.229e-06 sec for 100 iterations 0.024 usec (2.4472e-08 sec) per iteration: 2.4472e-05 sec for 1000 iterations 0.025 usec (2.4616e-08 sec) per iteration: 0.100588 sec for 4086302 iterations 0.025 usec (2.46209e-08 sec) per iteration: 0.100608 sec for 4086302 iterations 0.025 usec (2.46067e-08 sec) per iteration: 0.10055 sec for 4086302 iterations 0.025 usec (2.46758e-08 sec) per iteration: 0.100833 sec for 4086302 iterations 0.025 usec (2.46705e-08 sec) per iteration: 0.100811 sec for 4086302 iterations mean: 0.024638 usec stddev: 0.000029 usec relative stddev: 0.12% RESULT TestUncontendedMutexLock: x86-64_nnacl_newlib_static= {0.024638, 0.000029} us Measuring clock cycles: first runs (cycles): 246 110 84 82 83 84 84 83 84 84 ... slowest runs (cycles): ... 84 84 84 84 84 84 84 84 110 246 min: 81 cycles q1: 82 cycles median: 83 cycles q3: 84 cycles max: 246 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_nnacl_newlib_static= {83, 2} count Apparent clock speed: 3369 MHz TestCondvarSignalNoOp: Measuring real time: 0.352 usec (3.5221e-07 sec) per iteration: 3.5221e-05 sec for 100 iterations 0.306 usec (3.05891e-07 sec) per iteration: 0.086849 sec for 283921 iterations 0.306 usec (3.06032e-07 sec) per iteration: 0.086889 sec for 283921 iterations 0.306 usec (3.06106e-07 sec) per iteration: 0.08691 sec for 283921 iterations 0.306 usec (3.05647e-07 sec) per iteration: 0.0867795 sec for 283921 iterations 0.305 usec (3.05398e-07 sec) per iteration: 0.0867088 sec for 283921 iterations mean: 0.305815 usec stddev: 0.000261 usec relative stddev: 0.09% RESULT TestCondvarSignalNoOp: x86-64_nnacl_newlib_static= {0.305815, 0.000261} us Measuring clock cycles: first runs (cycles): 904 787 740 679 676 673 667 677 660 676 ... slowest runs (cycles): ... 706 708 708 714 715 717 740 761 787 904 min: 660 cycles q1: 667 cycles median: 671 cycles q3: 677 cycles max: 904 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_nnacl_newlib_static= {671, 10} count Apparent clock speed: 2194 MHz TestThreadCreateAndJoin: Measuring real time: 46.358 usec (4.63584e-05 sec) per iteration: 0.00463584 sec for 100 iterations 36.801 usec (3.68006e-05 sec) per iteration: 0.0793789 sec for 2157 iterations 32.064 usec (3.20645e-05 sec) per iteration: 0.0691631 sec for 2157 iterations 34.013 usec (3.40129e-05 sec) per iteration: 0.0733659 sec for 2157 iterations 34.174 usec (3.41743e-05 sec) per iteration: 0.073714 sec for 2157 iterations 30.042 usec (3.00416e-05 sec) per iteration: 0.0647998 sec for 2157 iterations mean: 33.418789 usec stddev: 2.262742 usec relative stddev: 6.77% RESULT TestThreadCreateAndJoin: x86-64_nnacl_newlib_static= {33.418789, 2.262742} us Measuring clock cycles: first runs (cycles): 62059 140248 66741 152339 58927 354252 114855 340844 69580 74935 ... slowest runs (cycles): ... 96409 100193 114855 129341 140248 152339 283829 340844 354252 435316 min: 42294 cycles q1: 50349 cycles median: 53189 cycles q3: 57976 cycles max: 435316 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_nnacl_newlib_static= {53189, 7627} count Apparent clock speed: 1592 MHz TestThreadWakeup: Measuring real time: 19.530 usec (1.95297e-05 sec) per iteration: 0.00195297 sec for 100 iterations 14.403 usec (1.44033e-05 sec) per iteration: 0.0737449 sec for 5120 iterations 14.298 usec (1.42984e-05 sec) per iteration: 0.0732076 sec for 5120 iterations 14.515 usec (1.45152e-05 sec) per iteration: 0.0743177 sec for 5120 iterations 16.472 usec (1.64723e-05 sec) per iteration: 0.0843382 sec for 5120 iterations 17.274 usec (1.7274e-05 sec) per iteration: 0.0884426 sec for 5120 iterations mean: 15.392619 usec stddev: 1.237028 usec relative stddev: 8.04% RESULT TestThreadWakeup: x86-64_nnacl_newlib_static= {15.392619, 1.237028} us Measuring clock cycles: first runs (cycles): 34927 30138 29728 29652 30372 30543 32736 30791 30549 30221 ... slowest runs (cycles): ... 34186 34251 34339 34432 34927 36005 36673 44372 52120 62648 min: 26142 cycles q1: 30205 cycles median: 30844 cycles q3: 32594 cycles max: 62648 cycles RESULT TestThreadWakeup_CycleCount: x86-64_nnacl_newlib_static= {30844, 2389} count Apparent clock speed: 2004 MHz TestCatchingFault: Measuring real time: 3.322 usec (3.32161e-06 sec) per iteration: 0.000332161 sec for 100 iterations 1.939 usec (1.9387e-06 sec) per iteration: 0.0583645 sec for 30105 iterations 2.007 usec (2.00694e-06 sec) per iteration: 0.0604189 sec for 30105 iterations 1.943 usec (1.94267e-06 sec) per iteration: 0.0584841 sec for 30105 iterations 1.922 usec (1.92242e-06 sec) per iteration: 0.0578743 sec for 30105 iterations 1.961 usec (1.96091e-06 sec) per iteration: 0.0590331 sec for 30105 iterations mean: 1.954326 usec stddev: 0.029017 usec relative stddev: 1.48% RESULT TestCatchingFault: x86-64_nnacl_newlib_static= {1.954326, 0.029017} us Measuring clock cycles: first runs (cycles): 5405 4333 4257 4204 29889 4628 4289 4244 4281 4186 ... slowest runs (cycles): ... 4963 5056 5104 5128 5237 5271 5405 5476 5812 29889 min: 4079 cycles q1: 4169 cycles median: 4234 cycles q3: 4283 cycles max: 29889 cycles RESULT TestCatchingFault_CycleCount: x86-64_nnacl_newlib_static= {4234, 114} count Apparent clock speed: 2166 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 68.446 usec (6.84458e-05 sec) per iteration: 0.00684458 sec for 100 iterations 63.565 usec (6.35648e-05 sec) per iteration: 0.0928682 sec for 1461 iterations 60.611 usec (6.06106e-05 sec) per iteration: 0.0885521 sec for 1461 iterations 66.239 usec (6.62394e-05 sec) per iteration: 0.0967758 sec for 1461 iterations 61.681 usec (6.16808e-05 sec) per iteration: 0.0901157 sec for 1461 iterations 50.461 usec (5.04608e-05 sec) per iteration: 0.0737232 sec for 1461 iterations mean: 60.511293 usec stddev: 5.376357 usec relative stddev: 8.88% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_nnacl_newlib_static= {60.511293, 5.376357} us Measuring clock cycles: first runs (cycles): 329502 422095 207489 406957 1106614 239296 138378 315848 132268 103667 ... slowest runs (cycles): ... 187464 207489 239296 268325 315848 329502 390963 406957 422095 1106614 min: 50348 cycles q1: 59421 cycles median: 74824 cycles q3: 103667 cycles max: 1106614 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_nnacl_newlib_static= {74824, 44246} count Apparent clock speed: 1237 MHz [ OK ] nacl_newlib.run_performance_test (5560 ms) /b/s/w/ir/cache/vpython/b574a6/bin/python /b/s/w/ir/k/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 /b/s/w/ir/k/native_client/scons-out/dbg-linux-x86-64/test_results/nacl_clock_cputime_test.out /b/s/w/ir/k/native_client/scons-out/dbg-linux-x86-64/obj/src/shared/platform/nacl_clock_test -c [ RUN ] nacl_newlib.run_nacl_clock_cputime_test /b/s/w/ir/k/native_client/scons-out/dbg-linux-x86-64/obj/src/shared/platform/nacl_clock_test -c [ OK ] nacl_newlib.run_nacl_clock_cputime_test (9100 ms) /b/s/w/ir/cache/vpython/b574a6/bin/python /b/s/w/ir/k/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 /b/s/w/ir/k/native_client/scons-out/dbg-linux-x86-64/test_results/nacl_interval_multi_reg_test.out /b/s/w/ir/k/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 /b/s/w/ir/k/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 (2840 ms) /b/s/w/ir/cache/vpython/b574a6/bin/python /b/s/w/ir/k/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 /b/s/w/ir/k/native_client/scons-out/dbg-linux-x86-64/test_results/performance_test.out /b/s/w/ir/k/native_client/scons-out/dbg-linux-x86-64/obj/tests/performance/performance_test trusted_linux_x86-64 [ RUN ] nacl_newlib.run_trusted_performance_test /b/s/w/ir/k/native_client/scons-out/dbg-linux-x86-64/obj/tests/performance/performance_test trusted_linux_x86-64 TestNull: Measuring real time: 0.005 usec (4.53e-09 sec) per iteration: 4.53e-07 sec for 100 iterations 0.003 usec (2.557e-09 sec) per iteration: 2.557e-06 sec for 1000 iterations 0.003 usec (2.5119e-09 sec) per iteration: 2.5119e-05 sec for 10000 iterations 0.003 usec (2.58705e-09 sec) per iteration: 0.102992 sec for 39810502 iterations 0.003 usec (2.6569e-09 sec) per iteration: 0.105772 sec for 39810502 iterations 0.003 usec (2.5866e-09 sec) per iteration: 0.102974 sec for 39810502 iterations 0.003 usec (2.58091e-09 sec) per iteration: 0.102748 sec for 39810502 iterations 0.003 usec (2.5837e-09 sec) per iteration: 0.102858 sec for 39810502 iterations mean: 0.002599 usec stddev: 0.000029 usec relative stddev: 1.12% RESULT TestNull: trusted_linux_x86-64= {0.002599, 0.000029} us Measuring clock cycles: first runs (cycles): 85 29 29 27 27 26 24 26 24 26 ... slowest runs (cycles): ... 29 29 29 29 29 29 29 29 29 85 min: 24 cycles q1: 26 cycles median: 27 cycles q3: 28 cycles max: 85 cycles RESULT TestNull_CycleCount: trusted_linux_x86-64= {27, 2} count Apparent clock speed: 10388 MHz TestHostSyscall: Measuring real time: 0.079 usec (7.85e-08 sec) per iteration: 7.85e-06 sec for 100 iterations 0.062 usec (6.1971e-08 sec) per iteration: 6.1971e-05 sec for 1000 iterations 0.063 usec (6.25881e-08 sec) per iteration: 0.100996 sec for 1613658 iterations 0.063 usec (6.27458e-08 sec) per iteration: 0.10125 sec for 1613658 iterations 0.063 usec (6.25516e-08 sec) per iteration: 0.100937 sec for 1613658 iterations 0.063 usec (6.2509e-08 sec) per iteration: 0.100868 sec for 1613658 iterations 0.062 usec (6.24091e-08 sec) per iteration: 0.100707 sec for 1613658 iterations mean: 0.062561 usec stddev: 0.000110 usec relative stddev: 0.18% RESULT TestHostSyscall: trusted_linux_x86-64= {0.062561, 0.000110} us Measuring clock cycles: first runs (cycles): 622 169 169 159 159 158 158 159 157 156 ... slowest runs (cycles): ... 159 159 159 159 159 159 159 169 169 622 min: 156 cycles q1: 156 cycles median: 156 cycles q3: 157 cycles max: 622 cycles RESULT TestHostSyscall_CycleCount: trusted_linux_x86-64= {156, 1} count Apparent clock speed: 2494 MHz TestSetjmpLongjmp: Measuring real time: 0.130 usec (1.2987e-07 sec) per iteration: 1.2987e-05 sec for 100 iterations 0.028 usec (2.75323e-08 sec) per iteration: 0.0211999 sec for 770000 iterations 0.027 usec (2.72917e-08 sec) per iteration: 0.0210146 sec for 770000 iterations 0.027 usec (2.74138e-08 sec) per iteration: 0.0211086 sec for 770000 iterations 0.027 usec (2.71918e-08 sec) per iteration: 0.0209377 sec for 770000 iterations 0.028 usec (2.75045e-08 sec) per iteration: 0.0211785 sec for 770000 iterations mean: 0.027387 usec stddev: 0.000129 usec relative stddev: 0.47% RESULT TestSetjmpLongjmp: trusted_linux_x86-64= {0.027387, 0.000129} us Measuring clock cycles: first runs (cycles): 396 110 83 79 79 82 79 82 84 81 ... slowest runs (cycles): ... 86 86 86 86 86 86 86 88 110 396 min: 77 cycles q1: 79 cycles median: 81 cycles q3: 82 cycles max: 396 cycles RESULT TestSetjmpLongjmp_CycleCount: trusted_linux_x86-64= {81, 3} count Apparent clock speed: 2958 MHz TestClockGetTime: Measuring real time: 0.064 usec (6.401e-08 sec) per iteration: 6.401e-06 sec for 100 iterations 0.058 usec (5.7711e-08 sec) per iteration: 5.7711e-05 sec for 1000 iterations 0.059 usec (5.85782e-08 sec) per iteration: 0.101503 sec for 1732771 iterations 0.059 usec (5.87737e-08 sec) per iteration: 0.101841 sec for 1732771 iterations 0.058 usec (5.76253e-08 sec) per iteration: 0.0998515 sec for 1732771 iterations 0.058 usec (5.82108e-08 sec) per iteration: 0.100866 sec for 1732771 iterations 0.059 usec (5.91639e-08 sec) per iteration: 0.102518 sec for 1732771 iterations mean: 0.058470 usec stddev: 0.000523 usec relative stddev: 0.89% RESULT TestClockGetTime: trusted_linux_x86-64= {0.058470, 0.000523} us Measuring clock cycles: first runs (cycles): 400 185 173 174 176 173 174 174 174 179 ... slowest runs (cycles): ... 176 176 176 178 178 178 178 179 185 400 min: 171 cycles q1: 174 cycles median: 176 cycles q3: 176 cycles max: 400 cycles RESULT TestClockGetTime_CycleCount: trusted_linux_x86-64= {176, 2} count Apparent clock speed: 3010 MHz TestTlsVariable: Measuring real time: 0.011 usec (1.051e-08 sec) per iteration: 1.051e-06 sec for 100 iterations 0.003 usec (3.43e-09 sec) per iteration: 3.43e-06 sec for 1000 iterations 0.003 usec (3.3436e-09 sec) per iteration: 3.3436e-05 sec for 10000 iterations 0.003 usec (3.40716e-09 sec) per iteration: 0.101901 sec for 29907883 iterations 0.003 usec (3.39626e-09 sec) per iteration: 0.101575 sec for 29907883 iterations 0.003 usec (3.3983e-09 sec) per iteration: 0.101636 sec for 29907883 iterations 0.003 usec (3.39141e-09 sec) per iteration: 0.10143 sec for 29907883 iterations 0.003 usec (3.4074e-09 sec) per iteration: 0.101908 sec for 29907883 iterations mean: 0.003400 usec stddev: 0.000006 usec relative stddev: 0.18% RESULT TestTlsVariable: trusted_linux_x86-64= {0.003400, 0.000006} us Measuring clock cycles: first runs (cycles): 92 30 28 31 29 27 27 29 29 29 ... slowest runs (cycles): ... 30 31 31 31 31 31 31 31 31 92 min: 24 cycles q1: 27 cycles median: 28 cycles q3: 29 cycles max: 92 cycles RESULT TestTlsVariable_CycleCount: trusted_linux_x86-64= {28, 2} count Apparent clock speed: 8235 MHz TestMmapAnonymous: Measuring real time: 0.721 usec (7.2078e-07 sec) per iteration: 7.2078e-05 sec for 100 iterations 0.512 usec (5.12373e-07 sec) per iteration: 0.0710856 sec for 138738 iterations 0.511 usec (5.10508e-07 sec) per iteration: 0.0708268 sec for 138738 iterations 0.511 usec (5.10971e-07 sec) per iteration: 0.0708911 sec for 138738 iterations 0.510 usec (5.10245e-07 sec) per iteration: 0.0707904 sec for 138738 iterations 0.511 usec (5.10861e-07 sec) per iteration: 0.0708758 sec for 138738 iterations mean: 0.510991 usec stddev: 0.000737 usec relative stddev: 0.14% RESULT TestMmapAnonymous: trusted_linux_x86-64= {0.510991, 0.000737} us Measuring clock cycles: first runs (cycles): 2199 1146 1125 1104 1103 1122 1122 1119 1117 1122 ... slowest runs (cycles): ... 1146 1146 1146 1150 1162 1162 1162 1170 1175 2199 min: 1102 cycles q1: 1113 cycles median: 1120 cycles q3: 1126 cycles max: 2199 cycles RESULT TestMmapAnonymous_CycleCount: trusted_linux_x86-64= {1120, 13} count Apparent clock speed: 2192 MHz TestAtomicIncrement: Measuring real time: 0.014 usec (1.396e-08 sec) per iteration: 1.396e-06 sec for 100 iterations 0.009 usec (9.312e-09 sec) per iteration: 9.312e-06 sec for 1000 iterations 0.009 usec (9.2934e-09 sec) per iteration: 9.2934e-05 sec for 10000 iterations 0.009 usec (9.42825e-09 sec) per iteration: 0.101451 sec for 10760324 iterations 0.009 usec (9.43635e-09 sec) per iteration: 0.101538 sec for 10760324 iterations 0.009 usec (9.44648e-09 sec) per iteration: 0.101647 sec for 10760324 iterations 0.009 usec (9.43383e-09 sec) per iteration: 0.101511 sec for 10760324 iterations 0.009 usec (9.43654e-09 sec) per iteration: 0.10154 sec for 10760324 iterations mean: 0.009436 usec stddev: 0.000006 usec relative stddev: 0.06% RESULT TestAtomicIncrement: trusted_linux_x86-64= {0.009436, 0.000006} us Measuring clock cycles: first runs (cycles): 279 62 60 59 62 59 62 62 57 55 ... slowest runs (cycles): ... 59 59 60 62 62 62 62 63 64 279 min: 55 cycles q1: 55 cycles median: 57 cycles q3: 57 cycles max: 279 cycles RESULT TestAtomicIncrement_CycleCount: trusted_linux_x86-64= {57, 2} count Apparent clock speed: 6041 MHz TestUncontendedMutexLock: Measuring real time: 0.032 usec (3.244e-08 sec) per iteration: 3.244e-06 sec for 100 iterations 0.025 usec (2.5033e-08 sec) per iteration: 2.5033e-05 sec for 1000 iterations 0.024 usec (2.42697e-08 sec) per iteration: 0.096951 sec for 3994726 iterations 0.024 usec (2.42532e-08 sec) per iteration: 0.096885 sec for 3994726 iterations 0.025 usec (2.50381e-08 sec) per iteration: 0.10002 sec for 3994726 iterations 0.024 usec (2.41616e-08 sec) per iteration: 0.0965188 sec for 3994726 iterations 0.024 usec (2.41558e-08 sec) per iteration: 0.0964956 sec for 3994726 iterations mean: 0.024376 usec stddev: 0.000334 usec relative stddev: 1.37% RESULT TestUncontendedMutexLock: trusted_linux_x86-64= {0.024376, 0.000334} us Measuring clock cycles: first runs (cycles): 172 94 88 88 88 91 85 88 86 88 ... slowest runs (cycles): ... 88 88 88 88 88 88 90 91 94 172 min: 85 cycles q1: 88 cycles median: 88 cycles q3: 88 cycles max: 172 cycles RESULT TestUncontendedMutexLock_CycleCount: trusted_linux_x86-64= {88, 0} count Apparent clock speed: 3610 MHz TestCondvarSignalNoOp: Measuring real time: 0.024 usec (2.408e-08 sec) per iteration: 2.408e-06 sec for 100 iterations 0.020 usec (1.9644e-08 sec) per iteration: 1.9644e-05 sec for 1000 iterations 0.020 usec (1.99409e-08 sec) per iteration: 0.101512 sec for 5090612 iterations 0.020 usec (1.99356e-08 sec) per iteration: 0.101484 sec for 5090612 iterations 0.020 usec (1.99415e-08 sec) per iteration: 0.101514 sec for 5090612 iterations 0.020 usec (2.00496e-08 sec) per iteration: 0.102065 sec for 5090612 iterations 0.020 usec (1.99073e-08 sec) per iteration: 0.10134 sec for 5090612 iterations mean: 0.019955 usec stddev: 0.000049 usec relative stddev: 0.25% RESULT TestCondvarSignalNoOp: trusted_linux_x86-64= {0.019955, 0.000049} us Measuring clock cycles: first runs (cycles): 154 72 75 74 73 72 73 79 72 75 ... slowest runs (cycles): ... 75 75 75 75 75 79 79 82 82 154 min: 70 cycles q1: 72 cycles median: 73 cycles q3: 75 cycles max: 154 cycles RESULT TestCondvarSignalNoOp_CycleCount: trusted_linux_x86-64= {73, 3} count Apparent clock speed: 3658 MHz TestThreadCreateAndJoin: Measuring real time: 36.722 usec (3.67219e-05 sec) per iteration: 0.00367219 sec for 100 iterations 25.620 usec (2.562e-05 sec) per iteration: 0.0697633 sec for 2723 iterations 24.755 usec (2.47552e-05 sec) per iteration: 0.0674085 sec for 2723 iterations 35.166 usec (3.51665e-05 sec) per iteration: 0.0957583 sec for 2723 iterations 23.963 usec (2.39634e-05 sec) per iteration: 0.0652522 sec for 2723 iterations 21.874 usec (2.1874e-05 sec) per iteration: 0.0595629 sec for 2723 iterations mean: 26.275805 usec stddev: 4.615395 usec relative stddev: 17.57% RESULT TestThreadCreateAndJoin: trusted_linux_x86-64= {26.275805, 4.615395} us Measuring clock cycles: first runs (cycles): 159993 51610 44478 42758 46599 46971 48924 44296 53981 48181 ... slowest runs (cycles): ... 53284 53981 58685 60515 65757 75965 81167 93057 159993 230230 min: 40464 cycles q1: 44349 cycles median: 45455 cycles q3: 47192 cycles max: 230230 cycles RESULT TestThreadCreateAndJoin_CycleCount: trusted_linux_x86-64= {45455, 2843} count Apparent clock speed: 1730 MHz TestThreadWakeup: Measuring real time: 16.464 usec (1.64642e-05 sec) per iteration: 0.00164642 sec for 100 iterations 13.967 usec (1.39673e-05 sec) per iteration: 0.0848232 sec for 6073 iterations 14.159 usec (1.41592e-05 sec) per iteration: 0.085989 sec for 6073 iterations 14.447 usec (1.44471e-05 sec) per iteration: 0.0877373 sec for 6073 iterations 15.635 usec (1.56351e-05 sec) per iteration: 0.0949517 sec for 6073 iterations 15.600 usec (1.55998e-05 sec) per iteration: 0.0947375 sec for 6073 iterations mean: 14.761687 usec stddev: 0.715291 usec relative stddev: 4.85% RESULT TestThreadWakeup: trusted_linux_x86-64= {14.761687, 0.715291} us Measuring clock cycles: first runs (cycles): 40545 31156 37546 38881 35679 37014 38534 39651 38980 36345 ... slowest runs (cycles): ... 38840 38881 38980 39651 39984 40545 41381 41533 46449 50515 min: 29607 cycles q1: 32781 cycles median: 33299 cycles q3: 35729 cycles max: 50515 cycles RESULT TestThreadWakeup_CycleCount: trusted_linux_x86-64= {33299, 2948} count Apparent clock speed: 2256 MHz WARNING: should have taken less than 2.000000 secs [ OK ] nacl_newlib.run_trusted_performance_test (4840 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