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-6-k9jm 4.4.0-78-generic #99~14.04.2-Ubuntu SMP Thu Apr 27 18:49:46 UTC 2017 x86_64 x86_64 x86_64 GNU/Linux ====================================================================== CPU: ====================================================================== model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2200.000 model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2200.000 model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2200.000 model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2200.000 model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2200.000 model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2200.000 model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2200.000 model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2200.000 ====================================================================== RAM: ====================================================================== MemTotal: 30882896 kB MemFree: 20511256 kB MemAvailable: 29466056 kB ====================================================================== LOAD: ====================================================================== 1.30 1.72 1.06 1/408 14470 ====================================================================== UPTIME: ====================================================================== 2394.39 17794.94 ====================================================================== 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 [14478,3439212416:09:46:02.936387] Native Client module will be loaded at base address 0x000010bf00000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_with_irt_nnacl_newlib_static= {0.090750, 0.003440} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (1390 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 (74960 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 [14507,567699328:09:46:18.500711] Native Client module will be loaded at base address 0x000049cc00000000 TestNull: Measuring real time: 0.008 usec (7.61e-09 sec) per iteration: 7.61e-07 sec for 100 iterations 0.002 usec (2.178e-09 sec) per iteration: 2.178e-06 sec for 1000 iterations 0.002 usec (1.901e-09 sec) per iteration: 1.901e-05 sec for 10000 iterations 0.002 usec (1.91367e-09 sec) per iteration: 0.100667 sec for 52603892 iterations 0.002 usec (1.88362e-09 sec) per iteration: 0.0990855 sec for 52603892 iterations 0.002 usec (1.88884e-09 sec) per iteration: 0.0993605 sec for 52603892 iterations 0.002 usec (1.88429e-09 sec) per iteration: 0.0991208 sec for 52603892 iterations 0.002 usec (1.88444e-09 sec) per iteration: 0.099129 sec for 52603892 iterations mean: 0.001891 usec stddev: 0.000012 usec relative stddev: 0.61% RESULT TestNull: x86-64_with_irt_nnacl_newlib_static= {0.001891, 0.000012} us Measuring clock cycles: first runs (cycles): 96 22 24 24 22 24 27 22 25 28 ... slowest runs (cycles): ... 28 28 28 28 29 29 29 29 29 96 min: 22 cycles q1: 24 cycles median: 25 cycles q3: 26 cycles max: 96 cycles RESULT TestNull_CycleCount: x86-64_with_irt_nnacl_newlib_static= {25, 2} count Apparent clock speed: 13221 MHz TestNaClSyscall: Measuring real time: 0.227 usec (2.2688e-07 sec) per iteration: 2.2688e-05 sec for 100 iterations 0.207 usec (2.06948e-07 sec) per iteration: 0.0912144 sec for 440761 iterations 0.215 usec (2.1509e-07 sec) per iteration: 0.0948033 sec for 440761 iterations 0.215 usec (2.14798e-07 sec) per iteration: 0.0946747 sec for 440761 iterations 0.213 usec (2.12956e-07 sec) per iteration: 0.0938628 sec for 440761 iterations 0.210 usec (2.09538e-07 sec) per iteration: 0.0923563 sec for 440761 iterations mean: 0.211866 usec stddev: 0.003156 usec relative stddev: 1.49% RESULT TestNaClSyscall: x86-64_with_irt_nnacl_newlib_static= {0.211866, 0.003156} us Measuring clock cycles: first runs (cycles): 521 453 403 343 341 387 343 345 350 352 ... slowest runs (cycles): ... 376 381 383 385 387 387 391 403 453 521 min: 339 cycles q1: 346 cycles median: 350 cycles q3: 352 cycles max: 521 cycles RESULT TestNaClSyscall_CycleCount: x86-64_with_irt_nnacl_newlib_static= {350, 6} count Apparent clock speed: 1652 MHz TestSetjmpLongjmp: Measuring real time: 0.017 usec (1.734e-08 sec) per iteration: 1.734e-06 sec for 100 iterations 0.013 usec (1.2883e-08 sec) per iteration: 1.2883e-05 sec for 1000 iterations 0.013 usec (1.27901e-08 sec) per iteration: 0.0992786 sec for 7762167 iterations 0.013 usec (1.27902e-08 sec) per iteration: 0.0992799 sec for 7762167 iterations 0.013 usec (1.282e-08 sec) per iteration: 0.0995112 sec for 7762167 iterations 0.013 usec (1.2873e-08 sec) per iteration: 0.0999225 sec for 7762167 iterations 0.013 usec (1.28175e-08 sec) per iteration: 0.0994919 sec for 7762167 iterations mean: 0.012818 usec stddev: 0.000030 usec relative stddev: 0.24% RESULT TestSetjmpLongjmp: x86-64_with_irt_nnacl_newlib_static= {0.012818, 0.000030} us Measuring clock cycles: first runs (cycles): 196 40 37 31 30 31 31 33 33 35 ... slowest runs (cycles): ... 31 31 31 31 33 33 35 37 40 196 min: 24 cycles q1: 26 cycles median: 28 cycles q3: 29 cycles max: 196 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {28, 3} count Apparent clock speed: 2184 MHz TestClockGetTime: Measuring real time: 0.282 usec (2.8194e-07 sec) per iteration: 2.8194e-05 sec for 100 iterations 0.279 usec (2.79042e-07 sec) per iteration: 0.0989719 sec for 354685 iterations 0.278 usec (2.77975e-07 sec) per iteration: 0.0985935 sec for 354685 iterations 0.278 usec (2.78344e-07 sec) per iteration: 0.0987245 sec for 354685 iterations 0.278 usec (2.7843e-07 sec) per iteration: 0.0987549 sec for 354685 iterations 0.279 usec (2.7904e-07 sec) per iteration: 0.0989713 sec for 354685 iterations mean: 0.278566 usec stddev: 0.000417 usec relative stddev: 0.15% RESULT TestClockGetTime: x86-64_with_irt_nnacl_newlib_static= {0.278566, 0.000417} us Measuring clock cycles: first runs (cycles): 889 645 651 623 620 618 642 621 613 621 ... slowest runs (cycles): ... 656 657 658 659 671 673 688 756 889 11186 min: 601 cycles q1: 614 cycles median: 619 cycles q3: 625 cycles max: 11186 cycles RESULT TestClockGetTime_CycleCount: x86-64_with_irt_nnacl_newlib_static= {619, 11} count Apparent clock speed: 2222 MHz TestTlsVariable: Measuring real time: 0.010 usec (9.87e-09 sec) per iteration: 9.87e-07 sec for 100 iterations 0.006 usec (5.935e-09 sec) per iteration: 5.935e-06 sec for 1000 iterations 0.006 usec (5.9789e-09 sec) per iteration: 5.9789e-05 sec for 10000 iterations 0.006 usec (5.75012e-09 sec) per iteration: 0.0961735 sec for 16725484 iterations 0.006 usec (5.66519e-09 sec) per iteration: 0.094753 sec for 16725484 iterations 0.006 usec (5.65818e-09 sec) per iteration: 0.0946358 sec for 16725484 iterations 0.006 usec (5.69224e-09 sec) per iteration: 0.0952054 sec for 16725484 iterations 0.006 usec (5.65141e-09 sec) per iteration: 0.0945225 sec for 16725484 iterations mean: 0.005683 usec stddev: 0.000036 usec relative stddev: 0.64% RESULT TestTlsVariable: x86-64_with_irt_nnacl_newlib_static= {0.005683, 0.000036} us Measuring clock cycles: first runs (cycles): 174 77 33 33 36 33 37 37 33 31 ... slowest runs (cycles): ... 33 33 33 33 33 36 37 37 77 174 min: 24 cycles q1: 28 cycles median: 29 cycles q3: 31 cycles max: 174 cycles RESULT TestTlsVariable_CycleCount: x86-64_with_irt_nnacl_newlib_static= {29, 3} count Apparent clock speed: 5103 MHz TestMmapAnonymous: Measuring real time: 3.470 usec (3.4699e-06 sec) per iteration: 0.00034699 sec for 100 iterations 2.687 usec (2.68687e-06 sec) per iteration: 0.077433 sec for 28819 iterations 2.686 usec (2.68554e-06 sec) per iteration: 0.0773946 sec for 28819 iterations 2.687 usec (2.68724e-06 sec) per iteration: 0.0774436 sec for 28819 iterations 2.685 usec (2.68468e-06 sec) per iteration: 0.0773697 sec for 28819 iterations 2.685 usec (2.68526e-06 sec) per iteration: 0.0773866 sec for 28819 iterations mean: 2.685919 usec stddev: 0.000977 usec relative stddev: 0.04% RESULT TestMmapAnonymous: x86-64_with_irt_nnacl_newlib_static= {2.685919, 0.000977} us Measuring clock cycles: first runs (cycles): 8069 6008 5997 5937 5852 5790 5843 5823 5810 5816 ... slowest runs (cycles): ... 5997 6008 6014 6021 6029 6052 6177 6296 8069 15526 min: 5762 cycles q1: 5820 cycles median: 5858 cycles q3: 5929 cycles max: 15526 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_with_irt_nnacl_newlib_static= {5858, 109} count Apparent clock speed: 2181 MHz TestAtomicIncrement: Measuring real time: 0.017 usec (1.721e-08 sec) per iteration: 1.721e-06 sec for 100 iterations 0.011 usec (1.0604e-08 sec) per iteration: 1.0604e-05 sec for 1000 iterations 0.010 usec (9.50957e-09 sec) per iteration: 0.0896791 sec for 9430403 iterations 0.009 usec (9.41007e-09 sec) per iteration: 0.0887408 sec for 9430403 iterations 0.009 usec (9.41979e-09 sec) per iteration: 0.0888325 sec for 9430403 iterations 0.009 usec (9.45975e-09 sec) per iteration: 0.0892092 sec for 9430403 iterations 0.009 usec (9.49782e-09 sec) per iteration: 0.0895683 sec for 9430403 iterations mean: 0.009459 usec stddev: 0.000040 usec relative stddev: 0.42% RESULT TestAtomicIncrement: x86-64_with_irt_nnacl_newlib_static= {0.009459, 0.000040} us Measuring clock cycles: first runs (cycles): 119 53 51 53 51 53 53 52 50 53 ... slowest runs (cycles): ... 53 53 53 53 53 53 53 55 55 119 min: 50 cycles q1: 51 cycles median: 53 cycles q3: 53 cycles max: 119 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_with_irt_nnacl_newlib_static= {53, 2} count Apparent clock speed: 5603 MHz TestUncontendedMutexLock: Measuring real time: 0.044 usec (4.385e-08 sec) per iteration: 4.385e-06 sec for 100 iterations 0.037 usec (3.6766e-08 sec) per iteration: 3.6766e-05 sec for 1000 iterations 0.025 usec (2.46827e-08 sec) per iteration: 0.0671345 sec for 2719904 iterations 0.025 usec (2.46659e-08 sec) per iteration: 0.0670889 sec for 2719904 iterations 0.025 usec (2.46333e-08 sec) per iteration: 0.0670002 sec for 2719904 iterations 0.025 usec (2.46485e-08 sec) per iteration: 0.0670417 sec for 2719904 iterations 0.024 usec (2.44812e-08 sec) per iteration: 0.0665865 sec for 2719904 iterations mean: 0.024622 usec stddev: 0.000072 usec relative stddev: 0.29% RESULT TestUncontendedMutexLock: x86-64_with_irt_nnacl_newlib_static= {0.024622, 0.000072} us Measuring clock cycles: first runs (cycles): 258 88 82 81 82 84 83 82 81 81 ... slowest runs (cycles): ... 84 84 84 84 84 84 84 88 91 258 min: 79 cycles q1: 81 cycles median: 82 cycles q3: 84 cycles max: 258 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_with_irt_nnacl_newlib_static= {82, 3} count Apparent clock speed: 3330 MHz TestCondvarSignalNoOp: Measuring real time: 0.338 usec (3.3792e-07 sec) per iteration: 3.3792e-05 sec for 100 iterations 0.305 usec (3.04823e-07 sec) per iteration: 0.0902058 sec for 295928 iterations 0.306 usec (3.0584e-07 sec) per iteration: 0.0905066 sec for 295928 iterations 0.306 usec (3.06009e-07 sec) per iteration: 0.0905565 sec for 295928 iterations 0.304 usec (3.04075e-07 sec) per iteration: 0.0899843 sec for 295928 iterations 0.308 usec (3.08086e-07 sec) per iteration: 0.0911713 sec for 295928 iterations mean: 0.305767 usec stddev: 0.001356 usec relative stddev: 0.44% RESULT TestCondvarSignalNoOp: x86-64_with_irt_nnacl_newlib_static= {0.305767, 0.001356} us Measuring clock cycles: first runs (cycles): 849 757 691 664 671 717 674 664 676 666 ... slowest runs (cycles): ... 717 718 719 722 748 757 757 772 818 849 min: 662 cycles q1: 667 cycles median: 673 cycles q3: 678 cycles max: 849 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {673, 11} count Apparent clock speed: 2201 MHz TestThreadCreateAndJoin: Measuring real time: 35.786 usec (3.57857e-05 sec) per iteration: 0.00357857 sec for 100 iterations 32.363 usec (3.23629e-05 sec) per iteration: 0.0904218 sec for 2794 iterations 26.852 usec (2.68518e-05 sec) per iteration: 0.0750239 sec for 2794 iterations 27.046 usec (2.7046e-05 sec) per iteration: 0.0755666 sec for 2794 iterations 26.715 usec (2.67146e-05 sec) per iteration: 0.0746405 sec for 2794 iterations 25.572 usec (2.55718e-05 sec) per iteration: 0.0714477 sec for 2794 iterations mean: 27.709421 usec stddev: 2.382815 usec relative stddev: 8.60% RESULT TestThreadCreateAndJoin: x86-64_with_irt_nnacl_newlib_static= {27.709421, 2.382815} us Measuring clock cycles: first runs (cycles): 78544 95332 63397 55083 49230 50141 55272 89313 48878 52459 ... slowest runs (cycles): ... 89313 89322 90305 92780 95332 97789 98154 100970 101135 128406 min: 45541 cycles q1: 48992 cycles median: 50497 cycles q3: 54362 cycles max: 128406 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_with_irt_nnacl_newlib_static= {50497, 5370} count Apparent clock speed: 1822 MHz TestThreadWakeup: Measuring real time: 12.570 usec (1.25702e-05 sec) per iteration: 0.00125702 sec for 100 iterations 14.872 usec (1.48717e-05 sec) per iteration: 0.118304 sec for 7955 iterations 15.438 usec (1.54383e-05 sec) per iteration: 0.122812 sec for 7955 iterations 14.287 usec (1.4287e-05 sec) per iteration: 0.113653 sec for 7955 iterations 13.649 usec (1.36494e-05 sec) per iteration: 0.108581 sec for 7955 iterations 14.782 usec (1.47816e-05 sec) per iteration: 0.117588 sec for 7955 iterations mean: 14.605593 usec stddev: 0.601838 usec relative stddev: 4.12% RESULT TestThreadWakeup: x86-64_with_irt_nnacl_newlib_static= {14.605593, 0.601838} us Measuring clock cycles: first runs (cycles): 100059 28193 63263 30841 32399 29735 29436 30117 30785 112598 ... slowest runs (cycles): ... 41933 42708 61543 61670 63263 63293 63813 76354 100059 112598 min: 20192 cycles q1: 29708 cycles median: 30047 cycles q3: 30844 cycles max: 112598 cycles RESULT TestThreadWakeup_CycleCount: x86-64_with_irt_nnacl_newlib_static= {30047, 1136} count Apparent clock speed: 2057 MHz TestCatchingFault: Measuring real time: 2.998 usec (2.99837e-06 sec) per iteration: 0.000299837 sec for 100 iterations 1.930 usec (1.92971e-06 sec) per iteration: 0.0643578 sec for 33351 iterations 1.917 usec (1.91709e-06 sec) per iteration: 0.0639367 sec for 33351 iterations 1.920 usec (1.91998e-06 sec) per iteration: 0.0640331 sec for 33351 iterations 1.922 usec (1.92216e-06 sec) per iteration: 0.0641058 sec for 33351 iterations 1.926 usec (1.92567e-06 sec) per iteration: 0.064223 sec for 33351 iterations mean: 1.922920 usec stddev: 0.004404 usec relative stddev: 0.23% RESULT TestCatchingFault: x86-64_with_irt_nnacl_newlib_static= {1.922920, 0.004404} us Measuring clock cycles: first runs (cycles): 5509 4411 4255 4312 4228 4179 4118 4126 4166 4267 ... slowest runs (cycles): ... 4300 4312 4318 4329 4330 4331 4335 4411 4532 5509 min: 4101 cycles q1: 4174 cycles median: 4217 cycles q3: 4243 cycles max: 5509 cycles RESULT TestCatchingFault_CycleCount: x86-64_with_irt_nnacl_newlib_static= {4217, 69} count Apparent clock speed: 2193 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 47.661 usec (4.76612e-05 sec) per iteration: 0.00476612 sec for 100 iterations 35.874 usec (3.58745e-05 sec) per iteration: 0.0752646 sec for 2098 iterations 26.647 usec (2.66468e-05 sec) per iteration: 0.055905 sec for 2098 iterations 26.865 usec (2.68655e-05 sec) per iteration: 0.0563638 sec for 2098 iterations 28.584 usec (2.85841e-05 sec) per iteration: 0.0599694 sec for 2098 iterations 28.195 usec (2.81945e-05 sec) per iteration: 0.0591521 sec for 2098 iterations mean: 29.233071 usec stddev: 3.403002 usec relative stddev: 11.64% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_with_irt_nnacl_newlib_static= {29.233071, 3.403002} us Measuring clock cycles: first runs (cycles): 77806 80042 78457 80219 78789 85074 76565 55854 57072 81334 ... slowest runs (cycles): ... 103475 105431 105913 106861 124032 152725 167097 187932 189139 298774 min: 46654 cycles q1: 49988 cycles median: 52484 cycles q3: 79036 cycles max: 298774 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_with_irt_nnacl_newlib_static= {52484, 29048} count Apparent clock speed: 1795 MHz [ OK ] nacl_newlib.run_performance_test_irt (5820 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