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-48jb 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: 19722100 kB MemAvailable: 29451004 kB ====================================================================== LOAD: ====================================================================== 1.46 1.79 1.06 1/437 6066 ====================================================================== UPTIME: ====================================================================== 9866.73 77095.84 ====================================================================== 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 [6076,634210176:08:13:33.536767] Native Client module will be loaded at base address 0x000064a000000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_with_irt_nnacl_newlib_static= {0.113090, 0.006580} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (1700 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 (75150 ms) doSizeCheck(["scons-out/nacl_irt_test-x86-64/obj/tests/hello_world/hello_world_size.out"], ["scons-out/nacl_irt_test-x86-64/obj/tests/hello_world/hello_world.nexe"]) RESULT hello_world_size.out: x86-64_with_irt_nnacl_newlib_static= 527.182 KB RESULT hello_world_size.out: ZIPPED_x86-64_with_irt_nnacl_newlib_static= 186.968 KB /b/s/w/ir/cache/vpython/b574a6/bin/python tools/command_tester.py --name nacl_newlib.run_performance_test_irt --time_warning 10 --time_error 100 --perf_env_description x86-64_with_irt_nnacl_newlib_static --using_nacl_signal_handler True --subarch 64 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt_test-x86-64/test_results/performance_test.out scons-out/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 [6104,2983425920:08:13:49.137593] Native Client module will be loaded at base address 0x0000313500000000 TestNull: Measuring real time: 0.007 usec (7.03e-09 sec) per iteration: 7.03e-07 sec for 100 iterations 0.003 usec (2.506e-09 sec) per iteration: 2.506e-06 sec for 1000 iterations 0.002 usec (2.2867e-09 sec) per iteration: 2.2867e-05 sec for 10000 iterations 0.002 usec (1.89795e-09 sec) per iteration: 0.0829996 sec for 43731140 iterations 0.002 usec (1.89854e-09 sec) per iteration: 0.0830253 sec for 43731140 iterations 0.002 usec (1.8922e-09 sec) per iteration: 0.0827481 sec for 43731140 iterations 0.002 usec (1.8919e-09 sec) per iteration: 0.0827351 sec for 43731140 iterations 0.002 usec (1.88714e-09 sec) per iteration: 0.0825267 sec for 43731140 iterations mean: 0.001894 usec stddev: 0.000004 usec relative stddev: 0.22% RESULT TestNull: x86-64_with_irt_nnacl_newlib_static= {0.001894, 0.000004} us Measuring clock cycles: first runs (cycles): 110 26 20 24 26 22 24 24 24 27 ... slowest runs (cycles): ... 29 29 29 29 29 31 37 38 41 110 min: 19 cycles q1: 25 cycles median: 27 cycles q3: 29 cycles max: 110 cycles RESULT TestNull_CycleCount: x86-64_with_irt_nnacl_newlib_static= {27, 4} count Apparent clock speed: 14259 MHz TestNaClSyscall: Measuring real time: 0.104 usec (1.039e-07 sec) per iteration: 1.039e-05 sec for 100 iterations 0.123 usec (1.22935e-07 sec) per iteration: 0.11832 sec for 962463 iterations 0.129 usec (1.28856e-07 sec) per iteration: 0.124019 sec for 962463 iterations 0.126 usec (1.25862e-07 sec) per iteration: 0.121138 sec for 962463 iterations 0.132 usec (1.31871e-07 sec) per iteration: 0.126921 sec for 962463 iterations 0.124 usec (1.24041e-07 sec) per iteration: 0.119385 sec for 962463 iterations mean: 0.126713 usec stddev: 0.003266 usec relative stddev: 2.58% RESULT TestNaClSyscall: x86-64_with_irt_nnacl_newlib_static= {0.126713, 0.003266} us Measuring clock cycles: first runs (cycles): 334 223 184 189 194 187 191 198 191 191 ... slowest runs (cycles): ... 200 200 202 203 205 209 209 223 228 334 min: 181 cycles q1: 187 cycles median: 189 cycles q3: 194 cycles max: 334 cycles RESULT TestNaClSyscall_CycleCount: x86-64_with_irt_nnacl_newlib_static= {189, 7} count Apparent clock speed: 1492 MHz TestSetjmpLongjmp: Measuring real time: 0.021 usec (2.062e-08 sec) per iteration: 2.062e-06 sec for 100 iterations 0.015 usec (1.4547e-08 sec) per iteration: 1.4547e-05 sec for 1000 iterations 0.013 usec (1.30216e-08 sec) per iteration: 0.0895143 sec for 6874269 iterations 0.013 usec (1.29992e-08 sec) per iteration: 0.0893598 sec for 6874269 iterations 0.013 usec (1.30364e-08 sec) per iteration: 0.0896161 sec for 6874269 iterations 0.013 usec (1.29356e-08 sec) per iteration: 0.0889227 sec for 6874269 iterations 0.013 usec (1.28296e-08 sec) per iteration: 0.0881941 sec for 6874269 iterations mean: 0.012964 usec stddev: 0.000076 usec relative stddev: 0.58% RESULT TestSetjmpLongjmp: x86-64_with_irt_nnacl_newlib_static= {0.012964, 0.000076} us Measuring clock cycles: first runs (cycles): 233 68 46 53 50 39 49 41 42 41 ... slowest runs (cycles): ... 51 53 55 55 62 64 68 68 68 233 min: 30 cycles q1: 33 cycles median: 38 cycles q3: 44 cycles max: 233 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {38, 11} count Apparent clock speed: 2931 MHz TestClockGetTime: Measuring real time: 0.201 usec (2.0122e-07 sec) per iteration: 2.0122e-05 sec for 100 iterations 0.162 usec (1.61542e-07 sec) per iteration: 0.0802811 sec for 496968 iterations 0.157 usec (1.57191e-07 sec) per iteration: 0.0781191 sec for 496968 iterations 0.159 usec (1.58954e-07 sec) per iteration: 0.0789951 sec for 496968 iterations 0.152 usec (1.5235e-07 sec) per iteration: 0.0757129 sec for 496968 iterations 0.152 usec (1.51857e-07 sec) per iteration: 0.0754681 sec for 496968 iterations mean: 0.156379 usec stddev: 0.003758 usec relative stddev: 2.40% RESULT TestClockGetTime: x86-64_with_irt_nnacl_newlib_static= {0.156379, 0.003758} us Measuring clock cycles: first runs (cycles): 755 420 404 381 389 371 377 378 378 378 ... slowest runs (cycles): ... 418 420 421 423 427 427 443 454 462 755 min: 356 cycles q1: 371 cycles median: 378 cycles q3: 389 cycles max: 755 cycles RESULT TestClockGetTime_CycleCount: x86-64_with_irt_nnacl_newlib_static= {378, 18} count Apparent clock speed: 2417 MHz TestTlsVariable: Measuring real time: 0.016 usec (1.631e-08 sec) per iteration: 1.631e-06 sec for 100 iterations 0.008 usec (7.874e-09 sec) per iteration: 7.874e-06 sec for 1000 iterations 0.009 usec (8.8329e-09 sec) per iteration: 8.8329e-05 sec for 10000 iterations 0.006 usec (5.68218e-09 sec) per iteration: 0.0643298 sec for 11321310 iterations 0.006 usec (5.66593e-09 sec) per iteration: 0.0641458 sec for 11321310 iterations 0.006 usec (5.67825e-09 sec) per iteration: 0.0642853 sec for 11321310 iterations 0.006 usec (5.71011e-09 sec) per iteration: 0.0646459 sec for 11321310 iterations 0.006 usec (5.67762e-09 sec) per iteration: 0.064278 sec for 11321310 iterations mean: 0.005683 usec stddev: 0.000015 usec relative stddev: 0.26% RESULT TestTlsVariable: x86-64_with_irt_nnacl_newlib_static= {0.005683, 0.000015} us Measuring clock cycles: first runs (cycles): 180 77 64 60 35 38 31 31 33 33 ... slowest runs (cycles): ... 44 44 44 46 46 46 60 64 77 180 min: 24 cycles q1: 29 cycles median: 33 cycles q3: 36 cycles max: 180 cycles RESULT TestTlsVariable_CycleCount: x86-64_with_irt_nnacl_newlib_static= {33, 7} count Apparent clock speed: 5807 MHz TestMmapAnonymous: Measuring real time: 2.814 usec (2.81442e-06 sec) per iteration: 0.000281442 sec for 100 iterations 1.949 usec (1.94865e-06 sec) per iteration: 0.0692376 sec for 35531 iterations 1.945 usec (1.94544e-06 sec) per iteration: 0.0691236 sec for 35531 iterations 1.948 usec (1.94779e-06 sec) per iteration: 0.069207 sec for 35531 iterations 1.943 usec (1.9428e-06 sec) per iteration: 0.0690295 sec for 35531 iterations 2.002 usec (2.00215e-06 sec) per iteration: 0.0711383 sec for 35531 iterations mean: 1.957367 usec stddev: 0.022483 usec relative stddev: 1.15% RESULT TestMmapAnonymous: x86-64_with_irt_nnacl_newlib_static= {1.957367, 0.022483} us Measuring clock cycles: first runs (cycles): 8399 5429 5533 5237 5218 5279 5198 5893 5141 5616 ... slowest runs (cycles): ... 6667 6774 6793 6969 7171 7539 7651 7722 7734 8399 min: 4175 cycles q1: 5198 cycles median: 5662 cycles q3: 6125 cycles max: 8399 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_with_irt_nnacl_newlib_static= {5662, 927} count Apparent clock speed: 2893 MHz TestAtomicIncrement: Measuring real time: 0.017 usec (1.676e-08 sec) per iteration: 1.676e-06 sec for 100 iterations 0.011 usec (1.067e-08 sec) per iteration: 1.067e-05 sec for 1000 iterations 0.009 usec (9.43282e-09 sec) per iteration: 0.0884051 sec for 9372071 iterations 0.010 usec (9.699e-09 sec) per iteration: 0.0908997 sec for 9372071 iterations 0.009 usec (9.42962e-09 sec) per iteration: 0.088375 sec for 9372071 iterations 0.009 usec (9.43099e-09 sec) per iteration: 0.0883879 sec for 9372071 iterations 0.009 usec (9.42504e-09 sec) per iteration: 0.0883321 sec for 9372071 iterations mean: 0.009483 usec stddev: 0.000108 usec relative stddev: 1.14% RESULT TestAtomicIncrement: x86-64_with_irt_nnacl_newlib_static= {0.009483, 0.000108} us Measuring clock cycles: first runs (cycles): 723 53 53 50 55 53 50 53 53 53 ... slowest runs (cycles): ... 55 55 55 55 55 55 55 55 57 723 min: 50 cycles q1: 51 cycles median: 53 cycles q3: 53 cycles max: 723 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_with_irt_nnacl_newlib_static= {53, 2} count Apparent clock speed: 5589 MHz TestUncontendedMutexLock: Measuring real time: 0.035 usec (3.458e-08 sec) per iteration: 3.458e-06 sec for 100 iterations 0.027 usec (2.6667e-08 sec) per iteration: 2.6667e-05 sec for 1000 iterations 0.025 usec (2.45511e-08 sec) per iteration: 0.0920655 sec for 3749953 iterations 0.025 usec (2.45666e-08 sec) per iteration: 0.0921237 sec for 3749953 iterations 0.025 usec (2.45421e-08 sec) per iteration: 0.0920318 sec for 3749953 iterations 0.025 usec (2.45133e-08 sec) per iteration: 0.0919236 sec for 3749953 iterations 0.024 usec (2.44982e-08 sec) per iteration: 0.0918672 sec for 3749953 iterations mean: 0.024534 usec stddev: 0.000025 usec relative stddev: 0.10% RESULT TestUncontendedMutexLock: x86-64_with_irt_nnacl_newlib_static= {0.024534, 0.000025} us Measuring clock cycles: first runs (cycles): 255 121 86 84 92 83 84 84 86 84 ... slowest runs (cycles): ... 92 92 92 93 94 95 95 101 121 255 min: 83 cycles q1: 84 cycles median: 86 cycles q3: 88 cycles max: 255 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_with_irt_nnacl_newlib_static= {86, 4} count Apparent clock speed: 3505 MHz TestCondvarSignalNoOp: Measuring real time: 0.283 usec (2.8299e-07 sec) per iteration: 2.8299e-05 sec for 100 iterations 0.217 usec (2.17058e-07 sec) per iteration: 0.0767017 sec for 353369 iterations 0.216 usec (2.15649e-07 sec) per iteration: 0.0762038 sec for 353369 iterations 0.214 usec (2.14424e-07 sec) per iteration: 0.0757709 sec for 353369 iterations 0.215 usec (2.14536e-07 sec) per iteration: 0.0758104 sec for 353369 iterations 0.216 usec (2.16189e-07 sec) per iteration: 0.0763946 sec for 353369 iterations mean: 0.215571 usec stddev: 0.000999 usec relative stddev: 0.46% RESULT TestCondvarSignalNoOp: x86-64_with_irt_nnacl_newlib_static= {0.215571, 0.000999} us Measuring clock cycles: first runs (cycles): 772 580 504 506 515 508 502 526 506 506 ... slowest runs (cycles): ... 554 555 561 563 567 571 575 580 585 772 min: 481 cycles q1: 497 cycles median: 506 cycles q3: 515 cycles max: 772 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {506, 18} count Apparent clock speed: 2347 MHz TestThreadCreateAndJoin: Measuring real time: 32.012 usec (3.20124e-05 sec) per iteration: 0.00320124 sec for 100 iterations 34.794 usec (3.47939e-05 sec) per iteration: 0.108661 sec for 3123 iterations 28.201 usec (2.82008e-05 sec) per iteration: 0.0880711 sec for 3123 iterations 24.819 usec (2.48195e-05 sec) per iteration: 0.0775112 sec for 3123 iterations 29.560 usec (2.95599e-05 sec) per iteration: 0.0923157 sec for 3123 iterations 30.002 usec (3.00017e-05 sec) per iteration: 0.0936954 sec for 3123 iterations mean: 29.475167 usec stddev: 3.220957 usec relative stddev: 10.93% RESULT TestThreadCreateAndJoin: x86-64_with_irt_nnacl_newlib_static= {29.475167, 3.220957} us Measuring clock cycles: first runs (cycles): 53037 103424 58517 49771 49830 48187 51145 51583 59503 67585 ... slowest runs (cycles): ... 65176 67585 67829 69960 73214 73898 103424 125217 139627 281296 min: 45543 cycles q1: 47837 cycles median: 49100 cycles q3: 51583 cycles max: 281296 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_with_irt_nnacl_newlib_static= {49100, 3746} count Apparent clock speed: 1666 MHz TestThreadWakeup: Measuring real time: 12.616 usec (1.2616e-05 sec) per iteration: 0.0012616 sec for 100 iterations 13.860 usec (1.386e-05 sec) per iteration: 0.109855 sec for 7926 iterations 14.315 usec (1.4315e-05 sec) per iteration: 0.113461 sec for 7926 iterations 14.793 usec (1.47932e-05 sec) per iteration: 0.117251 sec for 7926 iterations 14.501 usec (1.45009e-05 sec) per iteration: 0.114934 sec for 7926 iterations 14.416 usec (1.44164e-05 sec) per iteration: 0.114264 sec for 7926 iterations mean: 14.377113 usec stddev: 0.303711 usec relative stddev: 2.11% RESULT TestThreadWakeup: x86-64_with_irt_nnacl_newlib_static= {14.377113, 0.303711} us Measuring clock cycles: first runs (cycles): 31545 30993 29051 29380 30091 29869 29905 43834 30865 30863 ... slowest runs (cycles): ... 33776 33996 34606 35615 36268 36328 37621 42919 43834 47001 min: 24449 cycles q1: 30034 cycles median: 30346 cycles q3: 31400 cycles max: 47001 cycles RESULT TestThreadWakeup_CycleCount: x86-64_with_irt_nnacl_newlib_static= {30346, 1366} count Apparent clock speed: 2111 MHz TestCatchingFault: Measuring real time: 2.873 usec (2.87296e-06 sec) per iteration: 0.000287296 sec for 100 iterations 1.719 usec (1.71918e-06 sec) per iteration: 0.0598396 sec for 34807 iterations 1.706 usec (1.70641e-06 sec) per iteration: 0.0593952 sec for 34807 iterations 1.702 usec (1.70219e-06 sec) per iteration: 0.0592482 sec for 34807 iterations 1.712 usec (1.71183e-06 sec) per iteration: 0.0595838 sec for 34807 iterations 1.710 usec (1.71001e-06 sec) per iteration: 0.0595202 sec for 34807 iterations mean: 1.709926 usec stddev: 0.005683 usec relative stddev: 0.33% RESULT TestCatchingFault: x86-64_with_irt_nnacl_newlib_static= {1.709926, 0.005683} us Measuring clock cycles: first runs (cycles): 4373 3804 3715 3711 3698 3722 3725 3729 3708 3702 ... slowest runs (cycles): ... 3719 3720 3722 3725 3725 3727 3729 3804 3839 4373 min: 3670 cycles q1: 3693 cycles median: 3700 cycles q3: 3707 cycles max: 4373 cycles RESULT TestCatchingFault_CycleCount: x86-64_with_irt_nnacl_newlib_static= {3700, 14} count Apparent clock speed: 2164 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 43.818 usec (4.3818e-05 sec) per iteration: 0.0043818 sec for 100 iterations 33.348 usec (3.3348e-05 sec) per iteration: 0.0761001 sec for 2282 iterations 36.952 usec (3.69521e-05 sec) per iteration: 0.0843247 sec for 2282 iterations 42.004 usec (4.20043e-05 sec) per iteration: 0.0958537 sec for 2282 iterations 40.348 usec (4.03484e-05 sec) per iteration: 0.092075 sec for 2282 iterations 42.080 usec (4.20804e-05 sec) per iteration: 0.0960274 sec for 2282 iterations mean: 38.946624 usec stddev: 3.360273 usec relative stddev: 8.63% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_with_irt_nnacl_newlib_static= {38.946624, 3.360273} us Measuring clock cycles: first runs (cycles): 75302 88540 82229 83124 82231 88780 198942 70504 73694 131575 ... slowest runs (cycles): ... 126560 127325 129179 131575 144899 145406 164878 177875 198942 408336 min: 66936 cycles q1: 80597 cycles median: 84229 cycles q3: 104213 cycles max: 408336 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_with_irt_nnacl_newlib_static= {84229, 23616} count Apparent clock speed: 2163 MHz [ OK ] nacl_newlib.run_performance_test_irt (5800 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