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,opt-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,opt-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-0px6 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: 15361152 kB MemAvailable: 29345032 kB ====================================================================== LOAD: ====================================================================== 1.04 1.86 1.71 1/445 12989 ====================================================================== UPTIME: ====================================================================== 40784.91 315072.96 ====================================================================== 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: opt-linux TARGET_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-linux-x86-64 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-linux-x86-64/obj BUILD_TYPE_DESCRIPTION: Linux opt 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 -O2 -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 -DNDEBUG -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/opt-linux-x86-64/staging/nacl_helper_bootstrap scons-out/opt-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/opt-linux-x86-64/staging/nacl_helper_bootstrap scons-out/opt-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 [13255,4025157504:10:46:13.819291] Native Client module will be loaded at base address 0x0000491500000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_with_irt_nnacl_newlib_static= {0.090200, 0.001910} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (1280 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/opt-linux-x86-64/staging/nacl_helper_bootstrap scons-out/opt-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/opt-linux-x86-64/staging/nacl_helper_bootstrap scons-out/opt-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 (70090 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/opt-linux-x86-64/staging/nacl_helper_bootstrap scons-out/opt-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/opt-linux-x86-64/staging/nacl_helper_bootstrap scons-out/opt-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 [13287,58709888:10:46:28.528248] Native Client module will be loaded at base address 0x00005f3f00000000 TestNull: Measuring real time: 0.005 usec (5.48e-09 sec) per iteration: 5.48e-07 sec for 100 iterations 0.002 usec (1.985e-09 sec) per iteration: 1.985e-06 sec for 1000 iterations 0.002 usec (1.8151e-09 sec) per iteration: 1.8151e-05 sec for 10000 iterations 0.002 usec (1.82973e-09 sec) per iteration: 0.100806 sec for 55093383 iterations 0.002 usec (1.84322e-09 sec) per iteration: 0.101549 sec for 55093383 iterations 0.002 usec (1.83824e-09 sec) per iteration: 0.101275 sec for 55093383 iterations 0.002 usec (1.82602e-09 sec) per iteration: 0.100602 sec for 55093383 iterations 0.002 usec (1.82898e-09 sec) per iteration: 0.100765 sec for 55093383 iterations mean: 0.001833 usec stddev: 0.000006 usec relative stddev: 0.35% RESULT TestNull: x86-64_with_irt_nnacl_newlib_static= {0.001833, 0.000006} us Measuring clock cycles: first runs (cycles): 116 19 18 19 19 19 18 19 19 19 ... slowest runs (cycles): ... 19 19 19 19 19 22 22 22 22 116 min: 18 cycles q1: 19 cycles median: 19 cycles q3: 19 cycles max: 116 cycles RESULT TestNull_CycleCount: x86-64_with_irt_nnacl_newlib_static= {19, 0} count Apparent clock speed: 10364 MHz TestNaClSyscall: Measuring real time: 0.134 usec (1.338e-07 sec) per iteration: 1.338e-05 sec for 100 iterations 0.128 usec (1.28104e-07 sec) per iteration: 0.0957429 sec for 747384 iterations 0.128 usec (1.27539e-07 sec) per iteration: 0.0953207 sec for 747384 iterations 0.126 usec (1.26415e-07 sec) per iteration: 0.0944806 sec for 747384 iterations 0.128 usec (1.2816e-07 sec) per iteration: 0.0957845 sec for 747384 iterations 0.127 usec (1.27231e-07 sec) per iteration: 0.0950906 sec for 747384 iterations mean: 0.127490 usec stddev: 0.000640 usec relative stddev: 0.50% RESULT TestNaClSyscall: x86-64_with_irt_nnacl_newlib_static= {0.127490, 0.000640} us Measuring clock cycles: first runs (cycles): 257 213 173 173 172 173 173 173 172 173 ... slowest runs (cycles): ... 173 173 173 173 173 173 176 179 213 257 min: 170 cycles q1: 173 cycles median: 173 cycles q3: 173 cycles max: 257 cycles RESULT TestNaClSyscall_CycleCount: x86-64_with_irt_nnacl_newlib_static= {173, 0} count Apparent clock speed: 1357 MHz TestSetjmpLongjmp: Measuring real time: 0.016 usec (1.56e-08 sec) per iteration: 1.56e-06 sec for 100 iterations 0.012 usec (1.2395e-08 sec) per iteration: 1.2395e-05 sec for 1000 iterations 0.012 usec (1.24425e-08 sec) per iteration: 0.100383 sec for 8067769 iterations 0.013 usec (1.25051e-08 sec) per iteration: 0.100889 sec for 8067769 iterations 0.012 usec (1.24776e-08 sec) per iteration: 0.100667 sec for 8067769 iterations 0.013 usec (1.25136e-08 sec) per iteration: 0.100957 sec for 8067769 iterations 0.012 usec (1.24792e-08 sec) per iteration: 0.100679 sec for 8067769 iterations mean: 0.012484 usec stddev: 0.000025 usec relative stddev: 0.20% RESULT TestSetjmpLongjmp: x86-64_with_irt_nnacl_newlib_static= {0.012484, 0.000025} us Measuring clock cycles: first runs (cycles): 358 54 31 25 25 22 28 28 26 25 ... slowest runs (cycles): ... 26 26 26 26 28 28 29 31 54 358 min: 22 cycles q1: 22 cycles median: 25 cycles q3: 25 cycles max: 358 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {25, 3} count Apparent clock speed: 2003 MHz TestClockGetTime: Measuring real time: 0.143 usec (1.4309e-07 sec) per iteration: 1.4309e-05 sec for 100 iterations 0.147 usec (1.47368e-07 sec) per iteration: 0.10299 sec for 698860 iterations 0.146 usec (1.4597e-07 sec) per iteration: 0.102012 sec for 698860 iterations 0.145 usec (1.45161e-07 sec) per iteration: 0.101447 sec for 698860 iterations 0.146 usec (1.45739e-07 sec) per iteration: 0.101851 sec for 698860 iterations 0.177 usec (1.77086e-07 sec) per iteration: 0.123758 sec for 698860 iterations mean: 0.152265 usec stddev: 0.012432 usec relative stddev: 8.16% RESULT TestClockGetTime: x86-64_with_irt_nnacl_newlib_static= {0.152265, 0.012432} us Measuring clock cycles: first runs (cycles): 1732 562 543 568 522 531 534 525 544 547 ... slowest runs (cycles): ... 576 578 578 578 581 582 582 584 591 1732 min: 506 cycles q1: 544 cycles median: 560 cycles q3: 569 cycles max: 1732 cycles RESULT TestClockGetTime_CycleCount: x86-64_with_irt_nnacl_newlib_static= {560, 25} count Apparent clock speed: 3678 MHz TestTlsVariable: Measuring real time: 0.016 usec (1.603e-08 sec) per iteration: 1.603e-06 sec for 100 iterations 0.012 usec (1.1696e-08 sec) per iteration: 1.1696e-05 sec for 1000 iterations 0.007 usec (6.7804e-09 sec) per iteration: 0.057972 sec for 8549931 iterations 0.006 usec (5.85403e-09 sec) per iteration: 0.0500516 sec for 8549931 iterations 0.006 usec (5.84247e-09 sec) per iteration: 0.0499527 sec for 8549931 iterations 0.006 usec (5.86235e-09 sec) per iteration: 0.0501227 sec for 8549931 iterations 0.006 usec (5.91659e-09 sec) per iteration: 0.0505864 sec for 8549931 iterations mean: 0.006051 usec stddev: 0.000366 usec relative stddev: 6.04% RESULT TestTlsVariable: x86-64_with_irt_nnacl_newlib_static= {0.006051, 0.000366} us Measuring clock cycles: first runs (cycles): 142 63 40 22 18 22 18 22 19 22 ... slowest runs (cycles): ... 22 22 22 22 22 22 22 40 63 142 min: 18 cycles q1: 19 cycles median: 19 cycles q3: 22 cycles max: 142 cycles RESULT TestTlsVariable_CycleCount: x86-64_with_irt_nnacl_newlib_static= {19, 3} count Apparent clock speed: 3140 MHz TestMmapAnonymous: Measuring real time: 2.277 usec (2.27671e-06 sec) per iteration: 0.000227671 sec for 100 iterations 1.982 usec (1.98212e-06 sec) per iteration: 0.0870608 sec for 43923 iterations 1.954 usec (1.95391e-06 sec) per iteration: 0.0858217 sec for 43923 iterations 1.951 usec (1.95126e-06 sec) per iteration: 0.0857054 sec for 43923 iterations 1.965 usec (1.96499e-06 sec) per iteration: 0.0863082 sec for 43923 iterations 1.968 usec (1.96834e-06 sec) per iteration: 0.0864553 sec for 43923 iterations mean: 1.964125 usec stddev: 0.011065 usec relative stddev: 0.56% RESULT TestMmapAnonymous: x86-64_with_irt_nnacl_newlib_static= {1.964125, 0.011065} us Measuring clock cycles: first runs (cycles): 8206 4253 4268 4165 4082 4076 4061 4054 4076 4086 ... slowest runs (cycles): ... 4167 4167 4168 4180 4190 4193 4195 4253 4268 8206 min: 4023 cycles q1: 4067 cycles median: 4080 cycles q3: 4101 cycles max: 8206 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_with_irt_nnacl_newlib_static= {4080, 34} count Apparent clock speed: 2077 MHz TestAtomicIncrement: Measuring real time: 0.013 usec (1.258e-08 sec) per iteration: 1.258e-06 sec for 100 iterations 0.009 usec (9.451e-09 sec) per iteration: 9.451e-06 sec for 1000 iterations 0.009 usec (9.3081e-09 sec) per iteration: 9.3081e-05 sec for 10000 iterations 0.009 usec (9.44751e-09 sec) per iteration: 0.101498 sec for 10743331 iterations 0.009 usec (9.4165e-09 sec) per iteration: 0.101165 sec for 10743331 iterations 0.010 usec (9.52236e-09 sec) per iteration: 0.102302 sec for 10743331 iterations 0.009 usec (9.48259e-09 sec) per iteration: 0.101875 sec for 10743331 iterations 0.010 usec (9.52791e-09 sec) per iteration: 0.102361 sec for 10743331 iterations mean: 0.009479 usec stddev: 0.000043 usec relative stddev: 0.45% RESULT TestAtomicIncrement: x86-64_with_irt_nnacl_newlib_static= {0.009479, 0.000043} us Measuring clock cycles: first runs (cycles): 122 50 47 47 47 47 47 47 47 47 ... slowest runs (cycles): ... 47 47 47 47 47 47 47 47 50 122 min: 44 cycles q1: 44 cycles median: 44 cycles q3: 47 cycles max: 122 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_with_irt_nnacl_newlib_static= {44, 3} count Apparent clock speed: 4642 MHz TestUncontendedMutexLock: Measuring real time: 0.027 usec (2.673e-08 sec) per iteration: 2.673e-06 sec for 100 iterations 0.023 usec (2.3384e-08 sec) per iteration: 2.3384e-05 sec for 1000 iterations 0.024 usec (2.38128e-08 sec) per iteration: 0.101834 sec for 4276428 iterations 0.024 usec (2.35931e-08 sec) per iteration: 0.100894 sec for 4276428 iterations 0.024 usec (2.35802e-08 sec) per iteration: 0.100839 sec for 4276428 iterations 0.024 usec (2.35832e-08 sec) per iteration: 0.100852 sec for 4276428 iterations 0.024 usec (2.36634e-08 sec) per iteration: 0.101195 sec for 4276428 iterations mean: 0.023647 usec stddev: 0.000089 usec relative stddev: 0.37% RESULT TestUncontendedMutexLock: x86-64_with_irt_nnacl_newlib_static= {0.023647, 0.000089} us Measuring clock cycles: first runs (cycles): 355 100 75 76 75 75 76 76 75 75 ... slowest runs (cycles): ... 76 76 76 76 76 76 79 82 100 355 min: 72 cycles q1: 75 cycles median: 75 cycles q3: 76 cycles max: 355 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_with_irt_nnacl_newlib_static= {75, 1} count Apparent clock speed: 3172 MHz TestCondvarSignalNoOp: Measuring real time: 0.223 usec (2.2327e-07 sec) per iteration: 2.2327e-05 sec for 100 iterations 0.205 usec (2.04537e-07 sec) per iteration: 0.0916096 sec for 447888 iterations 0.204 usec (2.03544e-07 sec) per iteration: 0.0911648 sec for 447888 iterations 0.203 usec (2.03377e-07 sec) per iteration: 0.0910902 sec for 447888 iterations 0.204 usec (2.04227e-07 sec) per iteration: 0.0914707 sec for 447888 iterations 0.204 usec (2.04485e-07 sec) per iteration: 0.0915862 sec for 447888 iterations mean: 0.204034 usec stddev: 0.000483 usec relative stddev: 0.24% RESULT TestCondvarSignalNoOp: x86-64_with_irt_nnacl_newlib_static= {0.204034, 0.000483} us Measuring clock cycles: first runs (cycles): 930 487 478 447 452 449 453 452 449 453 ... slowest runs (cycles): ... 453 455 456 456 456 459 478 487 562 930 min: 447 cycles q1: 452 cycles median: 453 cycles q3: 453 cycles max: 930 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {453, 1} count Apparent clock speed: 2220 MHz TestThreadCreateAndJoin: Measuring real time: 34.067 usec (3.40674e-05 sec) per iteration: 0.00340674 sec for 100 iterations 28.219 usec (2.82193e-05 sec) per iteration: 0.0828236 sec for 2935 iterations 26.816 usec (2.68161e-05 sec) per iteration: 0.0787053 sec for 2935 iterations 29.287 usec (2.92872e-05 sec) per iteration: 0.0859579 sec for 2935 iterations 25.528 usec (2.5528e-05 sec) per iteration: 0.0749248 sec for 2935 iterations 24.548 usec (2.45484e-05 sec) per iteration: 0.0720495 sec for 2935 iterations mean: 26.879801 usec stddev: 1.723749 usec relative stddev: 6.41% RESULT TestThreadCreateAndJoin: x86-64_with_irt_nnacl_newlib_static= {26.879801, 1.723749} us Measuring clock cycles: first runs (cycles): 57860 51439 55852 52389 51847 57540 90338 188951 178272 125922 ... slowest runs (cycles): ... 67125 67270 74284 79765 90338 108026 108787 125922 178272 188951 min: 48755 cycles q1: 49877 cycles median: 50864 cycles q3: 53526 cycles max: 188951 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_with_irt_nnacl_newlib_static= {50864, 3649} count Apparent clock speed: 1892 MHz TestThreadWakeup: Measuring real time: 12.542 usec (1.25421e-05 sec) per iteration: 0.00125421 sec for 100 iterations 13.912 usec (1.39122e-05 sec) per iteration: 0.110922 sec for 7973 iterations 14.574 usec (1.45738e-05 sec) per iteration: 0.116197 sec for 7973 iterations 14.533 usec (1.45335e-05 sec) per iteration: 0.115876 sec for 7973 iterations 19.145 usec (1.91453e-05 sec) per iteration: 0.152645 sec for 7973 iterations 19.979 usec (1.9979e-05 sec) per iteration: 0.159292 sec for 7973 iterations mean: 16.428750 usec stddev: 2.582613 usec relative stddev: 15.72% RESULT TestThreadWakeup: x86-64_with_irt_nnacl_newlib_static= {16.428750, 2.582613} us Measuring clock cycles: first runs (cycles): 45669 42387 40933 42117 55298 44013 40602 41618 40778 41165 ... slowest runs (cycles): ... 49971 50223 55298 59858 60132 71946 72993 76874 228573 269264 min: 36989 cycles q1: 41093 cycles median: 41772 cycles q3: 45002 cycles max: 269264 cycles RESULT TestThreadWakeup_CycleCount: x86-64_with_irt_nnacl_newlib_static= {41772, 3909} count Apparent clock speed: 2543 MHz TestCatchingFault: Measuring real time: 2.536 usec (2.53603e-06 sec) per iteration: 0.000253603 sec for 100 iterations 1.589 usec (1.5891e-06 sec) per iteration: 0.0626597 sec for 39431 iterations 1.578 usec (1.57846e-06 sec) per iteration: 0.0622401 sec for 39431 iterations 1.576 usec (1.57595e-06 sec) per iteration: 0.0621411 sec for 39431 iterations 1.575 usec (1.57531e-06 sec) per iteration: 0.0621162 sec for 39431 iterations 1.579 usec (1.57902e-06 sec) per iteration: 0.0622624 sec for 39431 iterations mean: 1.579567 usec stddev: 0.004971 usec relative stddev: 0.31% RESULT TestCatchingFault: x86-64_with_irt_nnacl_newlib_static= {1.579567, 0.004971} us Measuring clock cycles: first runs (cycles): 5349 3476 3445 3436 3425 3426 3416 3432 3432 3425 ... slowest runs (cycles): ... 3442 3444 3444 3445 3445 3445 3448 3451 3476 5349 min: 3401 cycles q1: 3422 cycles median: 3426 cycles q3: 3435 cycles max: 5349 cycles RESULT TestCatchingFault_CycleCount: x86-64_with_irt_nnacl_newlib_static= {3426, 13} count Apparent clock speed: 2169 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 40.370 usec (4.037e-05 sec) per iteration: 0.004037 sec for 100 iterations 35.511 usec (3.5511e-05 sec) per iteration: 0.0879607 sec for 2477 iterations 35.045 usec (3.50453e-05 sec) per iteration: 0.0868073 sec for 2477 iterations 35.534 usec (3.55341e-05 sec) per iteration: 0.0880178 sec for 2477 iterations 35.474 usec (3.54737e-05 sec) per iteration: 0.0878684 sec for 2477 iterations 35.789 usec (3.57885e-05 sec) per iteration: 0.0886482 sec for 2477 iterations mean: 35.470530 usec stddev: 0.239835 usec relative stddev: 0.68% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_with_irt_nnacl_newlib_static= {35.470530, 0.239835} us Measuring clock cycles: first runs (cycles): 113507 67182 71883 81378 71660 70344 71996 70868 66462 88805 ... slowest runs (cycles): ... 94697 95555 97975 113507 115613 117665 119734 120001 124972 207347 min: 63521 cycles q1: 68722 cycles median: 70441 cycles q3: 77823 cycles max: 207347 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_with_irt_nnacl_newlib_static= {70441, 9101} count Apparent clock speed: 1986 MHz [ OK ] nacl_newlib.run_performance_test_irt (6170 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