Back to build
Raw log | Switch to full mode | Login

Running command: /b/s/w/ir/cache/vpython/b574a6/bin/python scons.py --verbose -k -j1 --mode=nacl,dbg-host,nacl_irt_test platform=x86-64 --clang breakpad_tools_dir=breakpad-out large_tests_irt scons: Reading SConscript files ... ====================================================================== SCONS ARGS:['scons.py', '--verbose', '-k', '-j1', '--mode=nacl,dbg-host,nacl_irt_test', 'platform=x86-64', '--clang', 'breakpad_tools_dir=breakpad-out', 'large_tests_irt'] ====================================================================== ====================================================================== Python Info: ====================================================================== linux2 2.7.14+chromium14 (df075345c7-dirty:df075345c7, Oct 3 2017, 17:29:00) [GCC 4.8.4] ====================================================================== ENV: ====================================================================== PATH /b/s/w/ir/cache/vpython/b574a6/bin:/b/s/w/ir/cipd_bin_packages:/b/s/w/ir/cipd_bin_packages/bin:/b/s/cipd_cache/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin:/b/s/w/ir/kitchen-checkout/depot_tools ====================================================================== OS: ====================================================================== Linux luci-flex-try-trusty-10-o8g7 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: 21624292 kB MemAvailable: 29459732 kB ====================================================================== LOAD: ====================================================================== 1.17 1.58 1.00 1/409 10676 ====================================================================== UPTIME: ====================================================================== 7770.47 60630.67 ====================================================================== 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 [10687,682162048:09:26:11.745538] Native Client module will be loaded at base address 0x0000408400000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_with_irt_nnacl_newlib_static= {0.105160, 0.012500} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (1680 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 (75040 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 [10715,2367965056:09:26:27.799423] Native Client module will be loaded at base address 0x000046ac00000000 TestNull: Measuring real time: 0.010 usec (9.84e-09 sec) per iteration: 9.84e-07 sec for 100 iterations 0.003 usec (2.598e-09 sec) per iteration: 2.598e-06 sec for 1000 iterations 0.002 usec (2.3103e-09 sec) per iteration: 2.3103e-05 sec for 10000 iterations 0.002 usec (1.90229e-09 sec) per iteration: 0.0823394 sec for 43284421 iterations 0.002 usec (1.89727e-09 sec) per iteration: 0.0821222 sec for 43284421 iterations 0.002 usec (1.89391e-09 sec) per iteration: 0.0819767 sec for 43284421 iterations 0.002 usec (1.89489e-09 sec) per iteration: 0.082019 sec for 43284421 iterations 0.002 usec (1.89432e-09 sec) per iteration: 0.0819944 sec for 43284421 iterations mean: 0.001897 usec stddev: 0.000003 usec relative stddev: 0.16% RESULT TestNull: x86-64_with_irt_nnacl_newlib_static= {0.001897, 0.000003} us Measuring clock cycles: first runs (cycles): 102 26 24 29 31 28 22 27 25 29 ... slowest runs (cycles): ... 29 29 31 31 31 31 35 37 38 102 min: 19 cycles q1: 26 cycles median: 27 cycles q3: 28 cycles max: 102 cycles RESULT TestNull_CycleCount: x86-64_with_irt_nnacl_newlib_static= {27, 2} count Apparent clock speed: 14237 MHz TestNaClSyscall: Measuring real time: 0.220 usec (2.1989e-07 sec) per iteration: 2.1989e-05 sec for 100 iterations 0.208 usec (2.08117e-07 sec) per iteration: 0.0946459 sec for 454772 iterations 0.210 usec (2.10361e-07 sec) per iteration: 0.0956662 sec for 454772 iterations 0.209 usec (2.09187e-07 sec) per iteration: 0.0951322 sec for 454772 iterations 0.210 usec (2.09582e-07 sec) per iteration: 0.0953121 sec for 454772 iterations 0.211 usec (2.11261e-07 sec) per iteration: 0.0960754 sec for 454772 iterations mean: 0.209701 usec stddev: 0.001063 usec relative stddev: 0.51% RESULT TestNaClSyscall: x86-64_with_irt_nnacl_newlib_static= {0.209701, 0.001063} us Measuring clock cycles: first runs (cycles): 641 537 412 412 389 378 519 407 429 391 ... slowest runs (cycles): ... 429 435 449 453 473 475 502 519 537 641 min: 371 cycles q1: 382 cycles median: 389 cycles q3: 398 cycles max: 641 cycles RESULT TestNaClSyscall_CycleCount: x86-64_with_irt_nnacl_newlib_static= {389, 16} count Apparent clock speed: 1855 MHz TestSetjmpLongjmp: Measuring real time: 0.021 usec (2.104e-08 sec) per iteration: 2.104e-06 sec for 100 iterations 0.015 usec (1.4562e-08 sec) per iteration: 1.4562e-05 sec for 1000 iterations 0.013 usec (1.28305e-08 sec) per iteration: 0.0881095 sec for 6867188 iterations 0.013 usec (1.28622e-08 sec) per iteration: 0.088327 sec for 6867188 iterations 0.013 usec (1.28392e-08 sec) per iteration: 0.088169 sec for 6867188 iterations 0.013 usec (1.28254e-08 sec) per iteration: 0.0880743 sec for 6867188 iterations 0.013 usec (1.2907e-08 sec) per iteration: 0.0886347 sec for 6867188 iterations mean: 0.012853 usec stddev: 0.000030 usec relative stddev: 0.23% RESULT TestSetjmpLongjmp: x86-64_with_irt_nnacl_newlib_static= {0.012853, 0.000030} us Measuring clock cycles: first runs (cycles): 220 66 37 35 35 51 42 47 44 44 ... slowest runs (cycles): ... 44 46 47 47 48 48 51 57 66 220 min: 29 cycles q1: 33 cycles median: 37 cycles q3: 42 cycles max: 220 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {37, 9} count Apparent clock speed: 2879 MHz TestClockGetTime: Measuring real time: 0.381 usec (3.815e-07 sec) per iteration: 3.815e-05 sec for 100 iterations 0.281 usec (2.8147e-07 sec) per iteration: 0.0737797 sec for 262123 iterations 0.280 usec (2.80044e-07 sec) per iteration: 0.0734059 sec for 262123 iterations 0.282 usec (2.82274e-07 sec) per iteration: 0.0739904 sec for 262123 iterations 0.281 usec (2.80595e-07 sec) per iteration: 0.0735503 sec for 262123 iterations 0.280 usec (2.80474e-07 sec) per iteration: 0.0735186 sec for 262123 iterations mean: 0.280971 usec stddev: 0.000799 usec relative stddev: 0.28% RESULT TestClockGetTime: x86-64_with_irt_nnacl_newlib_static= {0.280971, 0.000799} us Measuring clock cycles: first runs (cycles): 1234 792 810 763 741 708 759 695 770 779 ... slowest runs (cycles): ... 794 796 799 802 809 810 814 836 911 1234 min: 673 cycles q1: 706 cycles median: 732 cycles q3: 754 cycles max: 1234 cycles RESULT TestClockGetTime_CycleCount: x86-64_with_irt_nnacl_newlib_static= {732, 48} count Apparent clock speed: 2605 MHz TestTlsVariable: Measuring real time: 0.014 usec (1.427e-08 sec) per iteration: 1.427e-06 sec for 100 iterations 0.008 usec (7.955e-09 sec) per iteration: 7.955e-06 sec for 1000 iterations 0.010 usec (1.01717e-08 sec) per iteration: 0.000101717 sec for 10000 iterations 0.006 usec (5.69258e-09 sec) per iteration: 0.0559649 sec for 9831198 iterations 0.006 usec (5.72561e-09 sec) per iteration: 0.0562896 sec for 9831198 iterations 0.006 usec (5.78566e-09 sec) per iteration: 0.05688 sec for 9831198 iterations 0.006 usec (5.7117e-09 sec) per iteration: 0.0561528 sec for 9831198 iterations 0.006 usec (5.72719e-09 sec) per iteration: 0.0563052 sec for 9831198 iterations mean: 0.005729 usec stddev: 0.000031 usec relative stddev: 0.54% RESULT TestTlsVariable: x86-64_with_irt_nnacl_newlib_static= {0.005729, 0.000031} us Measuring clock cycles: first runs (cycles): 187 79 59 39 39 33 31 33 33 35 ... slowest runs (cycles): ... 38 39 39 42 42 44 48 59 79 187 min: 24 cycles q1: 30 cycles median: 31 cycles q3: 35 cycles max: 187 cycles RESULT TestTlsVariable_CycleCount: x86-64_with_irt_nnacl_newlib_static= {31, 5} count Apparent clock speed: 5411 MHz TestMmapAnonymous: Measuring real time: 3.620 usec (3.61955e-06 sec) per iteration: 0.000361955 sec for 100 iterations 2.731 usec (2.73107e-06 sec) per iteration: 0.0754512 sec for 27627 iterations 2.727 usec (2.72651e-06 sec) per iteration: 0.0753252 sec for 27627 iterations 2.720 usec (2.71976e-06 sec) per iteration: 0.0751387 sec for 27627 iterations 2.702 usec (2.70198e-06 sec) per iteration: 0.0746475 sec for 27627 iterations 2.703 usec (2.70337e-06 sec) per iteration: 0.0746859 sec for 27627 iterations mean: 2.716535 usec stddev: 0.011886 usec relative stddev: 0.44% RESULT TestMmapAnonymous: x86-64_with_irt_nnacl_newlib_static= {2.716535, 0.011886} us Measuring clock cycles: first runs (cycles): 12963 25606 8405 7695 8293 7874 7785 7605 7976 8718 ... slowest runs (cycles): ... 9433 9455 9637 9659 9812 10623 10704 10757 12963 25606 min: 5715 cycles q1: 5841 cycles median: 7976 cycles q3: 8729 cycles max: 25606 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_with_irt_nnacl_newlib_static= {7976, 2888} count Apparent clock speed: 2936 MHz TestAtomicIncrement: Measuring real time: 0.020 usec (1.96e-08 sec) per iteration: 1.96e-06 sec for 100 iterations 0.011 usec (1.0773e-08 sec) per iteration: 1.0773e-05 sec for 1000 iterations 0.010 usec (9.61014e-09 sec) per iteration: 0.0892058 sec for 9282465 iterations 0.009 usec (9.48112e-09 sec) per iteration: 0.0880081 sec for 9282465 iterations 0.009 usec (9.47647e-09 sec) per iteration: 0.087965 sec for 9282465 iterations 0.009 usec (9.4831e-09 sec) per iteration: 0.0880265 sec for 9282465 iterations 0.009 usec (9.44453e-09 sec) per iteration: 0.0876686 sec for 9282465 iterations mean: 0.009499 usec stddev: 0.000057 usec relative stddev: 0.60% RESULT TestAtomicIncrement: x86-64_with_irt_nnacl_newlib_static= {0.009499, 0.000057} us Measuring clock cycles: first runs (cycles): 119 53 57 53 58 55 51 52 53 59 ... slowest runs (cycles): ... 55 55 55 55 55 57 58 59 59 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: 5579 MHz TestUncontendedMutexLock: Measuring real time: 0.034 usec (3.438e-08 sec) per iteration: 3.438e-06 sec for 100 iterations 0.027 usec (2.6975e-08 sec) per iteration: 2.6975e-05 sec for 1000 iterations 0.025 usec (2.46511e-08 sec) per iteration: 0.091385 sec for 3707136 iterations 0.025 usec (2.45696e-08 sec) per iteration: 0.0910828 sec for 3707136 iterations 0.025 usec (2.46575e-08 sec) per iteration: 0.0914089 sec for 3707136 iterations 0.025 usec (2.45785e-08 sec) per iteration: 0.0911159 sec for 3707136 iterations 0.025 usec (2.45757e-08 sec) per iteration: 0.0911056 sec for 3707136 iterations mean: 0.024606 usec stddev: 0.000039 usec relative stddev: 0.16% RESULT TestUncontendedMutexLock: x86-64_with_irt_nnacl_newlib_static= {0.024606, 0.000039} us Measuring clock cycles: first runs (cycles): 253 108 84 85 86 84 84 86 85 86 ... slowest runs (cycles): ... 88 88 90 90 93 93 99 99 108 253 min: 82 cycles q1: 84 cycles median: 86 cycles q3: 86 cycles max: 253 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_with_irt_nnacl_newlib_static= {86, 2} count Apparent clock speed: 3495 MHz TestCondvarSignalNoOp: Measuring real time: 0.418 usec (4.1798e-07 sec) per iteration: 4.1798e-05 sec for 100 iterations 0.321 usec (3.20573e-07 sec) per iteration: 0.0766954 sec for 239245 iterations 0.310 usec (3.09705e-07 sec) per iteration: 0.0740954 sec for 239245 iterations 0.309 usec (3.08698e-07 sec) per iteration: 0.0738545 sec for 239245 iterations 0.307 usec (3.06999e-07 sec) per iteration: 0.073448 sec for 239245 iterations 0.306 usec (3.05803e-07 sec) per iteration: 0.0731618 sec for 239245 iterations mean: 0.310356 usec stddev: 0.005283 usec relative stddev: 1.70% RESULT TestCondvarSignalNoOp: x86-64_with_irt_nnacl_newlib_static= {0.310356, 0.005283} us Measuring clock cycles: first runs (cycles): 1110 830 784 755 788 823 821 783 818 728 ... slowest runs (cycles): ... 834 838 841 843 849 860 860 911 1007 1110 min: 706 cycles q1: 728 cycles median: 755 cycles q3: 799 cycles max: 1110 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {755, 71} count Apparent clock speed: 2433 MHz TestThreadCreateAndJoin: Measuring real time: 36.091 usec (3.60907e-05 sec) per iteration: 0.00360907 sec for 100 iterations 30.643 usec (3.06429e-05 sec) per iteration: 0.0848809 sec for 2770 iterations 29.753 usec (2.97531e-05 sec) per iteration: 0.0824162 sec for 2770 iterations 28.092 usec (2.80922e-05 sec) per iteration: 0.0778154 sec for 2770 iterations 33.791 usec (3.37905e-05 sec) per iteration: 0.0935998 sec for 2770 iterations 31.636 usec (3.16363e-05 sec) per iteration: 0.0876326 sec for 2770 iterations mean: 30.783016 usec stddev: 1.902317 usec relative stddev: 6.18% RESULT TestThreadCreateAndJoin: x86-64_with_irt_nnacl_newlib_static= {30.783016, 1.902317} us Measuring clock cycles: first runs (cycles): 67330 99197 92559 114837 63335 72365 110668 63052 55581 96576 ... slowest runs (cycles): ... 99197 99678 100751 106157 106214 110668 114837 143177 147773 211576 min: 48236 cycles q1: 54028 cycles median: 56225 cycles q3: 72365 cycles max: 211576 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_with_irt_nnacl_newlib_static= {56225, 18337} count Apparent clock speed: 1826 MHz TestThreadWakeup: Measuring real time: 12.782 usec (1.27819e-05 sec) per iteration: 0.00127819 sec for 100 iterations 14.847 usec (1.48471e-05 sec) per iteration: 0.116149 sec for 7823 iterations 15.477 usec (1.5477e-05 sec) per iteration: 0.121077 sec for 7823 iterations 17.775 usec (1.7775e-05 sec) per iteration: 0.139054 sec for 7823 iterations 15.381 usec (1.53812e-05 sec) per iteration: 0.120327 sec for 7823 iterations 15.400 usec (1.53997e-05 sec) per iteration: 0.120472 sec for 7823 iterations mean: 15.776019 usec stddev: 1.024270 usec relative stddev: 6.49% RESULT TestThreadWakeup: x86-64_with_irt_nnacl_newlib_static= {15.776019, 1.024270} us Measuring clock cycles: first runs (cycles): 31622 28895 29082 30620 36429 30427 30382 29652 29026 29449 ... slowest runs (cycles): ... 35211 35267 35764 36429 36549 37887 39485 39515 47833 48427 min: 26149 cycles q1: 32355 cycles median: 32782 cycles q3: 33407 cycles max: 48427 cycles RESULT TestThreadWakeup_CycleCount: x86-64_with_irt_nnacl_newlib_static= {32782, 1052} count Apparent clock speed: 2078 MHz TestCatchingFault: Measuring real time: 2.945 usec (2.94469e-06 sec) per iteration: 0.000294469 sec for 100 iterations 1.934 usec (1.93439e-06 sec) per iteration: 0.06569 sec for 33959 iterations 1.940 usec (1.94041e-06 sec) per iteration: 0.0658945 sec for 33959 iterations 1.936 usec (1.93583e-06 sec) per iteration: 0.0657387 sec for 33959 iterations 1.946 usec (1.94639e-06 sec) per iteration: 0.0660975 sec for 33959 iterations 1.941 usec (1.94123e-06 sec) per iteration: 0.0659221 sec for 33959 iterations mean: 1.939649 usec stddev: 0.004261 usec relative stddev: 0.22% RESULT TestCatchingFault: x86-64_with_irt_nnacl_newlib_static= {1.939649, 0.004261} us Measuring clock cycles: first runs (cycles): 7513 5295 4991 5117 5480 4786 4712 4705 4591 4650 ... slowest runs (cycles): ... 5510 5533 5696 5752 5803 5860 5919 5970 6004 7513 min: 4591 cycles q1: 4857 cycles median: 5009 cycles q3: 5220 cycles max: 7513 cycles RESULT TestCatchingFault_CycleCount: x86-64_with_irt_nnacl_newlib_static= {5009, 363} count Apparent clock speed: 2582 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 33.354 usec (3.33542e-05 sec) per iteration: 0.00333542 sec for 100 iterations 34.843 usec (3.48429e-05 sec) per iteration: 0.104459 sec for 2998 iterations 32.449 usec (3.24493e-05 sec) per iteration: 0.097283 sec for 2998 iterations 33.434 usec (3.34342e-05 sec) per iteration: 0.100236 sec for 2998 iterations 35.436 usec (3.54357e-05 sec) per iteration: 0.106236 sec for 2998 iterations 33.946 usec (3.39464e-05 sec) per iteration: 0.101771 sec for 2998 iterations mean: 34.021692 usec stddev: 1.048517 usec relative stddev: 3.08% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_with_irt_nnacl_newlib_static= {34.021692, 1.048517} us Measuring clock cycles: first runs (cycles): 96913 102962 109951 64285 96779 95965 84264 59538 53929 61438 ... slowest runs (cycles): ... 89884 90857 92386 94191 95965 96779 96913 102962 103107 109951 min: 50936 cycles q1: 54326 cycles median: 58319 cycles q3: 63115 cycles max: 109951 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_with_irt_nnacl_newlib_static= {58319, 8789} count Apparent clock speed: 1714 MHz [ OK ] nacl_newlib.run_performance_test_irt (5670 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