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: 15899988 kB MemAvailable: 29449188 kB ====================================================================== LOAD: ====================================================================== 1.56 2.22 1.76 1/441 8216 ====================================================================== UPTIME: ====================================================================== 40611.18 313982.96 ====================================================================== 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 [8224,2880440192:10:43:13.094166] Native Client module will be loaded at base address 0x000066f400000000 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 [8229,923989888:10:43:13.358201] Native Client module will be loaded at base address 0x0000321900000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_nnacl_newlib_static= {0.083850, 0.006470} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test (1200 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 (70420 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 [8257,662013824:10:43:27.993773] Native Client module will be loaded at base address 0x000008b000000000 TestNull: Measuring real time: 0.008 usec (8e-09 sec) per iteration: 8e-07 sec for 100 iterations 0.003 usec (2.697e-09 sec) per iteration: 2.697e-06 sec for 1000 iterations 0.004 usec (3.7095e-09 sec) per iteration: 3.7095e-05 sec for 10000 iterations 0.002 usec (1.82777e-09 sec) per iteration: 0.0492728 sec for 26957811 iterations 0.002 usec (1.81534e-09 sec) per iteration: 0.0489375 sec for 26957811 iterations 0.002 usec (1.81745e-09 sec) per iteration: 0.0489945 sec for 26957811 iterations 0.002 usec (1.81431e-09 sec) per iteration: 0.0489097 sec for 26957811 iterations 0.002 usec (1.81492e-09 sec) per iteration: 0.0489263 sec for 26957811 iterations mean: 0.001818 usec stddev: 0.000005 usec relative stddev: 0.28% RESULT TestNull: x86-64_nnacl_newlib_static= {0.001818, 0.000005} us Measuring clock cycles: first runs (cycles): 192 19 19 22 22 19 41 19 25 18 ... slowest runs (cycles): ... 22 22 22 22 25 25 26 28 41 192 min: 18 cycles q1: 19 cycles median: 19 cycles q3: 22 cycles max: 192 cycles RESULT TestNull_CycleCount: x86-64_nnacl_newlib_static= {19, 3} count Apparent clock speed: 10451 MHz TestNaClSyscall: Measuring real time: 0.096 usec (9.579e-08 sec) per iteration: 9.579e-06 sec for 100 iterations 0.098 usec (9.8287e-08 sec) per iteration: 9.8287e-05 sec for 1000 iterations 0.128 usec (1.27795e-07 sec) per iteration: 0.130023 sec for 1017428 iterations 0.128 usec (1.27538e-07 sec) per iteration: 0.129761 sec for 1017428 iterations 0.128 usec (1.27612e-07 sec) per iteration: 0.129836 sec for 1017428 iterations 0.128 usec (1.27687e-07 sec) per iteration: 0.129912 sec for 1017428 iterations 0.127 usec (1.27411e-07 sec) per iteration: 0.129632 sec for 1017428 iterations mean: 0.127609 usec stddev: 0.000130 usec relative stddev: 0.10% RESULT TestNaClSyscall: x86-64_nnacl_newlib_static= {0.127609, 0.000130} us Measuring clock cycles: first runs (cycles): 547 204 233 189 189 195 185 186 189 214 ... slowest runs (cycles): ... 195 195 204 204 207 208 214 214 233 547 min: 173 cycles q1: 176 cycles median: 180 cycles q3: 186 cycles max: 547 cycles RESULT TestNaClSyscall_CycleCount: x86-64_nnacl_newlib_static= {180, 10} count Apparent clock speed: 1411 MHz TestSetjmpLongjmp: Measuring real time: 0.020 usec (1.983e-08 sec) per iteration: 1.983e-06 sec for 100 iterations 0.017 usec (1.6535e-08 sec) per iteration: 1.6535e-05 sec for 1000 iterations 0.012 usec (1.23695e-08 sec) per iteration: 0.0748082 sec for 6047777 iterations 0.012 usec (1.24143e-08 sec) per iteration: 0.0750788 sec for 6047777 iterations 0.012 usec (1.23358e-08 sec) per iteration: 0.0746043 sec for 6047777 iterations 0.012 usec (1.2338e-08 sec) per iteration: 0.0746176 sec for 6047777 iterations 0.012 usec (1.23419e-08 sec) per iteration: 0.074641 sec for 6047777 iterations mean: 0.012360 usec stddev: 0.000030 usec relative stddev: 0.24% RESULT TestSetjmpLongjmp: x86-64_nnacl_newlib_static= {0.012360, 0.000030} us Measuring clock cycles: first runs (cycles): 374 210 88 50 38 41 34 35 34 35 ... slowest runs (cycles): ... 48 48 48 50 50 51 59 88 210 374 min: 31 cycles q1: 35 cycles median: 38 cycles q3: 41 cycles max: 374 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_nnacl_newlib_static= {38, 6} count Apparent clock speed: 3074 MHz TestClockGetTime: Measuring real time: 0.208 usec (2.0771e-07 sec) per iteration: 2.0771e-05 sec for 100 iterations 0.137 usec (1.36655e-07 sec) per iteration: 0.0657914 sec for 481440 iterations 0.137 usec (1.369e-07 sec) per iteration: 0.0659093 sec for 481440 iterations 0.137 usec (1.36885e-07 sec) per iteration: 0.065902 sec for 481440 iterations 0.137 usec (1.36655e-07 sec) per iteration: 0.0657912 sec for 481440 iterations 0.137 usec (1.36957e-07 sec) per iteration: 0.0659363 sec for 481440 iterations mean: 0.136811 usec stddev: 0.000129 usec relative stddev: 0.09% RESULT TestClockGetTime: x86-64_nnacl_newlib_static= {0.136811, 0.000129} us Measuring clock cycles: first runs (cycles): 1323 449 406 384 377 371 378 381 386 381 ... slowest runs (cycles): ... 418 421 440 443 449 456 459 468 481 1323 min: 355 cycles q1: 371 cycles median: 381 cycles q3: 396 cycles max: 1323 cycles RESULT TestClockGetTime_CycleCount: x86-64_nnacl_newlib_static= {381, 25} count Apparent clock speed: 2785 MHz TestTlsVariable: Measuring real time: 0.014 usec (1.425e-08 sec) per iteration: 1.425e-06 sec for 100 iterations 0.009 usec (8.608e-09 sec) per iteration: 8.608e-06 sec for 1000 iterations 0.010 usec (9.6811e-09 sec) per iteration: 9.6811e-05 sec for 10000 iterations 0.005 usec (5.4839e-09 sec) per iteration: 0.0566454 sec for 10329404 iterations 0.005 usec (5.49306e-09 sec) per iteration: 0.05674 sec for 10329404 iterations 0.005 usec (5.46024e-09 sec) per iteration: 0.056401 sec for 10329404 iterations 0.005 usec (5.46045e-09 sec) per iteration: 0.0564032 sec for 10329404 iterations 0.005 usec (5.49035e-09 sec) per iteration: 0.056712 sec for 10329404 iterations mean: 0.005478 usec stddev: 0.000014 usec relative stddev: 0.26% RESULT TestTlsVariable: x86-64_nnacl_newlib_static= {0.005478, 0.000014} us Measuring clock cycles: first runs (cycles): 213 94 47 47 31 35 38 38 47 47 ... slowest runs (cycles): ... 44 47 47 47 47 47 47 48 94 213 min: 22 cycles q1: 25 cycles median: 26 cycles q3: 31 cycles max: 213 cycles RESULT TestTlsVariable_CycleCount: x86-64_nnacl_newlib_static= {26, 6} count Apparent clock speed: 4747 MHz TestMmapAnonymous: Measuring real time: 2.867 usec (2.86728e-06 sec) per iteration: 0.000286728 sec for 100 iterations 1.863 usec (1.86334e-06 sec) per iteration: 0.064986 sec for 34876 iterations 1.863 usec (1.86325e-06 sec) per iteration: 0.0649828 sec for 34876 iterations 1.858 usec (1.85824e-06 sec) per iteration: 0.0648081 sec for 34876 iterations 1.857 usec (1.85695e-06 sec) per iteration: 0.064763 sec for 34876 iterations 1.857 usec (1.857e-06 sec) per iteration: 0.0647647 sec for 34876 iterations mean: 1.859758 usec stddev: 0.002927 usec relative stddev: 0.16% RESULT TestMmapAnonymous: x86-64_nnacl_newlib_static= {1.859758, 0.002927} us Measuring clock cycles: first runs (cycles): 13363 6147 5450 5245 5459 5503 5701 5315 5352 5673 ... slowest runs (cycles): ... 7062 7178 7191 7222 7417 7713 7807 7908 13363 28767 min: 3979 cycles q1: 5352 cycles median: 5849 cycles q3: 6232 cycles max: 28767 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_nnacl_newlib_static= {5849, 880} count Apparent clock speed: 3145 MHz TestAtomicIncrement: Measuring real time: 0.016 usec (1.592e-08 sec) per iteration: 1.592e-06 sec for 100 iterations 0.011 usec (1.0689e-08 sec) per iteration: 1.0689e-05 sec for 1000 iterations 0.009 usec (9.42519e-09 sec) per iteration: 0.0881765 sec for 9355412 iterations 0.009 usec (9.41358e-09 sec) per iteration: 0.088068 sec for 9355412 iterations 0.009 usec (9.42024e-09 sec) per iteration: 0.0881302 sec for 9355412 iterations 0.009 usec (9.45018e-09 sec) per iteration: 0.0884103 sec for 9355412 iterations 0.009 usec (9.40939e-09 sec) per iteration: 0.0880287 sec for 9355412 iterations mean: 0.009424 usec stddev: 0.000014 usec relative stddev: 0.15% RESULT TestAtomicIncrement: x86-64_nnacl_newlib_static= {0.009424, 0.000014} us Measuring clock cycles: first runs (cycles): 239 50 44 47 44 47 44 50 44 50 ... slowest runs (cycles): ... 51 51 51 51 51 54 72 75 78 239 min: 44 cycles q1: 47 cycles median: 47 cycles q3: 50 cycles max: 239 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_nnacl_newlib_static= {47, 3} count Apparent clock speed: 4987 MHz TestUncontendedMutexLock: Measuring real time: 0.037 usec (3.724e-08 sec) per iteration: 3.724e-06 sec for 100 iterations 0.026 usec (2.5855e-08 sec) per iteration: 2.5855e-05 sec for 1000 iterations 0.024 usec (2.35492e-08 sec) per iteration: 0.0910819 sec for 3867723 iterations 0.024 usec (2.35371e-08 sec) per iteration: 0.0910351 sec for 3867723 iterations 0.024 usec (2.36104e-08 sec) per iteration: 0.0913185 sec for 3867723 iterations 0.024 usec (2.3585e-08 sec) per iteration: 0.0912204 sec for 3867723 iterations 0.024 usec (2.35379e-08 sec) per iteration: 0.0910379 sec for 3867723 iterations mean: 0.023564 usec stddev: 0.000029 usec relative stddev: 0.12% RESULT TestUncontendedMutexLock: x86-64_nnacl_newlib_static= {0.023564, 0.000029} us Measuring clock cycles: first runs (cycles): 465 123 94 79 78 79 79 82 79 78 ... slowest runs (cycles): ... 85 85 85 88 91 94 94 103 123 465 min: 75 cycles q1: 78 cycles median: 79 cycles q3: 79 cycles max: 465 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_nnacl_newlib_static= {79, 1} count Apparent clock speed: 3353 MHz TestCondvarSignalNoOp: Measuring real time: 0.295 usec (2.9495e-07 sec) per iteration: 2.9495e-05 sec for 100 iterations 0.205 usec (2.04793e-07 sec) per iteration: 0.0694329 sec for 339040 iterations 0.205 usec (2.04866e-07 sec) per iteration: 0.0694578 sec for 339040 iterations 0.205 usec (2.04842e-07 sec) per iteration: 0.0694496 sec for 339040 iterations 0.205 usec (2.04779e-07 sec) per iteration: 0.0694282 sec for 339040 iterations 0.205 usec (2.05131e-07 sec) per iteration: 0.0695475 sec for 339040 iterations mean: 0.204882 usec stddev: 0.000128 usec relative stddev: 0.06% RESULT TestCondvarSignalNoOp: x86-64_nnacl_newlib_static= {0.204882, 0.000128} us Measuring clock cycles: first runs (cycles): 1421 604 587 516 537 512 503 490 494 502 ... slowest runs (cycles): ... 541 543 544 547 553 557 587 597 604 1421 min: 484 cycles q1: 500 cycles median: 509 cycles q3: 522 cycles max: 1421 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_nnacl_newlib_static= {509, 22} count Apparent clock speed: 2484 MHz TestThreadCreateAndJoin: Measuring real time: 33.755 usec (3.37551e-05 sec) per iteration: 0.00337551 sec for 100 iterations 25.649 usec (2.56487e-05 sec) per iteration: 0.0759715 sec for 2962 iterations 24.378 usec (2.43783e-05 sec) per iteration: 0.0722086 sec for 2962 iterations 23.739 usec (2.37385e-05 sec) per iteration: 0.0703136 sec for 2962 iterations 23.400 usec (2.33997e-05 sec) per iteration: 0.0693099 sec for 2962 iterations 23.347 usec (2.33467e-05 sec) per iteration: 0.0691528 sec for 2962 iterations mean: 24.102387 usec stddev: 0.856094 usec relative stddev: 3.55% RESULT TestThreadCreateAndJoin: x86-64_nnacl_newlib_static= {24.102387, 0.856094} us Measuring clock cycles: first runs (cycles): 92683 62448 62974 71421 76601 103440 71066 95200 48337 50864 ... slowest runs (cycles): ... 72424 72628 74114 76054 76601 80636 87252 92683 95200 103440 min: 44101 cycles q1: 47510 cycles median: 48588 cycles q3: 54655 cycles max: 103440 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_nnacl_newlib_static= {48588, 7145} count Apparent clock speed: 2016 MHz TestThreadWakeup: Measuring real time: 12.496 usec (1.24964e-05 sec) per iteration: 0.00124964 sec for 100 iterations 13.354 usec (1.33541e-05 sec) per iteration: 0.106859 sec for 8002 iterations 13.434 usec (1.34339e-05 sec) per iteration: 0.107498 sec for 8002 iterations 13.468 usec (1.34675e-05 sec) per iteration: 0.107767 sec for 8002 iterations 13.431 usec (1.34312e-05 sec) per iteration: 0.107477 sec for 8002 iterations 14.441 usec (1.44407e-05 sec) per iteration: 0.115555 sec for 8002 iterations mean: 13.625481 usec stddev: 0.409321 usec relative stddev: 3.00% RESULT TestThreadWakeup: x86-64_nnacl_newlib_static= {13.625481, 0.409321} us Measuring clock cycles: first runs (cycles): 77267 45484 42052 41816 39283 39805 49732 35329 42243 40543 ... slowest runs (cycles): ... 44355 44547 44622 45484 46776 49594 49732 57913 60317 77267 min: 33811 cycles q1: 34581 cycles median: 34949 cycles q3: 36897 cycles max: 77267 cycles RESULT TestThreadWakeup_CycleCount: x86-64_nnacl_newlib_static= {34949, 2316} count Apparent clock speed: 2565 MHz TestCatchingFault: Measuring real time: 2.871 usec (2.87131e-06 sec) per iteration: 0.000287131 sec for 100 iterations 1.593 usec (1.59256e-06 sec) per iteration: 0.0554643 sec for 34827 iterations 1.591 usec (1.59124e-06 sec) per iteration: 0.0554182 sec for 34827 iterations 1.589 usec (1.58917e-06 sec) per iteration: 0.0553462 sec for 34827 iterations 1.588 usec (1.58833e-06 sec) per iteration: 0.0553168 sec for 34827 iterations 1.587 usec (1.58748e-06 sec) per iteration: 0.0552873 sec for 34827 iterations mean: 1.589759 usec stddev: 0.001878 usec relative stddev: 0.12% RESULT TestCatchingFault: x86-64_nnacl_newlib_static= {1.589759, 0.001878} us Measuring clock cycles: first runs (cycles): 9045 4393 4139 3809 3818 4001 3966 3897 3970 4692 ... slowest runs (cycles): ... 4708 4802 4805 4900 4991 5038 5041 5170 9045 30844 min: 3809 cycles q1: 4044 cycles median: 4174 cycles q3: 4312 cycles max: 30844 cycles RESULT TestCatchingFault_CycleCount: x86-64_nnacl_newlib_static= {4174, 268} count Apparent clock speed: 2626 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 33.439 usec (3.34394e-05 sec) per iteration: 0.00334394 sec for 100 iterations 28.478 usec (2.8478e-05 sec) per iteration: 0.0851491 sec for 2990 iterations 25.474 usec (2.54741e-05 sec) per iteration: 0.0761674 sec for 2990 iterations 29.868 usec (2.9868e-05 sec) per iteration: 0.0893053 sec for 2990 iterations 24.930 usec (2.49295e-05 sec) per iteration: 0.0745392 sec for 2990 iterations 25.542 usec (2.55419e-05 sec) per iteration: 0.0763703 sec for 2990 iterations mean: 26.858282 usec stddev: 1.951963 usec relative stddev: 7.27% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_nnacl_newlib_static= {26.858282, 1.951963} us Measuring clock cycles: first runs (cycles): 66088 106747 97306 66132 100044 88277 64510 51313 66431 61898 ... slowest runs (cycles): ... 66132 66431 66858 67065 82179 88277 97306 100044 106747 108300 min: 49164 cycles q1: 50166 cycles median: 50826 cycles q3: 52514 cycles max: 108300 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_nnacl_newlib_static= {50826, 2348} count Apparent clock speed: 1892 MHz [ OK ] nacl_newlib.run_performance_test (5160 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 (2460 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 (920 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 (3.68e-09 sec) per iteration: 3.68e-07 sec for 100 iterations 0.002 usec (1.85e-09 sec) per iteration: 1.85e-06 sec for 1000 iterations 0.002 usec (1.7947e-09 sec) per iteration: 1.7947e-05 sec for 10000 iterations 0.002 usec (1.82268e-09 sec) per iteration: 0.101559 sec for 55719618 iterations 0.002 usec (1.8196e-09 sec) per iteration: 0.101388 sec for 55719618 iterations 0.002 usec (1.81706e-09 sec) per iteration: 0.101246 sec for 55719618 iterations 0.002 usec (1.81349e-09 sec) per iteration: 0.101047 sec for 55719618 iterations 0.002 usec (1.83455e-09 sec) per iteration: 0.10222 sec for 55719618 iterations mean: 0.001821 usec stddev: 0.000007 usec relative stddev: 0.40% RESULT TestNull: trusted_linux_x86-64= {0.001821, 0.000007} us Measuring clock cycles: first runs (cycles): 60 19 19 19 19 18 19 19 19 19 ... slowest runs (cycles): ... 22 22 22 22 22 22 22 22 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: 10431 MHz TestHostSyscall: Measuring real time: 0.067 usec (6.742e-08 sec) per iteration: 6.742e-06 sec for 100 iterations 0.054 usec (5.3672e-08 sec) per iteration: 5.3672e-05 sec for 1000 iterations 0.054 usec (5.44036e-08 sec) per iteration: 0.101363 sec for 1863168 iterations 0.055 usec (5.45938e-08 sec) per iteration: 0.101717 sec for 1863168 iterations 0.055 usec (5.45637e-08 sec) per iteration: 0.101661 sec for 1863168 iterations 0.055 usec (5.48056e-08 sec) per iteration: 0.102112 sec for 1863168 iterations 0.055 usec (5.47695e-08 sec) per iteration: 0.102045 sec for 1863168 iterations mean: 0.054627 usec stddev: 0.000146 usec relative stddev: 0.27% RESULT TestHostSyscall: trusted_linux_x86-64= {0.054627, 0.000146} us Measuring clock cycles: first runs (cycles): 302 261 136 136 136 136 135 135 135 308 ... slowest runs (cycles): ... 136 136 136 136 261 264 268 280 302 308 min: 132 cycles q1: 135 cycles median: 135 cycles q3: 136 cycles max: 308 cycles RESULT TestHostSyscall_CycleCount: trusted_linux_x86-64= {135, 1} count Apparent clock speed: 2471 MHz TestSetjmpLongjmp: Measuring real time: 0.036 usec (3.634e-08 sec) per iteration: 3.634e-06 sec for 100 iterations 0.024 usec (2.4401e-08 sec) per iteration: 2.4401e-05 sec for 1000 iterations 0.025 usec (2.46985e-08 sec) per iteration: 0.101219 sec for 4098192 iterations 0.025 usec (2.46795e-08 sec) per iteration: 0.101141 sec for 4098192 iterations 0.025 usec (2.46659e-08 sec) per iteration: 0.101086 sec for 4098192 iterations 0.025 usec (2.46561e-08 sec) per iteration: 0.101045 sec for 4098192 iterations 0.025 usec (2.46671e-08 sec) per iteration: 0.10109 sec for 4098192 iterations mean: 0.024673 usec stddev: 0.000015 usec relative stddev: 0.06% RESULT TestSetjmpLongjmp: trusted_linux_x86-64= {0.024673, 0.000015} us Measuring clock cycles: first runs (cycles): 192 94 145 63 63 63 60 56 66 60 ... slowest runs (cycles): ... 66 66 66 94 145 169 179 192 195 217 min: 56 cycles q1: 60 cycles median: 63 cycles q3: 63 cycles max: 217 cycles RESULT TestSetjmpLongjmp_CycleCount: trusted_linux_x86-64= {63, 3} count Apparent clock speed: 2553 MHz TestClockGetTime: Measuring real time: 0.048 usec (4.837e-08 sec) per iteration: 4.837e-06 sec for 100 iterations 0.047 usec (4.6519e-08 sec) per iteration: 4.6519e-05 sec for 1000 iterations 0.047 usec (4.69238e-08 sec) per iteration: 0.10087 sec for 2149659 iterations 0.047 usec (4.69244e-08 sec) per iteration: 0.100872 sec for 2149659 iterations 0.047 usec (4.69654e-08 sec) per iteration: 0.10096 sec for 2149659 iterations 0.047 usec (4.69412e-08 sec) per iteration: 0.100907 sec for 2149659 iterations 0.047 usec (4.71602e-08 sec) per iteration: 0.101378 sec for 2149659 iterations mean: 0.046983 usec stddev: 0.000090 usec relative stddev: 0.19% RESULT TestClockGetTime: trusted_linux_x86-64= {0.046983, 0.000090} us Measuring clock cycles: first runs (cycles): 339 157 135 110 110 110 110 110 110 110 ... slowest runs (cycles): ... 113 113 113 113 113 113 135 157 339 519 min: 110 cycles q1: 110 cycles median: 110 cycles q3: 110 cycles max: 519 cycles RESULT TestClockGetTime_CycleCount: trusted_linux_x86-64= {110, 0} count Apparent clock speed: 2341 MHz TestTlsVariable: Measuring real time: 0.005 usec (5.14e-09 sec) per iteration: 5.14e-07 sec for 100 iterations 0.002 usec (1.853e-09 sec) per iteration: 1.853e-06 sec for 1000 iterations 0.002 usec (1.7956e-09 sec) per iteration: 1.7956e-05 sec for 10000 iterations 0.002 usec (1.83836e-09 sec) per iteration: 0.102382 sec for 55691690 iterations 0.002 usec (1.83784e-09 sec) per iteration: 0.102353 sec for 55691690 iterations 0.002 usec (1.83217e-09 sec) per iteration: 0.102037 sec for 55691690 iterations 0.002 usec (1.81596e-09 sec) per iteration: 0.101134 sec for 55691690 iterations 0.002 usec (1.81239e-09 sec) per iteration: 0.100935 sec for 55691690 iterations mean: 0.001827 usec stddev: 0.000011 usec relative stddev: 0.60% RESULT TestTlsVariable: trusted_linux_x86-64= {0.001827, 0.000011} us Measuring clock cycles: first runs (cycles): 70 18 19 19 19 19 19 19 18 19 ... slowest runs (cycles): ... 19 19 19 19 19 19 19 19 19 70 min: 18 cycles q1: 19 cycles median: 19 cycles q3: 19 cycles max: 70 cycles RESULT TestTlsVariable_CycleCount: trusted_linux_x86-64= {19, 0} count Apparent clock speed: 10398 MHz TestMmapAnonymous: Measuring real time: 0.679 usec (6.7896e-07 sec) per iteration: 6.7896e-05 sec for 100 iterations 0.510 usec (5.10301e-07 sec) per iteration: 0.0751591 sec for 147284 iterations 0.513 usec (5.12971e-07 sec) per iteration: 0.0755525 sec for 147284 iterations 0.512 usec (5.11794e-07 sec) per iteration: 0.0753791 sec for 147284 iterations 0.514 usec (5.13713e-07 sec) per iteration: 0.0756618 sec for 147284 iterations 0.510 usec (5.10137e-07 sec) per iteration: 0.075135 sec for 147284 iterations mean: 0.511783 usec stddev: 0.001417 usec relative stddev: 0.28% RESULT TestMmapAnonymous: trusted_linux_x86-64= {0.511783, 0.001417} us Measuring clock cycles: first runs (cycles): 2244 1238 1129 1129 1125 1115 1125 1125 1107 1314 ... slowest runs (cycles): ... 1280 1314 1549 1591 1647 1675 1691 1712 1754 2244 min: 1084 cycles q1: 1103 cycles median: 1113 cycles q3: 1125 cycles max: 2244 cycles RESULT TestMmapAnonymous_CycleCount: trusted_linux_x86-64= {1113, 22} count Apparent clock speed: 2175 MHz TestAtomicIncrement: Measuring real time: 0.010 usec (9.97e-09 sec) per iteration: 9.97e-07 sec for 100 iterations 0.007 usec (6.83e-09 sec) per iteration: 6.83e-06 sec for 1000 iterations 0.007 usec (6.7997e-09 sec) per iteration: 6.7997e-05 sec for 10000 iterations 0.007 usec (6.91722e-09 sec) per iteration: 0.101728 sec for 14706531 iterations 0.007 usec (6.94569e-09 sec) per iteration: 0.102147 sec for 14706531 iterations 0.007 usec (6.99488e-09 sec) per iteration: 0.10287 sec for 14706531 iterations 0.007 usec (6.93698e-09 sec) per iteration: 0.102019 sec for 14706531 iterations 0.007 usec (6.9002e-09 sec) per iteration: 0.101478 sec for 14706531 iterations mean: 0.006939 usec stddev: 0.000032 usec relative stddev: 0.46% RESULT TestAtomicIncrement: trusted_linux_x86-64= {0.006939, 0.000032} us Measuring clock cycles: first runs (cycles): 88 308 50 44 47 44 47 44 47 336 ... slowest runs (cycles): ... 48 48 48 50 88 308 317 321 327 336 min: 44 cycles q1: 44 cycles median: 47 cycles q3: 47 cycles max: 336 cycles RESULT TestAtomicIncrement_CycleCount: trusted_linux_x86-64= {47, 3} count Apparent clock speed: 6773 MHz TestUncontendedMutexLock: Measuring real time: 0.023 usec (2.304e-08 sec) per iteration: 2.304e-06 sec for 100 iterations 0.019 usec (1.8978e-08 sec) per iteration: 1.8978e-05 sec for 1000 iterations 0.019 usec (1.93375e-08 sec) per iteration: 0.101894 sec for 5269259 iterations 0.019 usec (1.93793e-08 sec) per iteration: 0.102114 sec for 5269259 iterations 0.019 usec (1.91834e-08 sec) per iteration: 0.101082 sec for 5269259 iterations 0.019 usec (1.94188e-08 sec) per iteration: 0.102323 sec for 5269259 iterations 0.019 usec (1.9367e-08 sec) per iteration: 0.10205 sec for 5269259 iterations mean: 0.019337 usec stddev: 0.000081 usec relative stddev: 0.42% RESULT TestUncontendedMutexLock: trusted_linux_x86-64= {0.019337, 0.000081} us Measuring clock cycles: first runs (cycles): 205 242 72 69 72 73 69 69 72 283 ... slowest runs (cycles): ... 73 73 73 73 205 238 242 242 242 283 min: 66 cycles q1: 69 cycles median: 72 cycles q3: 72 cycles max: 283 cycles RESULT TestUncontendedMutexLock_CycleCount: trusted_linux_x86-64= {72, 3} count Apparent clock speed: 3723 MHz TestCondvarSignalNoOp: Measuring real time: 0.018 usec (1.837e-08 sec) per iteration: 1.837e-06 sec for 100 iterations 0.016 usec (1.6137e-08 sec) per iteration: 1.6137e-05 sec for 1000 iterations 0.017 usec (1.65253e-08 sec) per iteration: 0.102406 sec for 6196938 iterations 0.016 usec (1.63366e-08 sec) per iteration: 0.101237 sec for 6196938 iterations 0.016 usec (1.63863e-08 sec) per iteration: 0.101545 sec for 6196938 iterations 0.016 usec (1.63089e-08 sec) per iteration: 0.101065 sec for 6196938 iterations 0.016 usec (1.63094e-08 sec) per iteration: 0.101068 sec for 6196938 iterations mean: 0.016373 usec stddev: 0.000081 usec relative stddev: 0.50% RESULT TestCondvarSignalNoOp: trusted_linux_x86-64= {0.016373, 0.000081} us Measuring clock cycles: first runs (cycles): 122 229 63 60 63 63 63 60 63 270 ... slowest runs (cycles): ... 63 63 63 63 122 229 239 245 255 270 min: 59 cycles q1: 62 cycles median: 63 cycles q3: 63 cycles max: 270 cycles RESULT TestCondvarSignalNoOp_CycleCount: trusted_linux_x86-64= {63, 1} count Apparent clock speed: 3848 MHz TestThreadCreateAndJoin: Measuring real time: 33.152 usec (3.31522e-05 sec) per iteration: 0.00331522 sec for 100 iterations 22.764 usec (2.27645e-05 sec) per iteration: 0.0686576 sec for 3016 iterations 22.497 usec (2.24973e-05 sec) per iteration: 0.067852 sec for 3016 iterations 22.767 usec (2.27668e-05 sec) per iteration: 0.0686647 sec for 3016 iterations 24.761 usec (2.47606e-05 sec) per iteration: 0.074678 sec for 3016 iterations 24.488 usec (2.44875e-05 sec) per iteration: 0.0738543 sec for 3016 iterations mean: 23.455341 usec stddev: 0.963147 usec relative stddev: 4.11% RESULT TestThreadCreateAndJoin: trusted_linux_x86-64= {23.455341, 0.963147} us Measuring clock cycles: first runs (cycles): 46828 36168 36548 35722 35574 54777 38468 39302 36400 36680 ... slowest runs (cycles): ... 53331 54777 56172 57172 60899 62618 69159 77912 95433 221772 min: 31909 cycles q1: 32809 cycles median: 34618 cycles q3: 38642 cycles max: 221772 cycles RESULT TestThreadCreateAndJoin_CycleCount: trusted_linux_x86-64= {34618, 5833} count Apparent clock speed: 1476 MHz TestThreadWakeup: Measuring real time: 12.872 usec (1.28723e-05 sec) per iteration: 0.00128723 sec for 100 iterations 12.822 usec (1.28222e-05 sec) per iteration: 0.0996027 sec for 7768 iterations 13.445 usec (1.3445e-05 sec) per iteration: 0.104441 sec for 7768 iterations 13.226 usec (1.32263e-05 sec) per iteration: 0.102742 sec for 7768 iterations 13.410 usec (1.34096e-05 sec) per iteration: 0.104166 sec for 7768 iterations 13.437 usec (1.34369e-05 sec) per iteration: 0.104378 sec for 7768 iterations mean: 13.268011 usec stddev: 0.236821 usec relative stddev: 1.78% RESULT TestThreadWakeup: trusted_linux_x86-64= {13.268011, 0.236821} us Measuring clock cycles: first runs (cycles): 27290 33352 33019 33063 33305 33713 32692 32858 32548 32108 ... slowest runs (cycles): ... 35332 35423 36105 36262 36313 37170 38249 38500 39883 44999 min: 26595 cycles q1: 28506 cycles median: 29244 cycles q3: 32720 cycles max: 44999 cycles RESULT TestThreadWakeup_CycleCount: trusted_linux_x86-64= {29244, 4214} count Apparent clock speed: 2204 MHz WARNING: should have taken less than 2.000000 secs [ OK ] nacl_newlib.run_trusted_performance_test (5400 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