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 gce-trusty-e833d7b0-us-west1-b-dqtb 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: 22324284 kB MemAvailable: 29495872 kB ====================================================================== LOAD: ====================================================================== 1.79 1.87 0.92 1/436 8708 ====================================================================== UPTIME: ====================================================================== 426.29 2464.39 ====================================================================== 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: dbg-linux TARGET_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/dbg-linux-x86-64 OBJ_ROOT: /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: /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 -O0 -g -D_FORTIFY_SOURCE=2 -D_POSIX_C_SOURCE=199506 -D_XOPEN_SOURCE=600 -D_GNU_SOURCE=1 -D_LARGEFILE64_SOURCE=1 -D__STDC_LIMIT_MACROS=1 -D__STDC_FORMAT_MACROS=1 -D_DEBUG -I/b/s/w/ir/kitchen-workdir -c -o UNKNOWN COMPILER NACL_BUILD_FAMILY: UNTRUSTED_IRT BUILD_TYPE: nacl_irt TARGET_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt-x86-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 [8716,2419939200:13:46:58.315752] Native Client module will be loaded at base address 0x00005a7100000000 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/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 [8721,2587826048:13:46:58.594103] Native Client module will be loaded at base address 0x000038d000000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_nnacl_newlib_static= {0.106830, 0.004960} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test (1660 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/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 (75600 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/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 [8751,3254794112:13:47:14.154920] Native Client module will be loaded at base address 0x00003be300000000 TestNull: Measuring real time: 0.011 usec (1.062e-08 sec) per iteration: 1.062e-06 sec for 100 iterations 0.003 usec (2.653e-09 sec) per iteration: 2.653e-06 sec for 1000 iterations 0.002 usec (2.3214e-09 sec) per iteration: 2.3214e-05 sec for 10000 iterations 0.002 usec (1.89799e-09 sec) per iteration: 0.0817604 sec for 43077453 iterations 0.002 usec (1.89713e-09 sec) per iteration: 0.0817236 sec for 43077453 iterations 0.002 usec (1.90052e-09 sec) per iteration: 0.0818697 sec for 43077453 iterations 0.002 usec (1.90888e-09 sec) per iteration: 0.0822297 sec for 43077453 iterations 0.002 usec (1.89908e-09 sec) per iteration: 0.0818077 sec for 43077453 iterations mean: 0.001901 usec stddev: 0.000004 usec relative stddev: 0.22% RESULT TestNull: x86-64_nnacl_newlib_static= {0.001901, 0.000004} us Measuring clock cycles: first runs (cycles): 93 25 27 24 26 22 26 29 25 22 ... slowest runs (cycles): ... 26 27 27 27 27 27 28 29 29 93 min: 20 cycles q1: 24 cycles median: 24 cycles q3: 25 cycles max: 93 cycles RESULT TestNull_CycleCount: x86-64_nnacl_newlib_static= {24, 1} count Apparent clock speed: 12627 MHz TestNaClSyscall: Measuring real time: 0.226 usec (2.2587e-07 sec) per iteration: 2.2587e-05 sec for 100 iterations 0.215 usec (2.1494e-07 sec) per iteration: 0.095161 sec for 442732 iterations 0.209 usec (2.0909e-07 sec) per iteration: 0.0925707 sec for 442732 iterations 0.207 usec (2.07375e-07 sec) per iteration: 0.0918117 sec for 442732 iterations 0.205 usec (2.04878e-07 sec) per iteration: 0.090706 sec for 442732 iterations 0.213 usec (2.13006e-07 sec) per iteration: 0.0943044 sec for 442732 iterations mean: 0.209858 usec stddev: 0.003669 usec relative stddev: 1.75% RESULT TestNaClSyscall: x86-64_nnacl_newlib_static= {0.209858, 0.003669} us Measuring clock cycles: first runs (cycles): 502 376 350 352 350 352 352 387 352 385 ... slowest runs (cycles): ... 376 376 378 385 385 387 389 423 448 502 min: 341 cycles q1: 347 cycles median: 350 cycles q3: 352 cycles max: 502 cycles RESULT TestNaClSyscall_CycleCount: x86-64_nnacl_newlib_static= {350, 5} count Apparent clock speed: 1668 MHz TestSetjmpLongjmp: Measuring real time: 0.018 usec (1.819e-08 sec) per iteration: 1.819e-06 sec for 100 iterations 0.013 usec (1.2854e-08 sec) per iteration: 1.2854e-05 sec for 1000 iterations 0.013 usec (1.31266e-08 sec) per iteration: 0.10212 sec for 7779679 iterations 0.013 usec (1.29574e-08 sec) per iteration: 0.100804 sec for 7779679 iterations 0.013 usec (1.30064e-08 sec) per iteration: 0.101186 sec for 7779679 iterations 0.013 usec (1.33366e-08 sec) per iteration: 0.103754 sec for 7779679 iterations 0.013 usec (1.29311e-08 sec) per iteration: 0.1006 sec for 7779679 iterations mean: 0.013072 usec stddev: 0.000149 usec relative stddev: 1.14% RESULT TestSetjmpLongjmp: x86-64_nnacl_newlib_static= {0.013072, 0.000149} us Measuring clock cycles: first runs (cycles): 149 40 35 35 35 33 33 35 36 37 ... slowest runs (cycles): ... 33 35 35 35 35 35 36 37 40 149 min: 24 cycles q1: 26 cycles median: 28 cycles q3: 29 cycles max: 149 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_nnacl_newlib_static= {28, 3} count Apparent clock speed: 2142 MHz TestClockGetTime: Measuring real time: 0.275 usec (2.7474e-07 sec) per iteration: 2.7474e-05 sec for 100 iterations 0.276 usec (2.76292e-07 sec) per iteration: 0.100565 sec for 363980 iterations 0.275 usec (2.7462e-07 sec) per iteration: 0.0999563 sec for 363980 iterations 0.274 usec (2.74493e-07 sec) per iteration: 0.0999101 sec for 363980 iterations 0.275 usec (2.74804e-07 sec) per iteration: 0.100023 sec for 363980 iterations 0.277 usec (2.76544e-07 sec) per iteration: 0.100657 sec for 363980 iterations mean: 0.275351 usec stddev: 0.000881 usec relative stddev: 0.32% RESULT TestClockGetTime: x86-64_nnacl_newlib_static= {0.275351, 0.000881} us Measuring clock cycles: first runs (cycles): 1358 800 609 616 597 640 598 605 598 598 ... slowest runs (cycles): ... 636 638 638 640 642 645 671 675 800 1358 min: 592 cycles q1: 601 cycles median: 605 cycles q3: 611 cycles max: 1358 cycles RESULT TestClockGetTime_CycleCount: x86-64_nnacl_newlib_static= {605, 10} count Apparent clock speed: 2197 MHz TestTlsVariable: Measuring real time: 0.011 usec (1.086e-08 sec) per iteration: 1.086e-06 sec for 100 iterations 0.006 usec (5.536e-09 sec) per iteration: 5.536e-06 sec for 1000 iterations 0.006 usec (5.6572e-09 sec) per iteration: 5.6572e-05 sec for 10000 iterations 0.005 usec (5.32171e-09 sec) per iteration: 0.0940696 sec for 17676589 iterations 0.005 usec (5.32593e-09 sec) per iteration: 0.0941443 sec for 17676589 iterations 0.005 usec (5.3274e-09 sec) per iteration: 0.0941702 sec for 17676589 iterations 0.005 usec (5.29704e-09 sec) per iteration: 0.0936335 sec for 17676589 iterations 0.005 usec (5.29676e-09 sec) per iteration: 0.0936286 sec for 17676589 iterations mean: 0.005314 usec stddev: 0.000014 usec relative stddev: 0.26% RESULT TestTlsVariable: x86-64_nnacl_newlib_static= {0.005314, 0.000014} us Measuring clock cycles: first runs (cycles): 163 88 88 38 37 39 33 29 31 31 ... slowest runs (cycles): ... 52 53 53 55 59 62 75 88 88 163 min: 24 cycles q1: 31 cycles median: 37 cycles q3: 46 cycles max: 163 cycles RESULT TestTlsVariable_CycleCount: x86-64_nnacl_newlib_static= {37, 15} count Apparent clock speed: 6963 MHz TestMmapAnonymous: Measuring real time: 2.695 usec (2.69458e-06 sec) per iteration: 0.000269458 sec for 100 iterations 2.558 usec (2.55812e-06 sec) per iteration: 0.0949343 sec for 37111 iterations 2.479 usec (2.47936e-06 sec) per iteration: 0.0920115 sec for 37111 iterations 2.476 usec (2.47567e-06 sec) per iteration: 0.0918744 sec for 37111 iterations 2.472 usec (2.47197e-06 sec) per iteration: 0.0917374 sec for 37111 iterations 2.470 usec (2.47042e-06 sec) per iteration: 0.0916799 sec for 37111 iterations mean: 2.491108 usec stddev: 0.033647 usec relative stddev: 1.35% RESULT TestMmapAnonymous: x86-64_nnacl_newlib_static= {2.491108, 0.033647} us Measuring clock cycles: first runs (cycles): 7700 5367 5359 5477 5425 5328 5304 5295 5495 5508 ... slowest runs (cycles): ... 5496 5508 5548 5587 5649 5786 5827 7700 15053 22703 min: 5238 cycles q1: 5303 cycles median: 5332 cycles q3: 5413 cycles max: 22703 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_nnacl_newlib_static= {5332, 110} count Apparent clock speed: 2140 MHz TestAtomicIncrement: Measuring real time: 0.014 usec (1.429e-08 sec) per iteration: 1.429e-06 sec for 100 iterations 0.010 usec (9.552e-09 sec) per iteration: 9.552e-06 sec for 1000 iterations 0.009 usec (9.3224e-09 sec) per iteration: 9.3224e-05 sec for 10000 iterations 0.009 usec (9.43475e-09 sec) per iteration: 0.101205 sec for 10726851 iterations 0.009 usec (9.448e-09 sec) per iteration: 0.101347 sec for 10726851 iterations 0.009 usec (9.44185e-09 sec) per iteration: 0.101281 sec for 10726851 iterations 0.009 usec (9.4521e-09 sec) per iteration: 0.101391 sec for 10726851 iterations 0.010 usec (9.80999e-09 sec) per iteration: 0.10523 sec for 10726851 iterations mean: 0.009517 usec stddev: 0.000146 usec relative stddev: 1.54% RESULT TestAtomicIncrement: x86-64_nnacl_newlib_static= {0.009517, 0.000146} us Measuring clock cycles: first runs (cycles): 145 53 51 53 51 53 53 52 53 55 ... slowest runs (cycles): ... 55 55 55 55 55 55 55 55 55 145 min: 50 cycles q1: 52 cycles median: 53 cycles q3: 53 cycles max: 145 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_nnacl_newlib_static= {53, 1} count Apparent clock speed: 5569 MHz TestUncontendedMutexLock: Measuring real time: 0.043 usec (4.295e-08 sec) per iteration: 4.295e-06 sec for 100 iterations 0.036 usec (3.6171e-08 sec) per iteration: 3.6171e-05 sec for 1000 iterations 0.025 usec (2.471e-08 sec) per iteration: 0.0683143 sec for 2764645 iterations 0.025 usec (2.4585e-08 sec) per iteration: 0.0679688 sec for 2764645 iterations 0.025 usec (2.46019e-08 sec) per iteration: 0.0680154 sec for 2764645 iterations 0.025 usec (2.45722e-08 sec) per iteration: 0.0679335 sec for 2764645 iterations 0.025 usec (2.4564e-08 sec) per iteration: 0.0679107 sec for 2764645 iterations mean: 0.024607 usec stddev: 0.000053 usec relative stddev: 0.22% RESULT TestUncontendedMutexLock: x86-64_nnacl_newlib_static= {0.024607, 0.000053} us Measuring clock cycles: first runs (cycles): 233 83 81 83 83 83 83 83 83 81 ... slowest runs (cycles): ... 86 86 86 86 86 86 86 86 93 233 min: 81 cycles q1: 83 cycles median: 84 cycles q3: 84 cycles max: 233 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_nnacl_newlib_static= {84, 1} count Apparent clock speed: 3414 MHz TestCondvarSignalNoOp: Measuring real time: 0.596 usec (5.9593e-07 sec) per iteration: 5.9593e-05 sec for 100 iterations 0.306 usec (3.05847e-07 sec) per iteration: 0.0513224 sec for 167804 iterations 0.306 usec (3.05627e-07 sec) per iteration: 0.0512854 sec for 167804 iterations 0.306 usec (3.0603e-07 sec) per iteration: 0.051353 sec for 167804 iterations 0.305 usec (3.05471e-07 sec) per iteration: 0.0512592 sec for 167804 iterations 0.305 usec (3.05497e-07 sec) per iteration: 0.0512636 sec for 167804 iterations mean: 0.305694 usec stddev: 0.000214 usec relative stddev: 0.07% RESULT TestCondvarSignalNoOp: x86-64_nnacl_newlib_static= {0.305694, 0.000214} us Measuring clock cycles: first runs (cycles): 1498 764 748 721 687 677 680 684 686 687 ... slowest runs (cycles): ... 687 688 689 692 721 726 748 748 764 1498 min: 659 cycles q1: 671 cycles median: 675 cycles q3: 682 cycles max: 1498 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_nnacl_newlib_static= {675, 11} count Apparent clock speed: 2208 MHz TestThreadCreateAndJoin: Measuring real time: 48.198 usec (4.81979e-05 sec) per iteration: 0.00481979 sec for 100 iterations 40.583 usec (4.0583e-05 sec) per iteration: 0.0841691 sec for 2074 iterations 30.415 usec (3.04147e-05 sec) per iteration: 0.06308 sec for 2074 iterations 30.336 usec (3.03359e-05 sec) per iteration: 0.0629166 sec for 2074 iterations 28.057 usec (2.80571e-05 sec) per iteration: 0.0581903 sec for 2074 iterations 30.177 usec (3.01772e-05 sec) per iteration: 0.0625876 sec for 2074 iterations mean: 31.913555 usec stddev: 4.422267 usec relative stddev: 13.86% RESULT TestThreadCreateAndJoin: x86-64_nnacl_newlib_static= {31.913555, 4.422267} us Measuring clock cycles: first runs (cycles): 56148 50167 53383 53409 51276 53859 69188 52253 54109 58687 ... slowest runs (cycles): ... 89856 93002 102294 103367 116859 117096 121191 191182 205433 243502 min: 45691 cycles q1: 50800 cycles median: 52496 cycles q3: 69188 cycles max: 243502 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_nnacl_newlib_static= {52496, 18388} count Apparent clock speed: 1645 MHz TestThreadWakeup: Measuring real time: 14.500 usec (1.45004e-05 sec) per iteration: 0.00145004 sec for 100 iterations 17.623 usec (1.76228e-05 sec) per iteration: 0.121527 sec for 6896 iterations 20.251 usec (2.02511e-05 sec) per iteration: 0.139652 sec for 6896 iterations 21.054 usec (2.10538e-05 sec) per iteration: 0.145187 sec for 6896 iterations 26.302 usec (2.63024e-05 sec) per iteration: 0.181381 sec for 6896 iterations 26.601 usec (2.66006e-05 sec) per iteration: 0.183437 sec for 6896 iterations mean: 22.366140 usec stddev: 3.524736 usec relative stddev: 15.76% RESULT TestThreadWakeup: x86-64_nnacl_newlib_static= {22.366140, 3.524736} us Measuring clock cycles: first runs (cycles): 54755 45245 44648 53693 53614 77083 60753 73575 60438 67759 ... slowest runs (cycles): ... 60753 61325 61996 63280 67162 67759 70689 73575 77083 91772 min: 41077 cycles q1: 47780 cycles median: 48603 cycles q3: 53614 cycles max: 91772 cycles RESULT TestThreadWakeup_CycleCount: x86-64_nnacl_newlib_static= {48603, 5834} count Apparent clock speed: 2173 MHz TestCatchingFault: Measuring real time: 3.416 usec (3.41589e-06 sec) per iteration: 0.000341589 sec for 100 iterations 1.920 usec (1.9196e-06 sec) per iteration: 0.0561945 sec for 29274 iterations 1.925 usec (1.92507e-06 sec) per iteration: 0.0563545 sec for 29274 iterations 1.928 usec (1.92839e-06 sec) per iteration: 0.0564518 sec for 29274 iterations 1.935 usec (1.93506e-06 sec) per iteration: 0.0566469 sec for 29274 iterations 1.929 usec (1.92929e-06 sec) per iteration: 0.0564779 sec for 29274 iterations mean: 1.927482 usec stddev: 0.005086 usec relative stddev: 0.26% RESULT TestCatchingFault: x86-64_nnacl_newlib_static= {1.927482, 0.005086} us Measuring clock cycles: first runs (cycles): 5768 4346 4237 4321 4248 4281 4259 4191 4166 4230 ... slowest runs (cycles): ... 4261 4268 4269 4274 4281 4305 4321 4322 4346 5768 min: 4088 cycles q1: 4140 cycles median: 4173 cycles q3: 4220 cycles max: 5768 cycles RESULT TestCatchingFault_CycleCount: x86-64_nnacl_newlib_static= {4173, 80} count Apparent clock speed: 2165 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 83.864 usec (8.38642e-05 sec) per iteration: 0.00838642 sec for 100 iterations 66.598 usec (6.65983e-05 sec) per iteration: 0.0793852 sec for 1192 iterations 42.242 usec (4.22421e-05 sec) per iteration: 0.0503525 sec for 1192 iterations 46.654 usec (4.66537e-05 sec) per iteration: 0.0556112 sec for 1192 iterations 43.968 usec (4.39684e-05 sec) per iteration: 0.0524104 sec for 1192 iterations 51.824 usec (5.18243e-05 sec) per iteration: 0.0617746 sec for 1192 iterations mean: 50.257369 usec stddev: 8.789359 usec relative stddev: 17.49% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_nnacl_newlib_static= {50.257369, 8.789359} us Measuring clock cycles: first runs (cycles): 77669 73096 99506 81123 94438 204116 166876 155884 77172 65168 ... slowest runs (cycles): ... 208052 218507 254178 280547 280932 313648 360095 427293 632811 1403161 min: 58966 cycles q1: 84051 cycles median: 118887 cycles q3: 156667 cycles max: 1403161 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_nnacl_newlib_static= {118887, 72616} count Apparent clock speed: 2366 MHz [ OK ] nacl_newlib.run_performance_test (5900 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/dbg-linux-x86-64/test_results/nacl_clock_cputime_test.out /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 /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 (9090 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/dbg-linux-x86-64/test_results/nacl_interval_multi_reg_test.out /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 /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 (880 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/dbg-linux-x86-64/test_results/performance_test.out /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 /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.007 usec (6.53e-09 sec) per iteration: 6.53e-07 sec for 100 iterations 0.003 usec (2.603e-09 sec) per iteration: 2.603e-06 sec for 1000 iterations 0.003 usec (2.5218e-09 sec) per iteration: 2.5218e-05 sec for 10000 iterations 0.003 usec (2.58741e-09 sec) per iteration: 0.102602 sec for 39654215 iterations 0.003 usec (2.58021e-09 sec) per iteration: 0.102316 sec for 39654215 iterations 0.003 usec (2.57541e-09 sec) per iteration: 0.102126 sec for 39654215 iterations 0.003 usec (2.56928e-09 sec) per iteration: 0.101883 sec for 39654215 iterations 0.003 usec (2.5754e-09 sec) per iteration: 0.102126 sec for 39654215 iterations mean: 0.002578 usec stddev: 0.000006 usec relative stddev: 0.23% RESULT TestNull: trusted_linux_x86-64= {0.002578, 0.000006} us Measuring clock cycles: first runs (cycles): 154 26 24 26 24 27 26 24 26 29 ... slowest runs (cycles): ... 27 27 27 27 27 27 27 27 29 154 min: 24 cycles q1: 26 cycles median: 26 cycles q3: 27 cycles max: 154 cycles RESULT TestNull_CycleCount: trusted_linux_x86-64= {26, 1} count Apparent clock speed: 10087 MHz TestHostSyscall: Measuring real time: 0.073 usec (7.313e-08 sec) per iteration: 7.313e-06 sec for 100 iterations 0.061 usec (6.1335e-08 sec) per iteration: 6.1335e-05 sec for 1000 iterations 0.062 usec (6.16764e-08 sec) per iteration: 0.100557 sec for 1630390 iterations 0.062 usec (6.15204e-08 sec) per iteration: 0.100302 sec for 1630390 iterations 0.063 usec (6.3457e-08 sec) per iteration: 0.10346 sec for 1630390 iterations 0.062 usec (6.15918e-08 sec) per iteration: 0.100419 sec for 1630390 iterations 0.062 usec (6.15368e-08 sec) per iteration: 0.100329 sec for 1630390 iterations mean: 0.061956 usec stddev: 0.000752 usec relative stddev: 1.21% RESULT TestHostSyscall: trusted_linux_x86-64= {0.061956, 0.000752} us Measuring clock cycles: first runs (cycles): 444 202 158 156 156 156 157 158 159 158 ... slowest runs (cycles): ... 157 157 157 158 158 158 158 159 202 444 min: 154 cycles q1: 154 cycles median: 154 cycles q3: 156 cycles max: 444 cycles RESULT TestHostSyscall_CycleCount: trusted_linux_x86-64= {154, 2} count Apparent clock speed: 2486 MHz TestSetjmpLongjmp: Measuring real time: 0.105 usec (1.0502e-07 sec) per iteration: 1.0502e-05 sec for 100 iterations 0.027 usec (2.68877e-08 sec) per iteration: 0.0256025 sec for 952199 iterations 0.027 usec (2.70276e-08 sec) per iteration: 0.0257357 sec for 952199 iterations 0.027 usec (2.67351e-08 sec) per iteration: 0.0254572 sec for 952199 iterations 0.027 usec (2.6912e-08 sec) per iteration: 0.0256256 sec for 952199 iterations 0.027 usec (2.67249e-08 sec) per iteration: 0.0254474 sec for 952199 iterations mean: 0.026857 usec stddev: 0.000114 usec relative stddev: 0.43% RESULT TestSetjmpLongjmp: trusted_linux_x86-64= {0.026857, 0.000114} us Measuring clock cycles: first runs (cycles): 308 134 84 82 81 81 82 86 79 81 ... slowest runs (cycles): ... 83 84 84 84 84 84 84 86 134 308 min: 74 cycles q1: 77 cycles median: 77 cycles q3: 81 cycles max: 308 cycles RESULT TestSetjmpLongjmp_CycleCount: trusted_linux_x86-64= {77, 4} count Apparent clock speed: 2867 MHz TestClockGetTime: Measuring real time: 0.057 usec (5.734e-08 sec) per iteration: 5.734e-06 sec for 100 iterations 0.056 usec (5.5542e-08 sec) per iteration: 5.5542e-05 sec for 1000 iterations 0.059 usec (5.89542e-08 sec) per iteration: 0.106144 sec for 1800439 iterations 0.058 usec (5.79432e-08 sec) per iteration: 0.104323 sec for 1800439 iterations 0.059 usec (5.91186e-08 sec) per iteration: 0.106439 sec for 1800439 iterations 0.058 usec (5.81435e-08 sec) per iteration: 0.104684 sec for 1800439 iterations 0.059 usec (5.93234e-08 sec) per iteration: 0.106808 sec for 1800439 iterations mean: 0.058697 usec stddev: 0.000550 usec relative stddev: 0.94% RESULT TestClockGetTime: trusted_linux_x86-64= {0.058697, 0.000550} us Measuring clock cycles: first runs (cycles): 545 182 176 176 176 174 176 178 176 174 ... slowest runs (cycles): ... 176 176 176 178 178 178 178 179 182 545 min: 173 cycles q1: 174 cycles median: 176 cycles q3: 176 cycles max: 545 cycles RESULT TestClockGetTime_CycleCount: trusted_linux_x86-64= {176, 2} count Apparent clock speed: 2998 MHz TestTlsVariable: Measuring real time: 0.016 usec (1.587e-08 sec) per iteration: 1.587e-06 sec for 100 iterations 0.013 usec (1.2572e-08 sec) per iteration: 1.2572e-05 sec for 1000 iterations 0.003 usec (3.03264e-09 sec) per iteration: 0.0241222 sec for 7954183 iterations 0.003 usec (3.01543e-09 sec) per iteration: 0.0239853 sec for 7954183 iterations 0.003 usec (3.01307e-09 sec) per iteration: 0.0239665 sec for 7954183 iterations 0.003 usec (3.02815e-09 sec) per iteration: 0.0240865 sec for 7954183 iterations 0.003 usec (3.01181e-09 sec) per iteration: 0.0239565 sec for 7954183 iterations mean: 0.003020 usec stddev: 0.000009 usec relative stddev: 0.28% RESULT TestTlsVariable: trusted_linux_x86-64= {0.003020, 0.000009} us Measuring clock cycles: first runs (cycles): 181 27 29 29 29 29 27 27 26 26 ... slowest runs (cycles): ... 29 29 29 29 29 29 29 29 29 181 min: 24 cycles q1: 27 cycles median: 28 cycles q3: 29 cycles max: 181 cycles RESULT TestTlsVariable_CycleCount: trusted_linux_x86-64= {28, 2} count Apparent clock speed: 9271 MHz TestMmapAnonymous: Measuring real time: 0.640 usec (6.401e-07 sec) per iteration: 6.401e-05 sec for 100 iterations 0.513 usec (5.13478e-07 sec) per iteration: 0.0802181 sec for 156225 iterations 0.514 usec (5.13589e-07 sec) per iteration: 0.0802355 sec for 156225 iterations 0.513 usec (5.12871e-07 sec) per iteration: 0.0801233 sec for 156225 iterations 0.513 usec (5.12782e-07 sec) per iteration: 0.0801094 sec for 156225 iterations 0.513 usec (5.13063e-07 sec) per iteration: 0.0801533 sec for 156225 iterations mean: 0.513157 usec stddev: 0.000323 usec relative stddev: 0.06% RESULT TestMmapAnonymous: trusted_linux_x86-64= {0.513157, 0.000323} us Measuring clock cycles: first runs (cycles): 2099 1150 1131 1115 1129 1114 1115 1130 1135 1138 ... slowest runs (cycles): ... 1139 1142 1144 1150 1150 1150 1153 1163 1165 2099 min: 1106 cycles q1: 1120 cycles median: 1126 cycles q3: 1131 cycles max: 2099 cycles RESULT TestMmapAnonymous_CycleCount: trusted_linux_x86-64= {1126, 11} count Apparent clock speed: 2194 MHz TestAtomicIncrement: Measuring real time: 0.013 usec (1.317e-08 sec) per iteration: 1.317e-06 sec for 100 iterations 0.009 usec (9.351e-09 sec) per iteration: 9.351e-06 sec for 1000 iterations 0.009 usec (9.399e-09 sec) per iteration: 9.399e-05 sec for 10000 iterations 0.009 usec (9.43678e-09 sec) per iteration: 0.100402 sec for 10639429 iterations 0.010 usec (9.52891e-09 sec) per iteration: 0.101382 sec for 10639429 iterations 0.009 usec (9.42621e-09 sec) per iteration: 0.100289 sec for 10639429 iterations 0.009 usec (9.43115e-09 sec) per iteration: 0.100342 sec for 10639429 iterations 0.009 usec (9.42375e-09 sec) per iteration: 0.100263 sec for 10639429 iterations mean: 0.009449 usec stddev: 0.000040 usec relative stddev: 0.42% RESULT TestAtomicIncrement: trusted_linux_x86-64= {0.009449, 0.000040} us Measuring clock cycles: first runs (cycles): 488 62 61 62 62 61 62 63 62 55 ... slowest runs (cycles): ... 62 62 62 62 62 63 63 64 64 488 min: 55 cycles q1: 55 cycles median: 55 cycles q3: 57 cycles max: 488 cycles RESULT TestAtomicIncrement_CycleCount: trusted_linux_x86-64= {55, 2} count Apparent clock speed: 5821 MHz TestUncontendedMutexLock: Measuring real time: 0.063 usec (6.283e-08 sec) per iteration: 6.283e-06 sec for 100 iterations 0.056 usec (5.6316e-08 sec) per iteration: 5.6316e-05 sec for 1000 iterations 0.025 usec (2.45758e-08 sec) per iteration: 0.0436391 sec for 1775694 iterations 0.026 usec (2.61321e-08 sec) per iteration: 0.0464026 sec for 1775694 iterations 0.024 usec (2.43465e-08 sec) per iteration: 0.0432318 sec for 1775694 iterations 0.025 usec (2.46033e-08 sec) per iteration: 0.043688 sec for 1775694 iterations 0.024 usec (2.41683e-08 sec) per iteration: 0.0429155 sec for 1775694 iterations mean: 0.024765 usec stddev: 0.000702 usec relative stddev: 2.83% RESULT TestUncontendedMutexLock: trusted_linux_x86-64= {0.024765, 0.000702} us Measuring clock cycles: first runs (cycles): 363 92 88 88 86 88 85 88 88 86 ... slowest runs (cycles): ... 88 88 88 88 88 88 88 88 92 363 min: 85 cycles q1: 86 cycles median: 88 cycles q3: 88 cycles max: 363 cycles RESULT TestUncontendedMutexLock_CycleCount: trusted_linux_x86-64= {88, 2} count Apparent clock speed: 3553 MHz TestCondvarSignalNoOp: Measuring real time: 0.023 usec (2.346e-08 sec) per iteration: 2.346e-06 sec for 100 iterations 0.020 usec (1.9685e-08 sec) per iteration: 1.9685e-05 sec for 1000 iterations 0.020 usec (1.99667e-08 sec) per iteration: 0.101431 sec for 5080010 iterations 0.020 usec (2.00342e-08 sec) per iteration: 0.101774 sec for 5080010 iterations 0.020 usec (1.99558e-08 sec) per iteration: 0.101376 sec for 5080010 iterations 0.020 usec (1.99645e-08 sec) per iteration: 0.10142 sec for 5080010 iterations 0.020 usec (1.99528e-08 sec) per iteration: 0.10136 sec for 5080010 iterations mean: 0.019975 usec stddev: 0.000030 usec relative stddev: 0.15% RESULT TestCondvarSignalNoOp: trusted_linux_x86-64= {0.019975, 0.000030} us Measuring clock cycles: first runs (cycles): 297 82 73 72 73 73 73 79 72 73 ... slowest runs (cycles): ... 77 77 77 77 79 79 79 81 82 297 min: 70 cycles q1: 72 cycles median: 73 cycles q3: 73 cycles max: 297 cycles RESULT TestCondvarSignalNoOp_CycleCount: trusted_linux_x86-64= {73, 1} count Apparent clock speed: 3655 MHz TestThreadCreateAndJoin: Measuring real time: 30.984 usec (3.09838e-05 sec) per iteration: 0.00309838 sec for 100 iterations 24.902 usec (2.49024e-05 sec) per iteration: 0.08036 sec for 3227 iterations 25.123 usec (2.5123e-05 sec) per iteration: 0.0810721 sec for 3227 iterations 24.010 usec (2.40099e-05 sec) per iteration: 0.0774799 sec for 3227 iterations 26.051 usec (2.60506e-05 sec) per iteration: 0.0840652 sec for 3227 iterations 24.837 usec (2.48366e-05 sec) per iteration: 0.0801478 sec for 3227 iterations mean: 24.984507 usec stddev: 0.653328 usec relative stddev: 2.61% RESULT TestThreadCreateAndJoin: trusted_linux_x86-64= {24.984507, 0.653328} us Measuring clock cycles: first runs (cycles): 50955 46606 45411 46128 44529 44971 45389 63530 45446 595231 ... slowest runs (cycles): ... 64325 64637 67792 77616 81331 129996 169199 197896 338833 595231 min: 43851 cycles q1: 45446 cycles median: 46302 cycles q3: 48712 cycles max: 595231 cycles RESULT TestThreadCreateAndJoin_CycleCount: trusted_linux_x86-64= {46302, 3266} count Apparent clock speed: 1853 MHz TestThreadWakeup: Measuring real time: 14.603 usec (1.46028e-05 sec) per iteration: 0.00146028 sec for 100 iterations 17.127 usec (1.7127e-05 sec) per iteration: 0.117285 sec for 6848 iterations 17.946 usec (1.79459e-05 sec) per iteration: 0.122894 sec for 6848 iterations 17.514 usec (1.75137e-05 sec) per iteration: 0.119934 sec for 6848 iterations 17.456 usec (1.74561e-05 sec) per iteration: 0.119539 sec for 6848 iterations 17.472 usec (1.74722e-05 sec) per iteration: 0.11965 sec for 6848 iterations mean: 17.502980 usec stddev: 0.261103 usec relative stddev: 1.49% RESULT TestThreadWakeup: trusted_linux_x86-64= {17.502980, 0.261103} us Measuring clock cycles: first runs (cycles): 57985 43690 34073 41383 40256 43426 35738 51640 42069 42683 ... slowest runs (cycles): ... 43426 43690 44667 45305 49988 51640 53555 56725 57985 96798 min: 33684 cycles q1: 37033 cycles median: 37467 cycles q3: 38932 cycles max: 96798 cycles RESULT TestThreadWakeup_CycleCount: trusted_linux_x86-64= {37467, 1899} count Apparent clock speed: 2141 MHz WARNING: should have taken less than 2.000000 secs [ OK ] nacl_newlib.run_trusted_performance_test (4550 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