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-vj9b 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: 18284740 kB MemAvailable: 29431124 kB ====================================================================== LOAD: ====================================================================== 1.01 1.81 1.81 1/442 22375 ====================================================================== UPTIME: ====================================================================== 18179.91 136880.20 ====================================================================== 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 [22383,1374132096:21:04:42.929770] Native Client module will be loaded at base address 0x000072d700000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_with_irt_nnacl_newlib_static= {0.110820, 0.003120} 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/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 (74730 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 [22411,4172441472:21:04:58.689137] Native Client module will be loaded at base address 0x00003b3b00000000 TestNull: Measuring real time: 0.006 usec (6.04e-09 sec) per iteration: 6.04e-07 sec for 100 iterations 0.002 usec (2.06e-09 sec) per iteration: 2.06e-06 sec for 1000 iterations 0.002 usec (1.8755e-09 sec) per iteration: 1.8755e-05 sec for 10000 iterations 0.002 usec (1.88872e-09 sec) per iteration: 0.100705 sec for 53319114 iterations 0.002 usec (1.89819e-09 sec) per iteration: 0.10121 sec for 53319114 iterations 0.002 usec (1.89441e-09 sec) per iteration: 0.101008 sec for 53319114 iterations 0.002 usec (1.88554e-09 sec) per iteration: 0.100535 sec for 53319114 iterations 0.002 usec (1.89939e-09 sec) per iteration: 0.101274 sec for 53319114 iterations mean: 0.001893 usec stddev: 0.000005 usec relative stddev: 0.28% RESULT TestNull: x86-64_with_irt_nnacl_newlib_static= {0.001893, 0.000005} us Measuring clock cycles: first runs (cycles): 99 22 27 25 24 24 24 26 22 22 ... slowest runs (cycles): ... 27 27 27 27 27 27 28 28 29 99 min: 22 cycles q1: 24 cycles median: 24 cycles q3: 26 cycles max: 99 cycles RESULT TestNull_CycleCount: x86-64_with_irt_nnacl_newlib_static= {24, 2} count Apparent clock speed: 12677 MHz TestNaClSyscall: Measuring real time: 0.144 usec (1.442e-07 sec) per iteration: 1.442e-05 sec for 100 iterations 0.126 usec (1.26027e-07 sec) per iteration: 0.0873973 sec for 693481 iterations 0.124 usec (1.23731e-07 sec) per iteration: 0.0858049 sec for 693481 iterations 0.126 usec (1.26156e-07 sec) per iteration: 0.0874864 sec for 693481 iterations 0.123 usec (1.2287e-07 sec) per iteration: 0.0852078 sec for 693481 iterations 0.119 usec (1.19336e-07 sec) per iteration: 0.0827571 sec for 693481 iterations mean: 0.123624 usec stddev: 0.002496 usec relative stddev: 2.02% RESULT TestNaClSyscall: x86-64_with_irt_nnacl_newlib_static= {0.123624, 0.002496} us Measuring clock cycles: first runs (cycles): 302 245 206 179 179 218 216 215 178 181 ... slowest runs (cycles): ... 183 183 183 206 215 215 216 218 245 302 min: 178 cycles q1: 180 cycles median: 180 cycles q3: 181 cycles max: 302 cycles RESULT TestNaClSyscall_CycleCount: x86-64_with_irt_nnacl_newlib_static= {180, 1} count Apparent clock speed: 1456 MHz TestSetjmpLongjmp: Measuring real time: 0.016 usec (1.602e-08 sec) per iteration: 1.602e-06 sec for 100 iterations 0.013 usec (1.2754e-08 sec) per iteration: 1.2754e-05 sec for 1000 iterations 0.013 usec (1.28281e-08 sec) per iteration: 0.100581 sec for 7840677 iterations 0.013 usec (1.28607e-08 sec) per iteration: 0.100836 sec for 7840677 iterations 0.013 usec (1.29986e-08 sec) per iteration: 0.101918 sec for 7840677 iterations 0.013 usec (1.29196e-08 sec) per iteration: 0.101298 sec for 7840677 iterations 0.013 usec (1.28593e-08 sec) per iteration: 0.100825 sec for 7840677 iterations mean: 0.012893 usec stddev: 0.000060 usec relative stddev: 0.47% RESULT TestSetjmpLongjmp: x86-64_with_irt_nnacl_newlib_static= {0.012893, 0.000060} us Measuring clock cycles: first runs (cycles): 165 53 53 40 37 33 33 35 33 29 ... slowest runs (cycles): ... 31 33 33 33 35 37 40 53 53 165 min: 24 cycles q1: 27 cycles median: 28 cycles q3: 29 cycles max: 165 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {28, 2} count Apparent clock speed: 2172 MHz TestClockGetTime: Measuring real time: 0.150 usec (1.4974e-07 sec) per iteration: 1.4974e-05 sec for 100 iterations 0.156 usec (1.56259e-07 sec) per iteration: 0.104354 sec for 667824 iterations 0.156 usec (1.55909e-07 sec) per iteration: 0.10412 sec for 667824 iterations 0.153 usec (1.53289e-07 sec) per iteration: 0.10237 sec for 667824 iterations 0.155 usec (1.54853e-07 sec) per iteration: 0.103415 sec for 667824 iterations 0.156 usec (1.56171e-07 sec) per iteration: 0.104295 sec for 667824 iterations mean: 0.155296 usec stddev: 0.001122 usec relative stddev: 0.72% RESULT TestClockGetTime: x86-64_with_irt_nnacl_newlib_static= {0.155296, 0.001122} us Measuring clock cycles: first runs (cycles): 601 387 370 343 345 339 339 343 334 341 ... slowest runs (cycles): ... 343 343 343 343 343 345 345 370 387 601 min: 334 cycles q1: 337 cycles median: 337 cycles q3: 339 cycles max: 601 cycles RESULT TestClockGetTime_CycleCount: x86-64_with_irt_nnacl_newlib_static= {337, 2} count Apparent clock speed: 2170 MHz TestTlsVariable: Measuring real time: 0.009 usec (9.4e-09 sec) per iteration: 9.4e-07 sec for 100 iterations 0.006 usec (5.789e-09 sec) per iteration: 5.789e-06 sec for 1000 iterations 0.007 usec (6.7874e-09 sec) per iteration: 6.7874e-05 sec for 10000 iterations 0.006 usec (5.64051e-09 sec) per iteration: 0.0831027 sec for 14733182 iterations 0.006 usec (5.65125e-09 sec) per iteration: 0.083261 sec for 14733182 iterations 0.006 usec (5.66628e-09 sec) per iteration: 0.0834823 sec for 14733182 iterations 0.006 usec (5.6473e-09 sec) per iteration: 0.0832027 sec for 14733182 iterations 0.006 usec (5.63802e-09 sec) per iteration: 0.083066 sec for 14733182 iterations mean: 0.005649 usec stddev: 0.000010 usec relative stddev: 0.18% RESULT TestTlsVariable: x86-64_with_irt_nnacl_newlib_static= {0.005649, 0.000010} us Measuring clock cycles: first runs (cycles): 160 75 51 31 29 31 33 29 31 27 ... slowest runs (cycles): ... 35 35 35 35 36 36 36 51 75 160 min: 26 cycles q1: 28 cycles median: 30 cycles q3: 33 cycles max: 160 cycles RESULT TestTlsVariable_CycleCount: x86-64_with_irt_nnacl_newlib_static= {30, 5} count Apparent clock speed: 5311 MHz TestMmapAnonymous: Measuring real time: 2.309 usec (2.30864e-06 sec) per iteration: 0.000230864 sec for 100 iterations 1.956 usec (1.95563e-06 sec) per iteration: 0.084708 sec for 43315 iterations 1.934 usec (1.93393e-06 sec) per iteration: 0.083768 sec for 43315 iterations 1.953 usec (1.95317e-06 sec) per iteration: 0.0846016 sec for 43315 iterations 1.934 usec (1.93443e-06 sec) per iteration: 0.0837897 sec for 43315 iterations 1.939 usec (1.93875e-06 sec) per iteration: 0.0839772 sec for 43315 iterations mean: 1.943181 usec stddev: 0.009345 usec relative stddev: 0.48% RESULT TestMmapAnonymous: x86-64_with_irt_nnacl_newlib_static= {1.943181, 0.009345} us Measuring clock cycles: first runs (cycles): 5759 4364 4323 4215 4245 4244 4265 4250 4278 4237 ... slowest runs (cycles): ... 4278 4278 4281 4305 4307 4309 4323 4364 4481 5759 min: 4173 cycles q1: 4217 cycles median: 4232 cycles q3: 4245 cycles max: 5759 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_with_irt_nnacl_newlib_static= {4232, 28} count Apparent clock speed: 2178 MHz TestAtomicIncrement: Measuring real time: 0.015 usec (1.461e-08 sec) per iteration: 1.461e-06 sec for 100 iterations 0.009 usec (9.446e-09 sec) per iteration: 9.446e-06 sec for 1000 iterations 0.009 usec (9.3617e-09 sec) per iteration: 9.3617e-05 sec for 10000 iterations 0.009 usec (9.45663e-09 sec) per iteration: 0.101014 sec for 10681820 iterations 0.009 usec (9.41132e-09 sec) per iteration: 0.10053 sec for 10681820 iterations 0.009 usec (9.41622e-09 sec) per iteration: 0.100582 sec for 10681820 iterations 0.009 usec (9.41117e-09 sec) per iteration: 0.100528 sec for 10681820 iterations 0.009 usec (9.39956e-09 sec) per iteration: 0.100404 sec for 10681820 iterations mean: 0.009419 usec stddev: 0.000020 usec relative stddev: 0.21% RESULT TestAtomicIncrement: x86-64_with_irt_nnacl_newlib_static= {0.009419, 0.000020} us Measuring clock cycles: first runs (cycles): 105 53 50 53 50 51 50 51 50 53 ... slowest runs (cycles): ... 53 53 53 53 53 53 53 53 53 105 min: 50 cycles q1: 51 cycles median: 51 cycles q3: 53 cycles max: 105 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_with_irt_nnacl_newlib_static= {51, 2} count Apparent clock speed: 5415 MHz TestUncontendedMutexLock: Measuring real time: 0.030 usec (2.999e-08 sec) per iteration: 2.999e-06 sec for 100 iterations 0.024 usec (2.4279e-08 sec) per iteration: 2.4279e-05 sec for 1000 iterations 0.024 usec (2.44393e-08 sec) per iteration: 0.10066 sec for 4118785 iterations 0.024 usec (2.44423e-08 sec) per iteration: 0.100673 sec for 4118785 iterations 0.024 usec (2.44281e-08 sec) per iteration: 0.100614 sec for 4118785 iterations 0.024 usec (2.44209e-08 sec) per iteration: 0.100584 sec for 4118785 iterations 0.024 usec (2.44302e-08 sec) per iteration: 0.100623 sec for 4118785 iterations mean: 0.024432 usec stddev: 0.000008 usec relative stddev: 0.03% RESULT TestUncontendedMutexLock: x86-64_with_irt_nnacl_newlib_static= {0.024432, 0.000008} us Measuring clock cycles: first runs (cycles): 189 83 81 85 83 83 83 83 81 81 ... slowest runs (cycles): ... 86 86 86 86 86 86 86 86 86 189 min: 81 cycles q1: 83 cycles median: 84 cycles q3: 84 cycles max: 189 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_with_irt_nnacl_newlib_static= {84, 1} count Apparent clock speed: 3438 MHz TestCondvarSignalNoOp: Measuring real time: 0.300 usec (2.998e-07 sec) per iteration: 2.998e-05 sec for 100 iterations 0.215 usec (2.151e-07 sec) per iteration: 0.0717476 sec for 333555 iterations 0.215 usec (2.14808e-07 sec) per iteration: 0.0716501 sec for 333555 iterations 0.215 usec (2.14893e-07 sec) per iteration: 0.0716788 sec for 333555 iterations 0.215 usec (2.14638e-07 sec) per iteration: 0.0715935 sec for 333555 iterations 0.214 usec (2.1373e-07 sec) per iteration: 0.0712906 sec for 333555 iterations mean: 0.214634 usec stddev: 0.000476 usec relative stddev: 0.22% RESULT TestCondvarSignalNoOp: x86-64_with_irt_nnacl_newlib_static= {0.214634, 0.000476} us Measuring clock cycles: first runs (cycles): 623 479 480 477 480 482 475 519 479 482 ... slowest runs (cycles): ... 482 482 482 484 484 484 484 486 519 623 min: 475 cycles q1: 477 cycles median: 478 cycles q3: 480 cycles max: 623 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {478, 3} count Apparent clock speed: 2227 MHz TestThreadCreateAndJoin: Measuring real time: 33.102 usec (3.31023e-05 sec) per iteration: 0.00331023 sec for 100 iterations 26.020 usec (2.60201e-05 sec) per iteration: 0.0785808 sec for 3020 iterations 30.365 usec (3.03653e-05 sec) per iteration: 0.0917032 sec for 3020 iterations 27.620 usec (2.76203e-05 sec) per iteration: 0.0834134 sec for 3020 iterations 29.989 usec (2.99889e-05 sec) per iteration: 0.0905664 sec for 3020 iterations 26.515 usec (2.65147e-05 sec) per iteration: 0.0800744 sec for 3020 iterations mean: 28.101862 usec stddev: 1.775866 usec relative stddev: 6.32% RESULT TestThreadCreateAndJoin: x86-64_with_irt_nnacl_newlib_static= {28.101862, 1.775866} us Measuring clock cycles: first runs (cycles): 74117 76096 55209 52116 52266 54219 59127 56283 90039 91779 ... slowest runs (cycles): ... 91091 91779 92003 92318 94026 94929 96925 100746 111560 340406 min: 48176 cycles q1: 52002 cycles median: 53262 cycles q3: 59127 cycles max: 340406 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_with_irt_nnacl_newlib_static= {53262, 7125} count Apparent clock speed: 1895 MHz TestThreadWakeup: Measuring real time: 12.727 usec (1.27271e-05 sec) per iteration: 0.00127271 sec for 100 iterations 14.804 usec (1.48038e-05 sec) per iteration: 0.116313 sec for 7857 iterations 15.312 usec (1.53123e-05 sec) per iteration: 0.120309 sec for 7857 iterations 15.178 usec (1.51784e-05 sec) per iteration: 0.119257 sec for 7857 iterations 14.550 usec (1.45496e-05 sec) per iteration: 0.114316 sec for 7857 iterations 15.470 usec (1.54701e-05 sec) per iteration: 0.121549 sec for 7857 iterations mean: 15.062842 usec stddev: 0.338233 usec relative stddev: 2.25% RESULT TestThreadWakeup: x86-64_with_irt_nnacl_newlib_static= {15.062842, 0.338233} us Measuring clock cycles: first runs (cycles): 36871 36255 41689 63234 64391 34287 33754 33875 33442 35811 ... slowest runs (cycles): ... 58163 58883 60623 63234 64391 64530 68179 81161 92094 159085 min: 24117 cycles q1: 31717 cycles median: 33479 cycles q3: 41115 cycles max: 159085 cycles RESULT TestThreadWakeup_CycleCount: x86-64_with_irt_nnacl_newlib_static= {33479, 9398} count Apparent clock speed: 2223 MHz TestCatchingFault: Measuring real time: 2.675 usec (2.67501e-06 sec) per iteration: 0.000267501 sec for 100 iterations 1.707 usec (1.70669e-06 sec) per iteration: 0.0638013 sec for 37383 iterations 1.703 usec (1.70338e-06 sec) per iteration: 0.0636774 sec for 37383 iterations 1.700 usec (1.69994e-06 sec) per iteration: 0.0635488 sec for 37383 iterations 1.705 usec (1.70509e-06 sec) per iteration: 0.0637413 sec for 37383 iterations 1.698 usec (1.69801e-06 sec) per iteration: 0.0634766 sec for 37383 iterations mean: 1.702621 usec stddev: 0.003217 usec relative stddev: 0.19% RESULT TestCatchingFault: x86-64_with_irt_nnacl_newlib_static= {1.702621, 0.003217} us Measuring clock cycles: first runs (cycles): 4127 3786 3759 3734 3724 3715 3720 3718 3724 3707 ... slowest runs (cycles): ... 3724 3724 3724 3725 3726 3734 3759 3786 4127 16600 min: 3675 cycles q1: 3709 cycles median: 3713 cycles q3: 3719 cycles max: 16600 cycles RESULT TestCatchingFault_CycleCount: x86-64_with_irt_nnacl_newlib_static= {3713, 10} count Apparent clock speed: 2181 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 32.157 usec (3.21574e-05 sec) per iteration: 0.00321574 sec for 100 iterations 25.717 usec (2.57167e-05 sec) per iteration: 0.0799531 sec for 3109 iterations 26.890 usec (2.68897e-05 sec) per iteration: 0.0836002 sec for 3109 iterations 27.321 usec (2.73207e-05 sec) per iteration: 0.0849401 sec for 3109 iterations 27.622 usec (2.76215e-05 sec) per iteration: 0.0858754 sec for 3109 iterations 30.709 usec (3.07087e-05 sec) per iteration: 0.0954734 sec for 3109 iterations mean: 27.651473 usec stddev: 1.660167 usec relative stddev: 6.00% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_with_irt_nnacl_newlib_static= {27.651473, 1.660167} us Measuring clock cycles: first runs (cycles): 58615 51797 52312 55022 50316 53806 51628 62099 55436 96986 ... slowest runs (cycles): ... 97785 98484 98619 104015 111774 116160 116976 120078 124717 173246 min: 49853 cycles q1: 52871 cycles median: 59903 cycles q3: 71464 cycles max: 173246 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_with_irt_nnacl_newlib_static= {59903, 18593} count Apparent clock speed: 2166 MHz [ OK ] nacl_newlib.run_performance_test_irt (6060 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