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 gce-trusty-e833d7b0-us-west1-c-dppx 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: 20675124 kB MemAvailable: 29496852 kB ====================================================================== LOAD: ====================================================================== 1.22 1.70 1.04 1/436 7268 ====================================================================== UPTIME: ====================================================================== 1831.84 13274.49 ====================================================================== The following environments have been configured ====================================================================== NACL_BUILD_FAMILY: UNTRUSTED BUILD_TYPE: nacl TARGET_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-64 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-64/obj BUILD_TYPE_DESCRIPTION: NaCl module build BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 64 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 64 CC: /b/s/w/ir/kitchen-workdir/native_client/toolchain/linux_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /b/s/w/ir/kitchen-workdir/native_client/toolchain/linux_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang -m64 -D__STDC_LIMIT_MACROS=1 -D__STDC_FORMAT_MACROS=1 -D_GNU_SOURCE=1 -D_POSIX_C_SOURCE=199506 -D_XOPEN_SOURCE=600 -DDYNAMIC_ANNOTATIONS_ENABLED=1 -DDYNAMIC_ANNOTATIONS_PREFIX=NACL_ -DNACL_BUILD_ARCH=x86 -DNACL_BUILD_SUBARCH=64 -I/b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-64/include -I/b/s/w/ir/kitchen-workdir -c -o UNKNOWN COMPILER NACL_BUILD_FAMILY: TRUSTED BUILD_TYPE: dbg-linux TARGET_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/dbg-linux-x86-64 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/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/kitchen-workdir/third_party/llvm-build/Release+Asserts/bin/clang ASPPCOM: /b/s/w/ir/kitchen-workdir/third_party/llvm-build/Release+Asserts/bin/clang -fPIE -m64 -Wall -pedantic -Wextra -Wno-long-long -Wswitch-enum -Wsign-compare -Wundef -fdiagnostics-show-option -fvisibility=hidden -fstack-protector -Werror -Wno-variadic-macros -Wno-covered-switch-default -Wno-c++11-extensions -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/kitchen-workdir -c -o UNKNOWN COMPILER NACL_BUILD_FAMILY: UNTRUSTED_IRT_TESTS BUILD_TYPE: nacl_irt_test TARGET_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt_test-x86-64 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/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/kitchen-workdir/native_client/toolchain/linux_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /b/s/w/ir/kitchen-workdir/native_client/toolchain/linux_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang -m64 -D__STDC_LIMIT_MACROS=1 -D__STDC_FORMAT_MACROS=1 -D_GNU_SOURCE=1 -D_POSIX_C_SOURCE=199506 -D_XOPEN_SOURCE=600 -DDYNAMIC_ANNOTATIONS_ENABLED=1 -DDYNAMIC_ANNOTATIONS_PREFIX=NACL_ -DNACL_BUILD_ARCH=x86 -DNACL_BUILD_SUBARCH=64 -Iscons-out/nacl-x86-64/include -I/b/s/w/ir/kitchen-workdir -c -o UNKNOWN COMPILER NACL_BUILD_FAMILY: UNTRUSTED_IRT BUILD_TYPE: nacl_irt TARGET_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt-x86-64 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt-x86-64/obj BUILD_TYPE_DESCRIPTION: NaCl IRT build BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 64 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 64 CC: /b/s/w/ir/kitchen-workdir/native_client/toolchain/linux_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /b/s/w/ir/kitchen-workdir/native_client/toolchain/linux_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang -m64 -D__STDC_LIMIT_MACROS=1 -D__STDC_FORMAT_MACROS=1 -D_GNU_SOURCE=1 -D_POSIX_C_SOURCE=199506 -D_XOPEN_SOURCE=600 -DDYNAMIC_ANNOTATIONS_ENABLED=1 -DDYNAMIC_ANNOTATIONS_PREFIX=NACL_ -DNACL_BUILD_ARCH=x86 -DNACL_BUILD_SUBARCH=64 -I/b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt-x86-64/include -I/b/s/w/ir/kitchen-workdir -I/b/s/w/ir/kitchen-workdir/native_client/src/untrusted/pthread -c -o UNKNOWN COMPILER *** BROKEN run_ncval_annotate_test *** BROKEN run_gdb_invalid_memory_test *** BROKEN run_nanosleep_test *** BROKEN run_timefuncs_test *** BROKEN run_raw_timefuncs_test *** BROKEN run_main_thread_pthread_exit_test2 *** BROKEN run_overflowinline_test *** BROKEN run_stack_frame_noopt_frame_test *** BROKEN run_stack_frame_noopt_noframe_test *** BROKEN run_stack_frame_opt_frame_test *** BROKEN run_stack_frame_opt_noframe_test *** BROKEN run_vla_test *** BROKEN run_nacl_sync_cond_test SKIPPING test text_overlaps_rodata SKIPPING test text_overlaps_data *** 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/kitchen-workdir/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 [7277,2043996032:09:56:14.640460] Native Client module will be loaded at base address 0x0000760000000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_with_irt_nnacl_newlib_static= {0.112680, 0.000580} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (1810 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/kitchen-workdir/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 (75520 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= 530.002 KB RESULT hello_world_size.out: ZIPPED_x86-64_with_irt_nnacl_newlib_static= 187.085 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/kitchen-workdir/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 [7305,1788979072:09:56:29.993802] Native Client module will be loaded at base address 0x00007f3b00000000 TestNull: Measuring real time: 0.009 usec (8.88e-09 sec) per iteration: 8.88e-07 sec for 100 iterations 0.002 usec (2.2e-09 sec) per iteration: 2.2e-06 sec for 1000 iterations 0.002 usec (1.8901e-09 sec) per iteration: 1.8901e-05 sec for 10000 iterations 0.002 usec (1.88861e-09 sec) per iteration: 0.0999214 sec for 52907253 iterations 0.002 usec (1.88477e-09 sec) per iteration: 0.099718 sec for 52907253 iterations 0.002 usec (1.88855e-09 sec) per iteration: 0.0999181 sec for 52907253 iterations 0.002 usec (1.88831e-09 sec) per iteration: 0.0999051 sec for 52907253 iterations 0.002 usec (1.92936e-09 sec) per iteration: 0.102077 sec for 52907253 iterations mean: 0.001896 usec stddev: 0.000017 usec relative stddev: 0.89% RESULT TestNull: x86-64_with_irt_nnacl_newlib_static= {0.001896, 0.000017} us Measuring clock cycles: first runs (cycles): 93 24 25 24 26 24 29 22 26 24 ... slowest runs (cycles): ... 28 28 28 28 29 29 29 29 29 93 min: 20 cycles q1: 24 cycles median: 24 cycles q3: 26 cycles max: 93 cycles RESULT TestNull_CycleCount: x86-64_with_irt_nnacl_newlib_static= {24, 2} count Apparent clock speed: 12659 MHz TestNaClSyscall: Measuring real time: 0.223 usec (2.2327e-07 sec) per iteration: 2.2327e-05 sec for 100 iterations 0.213 usec (2.12883e-07 sec) per iteration: 0.0953478 sec for 447888 iterations 0.209 usec (2.08931e-07 sec) per iteration: 0.0935779 sec for 447888 iterations 0.206 usec (2.06062e-07 sec) per iteration: 0.0922927 sec for 447888 iterations 0.202 usec (2.01533e-07 sec) per iteration: 0.0902641 sec for 447888 iterations 0.206 usec (2.06087e-07 sec) per iteration: 0.0923041 sec for 447888 iterations mean: 0.207099 usec stddev: 0.003739 usec relative stddev: 1.81% RESULT TestNaClSyscall: x86-64_with_irt_nnacl_newlib_static= {0.207099, 0.003739} us Measuring clock cycles: first runs (cycles): 616 403 389 391 387 387 390 387 394 385 ... slowest runs (cycles): ... 398 401 403 405 407 412 414 433 440 616 min: 380 cycles q1: 385 cycles median: 387 cycles q3: 391 cycles max: 616 cycles RESULT TestNaClSyscall_CycleCount: x86-64_with_irt_nnacl_newlib_static= {387, 6} count Apparent clock speed: 1869 MHz TestSetjmpLongjmp: Measuring real time: 0.028 usec (2.793e-08 sec) per iteration: 2.793e-06 sec for 100 iterations 0.015 usec (1.5431e-08 sec) per iteration: 1.5431e-05 sec for 1000 iterations 0.013 usec (1.29696e-08 sec) per iteration: 0.0840488 sec for 6480461 iterations 0.013 usec (1.28389e-08 sec) per iteration: 0.0832023 sec for 6480461 iterations 0.013 usec (1.28924e-08 sec) per iteration: 0.0835489 sec for 6480461 iterations 0.013 usec (1.31643e-08 sec) per iteration: 0.0853106 sec for 6480461 iterations 0.013 usec (1.27502e-08 sec) per iteration: 0.0826271 sec for 6480461 iterations mean: 0.012923 usec stddev: 0.000140 usec relative stddev: 1.08% RESULT TestSetjmpLongjmp: x86-64_with_irt_nnacl_newlib_static= {0.012923, 0.000140} us Measuring clock cycles: first runs (cycles): 135 41 35 36 35 31 33 33 33 35 ... slowest runs (cycles): ... 33 33 33 33 35 35 35 36 41 135 min: 24 cycles q1: 27 cycles median: 28 cycles q3: 29 cycles max: 135 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {28, 2} count Apparent clock speed: 2167 MHz TestClockGetTime: Measuring real time: 0.282 usec (2.8232e-07 sec) per iteration: 2.8232e-05 sec for 100 iterations 0.279 usec (2.79163e-07 sec) per iteration: 0.0988815 sec for 354207 iterations 0.279 usec (2.78583e-07 sec) per iteration: 0.0986761 sec for 354207 iterations 0.280 usec (2.79662e-07 sec) per iteration: 0.0990583 sec for 354207 iterations 0.278 usec (2.78466e-07 sec) per iteration: 0.0986348 sec for 354207 iterations 0.280 usec (2.79613e-07 sec) per iteration: 0.0990409 sec for 354207 iterations mean: 0.279098 usec stddev: 0.000500 usec relative stddev: 0.18% RESULT TestClockGetTime: x86-64_with_irt_nnacl_newlib_static= {0.279098, 0.000500} us Measuring clock cycles: first runs (cycles): 805 713 635 662 616 627 618 662 622 627 ... slowest runs (cycles): ... 656 658 662 662 662 671 675 700 713 805 min: 605 cycles q1: 613 cycles median: 616 cycles q3: 625 cycles max: 805 cycles RESULT TestClockGetTime_CycleCount: x86-64_with_irt_nnacl_newlib_static= {616, 12} count Apparent clock speed: 2207 MHz TestTlsVariable: Measuring real time: 0.011 usec (1.062e-08 sec) per iteration: 1.062e-06 sec for 100 iterations 0.006 usec (5.564e-09 sec) per iteration: 5.564e-06 sec for 1000 iterations 0.005 usec (5.2263e-09 sec) per iteration: 5.2263e-05 sec for 10000 iterations 0.005 usec (5.31936e-09 sec) per iteration: 0.101781 sec for 19133995 iterations 0.005 usec (5.28848e-09 sec) per iteration: 0.10119 sec for 19133995 iterations 0.005 usec (5.28809e-09 sec) per iteration: 0.101182 sec for 19133995 iterations 0.005 usec (5.28068e-09 sec) per iteration: 0.101041 sec for 19133995 iterations 0.005 usec (5.28544e-09 sec) per iteration: 0.101132 sec for 19133995 iterations mean: 0.005292 usec stddev: 0.000014 usec relative stddev: 0.26% RESULT TestTlsVariable: x86-64_with_irt_nnacl_newlib_static= {0.005292, 0.000014} us Measuring clock cycles: first runs (cycles): 147 77 35 33 33 31 29 29 31 29 ... slowest runs (cycles): ... 33 33 33 33 33 33 33 35 77 147 min: 26 cycles q1: 28 cycles median: 29 cycles q3: 31 cycles max: 147 cycles RESULT TestTlsVariable_CycleCount: x86-64_with_irt_nnacl_newlib_static= {29, 3} count Apparent clock speed: 5480 MHz TestMmapAnonymous: Measuring real time: 3.968 usec (3.96799e-06 sec) per iteration: 0.000396799 sec for 100 iterations 2.654 usec (2.6537e-06 sec) per iteration: 0.066876 sec for 25201 iterations 2.757 usec (2.75681e-06 sec) per iteration: 0.0694744 sec for 25201 iterations 2.643 usec (2.6431e-06 sec) per iteration: 0.0666087 sec for 25201 iterations 2.620 usec (2.62016e-06 sec) per iteration: 0.0660307 sec for 25201 iterations 2.629 usec (2.62939e-06 sec) per iteration: 0.0662633 sec for 25201 iterations mean: 2.660634 usec stddev: 0.049437 usec relative stddev: 1.86% RESULT TestMmapAnonymous: x86-64_with_irt_nnacl_newlib_static= {2.660634, 0.049437} us Measuring clock cycles: first runs (cycles): 10900 7559 7468 7875 7629 7248 7511 8690 8658 8146 ... slowest runs (cycles): ... 8995 9112 9114 9399 9488 10055 10240 10363 10900 24387 min: 5572 cycles q1: 5667 cycles median: 5799 cycles q3: 8236 cycles max: 24387 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_with_irt_nnacl_newlib_static= {5799, 2569} count Apparent clock speed: 2180 MHz TestAtomicIncrement: Measuring real time: 0.019 usec (1.87e-08 sec) per iteration: 1.87e-06 sec for 100 iterations 0.011 usec (1.1147e-08 sec) per iteration: 1.1147e-05 sec for 1000 iterations 0.009 usec (9.43107e-09 sec) per iteration: 0.0846064 sec for 8971023 iterations 0.009 usec (9.46511e-09 sec) per iteration: 0.0849117 sec for 8971023 iterations 0.009 usec (9.4774e-09 sec) per iteration: 0.0850219 sec for 8971023 iterations 0.009 usec (9.44391e-09 sec) per iteration: 0.0847216 sec for 8971023 iterations 0.009 usec (9.45109e-09 sec) per iteration: 0.0847859 sec for 8971023 iterations mean: 0.009454 usec stddev: 0.000016 usec relative stddev: 0.17% RESULT TestAtomicIncrement: x86-64_with_irt_nnacl_newlib_static= {0.009454, 0.000016} us Measuring clock cycles: first runs (cycles): 119 53 53 52 51 53 57 52 51 53 ... slowest runs (cycles): ... 55 55 55 55 55 55 55 57 57 119 min: 50 cycles q1: 52 cycles median: 53 cycles q3: 53 cycles max: 119 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_with_irt_nnacl_newlib_static= {53, 1} count Apparent clock speed: 5606 MHz TestUncontendedMutexLock: Measuring real time: 0.037 usec (3.687e-08 sec) per iteration: 3.687e-06 sec for 100 iterations 0.027 usec (2.6561e-08 sec) per iteration: 2.6561e-05 sec for 1000 iterations 0.025 usec (2.45067e-08 sec) per iteration: 0.0922655 sec for 3764918 iterations 0.025 usec (2.52222e-08 sec) per iteration: 0.0949597 sec for 3764918 iterations 0.025 usec (2.45334e-08 sec) per iteration: 0.0923661 sec for 3764918 iterations 0.025 usec (2.45463e-08 sec) per iteration: 0.0924147 sec for 3764918 iterations 0.025 usec (2.45257e-08 sec) per iteration: 0.0923373 sec for 3764918 iterations mean: 0.024667 usec stddev: 0.000278 usec relative stddev: 1.13% RESULT TestUncontendedMutexLock: x86-64_with_irt_nnacl_newlib_static= {0.024667, 0.000278} us Measuring clock cycles: first runs (cycles): 258 114 86 86 86 83 84 86 86 86 ... slowest runs (cycles): ... 88 88 91 92 97 97 97 99 114 258 min: 82 cycles q1: 84 cycles median: 86 cycles q3: 86 cycles max: 258 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_with_irt_nnacl_newlib_static= {86, 2} count Apparent clock speed: 3486 MHz TestCondvarSignalNoOp: Measuring real time: 0.406 usec (4.0617e-07 sec) per iteration: 4.0617e-05 sec for 100 iterations 0.305 usec (3.05211e-07 sec) per iteration: 0.0751435 sec for 246202 iterations 0.305 usec (3.05277e-07 sec) per iteration: 0.0751597 sec for 246202 iterations 0.306 usec (3.06316e-07 sec) per iteration: 0.0754156 sec for 246202 iterations 0.306 usec (3.06269e-07 sec) per iteration: 0.0754039 sec for 246202 iterations 0.307 usec (3.07223e-07 sec) per iteration: 0.075639 sec for 246202 iterations mean: 0.306059 usec stddev: 0.000748 usec relative stddev: 0.24% RESULT TestCondvarSignalNoOp: x86-64_with_irt_nnacl_newlib_static= {0.306059, 0.000748} us Measuring clock cycles: first runs (cycles): 1095 829 756 838 756 767 789 754 759 825 ... slowest runs (cycles): ... 847 853 853 867 893 895 900 902 942 1095 min: 708 cycles q1: 735 cycles median: 756 cycles q3: 797 cycles max: 1095 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {756, 62} count Apparent clock speed: 2470 MHz TestThreadCreateAndJoin: Measuring real time: 104.609 usec (0.000104609 sec) per iteration: 0.0104609 sec for 100 iterations 38.535 usec (3.85348e-05 sec) per iteration: 0.0368007 sec for 955 iterations 30.279 usec (3.02794e-05 sec) per iteration: 0.0289168 sec for 955 iterations 32.070 usec (3.20695e-05 sec) per iteration: 0.0306264 sec for 955 iterations 27.377 usec (2.73768e-05 sec) per iteration: 0.0261448 sec for 955 iterations 30.237 usec (3.02367e-05 sec) per iteration: 0.0288761 sec for 955 iterations mean: 31.699445 usec stddev: 3.733638 usec relative stddev: 11.78% RESULT TestThreadCreateAndJoin: x86-64_with_irt_nnacl_newlib_static= {31.699445, 3.733638} us Measuring clock cycles: first runs (cycles): 115121 81329 101191 107630 116752 111775 63652 71936 66458 97694 ... slowest runs (cycles): ... 89886 89967 92957 92973 97694 101191 107630 111775 115121 116752 min: 50714 cycles q1: 54373 cycles median: 56414 cycles q3: 63109 cycles max: 116752 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_with_irt_nnacl_newlib_static= {56414, 8736} count Apparent clock speed: 1780 MHz TestThreadWakeup: Measuring real time: 13.650 usec (1.365e-05 sec) per iteration: 0.001365 sec for 100 iterations 14.473 usec (1.44732e-05 sec) per iteration: 0.106031 sec for 7326 iterations 15.386 usec (1.53857e-05 sec) per iteration: 0.112716 sec for 7326 iterations 16.334 usec (1.63338e-05 sec) per iteration: 0.119662 sec for 7326 iterations 17.271 usec (1.72711e-05 sec) per iteration: 0.126528 sec for 7326 iterations 17.206 usec (1.72063e-05 sec) per iteration: 0.126053 sec for 7326 iterations mean: 16.134032 usec stddev: 1.077136 usec relative stddev: 6.68% RESULT TestThreadWakeup: x86-64_with_irt_nnacl_newlib_static= {16.134032, 1.077136} us Measuring clock cycles: first runs (cycles): 70610 39383 30850 31736 31152 32372 32313 35962 35263 32386 ... slowest runs (cycles): ... 46041 46996 55107 56753 57563 63296 70407 70610 90375 207644 min: 25126 cycles q1: 34242 cycles median: 34959 cycles q3: 37089 cycles max: 207644 cycles RESULT TestThreadWakeup_CycleCount: x86-64_with_irt_nnacl_newlib_static= {34959, 2847} count Apparent clock speed: 2167 MHz TestCatchingFault: Measuring real time: 3.458 usec (3.45836e-06 sec) per iteration: 0.000345836 sec for 100 iterations 1.924 usec (1.92393e-06 sec) per iteration: 0.0556304 sec for 28915 iterations 1.933 usec (1.93333e-06 sec) per iteration: 0.0559023 sec for 28915 iterations 1.929 usec (1.92914e-06 sec) per iteration: 0.0557812 sec for 28915 iterations 1.931 usec (1.93075e-06 sec) per iteration: 0.0558277 sec for 28915 iterations 1.932 usec (1.93189e-06 sec) per iteration: 0.0558606 sec for 28915 iterations mean: 1.929809 usec stddev: 0.003245 usec relative stddev: 0.17% RESULT TestCatchingFault: x86-64_with_irt_nnacl_newlib_static= {1.929809, 0.003245} us Measuring clock cycles: first runs (cycles): 5852 5625 4802 4875 4640 4864 4564 4545 4588 4869 ... slowest runs (cycles): ... 5471 5576 5625 5662 5671 5741 5759 5776 5852 5908 min: 4235 cycles q1: 4692 cycles median: 4836 cycles q3: 4976 cycles max: 5908 cycles RESULT TestCatchingFault_CycleCount: x86-64_with_irt_nnacl_newlib_static= {4836, 284} count Apparent clock speed: 2506 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 39.126 usec (3.91265e-05 sec) per iteration: 0.00391265 sec for 100 iterations 29.398 usec (2.93985e-05 sec) per iteration: 0.0751131 sec for 2555 iterations 28.262 usec (2.8262e-05 sec) per iteration: 0.0722093 sec for 2555 iterations 37.109 usec (3.71088e-05 sec) per iteration: 0.0948129 sec for 2555 iterations 31.873 usec (3.18731e-05 sec) per iteration: 0.0814357 sec for 2555 iterations 30.387 usec (3.03866e-05 sec) per iteration: 0.0776378 sec for 2555 iterations mean: 31.405785 usec stddev: 3.088497 usec relative stddev: 9.83% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_with_irt_nnacl_newlib_static= {31.405785, 3.088497} us Measuring clock cycles: first runs (cycles): 55765 52118 56246 52177 51636 168617 86905 128929 59524 139319 ... slowest runs (cycles): ... 79195 83060 86905 89743 101085 111598 128929 139319 168617 369990 min: 43875 cycles q1: 51728 cycles median: 54612 cycles q3: 60571 cycles max: 369990 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_with_irt_nnacl_newlib_static= {54612, 8843} count Apparent clock speed: 1739 MHz [ OK ] nacl_newlib.run_performance_test_irt (5590 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