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-0px6 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: 15896348 kB MemAvailable: 29440404 kB ====================================================================== LOAD: ====================================================================== 1.58 1.78 1.30 1/445 458 ====================================================================== UPTIME: ====================================================================== 40020.79 310531.98 ====================================================================== 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 [466,1517492096:10:33:22.632834] Native Client module will be loaded at base address 0x0000259b00000000 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 [471,1562490752:10:33:22.890951] Native Client module will be loaded at base address 0x0000585e00000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_nnacl_newlib_static= {0.089160, 0.004360} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test (1250 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 (70040 ms) doSizeCheck(["scons-out/nacl-x86-64/obj/tests/hello_world/hello_world_size.out"], ["scons-out/nacl-x86-64/obj/tests/hello_world/hello_world.nexe"]) RESULT hello_world_size.out: x86-64_nnacl_newlib_static= 528.111 KB RESULT hello_world_size.out: ZIPPED_x86-64_nnacl_newlib_static= 187.907 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 [499,625424256:10:33:37.417971] Native Client module will be loaded at base address 0x0000207100000000 TestNull: Measuring real time: 0.006 usec (5.6e-09 sec) per iteration: 5.6e-07 sec for 100 iterations 0.018 usec (1.7662e-08 sec) per iteration: 1.7662e-05 sec for 1000 iterations 0.002 usec (1.82653e-09 sec) per iteration: 0.0103416 sec for 5661872 iterations 0.002 usec (1.86344e-09 sec) per iteration: 0.0105506 sec for 5661872 iterations 0.002 usec (1.82556e-09 sec) per iteration: 0.0103361 sec for 5661872 iterations 0.002 usec (1.83089e-09 sec) per iteration: 0.0103663 sec for 5661872 iterations 0.002 usec (1.8284e-09 sec) per iteration: 0.0103522 sec for 5661872 iterations mean: 0.001835 usec stddev: 0.000014 usec relative stddev: 0.78% RESULT TestNull: x86-64_nnacl_newlib_static= {0.001835, 0.000014} us Measuring clock cycles: first runs (cycles): 78 22 19 19 38 19 19 19 18 19 ... slowest runs (cycles): ... 19 19 19 19 19 19 19 22 38 78 min: 18 cycles q1: 19 cycles median: 19 cycles q3: 19 cycles max: 78 cycles RESULT TestNull_CycleCount: x86-64_nnacl_newlib_static= {19, 0} count Apparent clock speed: 10354 MHz TestNaClSyscall: Measuring real time: 0.112 usec (1.1243e-07 sec) per iteration: 1.1243e-05 sec for 100 iterations 0.128 usec (1.27557e-07 sec) per iteration: 0.113454 sec for 889442 iterations 0.128 usec (1.27691e-07 sec) per iteration: 0.113574 sec for 889442 iterations 0.127 usec (1.27416e-07 sec) per iteration: 0.113329 sec for 889442 iterations 0.128 usec (1.27649e-07 sec) per iteration: 0.113537 sec for 889442 iterations 0.128 usec (1.27702e-07 sec) per iteration: 0.113583 sec for 889442 iterations mean: 0.127603 usec stddev: 0.000107 usec relative stddev: 0.08% RESULT TestNaClSyscall: x86-64_nnacl_newlib_static= {0.127603, 0.000107} us Measuring clock cycles: first runs (cycles): 346 217 189 189 170 172 173 173 169 173 ... slowest runs (cycles): ... 173 173 173 173 173 173 189 189 217 346 min: 169 cycles q1: 172 cycles median: 173 cycles q3: 173 cycles max: 346 cycles RESULT TestNaClSyscall_CycleCount: x86-64_nnacl_newlib_static= {173, 1} count Apparent clock speed: 1356 MHz TestSetjmpLongjmp: Measuring real time: 0.016 usec (1.617e-08 sec) per iteration: 1.617e-06 sec for 100 iterations 0.012 usec (1.2361e-08 sec) per iteration: 1.2361e-05 sec for 1000 iterations 0.012 usec (1.24244e-08 sec) per iteration: 0.100513 sec for 8089960 iterations 0.012 usec (1.23616e-08 sec) per iteration: 0.100005 sec for 8089960 iterations 0.012 usec (1.2416e-08 sec) per iteration: 0.100445 sec for 8089960 iterations 0.013 usec (1.25066e-08 sec) per iteration: 0.101178 sec for 8089960 iterations 0.012 usec (1.23484e-08 sec) per iteration: 0.0998979 sec for 8089960 iterations mean: 0.012411 usec stddev: 0.000056 usec relative stddev: 0.45% RESULT TestSetjmpLongjmp: x86-64_nnacl_newlib_static= {0.012411, 0.000056} us Measuring clock cycles: first runs (cycles): 179 53 31 25 25 25 22 22 26 22 ... slowest runs (cycles): ... 26 26 26 26 26 26 26 31 53 179 min: 22 cycles q1: 22 cycles median: 25 cycles q3: 25 cycles max: 179 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_nnacl_newlib_static= {25, 3} count Apparent clock speed: 2014 MHz TestClockGetTime: Measuring real time: 0.138 usec (1.3838e-07 sec) per iteration: 1.3838e-05 sec for 100 iterations 0.137 usec (1.36723e-07 sec) per iteration: 0.0988021 sec for 722647 iterations 0.138 usec (1.37794e-07 sec) per iteration: 0.0995764 sec for 722647 iterations 0.137 usec (1.37189e-07 sec) per iteration: 0.0991391 sec for 722647 iterations 0.140 usec (1.40131e-07 sec) per iteration: 0.101265 sec for 722647 iterations 0.138 usec (1.38208e-07 sec) per iteration: 0.0998753 sec for 722647 iterations mean: 0.138009 usec stddev: 0.001176 usec relative stddev: 0.85% RESULT TestClockGetTime: x86-64_nnacl_newlib_static= {0.138009, 0.001176} us Measuring clock cycles: first runs (cycles): 603 386 314 318 311 311 314 308 308 311 ... slowest runs (cycles): ... 311 311 311 314 314 314 318 321 386 603 min: 305 cycles q1: 308 cycles median: 308 cycles q3: 311 cycles max: 603 cycles RESULT TestClockGetTime_CycleCount: x86-64_nnacl_newlib_static= {308, 3} count Apparent clock speed: 2232 MHz TestTlsVariable: Measuring real time: 0.010 usec (1.019e-08 sec) per iteration: 1.019e-06 sec for 100 iterations 0.006 usec (5.547e-09 sec) per iteration: 5.547e-06 sec for 1000 iterations 0.006 usec (6.2747e-09 sec) per iteration: 6.2747e-05 sec for 10000 iterations 0.005 usec (5.48338e-09 sec) per iteration: 0.0873888 sec for 15937016 iterations 0.005 usec (5.46541e-09 sec) per iteration: 0.0871024 sec for 15937016 iterations 0.005 usec (5.49613e-09 sec) per iteration: 0.087592 sec for 15937016 iterations 0.005 usec (5.4937e-09 sec) per iteration: 0.0875531 sec for 15937016 iterations 0.005 usec (5.46811e-09 sec) per iteration: 0.0871454 sec for 15937016 iterations mean: 0.005481 usec stddev: 0.000013 usec relative stddev: 0.23% RESULT TestTlsVariable: x86-64_nnacl_newlib_static= {0.005481, 0.000013} us Measuring clock cycles: first runs (cycles): 191 62 41 25 19 19 19 19 19 25 ... slowest runs (cycles): ... 19 19 19 19 19 25 25 41 62 191 min: 19 cycles q1: 19 cycles median: 19 cycles q3: 19 cycles max: 191 cycles RESULT TestTlsVariable_CycleCount: x86-64_nnacl_newlib_static= {19, 0} count Apparent clock speed: 3466 MHz TestMmapAnonymous: Measuring real time: 2.334 usec (2.33444e-06 sec) per iteration: 0.000233444 sec for 100 iterations 1.870 usec (1.86997e-06 sec) per iteration: 0.0801021 sec for 42836 iterations 1.858 usec (1.85796e-06 sec) per iteration: 0.0795876 sec for 42836 iterations 1.857 usec (1.85683e-06 sec) per iteration: 0.0795393 sec for 42836 iterations 1.867 usec (1.8671e-06 sec) per iteration: 0.0799791 sec for 42836 iterations 1.880 usec (1.87985e-06 sec) per iteration: 0.0805254 sec for 42836 iterations mean: 1.866343 usec stddev: 0.008450 usec relative stddev: 0.45% RESULT TestMmapAnonymous: x86-64_nnacl_newlib_static= {1.866343, 0.008450} us Measuring clock cycles: first runs (cycles): 8209 4145 4067 4013 5262 6188 5588 5949 4102 3994 ... slowest runs (cycles): ... 6330 6342 6502 6603 6616 6660 6763 6820 8209 15504 min: 3969 cycles q1: 4001 cycles median: 4020 cycles q3: 5296 cycles max: 15504 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_nnacl_newlib_static= {4020, 1295} count Apparent clock speed: 2154 MHz TestAtomicIncrement: Measuring real time: 0.014 usec (1.406e-08 sec) per iteration: 1.406e-06 sec for 100 iterations 0.009 usec (9.45e-09 sec) per iteration: 9.45e-06 sec for 1000 iterations 0.010 usec (1.02118e-08 sec) per iteration: 0.000102118 sec for 10000 iterations 0.009 usec (9.43943e-09 sec) per iteration: 0.0924365 sec for 9792592 iterations 0.009 usec (9.41545e-09 sec) per iteration: 0.0922016 sec for 9792592 iterations 0.009 usec (9.40988e-09 sec) per iteration: 0.0921472 sec for 9792592 iterations 0.009 usec (9.4268e-09 sec) per iteration: 0.0923129 sec for 9792592 iterations 0.009 usec (9.4207e-09 sec) per iteration: 0.0922531 sec for 9792592 iterations mean: 0.009422 usec stddev: 0.000010 usec relative stddev: 0.11% RESULT TestAtomicIncrement: x86-64_nnacl_newlib_static= {0.009422, 0.000010} us Measuring clock cycles: first runs (cycles): 367 50 50 47 47 47 47 47 47 47 ... slowest runs (cycles): ... 47 47 47 47 47 47 47 50 50 367 min: 47 cycles q1: 47 cycles median: 47 cycles q3: 47 cycles max: 367 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_nnacl_newlib_static= {47, 0} count Apparent clock speed: 4988 MHz TestUncontendedMutexLock: Measuring real time: 0.027 usec (2.693e-08 sec) per iteration: 2.693e-06 sec for 100 iterations 0.023 usec (2.3405e-08 sec) per iteration: 2.3405e-05 sec for 1000 iterations 0.024 usec (2.35645e-08 sec) per iteration: 0.100681 sec for 4272591 iterations 0.024 usec (2.36965e-08 sec) per iteration: 0.101246 sec for 4272591 iterations 0.024 usec (2.3546e-08 sec) per iteration: 0.100602 sec for 4272591 iterations 0.024 usec (2.36277e-08 sec) per iteration: 0.100951 sec for 4272591 iterations 0.024 usec (2.35941e-08 sec) per iteration: 0.100808 sec for 4272591 iterations mean: 0.023606 usec stddev: 0.000053 usec relative stddev: 0.23% RESULT TestUncontendedMutexLock: x86-64_nnacl_newlib_static= {0.023606, 0.000053} us Measuring clock cycles: first runs (cycles): 236 91 76 76 75 75 76 75 75 76 ... slowest runs (cycles): ... 76 76 76 76 76 76 82 85 91 236 min: 72 cycles q1: 75 cycles median: 75 cycles q3: 76 cycles max: 236 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_nnacl_newlib_static= {75, 1} count Apparent clock speed: 3177 MHz TestCondvarSignalNoOp: Measuring real time: 0.231 usec (2.3066e-07 sec) per iteration: 2.3066e-05 sec for 100 iterations 0.209 usec (2.09217e-07 sec) per iteration: 0.0907034 sec for 433538 iterations 0.208 usec (2.07869e-07 sec) per iteration: 0.0901191 sec for 433538 iterations 0.209 usec (2.08539e-07 sec) per iteration: 0.0904095 sec for 433538 iterations 0.209 usec (2.09048e-07 sec) per iteration: 0.0906302 sec for 433538 iterations 0.208 usec (2.07922e-07 sec) per iteration: 0.0901422 sec for 433538 iterations mean: 0.208519 usec stddev: 0.000556 usec relative stddev: 0.27% RESULT TestCondvarSignalNoOp: x86-64_nnacl_newlib_static= {0.208519, 0.000556} us Measuring clock cycles: first runs (cycles): 732 478 459 456 452 462 455 462 452 459 ... slowest runs (cycles): ... 459 459 459 459 462 462 462 469 478 732 min: 450 cycles q1: 453 cycles median: 458 cycles q3: 459 cycles max: 732 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_nnacl_newlib_static= {458, 6} count Apparent clock speed: 2196 MHz TestThreadCreateAndJoin: Measuring real time: 38.442 usec (3.84417e-05 sec) per iteration: 0.00384417 sec for 100 iterations 25.383 usec (2.5383e-05 sec) per iteration: 0.0660211 sec for 2601 iterations 24.534 usec (2.45337e-05 sec) per iteration: 0.0638121 sec for 2601 iterations 24.859 usec (2.4859e-05 sec) per iteration: 0.0646582 sec for 2601 iterations 25.213 usec (2.52135e-05 sec) per iteration: 0.0655802 sec for 2601 iterations 23.742 usec (2.37418e-05 sec) per iteration: 0.0617524 sec for 2601 iterations mean: 24.746173 usec stddev: 0.581474 usec relative stddev: 2.35% RESULT TestThreadCreateAndJoin: x86-64_nnacl_newlib_static= {24.746173, 0.581474} us Measuring clock cycles: first runs (cycles): 55958 50235 75819 50849 50857 50685 50474 50210 49390 60007 ... slowest runs (cycles): ... 68354 70038 74181 75819 76896 91177 93280 101889 104780 127725 min: 47991 cycles q1: 49173 cycles median: 49780 cycles q3: 50857 cycles max: 127725 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_nnacl_newlib_static= {49780, 1684} count Apparent clock speed: 2012 MHz TestThreadWakeup: Measuring real time: 14.841 usec (1.48406e-05 sec) per iteration: 0.00148406 sec for 100 iterations 12.971 usec (1.29712e-05 sec) per iteration: 0.0874001 sec for 6738 iterations 14.606 usec (1.4606e-05 sec) per iteration: 0.098415 sec for 6738 iterations 13.810 usec (1.38101e-05 sec) per iteration: 0.0930523 sec for 6738 iterations 13.556 usec (1.35562e-05 sec) per iteration: 0.0913415 sec for 6738 iterations 14.091 usec (1.40907e-05 sec) per iteration: 0.0949429 sec for 6738 iterations mean: 13.806820 usec stddev: 0.544109 usec relative stddev: 3.94% RESULT TestThreadWakeup: x86-64_nnacl_newlib_static= {13.806820, 0.544109} us Measuring clock cycles: first runs (cycles): 33971 39204 35800 31859 32636 27584 32123 31344 31381 32224 ... slowest runs (cycles): ... 39455 39789 41844 42391 42620 42881 43416 46932 48956 63049 min: 27584 cycles q1: 31208 cycles median: 31859 cycles q3: 34027 cycles max: 63049 cycles RESULT TestThreadWakeup_CycleCount: x86-64_nnacl_newlib_static= {31859, 2819} count Apparent clock speed: 2307 MHz TestCatchingFault: Measuring real time: 2.914 usec (2.91378e-06 sec) per iteration: 0.000291378 sec for 100 iterations 1.574 usec (1.57372e-06 sec) per iteration: 0.0540087 sec for 34319 iterations 1.576 usec (1.57579e-06 sec) per iteration: 0.0540794 sec for 34319 iterations 1.576 usec (1.57645e-06 sec) per iteration: 0.0541021 sec for 34319 iterations 1.590 usec (1.5896e-06 sec) per iteration: 0.0545535 sec for 34319 iterations 1.580 usec (1.57965e-06 sec) per iteration: 0.0542119 sec for 34319 iterations mean: 1.579041 usec stddev: 0.005612 usec relative stddev: 0.36% RESULT TestCatchingFault: x86-64_nnacl_newlib_static= {1.579041, 0.005612} us Measuring clock cycles: first runs (cycles): 5023 3463 3413 3426 3410 3413 3410 3413 3413 3413 ... slowest runs (cycles): ... 3442 3444 3445 3463 3589 4033 4118 4183 5023 10161 min: 3404 cycles q1: 3413 cycles median: 3420 cycles q3: 3429 cycles max: 10161 cycles RESULT TestCatchingFault_CycleCount: x86-64_nnacl_newlib_static= {3420, 16} count Apparent clock speed: 2166 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 32.574 usec (3.25737e-05 sec) per iteration: 0.00325737 sec for 100 iterations 25.316 usec (2.53157e-05 sec) per iteration: 0.0776938 sec for 3069 iterations 25.973 usec (2.59734e-05 sec) per iteration: 0.0797123 sec for 3069 iterations 25.109 usec (2.51092e-05 sec) per iteration: 0.0770602 sec for 3069 iterations 24.823 usec (2.48227e-05 sec) per iteration: 0.0761809 sec for 3069 iterations 25.145 usec (2.51449e-05 sec) per iteration: 0.0771697 sec for 3069 iterations mean: 25.273177 usec stddev: 0.384281 usec relative stddev: 1.52% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_nnacl_newlib_static= {25.273177, 0.384281} us Measuring clock cycles: first runs (cycles): 58259 51898 58416 50594 51433 54312 123382 75699 117634 121025 ... slowest runs (cycles): ... 70542 73074 75699 95386 99217 99955 117634 121025 123382 126456 min: 48846 cycles q1: 50009 cycles median: 50826 cycles q3: 53491 cycles max: 126456 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_nnacl_newlib_static= {50826, 3482} count Apparent clock speed: 2011 MHz [ OK ] nacl_newlib.run_performance_test (5400 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 (2670 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 (380 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.004 usec (4.05e-09 sec) per iteration: 4.05e-07 sec for 100 iterations 0.002 usec (1.861e-09 sec) per iteration: 1.861e-06 sec for 1000 iterations 0.002 usec (1.7955e-09 sec) per iteration: 1.7955e-05 sec for 10000 iterations 0.002 usec (1.81637e-09 sec) per iteration: 0.101162 sec for 55694792 iterations 0.002 usec (1.81418e-09 sec) per iteration: 0.10104 sec for 55694792 iterations 0.002 usec (1.82524e-09 sec) per iteration: 0.101657 sec for 55694792 iterations 0.002 usec (1.82093e-09 sec) per iteration: 0.101416 sec for 55694792 iterations 0.002 usec (1.81596e-09 sec) per iteration: 0.10114 sec for 55694792 iterations mean: 0.001819 usec stddev: 0.000004 usec relative stddev: 0.22% RESULT TestNull: trusted_linux_x86-64= {0.001819, 0.000004} us Measuring clock cycles: first runs (cycles): 60 22 18 19 19 19 19 19 19 19 ... slowest runs (cycles): ... 19 19 19 19 19 19 19 19 22 60 min: 18 cycles q1: 19 cycles median: 19 cycles q3: 19 cycles max: 60 cycles RESULT TestNull_CycleCount: trusted_linux_x86-64= {19, 0} count Apparent clock speed: 10448 MHz TestHostSyscall: Measuring real time: 0.065 usec (6.545e-08 sec) per iteration: 6.545e-06 sec for 100 iterations 0.058 usec (5.8274e-08 sec) per iteration: 5.8274e-05 sec for 1000 iterations 0.054 usec (5.43973e-08 sec) per iteration: 0.0933474 sec for 1716031 iterations 0.054 usec (5.42616e-08 sec) per iteration: 0.0931145 sec for 1716031 iterations 0.054 usec (5.44346e-08 sec) per iteration: 0.0934115 sec for 1716031 iterations 0.054 usec (5.43863e-08 sec) per iteration: 0.0933286 sec for 1716031 iterations 0.054 usec (5.43166e-08 sec) per iteration: 0.093209 sec for 1716031 iterations mean: 0.054359 usec stddev: 0.000062 usec relative stddev: 0.11% RESULT TestHostSyscall: trusted_linux_x86-64= {0.054359, 0.000062} us Measuring clock cycles: first runs (cycles): 452 147 475 135 135 138 135 135 135 135 ... slowest runs (cycles): ... 136 136 138 138 139 147 239 274 452 475 min: 132 cycles q1: 135 cycles median: 135 cycles q3: 136 cycles max: 475 cycles RESULT TestHostSyscall_CycleCount: trusted_linux_x86-64= {135, 1} count Apparent clock speed: 2483 MHz TestSetjmpLongjmp: Measuring real time: 0.034 usec (3.392e-08 sec) per iteration: 3.392e-06 sec for 100 iterations 0.026 usec (2.5698e-08 sec) per iteration: 2.5698e-05 sec for 1000 iterations 0.025 usec (2.46762e-08 sec) per iteration: 0.0960239 sec for 3891353 iterations 0.025 usec (2.48788e-08 sec) per iteration: 0.0968122 sec for 3891353 iterations 0.025 usec (2.47448e-08 sec) per iteration: 0.0962907 sec for 3891353 iterations 0.025 usec (2.46581e-08 sec) per iteration: 0.0959534 sec for 3891353 iterations 0.025 usec (2.47345e-08 sec) per iteration: 0.0962507 sec for 3891353 iterations mean: 0.024738 usec stddev: 0.000078 usec relative stddev: 0.31% RESULT TestSetjmpLongjmp: trusted_linux_x86-64= {0.024738, 0.000078} us Measuring clock cycles: first runs (cycles): 173 91 912 66 63 63 63 63 59 63 ... slowest runs (cycles): ... 66 66 66 66 66 91 173 298 302 912 min: 56 cycles q1: 60 cycles median: 63 cycles q3: 66 cycles max: 912 cycles RESULT TestSetjmpLongjmp_CycleCount: trusted_linux_x86-64= {63, 6} count Apparent clock speed: 2547 MHz TestClockGetTime: Measuring real time: 0.048 usec (4.805e-08 sec) per iteration: 4.805e-06 sec for 100 iterations 0.047 usec (4.6505e-08 sec) per iteration: 4.6505e-05 sec for 1000 iterations 0.047 usec (4.70359e-08 sec) per iteration: 0.101142 sec for 2150306 iterations 0.047 usec (4.72178e-08 sec) per iteration: 0.101533 sec for 2150306 iterations 0.047 usec (4.71005e-08 sec) per iteration: 0.10128 sec for 2150306 iterations 0.047 usec (4.6898e-08 sec) per iteration: 0.100845 sec for 2150306 iterations 0.047 usec (4.69433e-08 sec) per iteration: 0.100943 sec for 2150306 iterations mean: 0.047039 usec stddev: 0.000114 usec relative stddev: 0.24% RESULT TestClockGetTime: trusted_linux_x86-64= {0.047039, 0.000114} us Measuring clock cycles: first runs (cycles): 374 157 154 116 119 110 113 110 110 110 ... slowest runs (cycles): ... 113 113 113 113 113 116 119 154 157 374 min: 110 cycles q1: 110 cycles median: 110 cycles q3: 110 cycles max: 374 cycles RESULT TestClockGetTime_CycleCount: trusted_linux_x86-64= {110, 0} count Apparent clock speed: 2338 MHz TestTlsVariable: Measuring real time: 0.004 usec (4.01e-09 sec) per iteration: 4.01e-07 sec for 100 iterations 0.002 usec (1.854e-09 sec) per iteration: 1.854e-06 sec for 1000 iterations 0.002 usec (1.7952e-09 sec) per iteration: 1.7952e-05 sec for 10000 iterations 0.002 usec (1.81369e-09 sec) per iteration: 0.10103 sec for 55704099 iterations 0.002 usec (1.80955e-09 sec) per iteration: 0.100799 sec for 55704099 iterations 0.002 usec (1.81085e-09 sec) per iteration: 0.100872 sec for 55704099 iterations 0.002 usec (1.81102e-09 sec) per iteration: 0.100881 sec for 55704099 iterations 0.002 usec (1.8109e-09 sec) per iteration: 0.100874 sec for 55704099 iterations mean: 0.001811 usec stddev: 0.000001 usec relative stddev: 0.07% RESULT TestTlsVariable: trusted_linux_x86-64= {0.001811, 0.000001} us Measuring clock cycles: first runs (cycles): 81 19 19 22 18 19 19 22 18 19 ... slowest runs (cycles): ... 22 22 22 22 22 22 22 22 22 81 min: 18 cycles q1: 19 cycles median: 19 cycles q3: 22 cycles max: 81 cycles RESULT TestTlsVariable_CycleCount: trusted_linux_x86-64= {19, 3} count Apparent clock speed: 10490 MHz TestMmapAnonymous: Measuring real time: 0.788 usec (7.8834e-07 sec) per iteration: 7.8834e-05 sec for 100 iterations 0.509 usec (5.09436e-07 sec) per iteration: 0.0646209 sec for 126848 iterations 0.509 usec (5.09495e-07 sec) per iteration: 0.0646284 sec for 126848 iterations 0.507 usec (5.06529e-07 sec) per iteration: 0.0642522 sec for 126848 iterations 0.508 usec (5.07984e-07 sec) per iteration: 0.0644368 sec for 126848 iterations 0.509 usec (5.09358e-07 sec) per iteration: 0.064611 sec for 126848 iterations mean: 0.508560 usec stddev: 0.001160 usec relative stddev: 0.23% RESULT TestMmapAnonymous: trusted_linux_x86-64= {0.508560, 0.001160} us Measuring clock cycles: first runs (cycles): 2587 1377 1144 1110 1119 1115 1144 1116 1103 1379 ... slowest runs (cycles): ... 1138 1140 1144 1144 1157 1336 1361 1377 1379 2587 min: 1091 cycles q1: 1106 cycles median: 1115 cycles q3: 1125 cycles max: 2587 cycles RESULT TestMmapAnonymous_CycleCount: trusted_linux_x86-64= {1115, 19} count Apparent clock speed: 2192 MHz TestAtomicIncrement: Measuring real time: 0.010 usec (1.02e-08 sec) per iteration: 1.02e-06 sec for 100 iterations 0.007 usec (6.852e-09 sec) per iteration: 6.852e-06 sec for 1000 iterations 0.007 usec (6.8046e-09 sec) per iteration: 6.8046e-05 sec for 10000 iterations 0.007 usec (6.98162e-09 sec) per iteration: 0.102601 sec for 14695940 iterations 0.007 usec (6.91301e-09 sec) per iteration: 0.101593 sec for 14695940 iterations 0.007 usec (6.9105e-09 sec) per iteration: 0.101556 sec for 14695940 iterations 0.007 usec (6.87348e-09 sec) per iteration: 0.101012 sec for 14695940 iterations 0.007 usec (6.88754e-09 sec) per iteration: 0.101219 sec for 14695940 iterations mean: 0.006913 usec stddev: 0.000037 usec relative stddev: 0.54% RESULT TestAtomicIncrement: trusted_linux_x86-64= {0.006913, 0.000037} us Measuring clock cycles: first runs (cycles): 125 317 44 47 44 47 44 47 44 367 ... slowest runs (cycles): ... 48 48 48 48 125 317 333 346 355 367 min: 44 cycles q1: 44 cycles median: 47 cycles q3: 47 cycles max: 367 cycles RESULT TestAtomicIncrement_CycleCount: trusted_linux_x86-64= {47, 3} count Apparent clock speed: 6799 MHz TestUncontendedMutexLock: Measuring real time: 0.024 usec (2.415e-08 sec) per iteration: 2.415e-06 sec for 100 iterations 0.019 usec (1.8975e-08 sec) per iteration: 1.8975e-05 sec for 1000 iterations 0.019 usec (1.92127e-08 sec) per iteration: 0.101253 sec for 5270092 iterations 0.019 usec (1.94528e-08 sec) per iteration: 0.102518 sec for 5270092 iterations 0.019 usec (1.92741e-08 sec) per iteration: 0.101576 sec for 5270092 iterations 0.019 usec (1.92825e-08 sec) per iteration: 0.10162 sec for 5270092 iterations 0.019 usec (1.92275e-08 sec) per iteration: 0.101331 sec for 5270092 iterations mean: 0.019290 usec stddev: 0.000086 usec relative stddev: 0.44% RESULT TestUncontendedMutexLock: trusted_linux_x86-64= {0.019290, 0.000086} us Measuring clock cycles: first runs (cycles): 194 346 70 69 69 72 72 69 70 371 ... slowest runs (cycles): ... 73 73 73 73 194 346 352 356 358 371 min: 66 cycles q1: 69 cycles median: 69 cycles q3: 72 cycles max: 371 cycles RESULT TestUncontendedMutexLock_CycleCount: trusted_linux_x86-64= {69, 3} count Apparent clock speed: 3577 MHz TestCondvarSignalNoOp: Measuring real time: 0.021 usec (2.115e-08 sec) per iteration: 2.115e-06 sec for 100 iterations 0.016 usec (1.6118e-08 sec) per iteration: 1.6118e-05 sec for 1000 iterations 0.016 usec (1.632e-08 sec) per iteration: 0.101254 sec for 6204243 iterations 0.016 usec (1.63009e-08 sec) per iteration: 0.101135 sec for 6204243 iterations 0.016 usec (1.63116e-08 sec) per iteration: 0.101201 sec for 6204243 iterations 0.016 usec (1.62859e-08 sec) per iteration: 0.101042 sec for 6204243 iterations 0.016 usec (1.6316e-08 sec) per iteration: 0.101228 sec for 6204243 iterations mean: 0.016307 usec stddev: 0.000012 usec relative stddev: 0.08% RESULT TestCondvarSignalNoOp: trusted_linux_x86-64= {0.016307, 0.000012} us Measuring clock cycles: first runs (cycles): 157 346 62 60 63 62 63 60 62 359 ... slowest runs (cycles): ... 63 63 63 63 63 157 346 346 352 359 min: 59 cycles q1: 62 cycles median: 63 cycles q3: 63 cycles max: 359 cycles RESULT TestCondvarSignalNoOp_CycleCount: trusted_linux_x86-64= {63, 1} count Apparent clock speed: 3863 MHz TestThreadCreateAndJoin: Measuring real time: 27.153 usec (2.71532e-05 sec) per iteration: 0.00271532 sec for 100 iterations 20.191 usec (2.01915e-05 sec) per iteration: 0.074345 sec for 3682 iterations 20.079 usec (2.00786e-05 sec) per iteration: 0.0739296 sec for 3682 iterations 20.491 usec (2.04909e-05 sec) per iteration: 0.0754475 sec for 3682 iterations 20.486 usec (2.04857e-05 sec) per iteration: 0.0754285 sec for 3682 iterations 21.979 usec (2.19785e-05 sec) per iteration: 0.080925 sec for 3682 iterations mean: 20.645057 usec stddev: 0.686130 usec relative stddev: 3.32% RESULT TestThreadCreateAndJoin: trusted_linux_x86-64= {20.645057, 0.686130} us Measuring clock cycles: first runs (cycles): 530867 195445 195891 109333 59796 250932 94163 97975 44060 80461 ... slowest runs (cycles): ... 59796 63275 80461 94163 97975 109333 195445 195891 250932 530867 min: 40025 cycles q1: 41545 cycles median: 42539 cycles q3: 44204 cycles max: 530867 cycles RESULT TestThreadCreateAndJoin_CycleCount: trusted_linux_x86-64= {42539, 2659} count Apparent clock speed: 2060 MHz TestThreadWakeup: Measuring real time: 21.036 usec (2.10359e-05 sec) per iteration: 0.00210359 sec for 100 iterations 16.576 usec (1.65763e-05 sec) per iteration: 0.078787 sec for 4753 iterations 16.804 usec (1.6804e-05 sec) per iteration: 0.0798696 sec for 4753 iterations 16.718 usec (1.67176e-05 sec) per iteration: 0.079459 sec for 4753 iterations 16.775 usec (1.67749e-05 sec) per iteration: 0.0797313 sec for 4753 iterations 16.859 usec (1.68592e-05 sec) per iteration: 0.0801316 sec for 4753 iterations mean: 16.746413 usec stddev: 0.096563 usec relative stddev: 0.58% RESULT TestThreadWakeup: trusted_linux_x86-64= {16.746413, 0.096563} us Measuring clock cycles: first runs (cycles): 38004 37356 35803 36410 35923 35253 35427 35238 35514 36813 ... slowest runs (cycles): ... 38600 39537 39697 40433 40873 41043 41617 41771 53092 68527 min: 31485 cycles q1: 35266 cycles median: 35938 cycles q3: 37133 cycles max: 68527 cycles RESULT TestThreadWakeup_CycleCount: trusted_linux_x86-64= {35938, 1867} count Apparent clock speed: 2146 MHz WARNING: should have taken less than 2.000000 secs [ OK ] nacl_newlib.run_trusted_performance_test (5110 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