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

Running command: /b/s/w/ir/cache/vpython/b574a6/bin/python scons.py --verbose -k -j1 --mode=nacl,dbg-host platform=x86-64 --clang breakpad_tools_dir=breakpad-out large_tests scons: Reading SConscript files ... ====================================================================== SCONS ARGS:['scons.py', '--verbose', '-k', '-j1', '--mode=nacl,dbg-host', 'platform=x86-64', '--clang', 'breakpad_tools_dir=breakpad-out', 'large_tests'] ====================================================================== ====================================================================== Python Info: ====================================================================== linux2 2.7.14+chromium14 (df075345c7-dirty:df075345c7, Oct 3 2017, 17:29:00) [GCC 4.8.4] ====================================================================== ENV: ====================================================================== PATH /b/s/w/ir/cache/vpython/b574a6/bin:/b/s/w/ir/cipd_bin_packages:/b/s/w/ir/cipd_bin_packages/bin:/b/s/cipd_cache/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin:/b/s/w/ir/kitchen-checkout/depot_tools ====================================================================== OS: ====================================================================== Linux luci-flex-ci-trusty-6-k9jm 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: 20947612 kB MemAvailable: 29465460 kB ====================================================================== LOAD: ====================================================================== 1.72 1.90 0.94 1/408 13773 ====================================================================== UPTIME: ====================================================================== 2201.39 16571.29 ====================================================================== The following environments have been configured ====================================================================== NACL_BUILD_FAMILY: UNTRUSTED BUILD_TYPE: nacl TARGET_ROOT: /b/s/w/ir/k/native_client/scons-out/nacl-x86-64 OBJ_ROOT: /b/s/w/ir/k/native_client/scons-out/nacl-x86-64/obj BUILD_TYPE_DESCRIPTION: NaCl module build BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 64 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 64 CC: /b/s/w/ir/k/native_client/toolchain/linux_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /b/s/w/ir/k/native_client/toolchain/linux_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang -m64 -D__STDC_LIMIT_MACROS=1 -D__STDC_FORMAT_MACROS=1 -D_GNU_SOURCE=1 -D_POSIX_C_SOURCE=199506 -D_XOPEN_SOURCE=600 -DDYNAMIC_ANNOTATIONS_ENABLED=1 -DDYNAMIC_ANNOTATIONS_PREFIX=NACL_ -DNACL_BUILD_ARCH=x86 -DNACL_BUILD_SUBARCH=64 -I/b/s/w/ir/k/native_client/scons-out/nacl-x86-64/include -I/b/s/w/ir/k -c -o UNKNOWN COMPILER NACL_BUILD_FAMILY: TRUSTED BUILD_TYPE: dbg-linux TARGET_ROOT: /b/s/w/ir/k/native_client/scons-out/dbg-linux-x86-64 OBJ_ROOT: /b/s/w/ir/k/native_client/scons-out/dbg-linux-x86-64/obj BUILD_TYPE_DESCRIPTION: Linux dbg build BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 64 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 64 CC: /b/s/w/ir/k/third_party/llvm-build/Release+Asserts/bin/clang ASPPCOM: /b/s/w/ir/k/third_party/llvm-build/Release+Asserts/bin/clang -fPIE -m64 -Wall -pedantic -Wextra -Wno-long-long -Wswitch-enum -Wsign-compare -Wundef -fdiagnostics-show-option -fvisibility=hidden -fstack-protector -Werror -Wno-variadic-macros -Wno-covered-switch-default -Wno-c++11-extensions -O0 -g -D_FORTIFY_SOURCE=2 -D_POSIX_C_SOURCE=199506 -D_XOPEN_SOURCE=600 -D_GNU_SOURCE=1 -D_LARGEFILE64_SOURCE=1 -D__STDC_LIMIT_MACROS=1 -D__STDC_FORMAT_MACROS=1 -D_DEBUG -I/b/s/w/ir/k -c -o UNKNOWN COMPILER NACL_BUILD_FAMILY: UNTRUSTED_IRT BUILD_TYPE: nacl_irt TARGET_ROOT: /b/s/w/ir/k/native_client/scons-out/nacl_irt-x86-64 OBJ_ROOT: /b/s/w/ir/k/native_client/scons-out/nacl_irt-x86-64/obj BUILD_TYPE_DESCRIPTION: NaCl IRT build BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 64 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 64 CC: /b/s/w/ir/k/native_client/toolchain/linux_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /b/s/w/ir/k/native_client/toolchain/linux_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang -m64 -D__STDC_LIMIT_MACROS=1 -D__STDC_FORMAT_MACROS=1 -D_GNU_SOURCE=1 -D_POSIX_C_SOURCE=199506 -D_XOPEN_SOURCE=600 -DDYNAMIC_ANNOTATIONS_ENABLED=1 -DDYNAMIC_ANNOTATIONS_PREFIX=NACL_ -DNACL_BUILD_ARCH=x86 -DNACL_BUILD_SUBARCH=64 -I/b/s/w/ir/k/native_client/scons-out/nacl_irt-x86-64/include -I/b/s/w/ir/k -I/b/s/w/ir/k/native_client/src/untrusted/pthread -c -o UNKNOWN COMPILER *** BROKEN run_ncval_annotate_test *** BROKEN run_gdb_invalid_memory_test *** BROKEN run_nanosleep_test *** BROKEN run_timefuncs_test *** BROKEN run_raw_timefuncs_test *** BROKEN run_main_thread_pthread_exit_test2 *** BROKEN run_overflowinline_test *** BROKEN run_stack_frame_noopt_frame_test *** BROKEN run_stack_frame_noopt_noframe_test *** BROKEN run_stack_frame_opt_frame_test *** BROKEN run_stack_frame_opt_noframe_test *** BROKEN run_vla_test *** BROKEN run_nacl_sync_cond_test SKIPPING test text_overlaps_rodata SKIPPING test text_overlaps_data There are 13 broken tests. ====================================================================== B U I L D - O U T P U T: ====================================================================== scons: done reading SConscript files. scons: Building targets ... /b/s/w/ir/cache/vpython/b574a6/bin/python /b/s/w/ir/k/native_client/tools/command_tester.py --name nacl_newlib.run_signal_handler_single_step_test --time_warning 2 --time_error 20 --perf_env_description x86-64_nnacl_newlib_static --subarch 64 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/k/native_client/scons-out/nacl-x86-64/test_results/signal_handler_single_step.out /b/s/w/ir/k/native_client/scons-out/nacl-x86-64/obj/tests/signal_handler_single_step/step_test_host /b/s/w/ir/k/native_client/scons-out/nacl-x86-64/obj/tests/signal_handler_single_step/step_test_guest.nexe x86-64_nnacl_newlib_static [ RUN ] nacl_newlib.run_signal_handler_single_step_test /b/s/w/ir/k/native_client/scons-out/nacl-x86-64/obj/tests/signal_handler_single_step/step_test_host /b/s/w/ir/k/native_client/scons-out/nacl-x86-64/obj/tests/signal_handler_single_step/step_test_guest.nexe x86-64_nnacl_newlib_static [13781,4262586240:09:42:40.340216] Native Client module will be loaded at base address 0x0000263d00000000 Switching to untrusted: since previous switch: 330 instructions, 1466 instruction bytes, 31 jumps Switching to trusted: since previous switch: 11 instructions, 48 instruction bytes, 2 jumps Switching to untrusted: since previous switch: 572 instructions, 1957 instruction bytes, 72 jumps Switching to trusted: since previous switch: 9 instructions, 32 instruction bytes, 3 jumps Switching to untrusted: since previous switch: 572 instructions, 1957 instruction bytes, 72 jumps Switching to trusted: since previous switch: 9 instructions, 32 instruction bytes, 3 jumps Switching to untrusted: since previous switch: 572 instructions, 1957 instruction bytes, 72 jumps Switching to trusted: since previous switch: 9 instructions, 32 instruction bytes, 3 jumps Switching to untrusted: since previous switch: 572 instructions, 1957 instruction bytes, 72 jumps RESULT InstructionsPerSyscall: x86-64_nnacl_newlib_static= 572 count RESULT InstructionBytesPerSyscall: x86-64_nnacl_newlib_static= 1957 count RESULT JumpsPerSyscall: x86-64_nnacl_newlib_static= 72 count Switching to trusted: since previous switch: 9 instructions, 32 instruction bytes, 3 jumps [ OK ] nacl_newlib.run_signal_handler_single_step_test (10 ms) /b/s/w/ir/cache/vpython/b574a6/bin/python tools/command_tester.py --name nacl_newlib.run_benchmark_test --time_warning 10 --time_error 100 --perf_env_description x86-64_nnacl_newlib_static --using_nacl_signal_handler True --subarch 64 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/k/native_client/scons-out/nacl-x86-64/test_results/benchmark_test.out scons-out/dbg-linux-x86-64/staging/nacl_helper_bootstrap scons-out/dbg-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -- scons-out/nacl-x86-64/obj/tests/benchmark/benchmark_test.nexe x86-64_nnacl_newlib_static [ RUN ] nacl_newlib.run_benchmark_test scons-out/dbg-linux-x86-64/staging/nacl_helper_bootstrap scons-out/dbg-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -- scons-out/nacl-x86-64/obj/tests/benchmark/benchmark_test.nexe x86-64_nnacl_newlib_static [13786,29927296:09:42:40.651308] Native Client module will be loaded at base address 0x00006cb500000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_nnacl_newlib_static= {0.099470, 0.000920} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test (1580 ms) /b/s/w/ir/cache/vpython/b574a6/bin/python tools/command_tester.py --name nacl_newlib.run_clock_cputime_test --time_warning 60 --time_error 600 --perf_env_description x86-64_nnacl_newlib_static --using_nacl_signal_handler True --subarch 64 --arch x86 --output_stamp /b/s/w/ir/k/native_client/scons-out/nacl-x86-64/test_results/clock_cputime_test.out scons-out/dbg-linux-x86-64/staging/nacl_helper_bootstrap scons-out/dbg-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -- scons-out/nacl-x86-64/obj/tests/clock/clock_test.nexe -c [ RUN ] nacl_newlib.run_clock_cputime_test scons-out/dbg-linux-x86-64/staging/nacl_helper_bootstrap scons-out/dbg-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -- scons-out/nacl-x86-64/obj/tests/clock/clock_test.nexe -c WARNING: should have taken less than 60.000000 secs [ OK ] nacl_newlib.run_clock_cputime_test (75010 ms) doSizeCheck(["scons-out/nacl-x86-64/obj/tests/hello_world/hello_world_size.out"], ["scons-out/nacl-x86-64/obj/tests/hello_world/hello_world.nexe"]) RESULT hello_world_size.out: x86-64_nnacl_newlib_static= 524.920 KB RESULT hello_world_size.out: ZIPPED_x86-64_nnacl_newlib_static= 187.832 KB /b/s/w/ir/cache/vpython/b574a6/bin/python tools/command_tester.py --name nacl_newlib.run_performance_test --time_warning 10 --time_error 100 --perf_env_description x86-64_nnacl_newlib_static --using_nacl_signal_handler True --subarch 64 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/k/native_client/scons-out/nacl-x86-64/test_results/performance_test.out scons-out/dbg-linux-x86-64/staging/nacl_helper_bootstrap scons-out/dbg-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -e -- scons-out/nacl-x86-64/obj/tests/performance/performance_test.nexe x86-64_nnacl_newlib_static [ RUN ] nacl_newlib.run_performance_test scons-out/dbg-linux-x86-64/staging/nacl_helper_bootstrap scons-out/dbg-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -e -- scons-out/nacl-x86-64/obj/tests/performance/performance_test.nexe x86-64_nnacl_newlib_static [13814,4106528640:09:42:56.182007] Native Client module will be loaded at base address 0x0000500b00000000 TestNull: Measuring real time: 0.007 usec (7.08e-09 sec) per iteration: 7.08e-07 sec for 100 iterations 0.002 usec (2.158e-09 sec) per iteration: 2.158e-06 sec for 1000 iterations 0.002 usec (1.8932e-09 sec) per iteration: 1.8932e-05 sec for 10000 iterations 0.002 usec (1.89344e-09 sec) per iteration: 0.100013 sec for 52820621 iterations 0.002 usec (1.91044e-09 sec) per iteration: 0.100911 sec for 52820621 iterations 0.002 usec (1.88992e-09 sec) per iteration: 0.0998266 sec for 52820621 iterations 0.002 usec (1.89762e-09 sec) per iteration: 0.100234 sec for 52820621 iterations 0.002 usec (1.89211e-09 sec) per iteration: 0.0999425 sec for 52820621 iterations mean: 0.001897 usec stddev: 0.000007 usec relative stddev: 0.39% RESULT TestNull: x86-64_nnacl_newlib_static= {0.001897, 0.000007} us Measuring clock cycles: first runs (cycles): 110 22 24 24 29 20 24 25 25 167 ... slowest runs (cycles): ... 27 27 27 27 27 27 27 29 110 167 min: 20 cycles q1: 24 cycles median: 25 cycles q3: 26 cycles max: 167 cycles RESULT TestNull_CycleCount: x86-64_nnacl_newlib_static= {25, 2} count Apparent clock speed: 13181 MHz TestNaClSyscall: Measuring real time: 0.226 usec (2.2585e-07 sec) per iteration: 2.2585e-05 sec for 100 iterations 0.205 usec (2.05177e-07 sec) per iteration: 0.0908466 sec for 442771 iterations 0.216 usec (2.16025e-07 sec) per iteration: 0.0956496 sec for 442771 iterations 0.210 usec (2.09564e-07 sec) per iteration: 0.092789 sec for 442771 iterations 0.206 usec (2.06191e-07 sec) per iteration: 0.0912952 sec for 442771 iterations 0.205 usec (2.05463e-07 sec) per iteration: 0.0909729 sec for 442771 iterations mean: 0.208484 usec stddev: 0.004083 usec relative stddev: 1.96% RESULT TestNaClSyscall: x86-64_nnacl_newlib_static= {0.208484, 0.004083} us Measuring clock cycles: first runs (cycles): 506 365 350 416 345 348 381 341 345 354 ... slowest runs (cycles): ... 376 376 380 381 383 387 387 393 416 506 min: 338 cycles q1: 348 cycles median: 352 cycles q3: 354 cycles max: 506 cycles RESULT TestNaClSyscall_CycleCount: x86-64_nnacl_newlib_static= {352, 6} count Apparent clock speed: 1688 MHz TestSetjmpLongjmp: Measuring real time: 0.017 usec (1.741e-08 sec) per iteration: 1.741e-06 sec for 100 iterations 0.016 usec (1.6314e-08 sec) per iteration: 1.6314e-05 sec for 1000 iterations 0.013 usec (1.28604e-08 sec) per iteration: 0.0788306 sec for 6129704 iterations 0.013 usec (1.29684e-08 sec) per iteration: 0.0794922 sec for 6129704 iterations 0.013 usec (1.29755e-08 sec) per iteration: 0.079536 sec for 6129704 iterations 0.013 usec (1.27934e-08 sec) per iteration: 0.0784198 sec for 6129704 iterations 0.013 usec (1.298e-08 sec) per iteration: 0.0795633 sec for 6129704 iterations mean: 0.012916 usec stddev: 0.000075 usec relative stddev: 0.58% RESULT TestSetjmpLongjmp: x86-64_nnacl_newlib_static= {0.012916, 0.000075} us Measuring clock cycles: first runs (cycles): 160 40 36 31 29 29 30 29 31 31 ... slowest runs (cycles): ... 30 30 31 31 31 31 31 36 40 160 min: 24 cycles q1: 26 cycles median: 27 cycles q3: 29 cycles max: 160 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_nnacl_newlib_static= {27, 3} count Apparent clock speed: 2091 MHz TestClockGetTime: Measuring real time: 0.277 usec (2.7665e-07 sec) per iteration: 2.7665e-05 sec for 100 iterations 0.273 usec (2.73291e-07 sec) per iteration: 0.0987858 sec for 361467 iterations 0.272 usec (2.72157e-07 sec) per iteration: 0.0983759 sec for 361467 iterations 0.273 usec (2.73262e-07 sec) per iteration: 0.0987751 sec for 361467 iterations 0.273 usec (2.72725e-07 sec) per iteration: 0.0985812 sec for 361467 iterations 0.273 usec (2.73388e-07 sec) per iteration: 0.0988209 sec for 361467 iterations mean: 0.272965 usec stddev: 0.000466 usec relative stddev: 0.17% RESULT TestClockGetTime: x86-64_nnacl_newlib_static= {0.272965, 0.000466} us Measuring clock cycles: first runs (cycles): 917 715 660 638 653 618 618 602 614 603 ... slowest runs (cycles): ... 638 638 640 641 645 653 653 660 715 917 min: 594 cycles q1: 600 cycles median: 605 cycles q3: 609 cycles max: 917 cycles RESULT TestClockGetTime_CycleCount: x86-64_nnacl_newlib_static= {605, 9} count Apparent clock speed: 2216 MHz TestTlsVariable: Measuring real time: 0.010 usec (9.75e-09 sec) per iteration: 9.75e-07 sec for 100 iterations 0.006 usec (5.911e-09 sec) per iteration: 5.911e-06 sec for 1000 iterations 0.006 usec (5.5939e-09 sec) per iteration: 5.5939e-05 sec for 10000 iterations 0.006 usec (5.70795e-09 sec) per iteration: 0.102039 sec for 17876615 iterations 0.006 usec (5.68168e-09 sec) per iteration: 0.101569 sec for 17876615 iterations 0.006 usec (5.6643e-09 sec) per iteration: 0.101258 sec for 17876615 iterations 0.006 usec (5.74232e-09 sec) per iteration: 0.102653 sec for 17876615 iterations 0.006 usec (5.66919e-09 sec) per iteration: 0.101346 sec for 17876615 iterations mean: 0.005693 usec stddev: 0.000029 usec relative stddev: 0.51% RESULT TestTlsVariable: x86-64_nnacl_newlib_static= {0.005693, 0.000029} us Measuring clock cycles: first runs (cycles): 174 77 37 37 37 33 26 28 24 33 ... slowest runs (cycles): ... 31 31 33 33 33 37 37 37 77 174 min: 22 cycles q1: 26 cycles median: 28 cycles q3: 29 cycles max: 174 cycles RESULT TestTlsVariable_CycleCount: x86-64_nnacl_newlib_static= {28, 3} count Apparent clock speed: 4918 MHz TestMmapAnonymous: Measuring real time: 3.202 usec (3.20217e-06 sec) per iteration: 0.000320217 sec for 100 iterations 2.488 usec (2.48772e-06 sec) per iteration: 0.0776865 sec for 31228 iterations 2.471 usec (2.4712e-06 sec) per iteration: 0.0771708 sec for 31228 iterations 2.475 usec (2.4746e-06 sec) per iteration: 0.0772767 sec for 31228 iterations 2.481 usec (2.48121e-06 sec) per iteration: 0.0774833 sec for 31228 iterations 2.485 usec (2.48542e-06 sec) per iteration: 0.0776146 sec for 31228 iterations mean: 2.480030 usec stddev: 0.006276 usec relative stddev: 0.25% RESULT TestMmapAnonymous: x86-64_nnacl_newlib_static= {2.480030, 0.006276} us Measuring clock cycles: first runs (cycles): 8394 5562 5502 5440 5388 5455 5383 5365 5295 5312 ... slowest runs (cycles): ... 5440 5444 5451 5455 5462 5469 5484 5502 5562 8394 min: 5233 cycles q1: 5284 cycles median: 5332 cycles q3: 5381 cycles max: 8394 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_nnacl_newlib_static= {5332, 97} count Apparent clock speed: 2150 MHz TestAtomicIncrement: Measuring real time: 0.015 usec (1.489e-08 sec) per iteration: 1.489e-06 sec for 100 iterations 0.020 usec (2.0088e-08 sec) per iteration: 2.0088e-05 sec for 1000 iterations 0.009 usec (9.42789e-09 sec) per iteration: 0.0469329 sec for 4978096 iterations 0.009 usec (9.44818e-09 sec) per iteration: 0.0470339 sec for 4978096 iterations 0.009 usec (9.42088e-09 sec) per iteration: 0.0468981 sec for 4978096 iterations 0.009 usec (9.43733e-09 sec) per iteration: 0.0469799 sec for 4978096 iterations 0.009 usec (9.41977e-09 sec) per iteration: 0.0468925 sec for 4978096 iterations mean: 0.009431 usec stddev: 0.000011 usec relative stddev: 0.11% RESULT TestAtomicIncrement: x86-64_nnacl_newlib_static= {0.009431, 0.000011} us Measuring clock cycles: first runs (cycles): 121 55 53 53 51 53 51 51 51 51 ... slowest runs (cycles): ... 53 53 53 53 53 53 53 53 55 121 min: 48 cycles q1: 51 cycles median: 51 cycles q3: 53 cycles max: 121 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_nnacl_newlib_static= {51, 2} count Apparent clock speed: 5408 MHz TestUncontendedMutexLock: Measuring real time: 0.031 usec (3.106e-08 sec) per iteration: 3.106e-06 sec for 100 iterations 0.024 usec (2.4392e-08 sec) per iteration: 2.4392e-05 sec for 1000 iterations 0.025 usec (2.45108e-08 sec) per iteration: 0.100487 sec for 4099704 iterations 0.024 usec (2.44753e-08 sec) per iteration: 0.100341 sec for 4099704 iterations 0.024 usec (2.44912e-08 sec) per iteration: 0.100407 sec for 4099704 iterations 0.025 usec (2.48461e-08 sec) per iteration: 0.101862 sec for 4099704 iterations 0.025 usec (2.45001e-08 sec) per iteration: 0.100443 sec for 4099704 iterations mean: 0.024565 usec stddev: 0.000141 usec relative stddev: 0.57% RESULT TestUncontendedMutexLock: x86-64_nnacl_newlib_static= {0.024565, 0.000141} us Measuring clock cycles: first runs (cycles): 198 84 83 84 84 83 83 84 84 83 ... slowest runs (cycles): ... 84 84 84 84 84 84 84 84 84 198 min: 81 cycles q1: 83 cycles median: 84 cycles q3: 84 cycles max: 198 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_nnacl_newlib_static= {84, 1} count Apparent clock speed: 3420 MHz TestCondvarSignalNoOp: Measuring real time: 0.326 usec (3.2622e-07 sec) per iteration: 3.2622e-05 sec for 100 iterations 0.307 usec (3.06906e-07 sec) per iteration: 0.0940792 sec for 306541 iterations 0.307 usec (3.0699e-07 sec) per iteration: 0.0941052 sec for 306541 iterations 0.306 usec (3.06392e-07 sec) per iteration: 0.0939218 sec for 306541 iterations 0.308 usec (3.07979e-07 sec) per iteration: 0.0944083 sec for 306541 iterations 0.306 usec (3.05512e-07 sec) per iteration: 0.093652 sec for 306541 iterations mean: 0.306756 usec stddev: 0.000807 usec relative stddev: 0.26% RESULT TestCondvarSignalNoOp: x86-64_nnacl_newlib_static= {0.306756, 0.000807} us Measuring clock cycles: first runs (cycles): 864 678 779 787 693 676 691 676 690 688 ... slowest runs (cycles): ... 715 722 726 731 733 759 761 779 787 864 min: 662 cycles q1: 671 cycles median: 677 cycles q3: 680 cycles max: 864 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_nnacl_newlib_static= {677, 9} count Apparent clock speed: 2207 MHz TestThreadCreateAndJoin: Measuring real time: 37.008 usec (3.70083e-05 sec) per iteration: 0.00370083 sec for 100 iterations 31.184 usec (3.11844e-05 sec) per iteration: 0.0842602 sec for 2702 iterations 33.337 usec (3.33367e-05 sec) per iteration: 0.0900757 sec for 2702 iterations 31.199 usec (3.11989e-05 sec) per iteration: 0.0842995 sec for 2702 iterations 26.306 usec (2.63057e-05 sec) per iteration: 0.0710781 sec for 2702 iterations 27.072 usec (2.70719e-05 sec) per iteration: 0.0731482 sec for 2702 iterations mean: 29.819514 usec stddev: 2.684485 usec relative stddev: 9.00% RESULT TestThreadCreateAndJoin: x86-64_nnacl_newlib_static= {29.819514, 2.684485} us Measuring clock cycles: first runs (cycles): 56549 105481 55058 56349 104058 55335 90108 59615 49254 53722 ... slowest runs (cycles): ... 89623 90108 92034 92538 95512 95976 104058 104648 105481 117889 min: 48614 cycles q1: 52387 cycles median: 53955 cycles q3: 56549 cycles max: 117889 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_nnacl_newlib_static= {53955, 4162} count Apparent clock speed: 1809 MHz TestThreadWakeup: Measuring real time: 12.696 usec (1.26957e-05 sec) per iteration: 0.00126957 sec for 100 iterations 14.467 usec (1.44668e-05 sec) per iteration: 0.11394 sec for 7876 iterations 14.933 usec (1.49334e-05 sec) per iteration: 0.117615 sec for 7876 iterations 15.181 usec (1.51807e-05 sec) per iteration: 0.119563 sec for 7876 iterations 14.725 usec (1.4725e-05 sec) per iteration: 0.115974 sec for 7876 iterations 16.183 usec (1.61829e-05 sec) per iteration: 0.127457 sec for 7876 iterations mean: 15.097765 usec stddev: 0.591378 usec relative stddev: 3.92% RESULT TestThreadWakeup: x86-64_nnacl_newlib_static= {15.097765, 0.591378} us Measuring clock cycles: first runs (cycles): 63747 40990 39344 35053 36795 34273 34590 35619 35182 29878 ... slowest runs (cycles): ... 39593 40176 40793 40990 42469 45048 45624 45823 53159 63747 min: 29878 cycles q1: 35074 cycles median: 35582 cycles q3: 36584 cycles max: 63747 cycles RESULT TestThreadWakeup_CycleCount: x86-64_nnacl_newlib_static= {35582, 1510} count Apparent clock speed: 2357 MHz TestCatchingFault: Measuring real time: 3.207 usec (3.20651e-06 sec) per iteration: 0.000320651 sec for 100 iterations 1.913 usec (1.91323e-06 sec) per iteration: 0.059666 sec for 31186 iterations 1.918 usec (1.91801e-06 sec) per iteration: 0.0598152 sec for 31186 iterations 1.920 usec (1.91989e-06 sec) per iteration: 0.0598736 sec for 31186 iterations 1.920 usec (1.9199e-06 sec) per iteration: 0.0598739 sec for 31186 iterations 1.924 usec (1.9236e-06 sec) per iteration: 0.0599895 sec for 31186 iterations mean: 1.918926 usec stddev: 0.003377 usec relative stddev: 0.18% RESULT TestCatchingFault: x86-64_nnacl_newlib_static= {1.918926, 0.003377} us Measuring clock cycles: first runs (cycles): 5113 4378 4301 4312 4269 4316 4344 4319 4219 4292 ... slowest runs (cycles): ... 4340 4340 4344 4356 4358 4374 4377 4378 4382 5113 min: 4193 cycles q1: 4245 cycles median: 4269 cycles q3: 4307 cycles max: 5113 cycles RESULT TestCatchingFault_CycleCount: x86-64_nnacl_newlib_static= {4269, 62} count Apparent clock speed: 2225 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 33.079 usec (3.30794e-05 sec) per iteration: 0.00330794 sec for 100 iterations 31.298 usec (3.1298e-05 sec) per iteration: 0.0946138 sec for 3023 iterations 27.462 usec (2.74619e-05 sec) per iteration: 0.0830173 sec for 3023 iterations 31.569 usec (3.15694e-05 sec) per iteration: 0.0954342 sec for 3023 iterations 47.730 usec (4.773e-05 sec) per iteration: 0.144288 sec for 3023 iterations 45.539 usec (4.5539e-05 sec) per iteration: 0.137664 sec for 3023 iterations mean: 36.719654 usec stddev: 8.253906 usec relative stddev: 22.48% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_nnacl_newlib_static= {36.719654, 8.253906} us Measuring clock cycles: first runs (cycles): 66426 86653 82393 112742 110284 77876 77044 73340 67831 71762 ... slowest runs (cycles): ... 122729 122801 123846 141929 192621 203537 255281 269798 325073 658876 min: 49177 cycles q1: 69790 cycles median: 93574 cycles q3: 107136 cycles max: 658876 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_nnacl_newlib_static= {93574, 37346} count Apparent clock speed: 2548 MHz [ OK ] nacl_newlib.run_performance_test (6080 ms) /b/s/w/ir/cache/vpython/b574a6/bin/python /b/s/w/ir/k/native_client/tools/command_tester.py --name nacl_newlib.run_nacl_clock_cputime_test --time_warning 60 --time_error 600 --perf_env_description trusted_linux_x86-64 --subarch 64 --arch x86 --output_stamp /b/s/w/ir/k/native_client/scons-out/dbg-linux-x86-64/test_results/nacl_clock_cputime_test.out /b/s/w/ir/k/native_client/scons-out/dbg-linux-x86-64/obj/src/shared/platform/nacl_clock_test -c [ RUN ] nacl_newlib.run_nacl_clock_cputime_test /b/s/w/ir/k/native_client/scons-out/dbg-linux-x86-64/obj/src/shared/platform/nacl_clock_test -c [ OK ] nacl_newlib.run_nacl_clock_cputime_test (8660 ms) /b/s/w/ir/cache/vpython/b574a6/bin/python /b/s/w/ir/k/native_client/tools/command_tester.py --name nacl_newlib.run_nacl_interval_multi_reg_test --time_warning 60 --time_error 600 --perf_env_description trusted_linux_x86-64 --subarch 64 --arch x86 --output_stamp /b/s/w/ir/k/native_client/scons-out/dbg-linux-x86-64/test_results/nacl_interval_multi_reg_test.out /b/s/w/ir/k/native_client/scons-out/dbg-linux-x86-64/obj/src/trusted/interval_multiset/nacl_interval_test -k NaClIntervalListMultiset -k NaClIntervalRangeTree -c 1000000 [ RUN ] nacl_newlib.run_nacl_interval_multi_reg_test /b/s/w/ir/k/native_client/scons-out/dbg-linux-x86-64/obj/src/trusted/interval_multiset/nacl_interval_test -k NaClIntervalListMultiset -k NaClIntervalRangeTree -c 1000000 [ OK ] nacl_newlib.run_nacl_interval_multi_reg_test (1190 ms) /b/s/w/ir/cache/vpython/b574a6/bin/python /b/s/w/ir/k/native_client/tools/command_tester.py --name nacl_newlib.run_trusted_performance_test --time_warning 2 --time_error 20 --perf_env_description trusted_linux_x86-64 --subarch 64 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/k/native_client/scons-out/dbg-linux-x86-64/test_results/performance_test.out /b/s/w/ir/k/native_client/scons-out/dbg-linux-x86-64/obj/tests/performance/performance_test trusted_linux_x86-64 [ RUN ] nacl_newlib.run_trusted_performance_test /b/s/w/ir/k/native_client/scons-out/dbg-linux-x86-64/obj/tests/performance/performance_test trusted_linux_x86-64 TestNull: Measuring real time: 0.004 usec (4.42e-09 sec) per iteration: 4.42e-07 sec for 100 iterations 0.003 usec (2.574e-09 sec) per iteration: 2.574e-06 sec for 1000 iterations 0.003 usec (2.5098e-09 sec) per iteration: 2.5098e-05 sec for 10000 iterations 0.003 usec (2.60301e-09 sec) per iteration: 0.103714 sec for 39843812 iterations 0.003 usec (2.59604e-09 sec) per iteration: 0.103436 sec for 39843812 iterations 0.003 usec (2.60226e-09 sec) per iteration: 0.103684 sec for 39843812 iterations 0.003 usec (2.59931e-09 sec) per iteration: 0.103566 sec for 39843812 iterations 0.003 usec (2.60167e-09 sec) per iteration: 0.10366 sec for 39843812 iterations mean: 0.002600 usec stddev: 0.000003 usec relative stddev: 0.10% RESULT TestNull: trusted_linux_x86-64= {0.002600, 0.000003} us Measuring clock cycles: first runs (cycles): 92 25 27 420 27 24 26 28 31 28 ... slowest runs (cycles): ... 29 29 29 29 29 29 30 31 92 420 min: 24 cycles q1: 26 cycles median: 27 cycles q3: 28 cycles max: 420 cycles RESULT TestNull_CycleCount: trusted_linux_x86-64= {27, 2} count Apparent clock speed: 10383 MHz TestHostSyscall: Measuring real time: 0.082 usec (8.232e-08 sec) per iteration: 8.232e-06 sec for 100 iterations 0.061 usec (6.0972e-08 sec) per iteration: 6.0972e-05 sec for 1000 iterations 0.063 usec (6.25622e-08 sec) per iteration: 0.102608 sec for 1640097 iterations 0.063 usec (6.29056e-08 sec) per iteration: 0.103171 sec for 1640097 iterations 0.062 usec (6.24707e-08 sec) per iteration: 0.102458 sec for 1640097 iterations 0.062 usec (6.22826e-08 sec) per iteration: 0.10215 sec for 1640097 iterations 0.062 usec (6.23661e-08 sec) per iteration: 0.102286 sec for 1640097 iterations mean: 0.062517 usec stddev: 0.000216 usec relative stddev: 0.35% RESULT TestHostSyscall: trusted_linux_x86-64= {0.062517, 0.000216} us Measuring clock cycles: first runs (cycles): 350 174 178 157 154 156 157 156 156 157 ... slowest runs (cycles): ... 157 157 157 157 157 157 174 178 350 515 min: 154 cycles q1: 156 cycles median: 156 cycles q3: 156 cycles max: 515 cycles RESULT TestHostSyscall_CycleCount: trusted_linux_x86-64= {156, 0} count Apparent clock speed: 2495 MHz TestSetjmpLongjmp: Measuring real time: 0.175 usec (1.7532e-07 sec) per iteration: 1.7532e-05 sec for 100 iterations 0.027 usec (2.74179e-08 sec) per iteration: 0.0156387 sec for 570385 iterations 0.027 usec (2.7448e-08 sec) per iteration: 0.0156559 sec for 570385 iterations 0.027 usec (2.73143e-08 sec) per iteration: 0.0155797 sec for 570385 iterations 0.027 usec (2.7313e-08 sec) per iteration: 0.015579 sec for 570385 iterations 0.028 usec (2.78416e-08 sec) per iteration: 0.0158805 sec for 570385 iterations mean: 0.027467 usec stddev: 0.000195 usec relative stddev: 0.71% RESULT TestSetjmpLongjmp: trusted_linux_x86-64= {0.027467, 0.000195} us Measuring clock cycles: first runs (cycles): 220 108 86 86 86 81 79 79 77 82 ... slowest runs (cycles): ... 84 84 86 86 86 86 86 86 108 220 min: 77 cycles q1: 81 cycles median: 81 cycles q3: 83 cycles max: 220 cycles RESULT TestSetjmpLongjmp_CycleCount: trusted_linux_x86-64= {81, 2} count Apparent clock speed: 2949 MHz TestClockGetTime: Measuring real time: 0.056 usec (5.602e-08 sec) per iteration: 5.602e-06 sec for 100 iterations 0.054 usec (5.4286e-08 sec) per iteration: 5.4286e-05 sec for 1000 iterations 0.060 usec (6.00353e-08 sec) per iteration: 0.110591 sec for 1842095 iterations 0.060 usec (5.96677e-08 sec) per iteration: 0.109914 sec for 1842095 iterations 0.059 usec (5.86203e-08 sec) per iteration: 0.107984 sec for 1842095 iterations 0.060 usec (5.99665e-08 sec) per iteration: 0.110464 sec for 1842095 iterations 0.058 usec (5.81148e-08 sec) per iteration: 0.107053 sec for 1842095 iterations mean: 0.059281 usec stddev: 0.000773 usec relative stddev: 1.30% RESULT TestClockGetTime: trusted_linux_x86-64= {0.059281, 0.000773} us Measuring clock cycles: first runs (cycles): 315 187 136 139 141 141 137 139 136 138 ... slowest runs (cycles): ... 139 139 139 139 139 139 141 141 187 315 min: 134 cycles q1: 136 cycles median: 136 cycles q3: 137 cycles max: 315 cycles RESULT TestClockGetTime_CycleCount: trusted_linux_x86-64= {136, 1} count Apparent clock speed: 2294 MHz TestTlsVariable: Measuring real time: 0.007 usec (7.29e-09 sec) per iteration: 7.29e-07 sec for 100 iterations 0.003 usec (3.433e-09 sec) per iteration: 3.433e-06 sec for 1000 iterations 0.003 usec (3.3441e-09 sec) per iteration: 3.3441e-05 sec for 10000 iterations 0.003 usec (3.3968e-09 sec) per iteration: 0.101576 sec for 29903411 iterations 0.003 usec (3.39353e-09 sec) per iteration: 0.101478 sec for 29903411 iterations 0.003 usec (3.39771e-09 sec) per iteration: 0.101603 sec for 29903411 iterations 0.003 usec (3.40303e-09 sec) per iteration: 0.101762 sec for 29903411 iterations 0.003 usec (3.41023e-09 sec) per iteration: 0.101978 sec for 29903411 iterations mean: 0.003400 usec stddev: 0.000006 usec relative stddev: 0.17% RESULT TestTlsVariable: trusted_linux_x86-64= {0.003400, 0.000006} us Measuring clock cycles: first runs (cycles): 90 29 29 27 29 29 30 29 31 31 ... slowest runs (cycles): ... 29 29 29 29 29 30 31 31 31 90 min: 24 cycles q1: 27 cycles median: 28 cycles q3: 29 cycles max: 90 cycles RESULT TestTlsVariable_CycleCount: trusted_linux_x86-64= {28, 2} count Apparent clock speed: 8235 MHz TestMmapAnonymous: Measuring real time: 0.690 usec (6.9009e-07 sec) per iteration: 6.9009e-05 sec for 100 iterations 0.515 usec (5.14529e-07 sec) per iteration: 0.0745594 sec for 144908 iterations 0.514 usec (5.13999e-07 sec) per iteration: 0.0744826 sec for 144908 iterations 0.512 usec (5.11973e-07 sec) per iteration: 0.074189 sec for 144908 iterations 0.511 usec (5.11372e-07 sec) per iteration: 0.0741019 sec for 144908 iterations 0.512 usec (5.11986e-07 sec) per iteration: 0.0741908 sec for 144908 iterations mean: 0.512772 usec stddev: 0.001250 usec relative stddev: 0.24% RESULT TestMmapAnonymous: trusted_linux_x86-64= {0.512772, 0.001250} us Measuring clock cycles: first runs (cycles): 2814 1218 1126 1119 1124 1119 1122 1118 1113 1124 ... slowest runs (cycles): ... 1138 1140 1141 1144 1155 1157 1157 1166 1218 2814 min: 1104 cycles q1: 1113 cycles median: 1120 cycles q3: 1126 cycles max: 2814 cycles RESULT TestMmapAnonymous_CycleCount: trusted_linux_x86-64= {1120, 13} count Apparent clock speed: 2184 MHz TestAtomicIncrement: Measuring real time: 0.012 usec (1.239e-08 sec) per iteration: 1.239e-06 sec for 100 iterations 0.009 usec (9.323e-09 sec) per iteration: 9.323e-06 sec for 1000 iterations 0.009 usec (9.2672e-09 sec) per iteration: 9.2672e-05 sec for 10000 iterations 0.009 usec (9.43099e-09 sec) per iteration: 0.101767 sec for 10790745 iterations 0.009 usec (9.42752e-09 sec) per iteration: 0.10173 sec for 10790745 iterations 0.009 usec (9.43381e-09 sec) per iteration: 0.101798 sec for 10790745 iterations 0.009 usec (9.43495e-09 sec) per iteration: 0.10181 sec for 10790745 iterations 0.009 usec (9.43642e-09 sec) per iteration: 0.101826 sec for 10790745 iterations mean: 0.009433 usec stddev: 0.000003 usec relative stddev: 0.03% RESULT TestAtomicIncrement: trusted_linux_x86-64= {0.009433, 0.000003} us Measuring clock cycles: first runs (cycles): 117 227 64 62 59 60 59 61 55 62 ... slowest runs (cycles): ... 62 62 62 62 62 64 64 117 227 264 min: 55 cycles q1: 55 cycles median: 55 cycles q3: 57 cycles max: 264 cycles RESULT TestAtomicIncrement_CycleCount: trusted_linux_x86-64= {55, 2} count Apparent clock speed: 5831 MHz TestUncontendedMutexLock: Measuring real time: 0.031 usec (3.082e-08 sec) per iteration: 3.082e-06 sec for 100 iterations 0.024 usec (2.3779e-08 sec) per iteration: 2.3779e-05 sec for 1000 iterations 0.025 usec (2.47141e-08 sec) per iteration: 0.103933 sec for 4205391 iterations 0.024 usec (2.43346e-08 sec) per iteration: 0.102337 sec for 4205391 iterations 0.025 usec (2.47299e-08 sec) per iteration: 0.103999 sec for 4205391 iterations 0.024 usec (2.42989e-08 sec) per iteration: 0.102186 sec for 4205391 iterations 0.024 usec (2.43424e-08 sec) per iteration: 0.102369 sec for 4205391 iterations mean: 0.024484 usec stddev: 0.000195 usec relative stddev: 0.80% RESULT TestUncontendedMutexLock: trusted_linux_x86-64= {0.024484, 0.000195} us Measuring clock cycles: first runs (cycles): 194 237 88 90 88 90 86 88 85 88 ... slowest runs (cycles): ... 88 88 88 90 90 91 91 194 237 275 min: 85 cycles q1: 88 cycles median: 88 cycles q3: 88 cycles max: 275 cycles RESULT TestUncontendedMutexLock_CycleCount: trusted_linux_x86-64= {88, 0} count Apparent clock speed: 3594 MHz TestCondvarSignalNoOp: Measuring real time: 0.023 usec (2.262e-08 sec) per iteration: 2.262e-06 sec for 100 iterations 0.020 usec (1.9649e-08 sec) per iteration: 1.9649e-05 sec for 1000 iterations 0.020 usec (2.00364e-08 sec) per iteration: 0.101972 sec for 5089317 iterations 0.020 usec (2.00031e-08 sec) per iteration: 0.101802 sec for 5089317 iterations 0.020 usec (2.00495e-08 sec) per iteration: 0.102038 sec for 5089317 iterations 0.020 usec (1.99911e-08 sec) per iteration: 0.101741 sec for 5089317 iterations 0.020 usec (1.99574e-08 sec) per iteration: 0.10157 sec for 5089317 iterations mean: 0.020008 usec stddev: 0.000033 usec relative stddev: 0.16% RESULT TestCondvarSignalNoOp: trusted_linux_x86-64= {0.020008, 0.000033} us Measuring clock cycles: first runs (cycles): 179 233 86 72 71 72 71 75 72 75 ... slowest runs (cycles): ... 75 75 75 77 79 79 86 179 233 535 min: 70 cycles q1: 73 cycles median: 73 cycles q3: 75 cycles max: 535 cycles RESULT TestCondvarSignalNoOp_CycleCount: trusted_linux_x86-64= {73, 2} count Apparent clock speed: 3649 MHz TestThreadCreateAndJoin: Measuring real time: 40.061 usec (4.00612e-05 sec) per iteration: 0.00400612 sec for 100 iterations 19.224 usec (1.92236e-05 sec) per iteration: 0.047982 sec for 2496 iterations 20.506 usec (2.0506e-05 sec) per iteration: 0.0511831 sec for 2496 iterations 19.957 usec (1.99571e-05 sec) per iteration: 0.0498128 sec for 2496 iterations 19.358 usec (1.93577e-05 sec) per iteration: 0.0483169 sec for 2496 iterations 20.120 usec (2.01203e-05 sec) per iteration: 0.0502203 sec for 2496 iterations mean: 19.832942 usec stddev: 0.479216 usec relative stddev: 2.42% RESULT TestThreadCreateAndJoin: trusted_linux_x86-64= {19.832942, 0.479216} us Measuring clock cycles: first runs (cycles): 45120 43959 44593 44912 42872 51993 42989 44842 46024 48169 ... slowest runs (cycles): ... 70247 73156 73364 74356 78559 80038 90239 113051 155552 173122 min: 32745 cycles q1: 41966 cycles median: 42980 cycles q3: 46024 cycles max: 173122 cycles RESULT TestThreadCreateAndJoin_CycleCount: trusted_linux_x86-64= {42980, 4058} count Apparent clock speed: 2167 MHz TestThreadWakeup: Measuring real time: 14.425 usec (1.44248e-05 sec) per iteration: 0.00144248 sec for 100 iterations 13.968 usec (1.3968e-05 sec) per iteration: 0.096826 sec for 6932 iterations 13.801 usec (1.38015e-05 sec) per iteration: 0.0956717 sec for 6932 iterations 13.965 usec (1.39651e-05 sec) per iteration: 0.0968061 sec for 6932 iterations 14.490 usec (1.44904e-05 sec) per iteration: 0.100447 sec for 6932 iterations 15.202 usec (1.52023e-05 sec) per iteration: 0.105382 sec for 6932 iterations mean: 14.285439 usec stddev: 0.513861 usec relative stddev: 3.60% RESULT TestThreadWakeup: trusted_linux_x86-64= {14.285439, 0.513861} us Measuring clock cycles: first runs (cycles): 34757 32850 32593 33074 64250 33376 32487 32467 32221 37866 ... slowest runs (cycles): ... 39062 39128 39263 39575 40343 40765 42514 42755 52068 64250 min: 27993 cycles q1: 32338 cycles median: 32885 cycles q3: 35415 cycles max: 64250 cycles RESULT TestThreadWakeup_CycleCount: trusted_linux_x86-64= {32885, 3077} count Apparent clock speed: 2302 MHz WARNING: should have taken less than 2.000000 secs [ OK ] nacl_newlib.run_trusted_performance_test (4900 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