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-canary-e973df7c-us-central1-c-jkxq 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: 21977816 kB MemAvailable: 29504056 kB ====================================================================== LOAD: ====================================================================== 1.17 1.71 1.00 2/433 15724 ====================================================================== UPTIME: ====================================================================== 584.87 3467.47 ====================================================================== 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 [15732,2862696320:18:47:58.526300] Native Client module will be loaded at base address 0x00006ff000000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_with_irt_nnacl_newlib_static= {0.107670, 0.007020} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (1740 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 (74660 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= 527.182 KB RESULT hello_world_size.out: ZIPPED_x86-64_with_irt_nnacl_newlib_static= 186.968 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 [15760,351242112:18:48:14.004105] Native Client module will be loaded at base address 0x00007df600000000 TestNull: Measuring real time: 0.007 usec (7.36e-09 sec) per iteration: 7.36e-07 sec for 100 iterations 0.002 usec (2.262e-09 sec) per iteration: 2.262e-06 sec for 1000 iterations 0.002 usec (1.8928e-09 sec) per iteration: 1.8928e-05 sec for 10000 iterations 0.002 usec (1.94039e-09 sec) per iteration: 0.102514 sec for 52831783 iterations 0.002 usec (1.92235e-09 sec) per iteration: 0.101561 sec for 52831783 iterations 0.002 usec (1.90006e-09 sec) per iteration: 0.100384 sec for 52831783 iterations 0.002 usec (1.88341e-09 sec) per iteration: 0.0995037 sec for 52831783 iterations 0.002 usec (1.88953e-09 sec) per iteration: 0.099827 sec for 52831783 iterations mean: 0.001907 usec stddev: 0.000021 usec relative stddev: 1.11% RESULT TestNull: x86-64_with_irt_nnacl_newlib_static= {0.001907, 0.000021} us Measuring clock cycles: first runs (cycles): 95 26 24 24 27 26 22 24 26 22 ... slowest runs (cycles): ... 27 27 27 27 28 29 29 29 29 95 min: 22 cycles q1: 24 cycles median: 24 cycles q3: 26 cycles max: 95 cycles RESULT TestNull_CycleCount: x86-64_with_irt_nnacl_newlib_static= {24, 2} count Apparent clock speed: 12584 MHz TestNaClSyscall: Measuring real time: 0.299 usec (2.994e-07 sec) per iteration: 2.994e-05 sec for 100 iterations 0.207 usec (2.06685e-07 sec) per iteration: 0.0690331 sec for 334001 iterations 0.208 usec (2.08177e-07 sec) per iteration: 0.0695312 sec for 334001 iterations 0.210 usec (2.1003e-07 sec) per iteration: 0.0701503 sec for 334001 iterations 0.208 usec (2.08399e-07 sec) per iteration: 0.0696056 sec for 334001 iterations 0.210 usec (2.09742e-07 sec) per iteration: 0.0700539 sec for 334001 iterations mean: 0.208607 usec stddev: 0.001203 usec relative stddev: 0.58% RESULT TestNaClSyscall: x86-64_with_irt_nnacl_newlib_static= {0.208607, 0.001203} us Measuring clock cycles: first runs (cycles): 444 403 358 389 343 352 345 379 341 369 ... slowest runs (cycles): ... 358 365 365 369 379 380 385 389 403 444 min: 339 cycles q1: 345 cycles median: 349 cycles q3: 352 cycles max: 444 cycles RESULT TestNaClSyscall_CycleCount: x86-64_with_irt_nnacl_newlib_static= {349, 7} count Apparent clock speed: 1673 MHz TestSetjmpLongjmp: Measuring real time: 0.017 usec (1.731e-08 sec) per iteration: 1.731e-06 sec for 100 iterations 0.013 usec (1.2836e-08 sec) per iteration: 1.2836e-05 sec for 1000 iterations 0.013 usec (1.28829e-08 sec) per iteration: 0.100365 sec for 7790588 iterations 0.013 usec (1.2835e-08 sec) per iteration: 0.0999922 sec for 7790588 iterations 0.013 usec (1.27777e-08 sec) per iteration: 0.0995459 sec for 7790588 iterations 0.013 usec (1.30815e-08 sec) per iteration: 0.101913 sec for 7790588 iterations 0.013 usec (1.28652e-08 sec) per iteration: 0.100227 sec for 7790588 iterations mean: 0.012888 usec stddev: 0.000103 usec relative stddev: 0.80% RESULT TestSetjmpLongjmp: x86-64_with_irt_nnacl_newlib_static= {0.012888, 0.000103} us Measuring clock cycles: first runs (cycles): 143 42 35 35 35 33 35 36 38 35 ... slowest runs (cycles): ... 33 35 35 35 35 35 36 38 42 143 min: 24 cycles q1: 26 cycles median: 28 cycles q3: 29 cycles max: 143 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {28, 3} count Apparent clock speed: 2172 MHz TestClockGetTime: Measuring real time: 0.279 usec (2.7945e-07 sec) per iteration: 2.7945e-05 sec for 100 iterations 0.280 usec (2.79522e-07 sec) per iteration: 0.100026 sec for 357845 iterations 0.280 usec (2.80427e-07 sec) per iteration: 0.100349 sec for 357845 iterations 0.279 usec (2.78538e-07 sec) per iteration: 0.0996736 sec for 357845 iterations 0.280 usec (2.79756e-07 sec) per iteration: 0.100109 sec for 357845 iterations 0.279 usec (2.79128e-07 sec) per iteration: 0.0998844 sec for 357845 iterations mean: 0.279474 usec stddev: 0.000630 usec relative stddev: 0.23% RESULT TestClockGetTime: x86-64_with_irt_nnacl_newlib_static= {0.279474, 0.000630} us Measuring clock cycles: first runs (cycles): 906 629 627 665 653 662 614 618 618 698 ... slowest runs (cycles): ... 662 665 684 686 698 776 794 854 906 924 min: 605 cycles q1: 614 cycles median: 618 cycles q3: 625 cycles max: 924 cycles RESULT TestClockGetTime_CycleCount: x86-64_with_irt_nnacl_newlib_static= {618, 11} count Apparent clock speed: 2211 MHz TestTlsVariable: Measuring real time: 0.011 usec (1.081e-08 sec) per iteration: 1.081e-06 sec for 100 iterations 0.006 usec (5.523e-09 sec) per iteration: 5.523e-06 sec for 1000 iterations 0.005 usec (5.2282e-09 sec) per iteration: 5.2282e-05 sec for 10000 iterations 0.005 usec (5.2873e-09 sec) per iteration: 0.10113 sec for 19127041 iterations 0.005 usec (5.36718e-09 sec) per iteration: 0.102658 sec for 19127041 iterations 0.005 usec (5.29285e-09 sec) per iteration: 0.101237 sec for 19127041 iterations 0.005 usec (5.28818e-09 sec) per iteration: 0.101147 sec for 19127041 iterations 0.005 usec (5.321e-09 sec) per iteration: 0.101775 sec for 19127041 iterations mean: 0.005311 usec stddev: 0.000031 usec relative stddev: 0.58% RESULT TestTlsVariable: x86-64_with_irt_nnacl_newlib_static= {0.005311, 0.000031} us Measuring clock cycles: first runs (cycles): 147 77 35 35 28 33 31 35 31 29 ... slowest runs (cycles): ... 33 33 33 33 33 35 35 35 77 147 min: 24 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: 5460 MHz TestMmapAnonymous: Measuring real time: 3.579 usec (3.57866e-06 sec) per iteration: 0.000357866 sec for 100 iterations 2.690 usec (2.6896e-06 sec) per iteration: 0.0751554 sec for 27943 iterations 2.689 usec (2.6886e-06 sec) per iteration: 0.0751276 sec for 27943 iterations 2.692 usec (2.69244e-06 sec) per iteration: 0.0752349 sec for 27943 iterations 2.679 usec (2.67896e-06 sec) per iteration: 0.0748582 sec for 27943 iterations 2.681 usec (2.68116e-06 sec) per iteration: 0.0749198 sec for 27943 iterations mean: 2.686153 usec stddev: 0.005178 usec relative stddev: 0.19% RESULT TestMmapAnonymous: x86-64_with_irt_nnacl_newlib_static= {2.686153, 0.005178} us Measuring clock cycles: first runs (cycles): 7794 6091 6113 5849 5849 5794 5814 5927 5825 5807 ... slowest runs (cycles): ... 6018 6020 6056 6091 6113 6164 6510 7794 16488 23375 min: 5748 cycles q1: 5831 cycles median: 5865 cycles q3: 5929 cycles max: 23375 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_with_irt_nnacl_newlib_static= {5865, 98} count Apparent clock speed: 2183 MHz TestAtomicIncrement: Measuring real time: 0.014 usec (1.424e-08 sec) per iteration: 1.424e-06 sec for 100 iterations 0.010 usec (9.594e-09 sec) per iteration: 9.594e-06 sec for 1000 iterations 0.009 usec (9.2955e-09 sec) per iteration: 9.2955e-05 sec for 10000 iterations 0.009 usec (9.43355e-09 sec) per iteration: 0.101485 sec for 10757893 iterations 0.009 usec (9.49248e-09 sec) per iteration: 0.102119 sec for 10757893 iterations 0.009 usec (9.43438e-09 sec) per iteration: 0.101494 sec for 10757893 iterations 0.009 usec (9.43226e-09 sec) per iteration: 0.101471 sec for 10757893 iterations 0.010 usec (9.57495e-09 sec) per iteration: 0.103006 sec for 10757893 iterations mean: 0.009474 usec stddev: 0.000056 usec relative stddev: 0.59% RESULT TestAtomicIncrement: x86-64_with_irt_nnacl_newlib_static= {0.009474, 0.000056} us Measuring clock cycles: first runs (cycles): 110 53 51 53 50 53 50 53 50 51 ... slowest runs (cycles): ... 53 53 53 53 53 53 53 53 53 110 min: 50 cycles q1: 51 cycles median: 53 cycles q3: 53 cycles max: 110 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_with_irt_nnacl_newlib_static= {53, 2} count Apparent clock speed: 5595 MHz TestUncontendedMutexLock: Measuring real time: 0.029 usec (2.908e-08 sec) per iteration: 2.908e-06 sec for 100 iterations 0.024 usec (2.4404e-08 sec) per iteration: 2.4404e-05 sec for 1000 iterations 0.025 usec (2.49479e-08 sec) per iteration: 0.102229 sec for 4097688 iterations 0.025 usec (2.45022e-08 sec) per iteration: 0.100403 sec for 4097688 iterations 0.025 usec (2.4587e-08 sec) per iteration: 0.10075 sec for 4097688 iterations 0.025 usec (2.45487e-08 sec) per iteration: 0.100593 sec for 4097688 iterations 0.025 usec (2.45119e-08 sec) per iteration: 0.100442 sec for 4097688 iterations mean: 0.024620 usec stddev: 0.000167 usec relative stddev: 0.68% RESULT TestUncontendedMutexLock: x86-64_with_irt_nnacl_newlib_static= {0.024620, 0.000167} us Measuring clock cycles: first runs (cycles): 226 108 81 82 81 81 82 81 81 82 ... slowest runs (cycles): ... 84 84 84 84 84 84 84 84 108 226 min: 81 cycles q1: 81 cycles median: 83 cycles q3: 84 cycles max: 226 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_with_irt_nnacl_newlib_static= {83, 3} count Apparent clock speed: 3371 MHz TestCondvarSignalNoOp: Measuring real time: 0.335 usec (3.3465e-07 sec) per iteration: 3.3465e-05 sec for 100 iterations 0.313 usec (3.13468e-07 sec) per iteration: 0.0936702 sec for 298819 iterations 0.313 usec (3.12709e-07 sec) per iteration: 0.0934435 sec for 298819 iterations 0.317 usec (3.17196e-07 sec) per iteration: 0.0947842 sec for 298819 iterations 0.313 usec (3.13278e-07 sec) per iteration: 0.0936135 sec for 298819 iterations 0.314 usec (3.13763e-07 sec) per iteration: 0.0937584 sec for 298819 iterations mean: 0.314083 usec stddev: 0.001594 usec relative stddev: 0.51% RESULT TestCondvarSignalNoOp: x86-64_with_irt_nnacl_newlib_static= {0.314083, 0.001594} us Measuring clock cycles: first runs (cycles): 1199 869 776 755 711 700 697 702 704 700 ... slowest runs (cycles): ... 704 709 711 750 755 776 776 790 869 1199 min: 680 cycles q1: 687 cycles median: 691 cycles q3: 697 cycles max: 1199 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {691, 10} count Apparent clock speed: 2200 MHz TestThreadCreateAndJoin: Measuring real time: 32.678 usec (3.26783e-05 sec) per iteration: 0.00326783 sec for 100 iterations 28.100 usec (2.81002e-05 sec) per iteration: 0.0859867 sec for 3060 iterations 24.306 usec (2.43056e-05 sec) per iteration: 0.074375 sec for 3060 iterations 26.422 usec (2.6422e-05 sec) per iteration: 0.0808512 sec for 3060 iterations 25.151 usec (2.51506e-05 sec) per iteration: 0.0769607 sec for 3060 iterations 25.659 usec (2.56594e-05 sec) per iteration: 0.0785177 sec for 3060 iterations mean: 25.927538 usec stddev: 1.286200 usec relative stddev: 4.96% RESULT TestThreadCreateAndJoin: x86-64_with_irt_nnacl_newlib_static= {25.927538, 1.286200} us Measuring clock cycles: first runs (cycles): 53139 74089 44902 45686 44396 45974 45015 49218 52089 48431 ... slowest runs (cycles): ... 57683 58704 58979 64074 65583 66515 68416 74089 81181 96593 min: 44318 cycles q1: 47802 cycles median: 49373 cycles q3: 52089 cycles max: 96593 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_with_irt_nnacl_newlib_static= {49373, 4287} count Apparent clock speed: 1904 MHz TestThreadWakeup: Measuring real time: 11.819 usec (1.18191e-05 sec) per iteration: 0.00118191 sec for 100 iterations 13.778 usec (1.37779e-05 sec) per iteration: 0.116561 sec for 8460 iterations 13.979 usec (1.39792e-05 sec) per iteration: 0.118264 sec for 8460 iterations 14.100 usec (1.41002e-05 sec) per iteration: 0.119287 sec for 8460 iterations 14.104 usec (1.41037e-05 sec) per iteration: 0.119317 sec for 8460 iterations 11.942 usec (1.19421e-05 sec) per iteration: 0.10103 sec for 8460 iterations mean: 13.580596 usec stddev: 0.827773 usec relative stddev: 6.10% RESULT TestThreadWakeup: x86-64_with_irt_nnacl_newlib_static= {13.580596, 0.827773} us Measuring clock cycles: first runs (cycles): 60105 29541 31011 29346 30381 30513 29434 30538 32418 28674 ... slowest runs (cycles): ... 32418 32441 32780 33032 38418 38860 43790 46224 56214 60105 min: 28674 cycles q1: 29519 cycles median: 29819 cycles q3: 30586 cycles max: 60105 cycles RESULT TestThreadWakeup_CycleCount: x86-64_with_irt_nnacl_newlib_static= {29819, 1067} count Apparent clock speed: 2196 MHz TestCatchingFault: Measuring real time: 3.688 usec (3.68826e-06 sec) per iteration: 0.000368826 sec for 100 iterations 1.954 usec (1.95424e-06 sec) per iteration: 0.0529853 sec for 27113 iterations 1.959 usec (1.95862e-06 sec) per iteration: 0.0531041 sec for 27113 iterations 1.961 usec (1.96052e-06 sec) per iteration: 0.0531556 sec for 27113 iterations 1.970 usec (1.97033e-06 sec) per iteration: 0.0534216 sec for 27113 iterations 1.960 usec (1.96023e-06 sec) per iteration: 0.0531478 sec for 27113 iterations mean: 1.960789 usec stddev: 0.005273 usec relative stddev: 0.27% RESULT TestCatchingFault: x86-64_with_irt_nnacl_newlib_static= {1.960789, 0.005273} us Measuring clock cycles: first runs (cycles): 5001 4424 4301 4327 4325 4247 4294 4248 4247 4250 ... slowest runs (cycles): ... 4318 4322 4325 4327 4330 4362 4382 4424 4457 5001 min: 4222 cycles q1: 4243 cycles median: 4254 cycles q3: 4287 cycles max: 5001 cycles RESULT TestCatchingFault_CycleCount: x86-64_with_irt_nnacl_newlib_static= {4254, 44} count Apparent clock speed: 2170 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 55.716 usec (5.57159e-05 sec) per iteration: 0.00557159 sec for 100 iterations 50.919 usec (5.09187e-05 sec) per iteration: 0.0913482 sec for 1794 iterations 40.224 usec (4.02241e-05 sec) per iteration: 0.0721621 sec for 1794 iterations 30.652 usec (3.06518e-05 sec) per iteration: 0.0549893 sec for 1794 iterations 28.173 usec (2.81735e-05 sec) per iteration: 0.0505432 sec for 1794 iterations 39.127 usec (3.91273e-05 sec) per iteration: 0.0701943 sec for 1794 iterations mean: 37.819072 usec stddev: 8.043675 usec relative stddev: 21.27% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_with_irt_nnacl_newlib_static= {37.819072, 8.043675} us Measuring clock cycles: first runs (cycles): 66825 120453 66005 80699 379887 113713 460618 134433 81787 92412 ... slowest runs (cycles): ... 212295 226081 229812 241057 246460 255965 278663 296882 379887 460618 min: 47467 cycles q1: 58354 cycles median: 67750 cycles q3: 107152 cycles max: 460618 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_with_irt_nnacl_newlib_static= {67750, 48798} count Apparent clock speed: 1791 MHz [ OK ] nacl_newlib.run_performance_test_irt (5990 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