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,nacl_irt_test platform=x86-64 --clang breakpad_tools_dir=breakpad-out large_tests_irt scons: Reading SConscript files ... ====================================================================== SCONS ARGS:['scons.py', '--verbose', '-k', '-j1', '--mode=nacl,dbg-host,nacl_irt_test', 'platform=x86-64', '--clang', 'breakpad_tools_dir=breakpad-out', 'large_tests_irt'] ====================================================================== ====================================================================== 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-try-trusty-3-oss1 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: 4552160 kB MemAvailable: 29351656 kB ====================================================================== LOAD: ====================================================================== 1.04 1.52 1.10 1/416 7853 ====================================================================== UPTIME: ====================================================================== 35611.11 268562.45 ====================================================================== 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_TESTS BUILD_TYPE: nacl_irt_test TARGET_ROOT: /b/s/w/ir/k/native_client/scons-out/nacl_irt_test-x86-64 OBJ_ROOT: /b/s/w/ir/k/native_client/scons-out/nacl_irt_test-x86-64/obj BUILD_TYPE_DESCRIPTION: NaCl tests build with IRT 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 -Iscons-out/nacl-x86-64/include -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 *** BROKEN run_gdb_invalid_memory_test_irt *** BROKEN run_nanosleep_test_irt *** BROKEN run_timefuncs_test_irt *** BROKEN run_raw_timefuncs_test_irt *** BROKEN run_main_thread_pthread_exit_test2_irt *** BROKEN run_overflowinline_test_irt *** BROKEN run_stack_frame_noopt_frame_test_irt *** BROKEN run_stack_frame_noopt_noframe_test_irt *** BROKEN run_stack_frame_opt_frame_test_irt *** BROKEN run_stack_frame_opt_noframe_test_irt *** BROKEN run_vla_test_irt There are 24 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 tools/command_tester.py --name nacl_newlib.run_benchmark_test_irt --time_warning 10 --time_error 100 --perf_env_description x86-64_with_irt_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_irt_test-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 -B scons-out/nacl_irt-x86-64/staging/irt_core.nexe -- scons-out/nacl_irt_test-x86-64/obj/tests/benchmark/benchmark_test.nexe x86-64_with_irt_nnacl_newlib_static [ RUN ] nacl_newlib.run_benchmark_test_irt 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 -B scons-out/nacl_irt-x86-64/staging/irt_core.nexe -- scons-out/nacl_irt_test-x86-64/obj/tests/benchmark/benchmark_test.nexe x86-64_with_irt_nnacl_newlib_static [7863,1314428800:23:26:52.554525] Native Client module will be loaded at base address 0x00000d9f00000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_with_irt_nnacl_newlib_static= {0.104180, 0.005950} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (1670 ms) /b/s/w/ir/cache/vpython/b574a6/bin/python tools/command_tester.py --name nacl_newlib.run_clock_cputime_test_irt --time_warning 60 --time_error 600 --perf_env_description x86-64_with_irt_nnacl_newlib_static --using_nacl_signal_handler True --subarch 64 --arch x86 --output_stamp /b/s/w/ir/k/native_client/scons-out/nacl_irt_test-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 -B scons-out/nacl_irt-x86-64/staging/irt_core.nexe -- scons-out/nacl_irt_test-x86-64/obj/tests/clock/clock_test.nexe -c [ RUN ] nacl_newlib.run_clock_cputime_test_irt 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 -B scons-out/nacl_irt-x86-64/staging/irt_core.nexe -- scons-out/nacl_irt_test-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_irt (75170 ms) doSizeCheck(["scons-out/nacl_irt_test-x86-64/obj/tests/hello_world/hello_world_size.out"], ["scons-out/nacl_irt_test-x86-64/obj/tests/hello_world/hello_world.nexe"]) RESULT hello_world_size.out: x86-64_with_irt_nnacl_newlib_static= 526.650 KB RESULT hello_world_size.out: ZIPPED_x86-64_with_irt_nnacl_newlib_static= 187.021 KB /b/s/w/ir/cache/vpython/b574a6/bin/python tools/command_tester.py --name nacl_newlib.run_performance_test_irt --time_warning 10 --time_error 100 --perf_env_description x86-64_with_irt_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_irt_test-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 -B scons-out/nacl_irt-x86-64/staging/irt_core.nexe -- scons-out/nacl_irt_test-x86-64/obj/tests/performance/performance_test.nexe x86-64_with_irt_nnacl_newlib_static [ RUN ] nacl_newlib.run_performance_test_irt 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 -B scons-out/nacl_irt-x86-64/staging/irt_core.nexe -- scons-out/nacl_irt_test-x86-64/obj/tests/performance/performance_test.nexe x86-64_with_irt_nnacl_newlib_static [7891,2337200000:23:27:08.565790] Native Client module will be loaded at base address 0x00000ace00000000 TestNull: Measuring real time: 0.008 usec (8.2e-09 sec) per iteration: 8.2e-07 sec for 100 iterations 0.002 usec (2.202e-09 sec) per iteration: 2.202e-06 sec for 1000 iterations 0.002 usec (1.8898e-09 sec) per iteration: 1.8898e-05 sec for 10000 iterations 0.002 usec (1.90696e-09 sec) per iteration: 0.100908 sec for 52915652 iterations 0.002 usec (1.88986e-09 sec) per iteration: 0.100003 sec for 52915652 iterations 0.002 usec (1.88916e-09 sec) per iteration: 0.0999663 sec for 52915652 iterations 0.002 usec (1.89287e-09 sec) per iteration: 0.100162 sec for 52915652 iterations 0.002 usec (1.89111e-09 sec) per iteration: 0.100069 sec for 52915652 iterations mean: 0.001894 usec stddev: 0.000007 usec relative stddev: 0.35% RESULT TestNull: x86-64_with_irt_nnacl_newlib_static= {0.001894, 0.000007} us Measuring clock cycles: first runs (cycles): 112 26 24 24 26 24 24 22 27 24 ... slowest runs (cycles): ... 27 27 28 28 29 29 29 29 29 112 min: 20 cycles q1: 24 cycles median: 25 cycles q3: 26 cycles max: 112 cycles RESULT TestNull_CycleCount: x86-64_with_irt_nnacl_newlib_static= {25, 2} count Apparent clock speed: 13200 MHz TestNaClSyscall: Measuring real time: 0.226 usec (2.2627e-07 sec) per iteration: 2.2627e-05 sec for 100 iterations 0.210 usec (2.10393e-07 sec) per iteration: 0.092983 sec for 441949 iterations 0.212 usec (2.11838e-07 sec) per iteration: 0.0936216 sec for 441949 iterations 0.217 usec (2.16561e-07 sec) per iteration: 0.095709 sec for 441949 iterations 0.214 usec (2.13751e-07 sec) per iteration: 0.0944673 sec for 441949 iterations 0.210 usec (2.10254e-07 sec) per iteration: 0.0929213 sec for 441949 iterations mean: 0.212559 usec stddev: 0.002365 usec relative stddev: 1.11% RESULT TestNaClSyscall: x86-64_with_irt_nnacl_newlib_static= {0.212559, 0.002365} us Measuring clock cycles: first runs (cycles): 475 405 395 396 394 391 427 392 394 396 ... slowest runs (cycles): ... 400 405 414 418 418 426 427 438 455 475 min: 383 cycles q1: 389 cycles median: 394 cycles q3: 396 cycles max: 475 cycles RESULT TestNaClSyscall_CycleCount: x86-64_with_irt_nnacl_newlib_static= {394, 7} count Apparent clock speed: 1854 MHz TestSetjmpLongjmp: Measuring real time: 0.028 usec (2.836e-08 sec) per iteration: 2.836e-06 sec for 100 iterations 0.021 usec (2.0791e-08 sec) per iteration: 2.0791e-05 sec for 1000 iterations 0.013 usec (1.29424e-08 sec) per iteration: 0.0622499 sec for 4809773 iterations 0.013 usec (1.28214e-08 sec) per iteration: 0.061668 sec for 4809773 iterations 0.013 usec (1.27916e-08 sec) per iteration: 0.0615248 sec for 4809773 iterations 0.013 usec (1.27915e-08 sec) per iteration: 0.0615244 sec for 4809773 iterations 0.013 usec (1.2846e-08 sec) per iteration: 0.0617865 sec for 4809773 iterations mean: 0.012839 usec stddev: 0.000056 usec relative stddev: 0.43% RESULT TestSetjmpLongjmp: x86-64_with_irt_nnacl_newlib_static= {0.012839, 0.000056} us Measuring clock cycles: first runs (cycles): 183 75 39 35 35 31 31 31 28 27 ... slowest runs (cycles): ... 31 31 31 31 31 35 35 39 75 183 min: 24 cycles q1: 26 cycles median: 28 cycles q3: 29 cycles max: 183 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {28, 3} count Apparent clock speed: 2181 MHz TestClockGetTime: Measuring real time: 0.283 usec (2.8256e-07 sec) per iteration: 2.8256e-05 sec for 100 iterations 0.280 usec (2.79788e-07 sec) per iteration: 0.0990191 sec for 353907 iterations 0.279 usec (2.78976e-07 sec) per iteration: 0.0987316 sec for 353907 iterations 0.280 usec (2.80175e-07 sec) per iteration: 0.099156 sec for 353907 iterations 0.281 usec (2.80589e-07 sec) per iteration: 0.0993023 sec for 353907 iterations 0.280 usec (2.79571e-07 sec) per iteration: 0.098942 sec for 353907 iterations mean: 0.279820 usec stddev: 0.000546 usec relative stddev: 0.20% RESULT TestClockGetTime: x86-64_with_irt_nnacl_newlib_static= {0.279820, 0.000546} us Measuring clock cycles: first runs (cycles): 1003 654 649 624 660 612 673 653 618 713 ... slowest runs (cycles): ... 660 663 665 669 673 682 688 701 713 1003 min: 607 cycles q1: 614 cycles median: 616 cycles q3: 625 cycles max: 1003 cycles RESULT TestClockGetTime_CycleCount: x86-64_with_irt_nnacl_newlib_static= {616, 11} count Apparent clock speed: 2201 MHz TestTlsVariable: Measuring real time: 0.011 usec (1.135e-08 sec) per iteration: 1.135e-06 sec for 100 iterations 0.006 usec (5.952e-09 sec) per iteration: 5.952e-06 sec for 1000 iterations 0.006 usec (5.6006e-09 sec) per iteration: 5.6006e-05 sec for 10000 iterations 0.006 usec (5.66539e-09 sec) per iteration: 0.101157 sec for 17855229 iterations 0.006 usec (5.67777e-09 sec) per iteration: 0.101378 sec for 17855229 iterations 0.006 usec (5.69266e-09 sec) per iteration: 0.101644 sec for 17855229 iterations 0.006 usec (5.71298e-09 sec) per iteration: 0.102007 sec for 17855229 iterations 0.006 usec (5.6886e-09 sec) per iteration: 0.101571 sec for 17855229 iterations mean: 0.005687 usec stddev: 0.000016 usec relative stddev: 0.28% RESULT TestTlsVariable: x86-64_with_irt_nnacl_newlib_static= {0.005687, 0.000016} us Measuring clock cycles: first runs (cycles): 158 77 57 35 36 33 33 33 28 26 ... slowest runs (cycles): ... 33 33 33 33 35 36 57 77 158 176 min: 24 cycles q1: 28 cycles median: 29 cycles q3: 31 cycles max: 176 cycles RESULT TestTlsVariable_CycleCount: x86-64_with_irt_nnacl_newlib_static= {29, 3} count Apparent clock speed: 5099 MHz TestMmapAnonymous: Measuring real time: 3.295 usec (3.29471e-06 sec) per iteration: 0.000329471 sec for 100 iterations 2.771 usec (2.77132e-06 sec) per iteration: 0.0841124 sec for 30351 iterations 2.765 usec (2.76546e-06 sec) per iteration: 0.0839346 sec for 30351 iterations 2.771 usec (2.77113e-06 sec) per iteration: 0.0841066 sec for 30351 iterations 2.772 usec (2.77174e-06 sec) per iteration: 0.0841251 sec for 30351 iterations 2.854 usec (2.85369e-06 sec) per iteration: 0.0866124 sec for 30351 iterations mean: 2.786670 usec stddev: 0.033589 usec relative stddev: 1.21% RESULT TestMmapAnonymous: x86-64_with_irt_nnacl_newlib_static= {2.786670, 0.033589} us Measuring clock cycles: first runs (cycles): 8414 6281 6097 6151 6190 6072 6247 6034 6323 6067 ... slowest runs (cycles): ... 6183 6190 6195 6225 6247 6281 6323 6709 8414 39144 min: 5905 cycles q1: 5995 cycles median: 6029 cycles q3: 6093 cycles max: 39144 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_with_irt_nnacl_newlib_static= {6029, 98} count Apparent clock speed: 2164 MHz TestAtomicIncrement: Measuring real time: 0.017 usec (1.683e-08 sec) per iteration: 1.683e-06 sec for 100 iterations 0.010 usec (9.63e-09 sec) per iteration: 9.63e-06 sec for 1000 iterations 0.009 usec (9.3084e-09 sec) per iteration: 9.3084e-05 sec for 10000 iterations 0.009 usec (9.46079e-09 sec) per iteration: 0.101637 sec for 10742984 iterations 0.010 usec (9.73403e-09 sec) per iteration: 0.104572 sec for 10742984 iterations 0.009 usec (9.46962e-09 sec) per iteration: 0.101732 sec for 10742984 iterations 0.009 usec (9.42632e-09 sec) per iteration: 0.101267 sec for 10742984 iterations 0.009 usec (9.43687e-09 sec) per iteration: 0.10138 sec for 10742984 iterations mean: 0.009506 usec stddev: 0.000115 usec relative stddev: 1.21% RESULT TestAtomicIncrement: x86-64_with_irt_nnacl_newlib_static= {0.009506, 0.000115} us Measuring clock cycles: first runs (cycles): 124 55 51 50 51 50 51 50 51 51 ... slowest runs (cycles): ... 53 53 53 53 53 53 53 53 55 124 min: 48 cycles q1: 51 cycles median: 51 cycles q3: 53 cycles max: 124 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_with_irt_nnacl_newlib_static= {51, 2} count Apparent clock speed: 5365 MHz TestUncontendedMutexLock: Measuring real time: 0.029 usec (2.881e-08 sec) per iteration: 2.881e-06 sec for 100 iterations 0.024 usec (2.4464e-08 sec) per iteration: 2.4464e-05 sec for 1000 iterations 0.025 usec (2.45131e-08 sec) per iteration: 0.100201 sec for 4087638 iterations 0.025 usec (2.46585e-08 sec) per iteration: 0.100795 sec for 4087638 iterations 0.025 usec (2.46402e-08 sec) per iteration: 0.10072 sec for 4087638 iterations 0.025 usec (2.45316e-08 sec) per iteration: 0.100276 sec for 4087638 iterations 0.025 usec (2.45377e-08 sec) per iteration: 0.100301 sec for 4087638 iterations mean: 0.024576 usec stddev: 0.000061 usec relative stddev: 0.25% RESULT TestUncontendedMutexLock: x86-64_with_irt_nnacl_newlib_static= {0.024576, 0.000061} us Measuring clock cycles: first runs (cycles): 257 112 81 84 84 81 84 84 81 84 ... slowest runs (cycles): ... 84 84 84 84 84 84 84 90 112 257 min: 81 cycles q1: 81 cycles median: 82 cycles q3: 82 cycles max: 257 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_with_irt_nnacl_newlib_static= {82, 1} count Apparent clock speed: 3337 MHz TestCondvarSignalNoOp: Measuring real time: 0.358 usec (3.5808e-07 sec) per iteration: 3.5808e-05 sec for 100 iterations 0.304 usec (3.04191e-07 sec) per iteration: 0.0849505 sec for 279267 iterations 0.305 usec (3.04829e-07 sec) per iteration: 0.0851288 sec for 279267 iterations 0.305 usec (3.05112e-07 sec) per iteration: 0.0852076 sec for 279267 iterations 0.306 usec (3.05532e-07 sec) per iteration: 0.085325 sec for 279267 iterations 0.304 usec (3.03784e-07 sec) per iteration: 0.0848368 sec for 279267 iterations mean: 0.304690 usec stddev: 0.000629 usec relative stddev: 0.21% RESULT TestCondvarSignalNoOp: x86-64_with_irt_nnacl_newlib_static= {0.304690, 0.000629} us Measuring clock cycles: first runs (cycles): 874 732 801 711 675 669 763 678 673 717 ... slowest runs (cycles): ... 704 708 711 717 732 743 763 801 874 979 min: 657 cycles q1: 666 cycles median: 669 cycles q3: 673 cycles max: 979 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {669, 7} count Apparent clock speed: 2196 MHz TestThreadCreateAndJoin: Measuring real time: 42.744 usec (4.2744e-05 sec) per iteration: 0.0042744 sec for 100 iterations 32.921 usec (3.29213e-05 sec) per iteration: 0.0770029 sec for 2339 iterations 27.981 usec (2.7981e-05 sec) per iteration: 0.0654476 sec for 2339 iterations 36.251 usec (3.62508e-05 sec) per iteration: 0.0847906 sec for 2339 iterations 29.721 usec (2.97208e-05 sec) per iteration: 0.0695169 sec for 2339 iterations 30.829 usec (3.08293e-05 sec) per iteration: 0.0721098 sec for 2339 iterations mean: 31.540638 usec stddev: 2.848869 usec relative stddev: 9.03% RESULT TestThreadCreateAndJoin: x86-64_with_irt_nnacl_newlib_static= {31.540638, 2.848869} us Measuring clock cycles: first runs (cycles): 53563 273167 64226 49545 50802 84099 61230 50054 61899 48226 ... slowest runs (cycles): ... 61230 61899 64226 64710 64923 66261 69596 71201 84099 273167 min: 45046 cycles q1: 48929 cycles median: 50537 cycles q3: 53035 cycles max: 273167 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_with_irt_nnacl_newlib_static= {50537, 4106} count Apparent clock speed: 1602 MHz TestThreadWakeup: Measuring real time: 15.976 usec (1.59763e-05 sec) per iteration: 0.00159763 sec for 100 iterations 14.662 usec (1.46616e-05 sec) per iteration: 0.0917672 sec for 6259 iterations 16.031 usec (1.60306e-05 sec) per iteration: 0.100336 sec for 6259 iterations 14.493 usec (1.44929e-05 sec) per iteration: 0.0907108 sec for 6259 iterations 15.890 usec (1.58895e-05 sec) per iteration: 0.0994527 sec for 6259 iterations 19.338 usec (1.93383e-05 sec) per iteration: 0.121038 sec for 6259 iterations mean: 16.082599 usec stddev: 1.742755 usec relative stddev: 10.84% RESULT TestThreadWakeup: x86-64_with_irt_nnacl_newlib_static= {16.082599, 1.742755} us Measuring clock cycles: first runs (cycles): 36235 33523 32586 34299 32744 33127 32690 32621 33475 32217 ... slowest runs (cycles): ... 48960 52002 53301 56465 57399 88879 114394 181743 194552 201409 min: 24677 cycles q1: 30194 cycles median: 31460 cycles q3: 33802 cycles max: 201409 cycles RESULT TestThreadWakeup_CycleCount: x86-64_with_irt_nnacl_newlib_static= {31460, 3608} count Apparent clock speed: 1956 MHz TestCatchingFault: Measuring real time: 3.174 usec (3.17375e-06 sec) per iteration: 0.000317375 sec for 100 iterations 1.982 usec (1.98212e-06 sec) per iteration: 0.0624527 sec for 31508 iterations 1.981 usec (1.9813e-06 sec) per iteration: 0.0624269 sec for 31508 iterations 1.973 usec (1.97325e-06 sec) per iteration: 0.0621732 sec for 31508 iterations 1.969 usec (1.96935e-06 sec) per iteration: 0.0620504 sec for 31508 iterations 1.989 usec (1.98894e-06 sec) per iteration: 0.0626675 sec for 31508 iterations mean: 1.978994 usec stddev: 0.006927 usec relative stddev: 0.35% RESULT TestCatchingFault: x86-64_with_irt_nnacl_newlib_static= {1.978994, 0.006927} us Measuring clock cycles: first runs (cycles): 8207 5277 4780 5073 4994 4670 4798 4780 5326 4609 ... slowest runs (cycles): ... 5887 5913 5935 5963 6089 6104 6406 6417 8207 36159 min: 4609 cycles q1: 4850 cycles median: 5071 cycles q3: 5293 cycles max: 36159 cycles RESULT TestCatchingFault_CycleCount: x86-64_with_irt_nnacl_newlib_static= {5071, 443} count Apparent clock speed: 2562 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 53.169 usec (5.31694e-05 sec) per iteration: 0.00531694 sec for 100 iterations 57.961 usec (5.79609e-05 sec) per iteration: 0.108966 sec for 1880 iterations 51.189 usec (5.11893e-05 sec) per iteration: 0.096236 sec for 1880 iterations 57.864 usec (5.78636e-05 sec) per iteration: 0.108784 sec for 1880 iterations 47.345 usec (4.73449e-05 sec) per iteration: 0.0890084 sec for 1880 iterations 39.315 usec (3.93147e-05 sec) per iteration: 0.0739117 sec for 1880 iterations mean: 50.734688 usec stddev: 7.002173 usec relative stddev: 13.80% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_with_irt_nnacl_newlib_static= {50.734688, 7.002173} us Measuring clock cycles: first runs (cycles): 70630 65192 66699 93846 65258 94871 87778 142557 230350 312815 ... slowest runs (cycles): ... 216420 230350 236689 258019 270783 272586 303617 312187 312815 548556 min: 54646 cycles q1: 58181 cycles median: 66487 cycles q3: 94871 cycles max: 548556 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_with_irt_nnacl_newlib_static= {66487, 36690} count Apparent clock speed: 1310 MHz [ OK ] nacl_newlib.run_performance_test_irt (5910 ms) scons: done building targets. ********************************************************************** COMMAND EXECUTION REPORT ********************************************************************** 3 command_tester.py 1 doSizeCheck ********************************************************************** ENVIRONMENT USAGE REPORT ********************************************************************** 4 nacl_irt_test-x86-64 Command return code: 0