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-ci-trusty-4-iiux 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: 21923824 kB MemAvailable: 29481372 kB ====================================================================== LOAD: ====================================================================== 1.05 1.55 1.00 1/408 11993 ====================================================================== UPTIME: ====================================================================== 674.77 4072.24 ====================================================================== 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 [12003,2140870528:05:22:22.817955] Native Client module will be loaded at base address 0x00006d9500000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_with_irt_nnacl_newlib_static= {0.098930, 0.003010} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (1460 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 (76670 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 [12031,2845185920:05:22:38.760746] Native Client module will be loaded at base address 0x0000603300000000 TestNull: Measuring real time: 0.007 usec (6.7e-09 sec) per iteration: 6.7e-07 sec for 100 iterations 0.002 usec (2.184e-09 sec) per iteration: 2.184e-06 sec for 1000 iterations 0.002 usec (1.8946e-09 sec) per iteration: 1.8946e-05 sec for 10000 iterations 0.002 usec (1.91054e-09 sec) per iteration: 0.100841 sec for 52781589 iterations 0.002 usec (1.89994e-09 sec) per iteration: 0.100282 sec for 52781589 iterations 0.002 usec (1.90697e-09 sec) per iteration: 0.100653 sec for 52781589 iterations 0.002 usec (1.911e-09 sec) per iteration: 0.100866 sec for 52781589 iterations 0.002 usec (1.90113e-09 sec) per iteration: 0.100345 sec for 52781589 iterations mean: 0.001906 usec stddev: 0.000005 usec relative stddev: 0.24% RESULT TestNull: x86-64_with_irt_nnacl_newlib_static= {0.001906, 0.000005} us Measuring clock cycles: first runs (cycles): 99 24 24 24 26 25 25 22 26 24 ... slowest runs (cycles): ... 27 27 27 28 28 28 29 29 29 99 min: 22 cycles q1: 24 cycles median: 25 cycles q3: 26 cycles max: 99 cycles RESULT TestNull_CycleCount: x86-64_with_irt_nnacl_newlib_static= {25, 2} count Apparent clock speed: 13117 MHz TestNaClSyscall: Measuring real time: 0.227 usec (2.2701e-07 sec) per iteration: 2.2701e-05 sec for 100 iterations 0.221 usec (2.206e-07 sec) per iteration: 0.0971762 sec for 440509 iterations 0.213 usec (2.13277e-07 sec) per iteration: 0.0939506 sec for 440509 iterations 0.210 usec (2.09681e-07 sec) per iteration: 0.0923664 sec for 440509 iterations 0.211 usec (2.11341e-07 sec) per iteration: 0.0930977 sec for 440509 iterations 0.214 usec (2.14105e-07 sec) per iteration: 0.0943152 sec for 440509 iterations mean: 0.213801 usec stddev: 0.003731 usec relative stddev: 1.75% RESULT TestNaClSyscall: x86-64_with_irt_nnacl_newlib_static= {0.213801, 0.003731} us Measuring clock cycles: first runs (cycles): 567 403 365 418 427 458 356 363 358 359 ... slowest runs (cycles): ... 363 365 367 370 394 403 418 427 458 567 min: 341 cycles q1: 348 cycles median: 352 cycles q3: 357 cycles max: 567 cycles RESULT TestNaClSyscall_CycleCount: x86-64_with_irt_nnacl_newlib_static= {352, 9} count Apparent clock speed: 1646 MHz TestSetjmpLongjmp: Measuring real time: 0.017 usec (1.747e-08 sec) per iteration: 1.747e-06 sec for 100 iterations 0.013 usec (1.2887e-08 sec) per iteration: 1.2887e-05 sec for 1000 iterations 0.013 usec (1.29419e-08 sec) per iteration: 0.100426 sec for 7759757 iterations 0.013 usec (1.28527e-08 sec) per iteration: 0.0997339 sec for 7759757 iterations 0.013 usec (1.29301e-08 sec) per iteration: 0.100334 sec for 7759757 iterations 0.013 usec (1.29312e-08 sec) per iteration: 0.100343 sec for 7759757 iterations 0.013 usec (1.29904e-08 sec) per iteration: 0.100803 sec for 7759757 iterations mean: 0.012929 usec stddev: 0.000044 usec relative stddev: 0.34% RESULT TestSetjmpLongjmp: x86-64_with_irt_nnacl_newlib_static= {0.012929, 0.000044} us Measuring clock cycles: first runs (cycles): 134 42 33 33 31 31 31 33 29 28 ... slowest runs (cycles): ... 57 59 59 61 62 62 64 66 134 5765 min: 24 cycles q1: 26 cycles median: 28 cycles q3: 29 cycles max: 5765 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {28, 3} count Apparent clock speed: 2166 MHz TestClockGetTime: Measuring real time: 0.358 usec (3.5827e-07 sec) per iteration: 3.5827e-05 sec for 100 iterations 0.282 usec (2.81532e-07 sec) per iteration: 0.0785809 sec for 279119 iterations 0.282 usec (2.82344e-07 sec) per iteration: 0.0788076 sec for 279119 iterations 0.283 usec (2.82664e-07 sec) per iteration: 0.0788969 sec for 279119 iterations 0.281 usec (2.80829e-07 sec) per iteration: 0.0783848 sec for 279119 iterations 0.280 usec (2.80011e-07 sec) per iteration: 0.0781563 sec for 279119 iterations mean: 0.281476 usec stddev: 0.000973 usec relative stddev: 0.35% RESULT TestClockGetTime: x86-64_with_irt_nnacl_newlib_static= {0.281476, 0.000973} us Measuring clock cycles: first runs (cycles): 1040 649 616 618 609 623 622 618 614 620 ... slowest runs (cycles): ... 647 649 651 654 656 660 674 691 723 1040 min: 607 cycles q1: 614 cycles median: 618 cycles q3: 622 cycles max: 1040 cycles RESULT TestClockGetTime_CycleCount: x86-64_with_irt_nnacl_newlib_static= {618, 8} count Apparent clock speed: 2196 MHz TestTlsVariable: Measuring real time: 0.010 usec (1.04e-08 sec) per iteration: 1.04e-06 sec for 100 iterations 0.006 usec (5.889e-09 sec) per iteration: 5.889e-06 sec for 1000 iterations 0.006 usec (5.5922e-09 sec) per iteration: 5.5922e-05 sec for 10000 iterations 0.006 usec (5.71264e-09 sec) per iteration: 0.102154 sec for 17882049 iterations 0.006 usec (5.84098e-09 sec) per iteration: 0.104449 sec for 17882049 iterations 0.006 usec (5.72114e-09 sec) per iteration: 0.102306 sec for 17882049 iterations 0.006 usec (5.709e-09 sec) per iteration: 0.102089 sec for 17882049 iterations 0.006 usec (5.70746e-09 sec) per iteration: 0.102061 sec for 17882049 iterations mean: 0.005738 usec stddev: 0.000052 usec relative stddev: 0.90% RESULT TestTlsVariable: x86-64_with_irt_nnacl_newlib_static= {0.005738, 0.000052} us Measuring clock cycles: first runs (cycles): 165 77 53 55 35 31 35 31 35 29 ... slowest runs (cycles): ... 33 33 35 35 35 35 53 55 77 165 min: 24 cycles q1: 28 cycles median: 31 cycles q3: 33 cycles max: 165 cycles RESULT TestTlsVariable_CycleCount: x86-64_with_irt_nnacl_newlib_static= {31, 5} count Apparent clock speed: 5402 MHz TestMmapAnonymous: Measuring real time: 2.927 usec (2.92728e-06 sec) per iteration: 0.000292728 sec for 100 iterations 2.681 usec (2.68111e-06 sec) per iteration: 0.0915895 sec for 34161 iterations 2.690 usec (2.68985e-06 sec) per iteration: 0.0918879 sec for 34161 iterations 2.780 usec (2.77995e-06 sec) per iteration: 0.094966 sec for 34161 iterations 2.703 usec (2.7027e-06 sec) per iteration: 0.092327 sec for 34161 iterations 2.695 usec (2.69511e-06 sec) per iteration: 0.0920677 sec for 34161 iterations mean: 2.709746 usec stddev: 0.035801 usec relative stddev: 1.32% RESULT TestMmapAnonymous: x86-64_with_irt_nnacl_newlib_static= {2.709746, 0.035801} us Measuring clock cycles: first runs (cycles): 7598 6040 5851 6023 5926 5768 5651 5907 5735 5742 ... slowest runs (cycles): ... 5887 5893 5907 5922 5926 5938 5968 6023 6040 7598 min: 5632 cycles q1: 5706 cycles median: 5755 cycles q3: 5807 cycles max: 7598 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_with_irt_nnacl_newlib_static= {5755, 101} count Apparent clock speed: 2124 MHz TestAtomicIncrement: Measuring real time: 0.016 usec (1.622e-08 sec) per iteration: 1.622e-06 sec for 100 iterations 0.010 usec (9.628e-09 sec) per iteration: 9.628e-06 sec for 1000 iterations 0.010 usec (1.03108e-08 sec) per iteration: 0.000103108 sec for 10000 iterations 0.010 usec (9.50183e-09 sec) per iteration: 0.0921541 sec for 9698568 iterations 0.010 usec (9.50082e-09 sec) per iteration: 0.0921444 sec for 9698568 iterations 0.009 usec (9.48452e-09 sec) per iteration: 0.0919862 sec for 9698568 iterations 0.009 usec (9.46749e-09 sec) per iteration: 0.0918211 sec for 9698568 iterations 0.009 usec (9.471e-09 sec) per iteration: 0.0918551 sec for 9698568 iterations mean: 0.009485 usec stddev: 0.000014 usec relative stddev: 0.15% RESULT TestAtomicIncrement: x86-64_with_irt_nnacl_newlib_static= {0.009485, 0.000014} us Measuring clock cycles: first runs (cycles): 113 53 51 53 51 194 53 50 52 53 ... slowest runs (cycles): ... 55 55 55 55 55 55 55 61 113 194 min: 50 cycles q1: 52 cycles median: 53 cycles q3: 53 cycles max: 194 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_with_irt_nnacl_newlib_static= {53, 1} count Apparent clock speed: 5588 MHz TestUncontendedMutexLock: Measuring real time: 0.032 usec (3.228e-08 sec) per iteration: 3.228e-06 sec for 100 iterations 0.025 usec (2.5093e-08 sec) per iteration: 2.5093e-05 sec for 1000 iterations 0.025 usec (2.47515e-08 sec) per iteration: 0.0986392 sec for 3985175 iterations 0.025 usec (2.4587e-08 sec) per iteration: 0.0979835 sec for 3985175 iterations 0.025 usec (2.46991e-08 sec) per iteration: 0.0984302 sec for 3985175 iterations 0.025 usec (2.46145e-08 sec) per iteration: 0.0980931 sec for 3985175 iterations 0.025 usec (2.46336e-08 sec) per iteration: 0.0981693 sec for 3985175 iterations mean: 0.024657 usec stddev: 0.000060 usec relative stddev: 0.24% RESULT TestUncontendedMutexLock: x86-64_with_irt_nnacl_newlib_static= {0.024657, 0.000060} us Measuring clock cycles: first runs (cycles): 198 84 82 84 86 82 86 84 84 84 ... slowest runs (cycles): ... 86 86 86 86 86 86 86 86 86 198 min: 81 cycles q1: 83 cycles median: 84 cycles q3: 84 cycles max: 198 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_with_irt_nnacl_newlib_static= {84, 1} count Apparent clock speed: 3407 MHz TestCondvarSignalNoOp: Measuring real time: 0.594 usec (5.9385e-07 sec) per iteration: 5.9385e-05 sec for 100 iterations 0.324 usec (3.2419e-07 sec) per iteration: 0.054591 sec for 168392 iterations 0.323 usec (3.23324e-07 sec) per iteration: 0.0544451 sec for 168392 iterations 0.323 usec (3.22851e-07 sec) per iteration: 0.0543656 sec for 168392 iterations 0.323 usec (3.22854e-07 sec) per iteration: 0.054366 sec for 168392 iterations 0.322 usec (3.21947e-07 sec) per iteration: 0.0542133 sec for 168392 iterations mean: 0.323033 usec stddev: 0.000731 usec relative stddev: 0.23% RESULT TestCondvarSignalNoOp: x86-64_with_irt_nnacl_newlib_static= {0.323033, 0.000731} us Measuring clock cycles: first runs (cycles): 922 838 805 747 719 717 723 717 701 710 ... slowest runs (cycles): ... 746 747 748 759 779 784 792 805 838 922 min: 695 cycles q1: 704 cycles median: 709 cycles q3: 717 cycles max: 922 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {709, 13} count Apparent clock speed: 2195 MHz TestThreadCreateAndJoin: Measuring real time: 41.957 usec (4.19571e-05 sec) per iteration: 0.00419571 sec for 100 iterations 30.857 usec (3.0857e-05 sec) per iteration: 0.0735323 sec for 2383 iterations 27.055 usec (2.70551e-05 sec) per iteration: 0.0644724 sec for 2383 iterations 28.817 usec (2.88167e-05 sec) per iteration: 0.0686703 sec for 2383 iterations 27.628 usec (2.76279e-05 sec) per iteration: 0.0658372 sec for 2383 iterations 27.399 usec (2.73988e-05 sec) per iteration: 0.0652915 sec for 2383 iterations mean: 28.351126 usec stddev: 1.386062 usec relative stddev: 4.89% RESULT TestThreadCreateAndJoin: x86-64_with_irt_nnacl_newlib_static= {28.351126, 1.386062} us Measuring clock cycles: first runs (cycles): 55402 106863 59012 79684 54349 75348 96982 94179 55995 249000 ... slowest runs (cycles): ... 77082 78468 79684 82913 93817 94179 96982 106863 107358 249000 min: 49369 cycles q1: 51533 cycles median: 53708 cycles q3: 55402 cycles max: 249000 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_with_irt_nnacl_newlib_static= {53708, 3869} count Apparent clock speed: 1894 MHz TestThreadWakeup: Measuring real time: 14.737 usec (1.4737e-05 sec) per iteration: 0.0014737 sec for 100 iterations 13.733 usec (1.37332e-05 sec) per iteration: 0.0931794 sec for 6785 iterations 14.183 usec (1.41834e-05 sec) per iteration: 0.0962341 sec for 6785 iterations 14.608 usec (1.46081e-05 sec) per iteration: 0.0991157 sec for 6785 iterations 14.110 usec (1.41104e-05 sec) per iteration: 0.0957389 sec for 6785 iterations 14.031 usec (1.40307e-05 sec) per iteration: 0.095198 sec for 6785 iterations mean: 14.133123 usec stddev: 0.282520 usec relative stddev: 2.00% RESULT TestThreadWakeup: x86-64_with_irt_nnacl_newlib_static= {14.133123, 0.282520} us Measuring clock cycles: first runs (cycles): 34018 30010 29464 30533 30263 30952 30288 32711 30065 29757 ... slowest runs (cycles): ... 32254 32399 32503 32711 33026 33160 34018 38536 47131 47415 min: 24295 cycles q1: 27144 cycles median: 27726 cycles q3: 30230 cycles max: 47415 cycles RESULT TestThreadWakeup_CycleCount: x86-64_with_irt_nnacl_newlib_static= {27726, 3086} count Apparent clock speed: 1962 MHz TestCatchingFault: Measuring real time: 3.472 usec (3.47191e-06 sec) per iteration: 0.000347191 sec for 100 iterations 2.011 usec (2.01098e-06 sec) per iteration: 0.0579202 sec for 28802 iterations 2.019 usec (2.019e-06 sec) per iteration: 0.0581512 sec for 28802 iterations 2.003 usec (2.00341e-06 sec) per iteration: 0.0577021 sec for 28802 iterations 2.009 usec (2.00877e-06 sec) per iteration: 0.0578566 sec for 28802 iterations 2.004 usec (2.00412e-06 sec) per iteration: 0.0577226 sec for 28802 iterations mean: 2.009254 usec stddev: 0.005634 usec relative stddev: 0.28% RESULT TestCatchingFault: x86-64_with_irt_nnacl_newlib_static= {2.009254, 0.005634} us Measuring clock cycles: first runs (cycles): 8185 5117 5216 4892 5092 4796 4864 5088 5134 5097 ... slowest runs (cycles): ... 5554 5590 5825 5876 5895 5919 5974 6052 6293 8185 min: 4270 cycles q1: 4356 cycles median: 5088 cycles q3: 5324 cycles max: 8185 cycles RESULT TestCatchingFault_CycleCount: x86-64_with_irt_nnacl_newlib_static= {5088, 968} count Apparent clock speed: 2532 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 35.387 usec (3.53869e-05 sec) per iteration: 0.00353869 sec for 100 iterations 30.143 usec (3.0143e-05 sec) per iteration: 0.085154 sec for 2825 iterations 30.698 usec (3.06982e-05 sec) per iteration: 0.0867223 sec for 2825 iterations 29.114 usec (2.91145e-05 sec) per iteration: 0.0822485 sec for 2825 iterations 26.929 usec (2.69295e-05 sec) per iteration: 0.0760757 sec for 2825 iterations 29.611 usec (2.96106e-05 sec) per iteration: 0.08365 sec for 2825 iterations mean: 29.299156 usec stddev: 1.297372 usec relative stddev: 4.43% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_with_irt_nnacl_newlib_static= {29.299156, 1.297372} us Measuring clock cycles: first runs (cycles): 54784 56791 71645 74706 58407 70689 69508 72570 49045 92238 ... slowest runs (cycles): ... 111436 113097 114943 124292 130453 155934 162685 187701 261688 295319 min: 40028 cycles q1: 52461 cycles median: 61560 cycles q3: 85150 cycles max: 295319 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_with_irt_nnacl_newlib_static= {61560, 32689} count Apparent clock speed: 2101 MHz [ OK ] nacl_newlib.run_performance_test_irt (5709 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