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

Running command: /b/s/w/ir/cache/vpython/b574a6/bin/python scons.py --verbose -k -j1 --mode=nacl,opt-host platform=x86-64 --clang breakpad_tools_dir=breakpad-out large_tests scons: Reading SConscript files ... ====================================================================== SCONS ARGS:['scons.py', '--verbose', '-k', '-j1', '--mode=nacl,opt-host', 'platform=x86-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 gce-trusty-e833d7b0-us-west1-c-48jb 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: 20165588 kB MemAvailable: 29460448 kB ====================================================================== LOAD: ====================================================================== 1.86 1.85 0.91 1/436 9280 ====================================================================== UPTIME: ====================================================================== 9696.44 76026.53 ====================================================================== 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-64 OBJ_ROOT: /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: /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 -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/kitchen-workdir/native_client/scons-out/nacl-x86-64/include -I/b/s/w/ir/kitchen-workdir -c -o UNKNOWN COMPILER NACL_BUILD_FAMILY: TRUSTED BUILD_TYPE: opt-linux TARGET_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-linux-x86-64 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-linux-x86-64/obj BUILD_TYPE_DESCRIPTION: Linux opt build BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 64 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 64 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 -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 -O2 -D_FORTIFY_SOURCE=2 -D_POSIX_C_SOURCE=199506 -D_XOPEN_SOURCE=600 -D_GNU_SOURCE=1 -D_LARGEFILE64_SOURCE=1 -D__STDC_LIMIT_MACROS=1 -D__STDC_FORMAT_MACROS=1 -DNDEBUG -I/b/s/w/ir/kitchen-workdir -c -o UNKNOWN COMPILER NACL_BUILD_FAMILY: UNTRUSTED_IRT BUILD_TYPE: nacl_irt TARGET_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt-x86-64 OBJ_ROOT: /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: /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 -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/kitchen-workdir/native_client/scons-out/nacl_irt-x86-64/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_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/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 /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-64/test_results/signal_handler_single_step.out /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-64/obj/tests/signal_handler_single_step/step_test_host /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 /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-64/obj/tests/signal_handler_single_step/step_test_host /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 [9290,4279883648:08:10:35.851951] Native Client module will be loaded at base address 0x00007cad00000000 Switching to untrusted: since previous switch: 151 instructions, 490 instruction bytes, 20 jumps Switching to trusted: since previous switch: 11 instructions, 48 instruction bytes, 2 jumps Switching to untrusted: since previous switch: 185 instructions, 596 instruction bytes, 18 jumps Switching to trusted: since previous switch: 9 instructions, 32 instruction bytes, 3 jumps Switching to untrusted: since previous switch: 185 instructions, 596 instruction bytes, 18 jumps Switching to trusted: since previous switch: 9 instructions, 32 instruction bytes, 3 jumps Switching to untrusted: since previous switch: 185 instructions, 596 instruction bytes, 18 jumps Switching to trusted: since previous switch: 9 instructions, 32 instruction bytes, 3 jumps Switching to untrusted: since previous switch: 185 instructions, 596 instruction bytes, 18 jumps RESULT InstructionsPerSyscall: x86-64_nnacl_newlib_static= 185 count RESULT InstructionBytesPerSyscall: x86-64_nnacl_newlib_static= 596 count RESULT JumpsPerSyscall: x86-64_nnacl_newlib_static= 18 count Switching to trusted: since previous switch: 9 instructions, 32 instruction bytes, 3 jumps [ OK ] nacl_newlib.run_signal_handler_single_step_test (0 ms) /b/s/w/ir/cache/vpython/b574a6/bin/python tools/command_tester.py --name nacl_newlib.run_benchmark_test --time_warning 10 --time_error 100 --perf_env_description x86-64_nnacl_newlib_static --using_nacl_signal_handler True --subarch 64 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-64/test_results/benchmark_test.out scons-out/opt-linux-x86-64/staging/nacl_helper_bootstrap scons-out/opt-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/opt-linux-x86-64/staging/nacl_helper_bootstrap scons-out/opt-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 [9295,3820697472:08:10:36.108549] Native Client module will be loaded at base address 0x000012c800000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_nnacl_newlib_static= {0.100910, 0.004350} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test (1580 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/kitchen-workdir/native_client/scons-out/nacl-x86-64/test_results/clock_cputime_test.out scons-out/opt-linux-x86-64/staging/nacl_helper_bootstrap scons-out/opt-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/opt-linux-x86-64/staging/nacl_helper_bootstrap scons-out/opt-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 (75850 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= 525.291 KB RESULT hello_world_size.out: ZIPPED_x86-64_nnacl_newlib_static= 187.863 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/kitchen-workdir/native_client/scons-out/nacl-x86-64/test_results/performance_test.out scons-out/opt-linux-x86-64/staging/nacl_helper_bootstrap scons-out/opt-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/opt-linux-x86-64/staging/nacl_helper_bootstrap scons-out/opt-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 [9323,313161600:08:10:51.771827] Native Client module will be loaded at base address 0x00002b3600000000 TestNull: Measuring real time: 0.005 usec (5.26e-09 sec) per iteration: 5.26e-07 sec for 100 iterations 0.002 usec (2.022e-09 sec) per iteration: 2.022e-06 sec for 1000 iterations 0.003 usec (2.7645e-09 sec) per iteration: 2.7645e-05 sec for 10000 iterations 0.002 usec (2.00864e-09 sec) per iteration: 0.0726584 sec for 36172906 iterations 0.002 usec (1.91189e-09 sec) per iteration: 0.0691585 sec for 36172906 iterations 0.002 usec (1.89394e-09 sec) per iteration: 0.0685094 sec for 36172906 iterations 0.002 usec (1.88775e-09 sec) per iteration: 0.0682856 sec for 36172906 iterations 0.002 usec (1.89605e-09 sec) per iteration: 0.0685858 sec for 36172906 iterations mean: 0.001920 usec stddev: 0.000045 usec relative stddev: 2.35% RESULT TestNull: x86-64_nnacl_newlib_static= {0.001920, 0.000045} us Measuring clock cycles: first runs (cycles): 92 27 22 22 26 24 20 24 27 25 ... slowest runs (cycles): ... 27 27 27 28 28 28 29 29 29 92 min: 20 cycles q1: 24 cycles median: 24 cycles q3: 26 cycles max: 92 cycles RESULT TestNull_CycleCount: x86-64_nnacl_newlib_static= {24, 2} count Apparent clock speed: 12502 MHz TestNaClSyscall: Measuring real time: 0.142 usec (1.4236e-07 sec) per iteration: 1.4236e-05 sec for 100 iterations 0.138 usec (1.38228e-07 sec) per iteration: 0.0970972 sec for 702444 iterations 0.135 usec (1.34855e-07 sec) per iteration: 0.0947279 sec for 702444 iterations 0.124 usec (1.24279e-07 sec) per iteration: 0.0872992 sec for 702444 iterations 0.122 usec (1.22023e-07 sec) per iteration: 0.0857143 sec for 702444 iterations 0.126 usec (1.25672e-07 sec) per iteration: 0.0882779 sec for 702444 iterations mean: 0.129011 usec stddev: 0.006348 usec relative stddev: 4.92% RESULT TestNaClSyscall: x86-64_nnacl_newlib_static= {0.129011, 0.006348} us Measuring clock cycles: first runs (cycles): 312 220 217 220 220 223 218 219 218 217 ... slowest runs (cycles): ... 222 223 223 223 223 223 224 224 257 312 min: 217 cycles q1: 220 cycles median: 220 cycles q3: 222 cycles max: 312 cycles RESULT TestNaClSyscall_CycleCount: x86-64_nnacl_newlib_static= {220, 2} count Apparent clock speed: 1705 MHz TestSetjmpLongjmp: Measuring real time: 0.016 usec (1.647e-08 sec) per iteration: 1.647e-06 sec for 100 iterations 0.013 usec (1.27e-08 sec) per iteration: 1.27e-05 sec for 1000 iterations 0.013 usec (1.34282e-08 sec) per iteration: 0.105734 sec for 7874015 iterations 0.013 usec (1.30808e-08 sec) per iteration: 0.102998 sec for 7874015 iterations 0.013 usec (1.31167e-08 sec) per iteration: 0.103281 sec for 7874015 iterations 0.013 usec (1.30837e-08 sec) per iteration: 0.103021 sec for 7874015 iterations 0.013 usec (1.29233e-08 sec) per iteration: 0.101758 sec for 7874015 iterations mean: 0.013127 usec stddev: 0.000165 usec relative stddev: 1.26% RESULT TestSetjmpLongjmp: x86-64_nnacl_newlib_static= {0.013127, 0.000165} us Measuring clock cycles: first runs (cycles): 143 40 33 61 61 40 35 33 27 26 ... slowest runs (cycles): ... 30 30 33 33 35 40 40 61 61 143 min: 24 cycles q1: 26 cycles median: 28 cycles q3: 29 cycles max: 143 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_nnacl_newlib_static= {28, 3} count Apparent clock speed: 2133 MHz TestClockGetTime: Measuring real time: 0.144 usec (1.4421e-07 sec) per iteration: 1.4421e-05 sec for 100 iterations 0.152 usec (1.5224e-07 sec) per iteration: 0.105568 sec for 693433 iterations 0.152 usec (1.52039e-07 sec) per iteration: 0.105429 sec for 693433 iterations 0.147 usec (1.4732e-07 sec) per iteration: 0.102156 sec for 693433 iterations 0.150 usec (1.50483e-07 sec) per iteration: 0.10435 sec for 693433 iterations 0.151 usec (1.51315e-07 sec) per iteration: 0.104927 sec for 693433 iterations mean: 0.150680 usec stddev: 0.001790 usec relative stddev: 1.19% RESULT TestClockGetTime: x86-64_nnacl_newlib_static= {0.150680, 0.001790} us Measuring clock cycles: first runs (cycles): 510 352 330 330 332 330 328 332 332 328 ... slowest runs (cycles): ... 332 332 333 333 333 333 339 352 372 510 min: 325 cycles q1: 330 cycles median: 330 cycles q3: 332 cycles max: 510 cycles RESULT TestClockGetTime_CycleCount: x86-64_nnacl_newlib_static= {330, 2} count Apparent clock speed: 2190 MHz TestTlsVariable: Measuring real time: 0.008 usec (8.19e-09 sec) per iteration: 8.19e-07 sec for 100 iterations 0.006 usec (5.736e-09 sec) per iteration: 5.736e-06 sec for 1000 iterations 0.006 usec (5.7834e-09 sec) per iteration: 5.7834e-05 sec for 10000 iterations 0.006 usec (5.69355e-09 sec) per iteration: 0.0984465 sec for 17290866 iterations 0.006 usec (5.83258e-09 sec) per iteration: 0.10085 sec for 17290866 iterations 0.006 usec (5.69176e-09 sec) per iteration: 0.0984155 sec for 17290866 iterations 0.006 usec (5.67219e-09 sec) per iteration: 0.098077 sec for 17290866 iterations 0.006 usec (5.66191e-09 sec) per iteration: 0.0978992 sec for 17290866 iterations mean: 0.005710 usec stddev: 0.000062 usec relative stddev: 1.09% RESULT TestTlsVariable: x86-64_nnacl_newlib_static= {0.005710, 0.000062} us Measuring clock cycles: first runs (cycles): 158 77 36 36 35 33 27 29 29 25 ... slowest runs (cycles): ... 33 33 33 33 33 35 36 36 77 158 min: 24 cycles q1: 27 cycles median: 29 cycles q3: 31 cycles max: 158 cycles RESULT TestTlsVariable_CycleCount: x86-64_nnacl_newlib_static= {29, 4} count Apparent clock speed: 5078 MHz TestMmapAnonymous: Measuring real time: 2.107 usec (2.10717e-06 sec) per iteration: 0.000210717 sec for 100 iterations 1.793 usec (1.79284e-06 sec) per iteration: 0.0850827 sec for 47457 iterations 1.800 usec (1.79955e-06 sec) per iteration: 0.0854015 sec for 47457 iterations 1.788 usec (1.78831e-06 sec) per iteration: 0.0848678 sec for 47457 iterations 1.791 usec (1.79101e-06 sec) per iteration: 0.0849957 sec for 47457 iterations 1.789 usec (1.78902e-06 sec) per iteration: 0.0849016 sec for 47457 iterations mean: 1.792146 usec stddev: 0.004029 usec relative stddev: 0.22% RESULT TestMmapAnonymous: x86-64_nnacl_newlib_static= {1.792146, 0.004029} us Measuring clock cycles: first runs (cycles): 5067 3944 3880 3841 3848 3980 3834 3823 3865 3847 ... slowest runs (cycles): ... 3898 3911 3922 3935 3944 3951 3980 4022 4065 5067 min: 3815 cycles q1: 3836 cycles median: 3849 cycles q3: 3863 cycles max: 5067 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_nnacl_newlib_static= {3849, 27} count Apparent clock speed: 2148 MHz TestAtomicIncrement: Measuring real time: 0.015 usec (1.531e-08 sec) per iteration: 1.531e-06 sec for 100 iterations 0.009 usec (9.45e-09 sec) per iteration: 9.45e-06 sec for 1000 iterations 0.010 usec (9.9077e-09 sec) per iteration: 9.9077e-05 sec for 10000 iterations 0.009 usec (9.4501e-09 sec) per iteration: 0.0953813 sec for 10093159 iterations 0.010 usec (9.7059e-09 sec) per iteration: 0.0979632 sec for 10093159 iterations 0.010 usec (9.61034e-09 sec) per iteration: 0.0969986 sec for 10093159 iterations 0.010 usec (9.58628e-09 sec) per iteration: 0.0967558 sec for 10093159 iterations 0.010 usec (9.56423e-09 sec) per iteration: 0.0965332 sec for 10093159 iterations mean: 0.009583 usec stddev: 0.000082 usec relative stddev: 0.86% RESULT TestAtomicIncrement: x86-64_nnacl_newlib_static= {0.009583, 0.000082} us Measuring clock cycles: first runs (cycles): 121 53 52 53 53 51 51 53 51 50 ... slowest runs (cycles): ... 53 53 53 53 53 53 53 53 53 121 min: 48 cycles q1: 51 cycles median: 51 cycles q3: 53 cycles max: 121 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_nnacl_newlib_static= {51, 2} count Apparent clock speed: 5322 MHz TestUncontendedMutexLock: Measuring real time: 0.028 usec (2.822e-08 sec) per iteration: 2.822e-06 sec for 100 iterations 0.024 usec (2.4271e-08 sec) per iteration: 2.4271e-05 sec for 1000 iterations 0.025 usec (2.486e-08 sec) per iteration: 0.102427 sec for 4120143 iterations 0.025 usec (2.4903e-08 sec) per iteration: 0.102604 sec for 4120143 iterations 0.025 usec (2.46474e-08 sec) per iteration: 0.101551 sec for 4120143 iterations 0.025 usec (2.45736e-08 sec) per iteration: 0.101247 sec for 4120143 iterations 0.025 usec (2.46109e-08 sec) per iteration: 0.1014 sec for 4120143 iterations mean: 0.024719 usec stddev: 0.000135 usec relative stddev: 0.55% RESULT TestUncontendedMutexLock: x86-64_nnacl_newlib_static= {0.024719, 0.000135} us Measuring clock cycles: first runs (cycles): 233 110 83 84 81 81 84 81 81 84 ... slowest runs (cycles): ... 84 84 84 84 84 84 84 90 110 233 min: 79 cycles q1: 81 cycles median: 82 cycles q3: 83 cycles max: 233 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_nnacl_newlib_static= {82, 2} count Apparent clock speed: 3317 MHz TestCondvarSignalNoOp: Measuring real time: 0.238 usec (2.3792e-07 sec) per iteration: 2.3792e-05 sec for 100 iterations 0.217 usec (2.16894e-07 sec) per iteration: 0.0911624 sec for 420309 iterations 0.215 usec (2.15477e-07 sec) per iteration: 0.0905669 sec for 420309 iterations 0.217 usec (2.16594e-07 sec) per iteration: 0.0910363 sec for 420309 iterations 0.223 usec (2.23057e-07 sec) per iteration: 0.0937528 sec for 420309 iterations 0.216 usec (2.16188e-07 sec) per iteration: 0.0908656 sec for 420309 iterations mean: 0.217642 usec stddev: 0.002749 usec relative stddev: 1.26% RESULT TestCondvarSignalNoOp: x86-64_nnacl_newlib_static= {0.217642, 0.002749} us Measuring clock cycles: first runs (cycles): 711 502 502 499 480 480 482 482 477 480 ... slowest runs (cycles): ... 482 482 482 482 482 482 499 502 502 711 min: 473 cycles q1: 477 cycles median: 479 cycles q3: 480 cycles max: 711 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_nnacl_newlib_static= {479, 3} count Apparent clock speed: 2201 MHz TestThreadCreateAndJoin: Measuring real time: 42.412 usec (4.24116e-05 sec) per iteration: 0.00424116 sec for 100 iterations 29.857 usec (2.98573e-05 sec) per iteration: 0.0703737 sec for 2357 iterations 28.407 usec (2.84072e-05 sec) per iteration: 0.0669557 sec for 2357 iterations 28.143 usec (2.81435e-05 sec) per iteration: 0.0663342 sec for 2357 iterations 29.349 usec (2.93489e-05 sec) per iteration: 0.0691753 sec for 2357 iterations 26.622 usec (2.66215e-05 sec) per iteration: 0.0627469 sec for 2357 iterations mean: 28.475688 usec stddev: 1.115739 usec relative stddev: 3.92% RESULT TestThreadCreateAndJoin: x86-64_nnacl_newlib_static= {28.475688, 1.115739} us Measuring clock cycles: first runs (cycles): 62549 45652 50684 47619 48341 48874 85276 48930 44870 50054 ... slowest runs (cycles): ... 59705 62549 63935 66412 67811 69985 74615 85276 85916 90408 min: 42375 cycles q1: 47096 cycles median: 48389 cycles q3: 51871 cycles max: 90408 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_nnacl_newlib_static= {48389, 4775} count Apparent clock speed: 1699 MHz TestThreadWakeup: Measuring real time: 11.989 usec (1.19885e-05 sec) per iteration: 0.00119885 sec for 100 iterations 14.207 usec (1.42066e-05 sec) per iteration: 0.118498 sec for 8341 iterations 12.258 usec (1.22579e-05 sec) per iteration: 0.102243 sec for 8341 iterations 13.716 usec (1.37162e-05 sec) per iteration: 0.114407 sec for 8341 iterations 13.773 usec (1.37728e-05 sec) per iteration: 0.114879 sec for 8341 iterations 14.631 usec (1.46306e-05 sec) per iteration: 0.122034 sec for 8341 iterations mean: 13.716826 usec stddev: 0.800793 usec relative stddev: 5.84% RESULT TestThreadWakeup: x86-64_nnacl_newlib_static= {13.716826, 0.800793} us Measuring clock cycles: first runs (cycles): 144350 169427 27436 26079 20047 27755 27196 27559 26746 26548 ... slowest runs (cycles): ... 33596 35140 36748 46003 49078 52105 61098 80958 144350 169427 min: 20047 cycles q1: 26935 cycles median: 28067 cycles q3: 30738 cycles max: 169427 cycles RESULT TestThreadWakeup_CycleCount: x86-64_nnacl_newlib_static= {28067, 3803} count Apparent clock speed: 2046 MHz TestCatchingFault: Measuring real time: 2.634 usec (2.63435e-06 sec) per iteration: 0.000263435 sec for 100 iterations 1.713 usec (1.71255e-06 sec) per iteration: 0.0650082 sec for 37960 iterations 1.708 usec (1.70775e-06 sec) per iteration: 0.0648262 sec for 37960 iterations 1.706 usec (1.7064e-06 sec) per iteration: 0.0647748 sec for 37960 iterations 1.715 usec (1.71533e-06 sec) per iteration: 0.0651141 sec for 37960 iterations 1.721 usec (1.72077e-06 sec) per iteration: 0.0653206 sec for 37960 iterations mean: 1.712560 usec stddev: 0.005221 usec relative stddev: 0.30% RESULT TestCatchingFault: x86-64_nnacl_newlib_static= {1.712560, 0.005221} us Measuring clock cycles: first runs (cycles): 5367 4496 3718 3727 3715 3764 3718 3709 3716 3733 ... slowest runs (cycles): ... 3733 3755 3764 3766 3772 4455 4466 4496 4551 5367 min: 3695 cycles q1: 3713 cycles median: 3717 cycles q3: 3722 cycles max: 5367 cycles RESULT TestCatchingFault_CycleCount: x86-64_nnacl_newlib_static= {3717, 9} count Apparent clock speed: 2170 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 35.986 usec (3.59864e-05 sec) per iteration: 0.00359864 sec for 100 iterations 27.481 usec (2.74812e-05 sec) per iteration: 0.0763427 sec for 2778 iterations 28.504 usec (2.85037e-05 sec) per iteration: 0.0791833 sec for 2778 iterations 30.670 usec (3.06695e-05 sec) per iteration: 0.0851999 sec for 2778 iterations 32.701 usec (3.27009e-05 sec) per iteration: 0.0908431 sec for 2778 iterations 23.333 usec (2.33335e-05 sec) per iteration: 0.0648204 sec for 2778 iterations mean: 28.537755 usec stddev: 3.164749 usec relative stddev: 11.09% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_nnacl_newlib_static= {28.537755, 3.164749} us Measuring clock cycles: first runs (cycles): 49881 44437 49197 51302 47598 47112 47736 47358 50310 57618 ... slowest runs (cycles): ... 55378 55875 57618 59690 63023 64591 66576 66738 76807 90892 min: 44437 cycles q1: 46974 cycles median: 48180 cycles q3: 49931 cycles max: 90892 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_nnacl_newlib_static= {48180, 2957} count Apparent clock speed: 1688 MHz [ OK ] nacl_newlib.run_performance_test (5990 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-64 --subarch 64 --arch x86 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-linux-x86-64/test_results/nacl_clock_cputime_test.out /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-linux-x86-64/obj/src/shared/platform/nacl_clock_test -c [ RUN ] nacl_newlib.run_nacl_clock_cputime_test /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-linux-x86-64/obj/src/shared/platform/nacl_clock_test -c [ OK ] nacl_newlib.run_nacl_clock_cputime_test (2720 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-64 --subarch 64 --arch x86 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-linux-x86-64/test_results/nacl_interval_multi_reg_test.out /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-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/kitchen-workdir/native_client/scons-out/opt-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 (590 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-64 --subarch 64 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-linux-x86-64/test_results/performance_test.out /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-linux-x86-64/obj/tests/performance/performance_test trusted_linux_x86-64 [ RUN ] nacl_newlib.run_trusted_performance_test /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-linux-x86-64/obj/tests/performance/performance_test trusted_linux_x86-64 TestNull: Measuring real time: 0.005 usec (5.27e-09 sec) per iteration: 5.27e-07 sec for 100 iterations 0.002 usec (2.186e-09 sec) per iteration: 2.186e-06 sec for 1000 iterations 0.002 usec (1.8626e-09 sec) per iteration: 1.8626e-05 sec for 10000 iterations 0.002 usec (1.8901e-09 sec) per iteration: 0.101477 sec for 53688392 iterations 0.002 usec (1.88999e-09 sec) per iteration: 0.101471 sec for 53688392 iterations 0.002 usec (1.89662e-09 sec) per iteration: 0.101826 sec for 53688392 iterations 0.002 usec (1.89099e-09 sec) per iteration: 0.101524 sec for 53688392 iterations 0.002 usec (1.91711e-09 sec) per iteration: 0.102927 sec for 53688392 iterations mean: 0.001897 usec stddev: 0.000010 usec relative stddev: 0.55% RESULT TestNull: trusted_linux_x86-64= {0.001897, 0.000010} us Measuring clock cycles: first runs (cycles): 77 20 24 24 24 24 24 24 24 24 ... slowest runs (cycles): ... 25 25 25 25 25 26 26 27 27 77 min: 20 cycles q1: 22 cycles median: 24 cycles q3: 24 cycles max: 77 cycles RESULT TestNull_CycleCount: trusted_linux_x86-64= {24, 2} count Apparent clock speed: 12652 MHz TestHostSyscall: Measuring real time: 0.070 usec (7.046e-08 sec) per iteration: 7.046e-06 sec for 100 iterations 0.056 usec (5.6371e-08 sec) per iteration: 5.6371e-05 sec for 1000 iterations 0.059 usec (5.86113e-08 sec) per iteration: 0.103974 sec for 1773961 iterations 0.057 usec (5.69583e-08 sec) per iteration: 0.101042 sec for 1773961 iterations 0.057 usec (5.69179e-08 sec) per iteration: 0.10097 sec for 1773961 iterations 0.057 usec (5.70064e-08 sec) per iteration: 0.101127 sec for 1773961 iterations 0.057 usec (5.68904e-08 sec) per iteration: 0.100921 sec for 1773961 iterations mean: 0.057277 usec stddev: 0.000668 usec relative stddev: 1.17% RESULT TestHostSyscall: trusted_linux_x86-64= {0.057277, 0.000668} us Measuring clock cycles: first runs (cycles): 304 165 170 147 145 150 147 145 145 146 ... slowest runs (cycles): ... 149 150 150 150 151 152 154 165 170 304 min: 143 cycles q1: 145 cycles median: 145 cycles q3: 146 cycles max: 304 cycles RESULT TestHostSyscall_CycleCount: trusted_linux_x86-64= {145, 1} count Apparent clock speed: 2532 MHz TestSetjmpLongjmp: Measuring real time: 0.034 usec (3.414e-08 sec) per iteration: 3.414e-06 sec for 100 iterations 0.028 usec (2.8061e-08 sec) per iteration: 2.8061e-05 sec for 1000 iterations 0.028 usec (2.75612e-08 sec) per iteration: 0.0982188 sec for 3563664 iterations 0.028 usec (2.75205e-08 sec) per iteration: 0.0980738 sec for 3563664 iterations 0.027 usec (2.74547e-08 sec) per iteration: 0.0978394 sec for 3563664 iterations 0.027 usec (2.74072e-08 sec) per iteration: 0.0976702 sec for 3563664 iterations 0.027 usec (2.73843e-08 sec) per iteration: 0.0975883 sec for 3563664 iterations mean: 0.027466 usec stddev: 0.000067 usec relative stddev: 0.24% RESULT TestSetjmpLongjmp: trusted_linux_x86-64= {0.027466, 0.000067} us Measuring clock cycles: first runs (cycles): 174 106 127 105 75 79 75 75 75 75 ... slowest runs (cycles): ... 77 77 79 79 79 105 106 127 174 202 min: 70 cycles q1: 74 cycles median: 75 cycles q3: 77 cycles max: 202 cycles RESULT TestSetjmpLongjmp_CycleCount: trusted_linux_x86-64= {75, 3} count Apparent clock speed: 2731 MHz TestClockGetTime: Measuring real time: 0.055 usec (5.538e-08 sec) per iteration: 5.538e-06 sec for 100 iterations 0.052 usec (5.1624e-08 sec) per iteration: 5.1624e-05 sec for 1000 iterations 0.058 usec (5.84367e-08 sec) per iteration: 0.113197 sec for 1937083 iterations 0.053 usec (5.32961e-08 sec) per iteration: 0.103239 sec for 1937083 iterations 0.056 usec (5.6356e-08 sec) per iteration: 0.109166 sec for 1937083 iterations 0.057 usec (5.66846e-08 sec) per iteration: 0.109803 sec for 1937083 iterations 0.058 usec (5.82575e-08 sec) per iteration: 0.11285 sec for 1937083 iterations mean: 0.056606 usec stddev: 0.001849 usec relative stddev: 3.27% RESULT TestClockGetTime: trusted_linux_x86-64= {0.056606, 0.001849} us Measuring clock cycles: first runs (cycles): 297 178 132 132 132 137 132 134 127 132 ... slowest runs (cycles): ... 134 134 134 134 134 134 135 137 178 297 min: 127 cycles q1: 130 cycles median: 132 cycles q3: 132 cycles max: 297 cycles RESULT TestClockGetTime_CycleCount: trusted_linux_x86-64= {132, 2} count Apparent clock speed: 2332 MHz TestTlsVariable: Measuring real time: 0.005 usec (5.17e-09 sec) per iteration: 5.17e-07 sec for 100 iterations 0.002 usec (1.939e-09 sec) per iteration: 1.939e-06 sec for 1000 iterations 0.002 usec (1.9727e-09 sec) per iteration: 1.9727e-05 sec for 10000 iterations 0.002 usec (1.90076e-09 sec) per iteration: 0.0963533 sec for 50691945 iterations 0.002 usec (1.89041e-09 sec) per iteration: 0.0958287 sec for 50691945 iterations 0.002 usec (1.90291e-09 sec) per iteration: 0.0964625 sec for 50691945 iterations 0.002 usec (1.88459e-09 sec) per iteration: 0.0955335 sec for 50691945 iterations 0.002 usec (1.89025e-09 sec) per iteration: 0.0958203 sec for 50691945 iterations mean: 0.001894 usec stddev: 0.000007 usec relative stddev: 0.37% RESULT TestTlsVariable: trusted_linux_x86-64= {0.001894, 0.000007} us Measuring clock cycles: first runs (cycles): 77 24 24 22 24 25 25 25 25 22 ... slowest runs (cycles): ... 25 25 25 25 25 25 26 26 27 77 min: 19 cycles q1: 22 cycles median: 24 cycles q3: 24 cycles max: 77 cycles RESULT TestTlsVariable_CycleCount: trusted_linux_x86-64= {24, 2} count Apparent clock speed: 12673 MHz TestMmapAnonymous: Measuring real time: 0.676 usec (6.7583e-07 sec) per iteration: 6.7583e-05 sec for 100 iterations 0.513 usec (5.12964e-07 sec) per iteration: 0.0759012 sec for 147966 iterations 0.502 usec (5.01648e-07 sec) per iteration: 0.0742268 sec for 147966 iterations 0.501 usec (5.01035e-07 sec) per iteration: 0.0741361 sec for 147966 iterations 0.500 usec (5.00212e-07 sec) per iteration: 0.0740144 sec for 147966 iterations 0.501 usec (5.00705e-07 sec) per iteration: 0.0740874 sec for 147966 iterations mean: 0.503313 usec stddev: 0.004848 usec relative stddev: 0.96% RESULT TestMmapAnonymous: trusted_linux_x86-64= {0.503313, 0.004848} us Measuring clock cycles: first runs (cycles): 1533 1386 1124 1100 1100 1093 1093 1094 1095 1349 ... slowest runs (cycles): ... 1124 1124 1137 1144 1144 1148 1166 1349 1386 1533 min: 1086 cycles q1: 1098 cycles median: 1102 cycles q3: 1109 cycles max: 1533 cycles RESULT TestMmapAnonymous_CycleCount: trusted_linux_x86-64= {1102, 11} count Apparent clock speed: 2189 MHz TestAtomicIncrement: Measuring real time: 0.013 usec (1.289e-08 sec) per iteration: 1.289e-06 sec for 100 iterations 0.009 usec (9.322e-09 sec) per iteration: 9.322e-06 sec for 1000 iterations 0.009 usec (9.196e-09 sec) per iteration: 9.196e-05 sec for 10000 iterations 0.009 usec (9.37428e-09 sec) per iteration: 0.101939 sec for 10874293 iterations 0.009 usec (9.37607e-09 sec) per iteration: 0.101958 sec for 10874293 iterations 0.009 usec (9.37487e-09 sec) per iteration: 0.101945 sec for 10874293 iterations 0.009 usec (9.38235e-09 sec) per iteration: 0.102026 sec for 10874293 iterations 0.009 usec (9.40014e-09 sec) per iteration: 0.10222 sec for 10874293 iterations mean: 0.009382 usec stddev: 0.000010 usec relative stddev: 0.10% RESULT TestAtomicIncrement: trusted_linux_x86-64= {0.009382, 0.000010} us Measuring clock cycles: first runs (cycles): 97 365 50 51 51 50 51 48 50 451 ... slowest runs (cycles): ... 51 51 55 57 57 97 345 363 365 451 min: 48 cycles q1: 48 cycles median: 50 cycles q3: 51 cycles max: 451 cycles RESULT TestAtomicIncrement_CycleCount: trusted_linux_x86-64= {50, 3} count Apparent clock speed: 5330 MHz TestUncontendedMutexLock: Measuring real time: 0.029 usec (2.898e-08 sec) per iteration: 2.898e-06 sec for 100 iterations 0.022 usec (2.1606e-08 sec) per iteration: 2.1606e-05 sec for 1000 iterations 0.023 usec (2.32513e-08 sec) per iteration: 0.107615 sec for 4628343 iterations 0.022 usec (2.24843e-08 sec) per iteration: 0.104065 sec for 4628343 iterations 0.022 usec (2.20824e-08 sec) per iteration: 0.102205 sec for 4628343 iterations 0.022 usec (2.21985e-08 sec) per iteration: 0.102742 sec for 4628343 iterations 0.022 usec (2.24091e-08 sec) per iteration: 0.103717 sec for 4628343 iterations mean: 0.022485 usec stddev: 0.000409 usec relative stddev: 1.82% RESULT TestUncontendedMutexLock: trusted_linux_x86-64= {0.022485, 0.000409} us Measuring clock cycles: first runs (cycles): 205 895 79 79 81 81 79 77 77 365 ... slowest runs (cycles): ... 82 82 82 82 82 205 363 365 385 895 min: 77 cycles q1: 79 cycles median: 79 cycles q3: 81 cycles max: 895 cycles RESULT TestUncontendedMutexLock_CycleCount: trusted_linux_x86-64= {79, 2} count Apparent clock speed: 3513 MHz TestCondvarSignalNoOp: Measuring real time: 0.022 usec (2.155e-08 sec) per iteration: 2.155e-06 sec for 100 iterations 0.016 usec (1.5987e-08 sec) per iteration: 1.5987e-05 sec for 1000 iterations 0.016 usec (1.62436e-08 sec) per iteration: 0.101605 sec for 6255082 iterations 0.016 usec (1.62406e-08 sec) per iteration: 0.101586 sec for 6255082 iterations 0.016 usec (1.62273e-08 sec) per iteration: 0.101503 sec for 6255082 iterations 0.016 usec (1.6248e-08 sec) per iteration: 0.101633 sec for 6255082 iterations 0.017 usec (1.66456e-08 sec) per iteration: 0.10412 sec for 6255082 iterations mean: 0.016321 usec stddev: 0.000162 usec relative stddev: 1.00% RESULT TestCondvarSignalNoOp: trusted_linux_x86-64= {0.016321, 0.000162} us Measuring clock cycles: first runs (cycles): 154 255 68 68 68 68 68 68 68 281 ... slowest runs (cycles): ... 73 73 73 74 75 154 237 255 255 281 min: 66 cycles q1: 68 cycles median: 69 cycles q3: 72 cycles max: 281 cycles RESULT TestCondvarSignalNoOp_CycleCount: trusted_linux_x86-64= {69, 4} count Apparent clock speed: 4228 MHz TestThreadCreateAndJoin: Measuring real time: 43.551 usec (4.35506e-05 sec) per iteration: 0.00435506 sec for 100 iterations 31.603 usec (3.16026e-05 sec) per iteration: 0.0725595 sec for 2296 iterations 32.255 usec (3.22549e-05 sec) per iteration: 0.0740572 sec for 2296 iterations 24.691 usec (2.46912e-05 sec) per iteration: 0.056691 sec for 2296 iterations 31.036 usec (3.10364e-05 sec) per iteration: 0.0712596 sec for 2296 iterations 34.566 usec (3.45658e-05 sec) per iteration: 0.0793631 sec for 2296 iterations mean: 30.830168 usec stddev: 3.295786 usec relative stddev: 10.69% RESULT TestThreadCreateAndJoin: trusted_linux_x86-64= {30.830168, 3.295786} us Measuring clock cycles: first runs (cycles): 55499 55216 53946 53092 53829 53993 812195 210514 264939 116944 ... slowest runs (cycles): ... 207811 210514 218050 219218 241311 264939 278113 310063 365089 812195 min: 53092 cycles q1: 61148 cycles median: 81705 cycles q3: 105314 cycles max: 812195 cycles RESULT TestThreadCreateAndJoin_CycleCount: trusted_linux_x86-64= {81705, 44166} count Apparent clock speed: 2650 MHz TestThreadWakeup: Measuring real time: 17.892 usec (1.78923e-05 sec) per iteration: 0.00178923 sec for 100 iterations 20.430 usec (2.04301e-05 sec) per iteration: 0.114184 sec for 5589 iterations 19.843 usec (1.98434e-05 sec) per iteration: 0.110905 sec for 5589 iterations 19.830 usec (1.98299e-05 sec) per iteration: 0.110829 sec for 5589 iterations 19.914 usec (1.99138e-05 sec) per iteration: 0.111298 sec for 5589 iterations 20.580 usec (2.05797e-05 sec) per iteration: 0.11502 sec for 5589 iterations mean: 20.119368 usec stddev: 0.319569 usec relative stddev: 1.59% RESULT TestThreadWakeup: trusted_linux_x86-64= {20.119368, 0.319569} us Measuring clock cycles: first runs (cycles): 40816 37608 37771 39141 38094 36314 38798 37822 36858 39571 ... slowest runs (cycles): ... 40930 41156 41583 43924 46640 47464 53259 54423 78702 80038 min: 32421 cycles q1: 38012 cycles median: 38694 cycles q3: 39458 cycles max: 80038 cycles RESULT TestThreadWakeup_CycleCount: trusted_linux_x86-64= {38694, 1446} count Apparent clock speed: 1923 MHz WARNING: should have taken less than 2.000000 secs [ OK ] nacl_newlib.run_trusted_performance_test (5430 ms) scons: done building targets. ********************************************************************** COMMAND EXECUTION REPORT ********************************************************************** 7 command_tester.py 1 doSizeCheck ********************************************************************** ENVIRONMENT USAGE REPORT ********************************************************************** 5 nacl-x86-64 3 opt-linux-x86-64 Command return code: 0