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

Running command: /b/s/w/ir/cache/vpython/b50d97/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/b50d97/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/bin:/usr/sbin:/sbin:/bin:/mnt/data/b/s/w/ir/kitchen-checkout/depot_tools ====================================================================== OS: ====================================================================== Linux vm316-m1.golo.chromium.org 3.13.0-65-generic #106-Ubuntu SMP Fri Oct 2 22:08:27 UTC 2015 x86_64 x86_64 x86_64 GNU/Linux ====================================================================== CPU: ====================================================================== model name : Intel(R) Xeon(R) CPU E5-2670 0 @ 2.60GHz stepping : 7 cpu MHz : 2600.000 model name : Intel(R) Xeon(R) CPU E5-2670 0 @ 2.60GHz stepping : 7 cpu MHz : 2600.000 model name : Intel(R) Xeon(R) CPU E5-2670 0 @ 2.60GHz stepping : 7 cpu MHz : 2600.000 model name : Intel(R) Xeon(R) CPU E5-2670 0 @ 2.60GHz stepping : 7 cpu MHz : 2600.000 model name : Intel(R) Xeon(R) CPU E5-2670 0 @ 2.60GHz stepping : 7 cpu MHz : 2600.000 model name : Intel(R) Xeon(R) CPU E5-2670 0 @ 2.60GHz stepping : 7 cpu MHz : 2600.000 model name : Intel(R) Xeon(R) CPU E5-2670 0 @ 2.60GHz stepping : 7 cpu MHz : 2600.000 model name : Intel(R) Xeon(R) CPU E5-2670 0 @ 2.60GHz stepping : 7 cpu MHz : 2600.000 ====================================================================== RAM: ====================================================================== MemTotal: 16433828 kB MemFree: 7449272 kB ====================================================================== LOAD: ====================================================================== 1.20 1.43 0.82 1/294 30490 ====================================================================== UPTIME: ====================================================================== 8812.79 67059.85 ====================================================================== The following environments have been configured ====================================================================== NACL_BUILD_FAMILY: UNTRUSTED BUILD_TYPE: nacl TARGET_ROOT: /mnt/data/b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-64 OBJ_ROOT: /mnt/data/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: /mnt/data/b/s/w/ir/kitchen-workdir/native_client/toolchain/linux_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /mnt/data/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/mnt/data/b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-64/include -I/mnt/data/b/s/w/ir/kitchen-workdir -c -o UNKNOWN COMPILER NACL_BUILD_FAMILY: TRUSTED BUILD_TYPE: dbg-linux TARGET_ROOT: /mnt/data/b/s/w/ir/kitchen-workdir/native_client/scons-out/dbg-linux-x86-64 OBJ_ROOT: /mnt/data/b/s/w/ir/kitchen-workdir/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: /mnt/data/b/s/w/ir/kitchen-workdir/third_party/llvm-build/Release+Asserts/bin/clang ASPPCOM: /mnt/data/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 -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/mnt/data/b/s/w/ir/kitchen-workdir -c -o UNKNOWN COMPILER NACL_BUILD_FAMILY: UNTRUSTED_IRT_TESTS BUILD_TYPE: nacl_irt_test TARGET_ROOT: /mnt/data/b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt_test-x86-64 OBJ_ROOT: /mnt/data/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: /mnt/data/b/s/w/ir/kitchen-workdir/native_client/toolchain/linux_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /mnt/data/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/mnt/data/b/s/w/ir/kitchen-workdir -c -o UNKNOWN COMPILER NACL_BUILD_FAMILY: UNTRUSTED_IRT BUILD_TYPE: nacl_irt TARGET_ROOT: /mnt/data/b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt-x86-64 OBJ_ROOT: /mnt/data/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: /mnt/data/b/s/w/ir/kitchen-workdir/native_client/toolchain/linux_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /mnt/data/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/mnt/data/b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt-x86-64/include -I/mnt/data/b/s/w/ir/kitchen-workdir -I/mnt/data/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/b50d97/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 /mnt/data/b/s/w/ir/kitchen-workdir/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 [30498,3274737536:10:27:36.331702] Native Client module will be loaded at base address 0x0000326900000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_with_irt_nnacl_newlib_static= {0.068350, 0.034170} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (850 ms) /b/s/w/ir/cache/vpython/b50d97/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 /mnt/data/b/s/w/ir/kitchen-workdir/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 (61680 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.346 KB RESULT hello_world_size.out: ZIPPED_x86-64_with_irt_nnacl_newlib_static= 187.107 KB /b/s/w/ir/cache/vpython/b50d97/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 /mnt/data/b/s/w/ir/kitchen-workdir/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 [30532,1270032256:10:27:49.427217] Native Client module will be loaded at base address 0x0000096400000000 TestNull: Measuring real time: 0.010 usec (9.94e-09 sec) per iteration: 9.94e-07 sec for 100 iterations 0.004 usec (3.552e-09 sec) per iteration: 3.552e-06 sec for 1000 iterations 0.003 usec (2.8917e-09 sec) per iteration: 2.8917e-05 sec for 10000 iterations 0.002 usec (2.0196e-09 sec) per iteration: 0.0698412 sec for 34581733 iterations 0.002 usec (2.00755e-09 sec) per iteration: 0.0694244 sec for 34581733 iterations 0.002 usec (2.00883e-09 sec) per iteration: 0.0694687 sec for 34581733 iterations 0.002 usec (2.00707e-09 sec) per iteration: 0.0694079 sec for 34581733 iterations 0.002 usec (2.00835e-09 sec) per iteration: 0.0694521 sec for 34581733 iterations mean: 0.002010 usec stddev: 0.000005 usec relative stddev: 0.23% RESULT TestNull: x86-64_with_irt_nnacl_newlib_static= {0.002010, 0.000005} us Measuring clock cycles: first runs (cycles): 171 24 21 27 21 24 27 24 21 21 ... slowest runs (cycles): ... 31 31 31 31 34 34 34 34 37 171 min: 18 cycles q1: 21 cycles median: 28 cycles q3: 28 cycles max: 171 cycles RESULT TestNull_CycleCount: x86-64_with_irt_nnacl_newlib_static= {28, 7} count Apparent clock speed: 13928 MHz TestNaClSyscall: Measuring real time: 0.231 usec (2.3062e-07 sec) per iteration: 2.3062e-05 sec for 100 iterations 0.155 usec (1.54742e-07 sec) per iteration: 0.0670982 sec for 433613 iterations 0.156 usec (1.55562e-07 sec) per iteration: 0.0674535 sec for 433613 iterations 0.156 usec (1.55603e-07 sec) per iteration: 0.0674714 sec for 433613 iterations 0.156 usec (1.55531e-07 sec) per iteration: 0.0674404 sec for 433613 iterations 0.156 usec (1.56389e-07 sec) per iteration: 0.0678122 sec for 433613 iterations mean: 0.155565 usec stddev: 0.000521 usec relative stddev: 0.34% RESULT TestNaClSyscall: x86-64_with_irt_nnacl_newlib_static= {0.155565, 0.000521} us Measuring clock cycles: first runs (cycles): 1355 566 569 532 560 529 529 545 517 532 ... slowest runs (cycles): ... 612 612 624 633 645 667 722 774 973 1355 min: 501 cycles q1: 532 cycles median: 553 cycles q3: 572 cycles max: 1355 cycles RESULT TestNaClSyscall_CycleCount: x86-64_with_irt_nnacl_newlib_static= {553, 40} count Apparent clock speed: 3555 MHz TestSetjmpLongjmp: Measuring real time: 0.026 usec (2.645e-08 sec) per iteration: 2.645e-06 sec for 100 iterations 0.017 usec (1.68e-08 sec) per iteration: 1.68e-05 sec for 1000 iterations 0.013 usec (1.26815e-08 sec) per iteration: 0.075485 sec for 5952380 iterations 0.013 usec (1.26759e-08 sec) per iteration: 0.0754515 sec for 5952380 iterations 0.013 usec (1.2689e-08 sec) per iteration: 0.0755299 sec for 5952380 iterations 0.013 usec (1.26763e-08 sec) per iteration: 0.0754541 sec for 5952380 iterations 0.013 usec (1.26765e-08 sec) per iteration: 0.0754551 sec for 5952380 iterations mean: 0.012680 usec stddev: 0.000005 usec relative stddev: 0.04% RESULT TestSetjmpLongjmp: x86-64_with_irt_nnacl_newlib_static= {0.012680, 0.000005} us Measuring clock cycles: first runs (cycles): 899 92 46 49 58 58 46 49 46 49 ... slowest runs (cycles): ... 58 58 61 61 61 61 73 80 92 899 min: 36 cycles q1: 46 cycles median: 49 cycles q3: 52 cycles max: 899 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {49, 6} count Apparent clock speed: 3864 MHz TestClockGetTime: Measuring real time: 0.425 usec (4.2471e-07 sec) per iteration: 4.2471e-05 sec for 100 iterations 0.261 usec (2.61044e-07 sec) per iteration: 0.0614639 sec for 235454 iterations 0.264 usec (2.63802e-07 sec) per iteration: 0.0621132 sec for 235454 iterations 0.268 usec (2.68073e-07 sec) per iteration: 0.0631189 sec for 235454 iterations 0.260 usec (2.60336e-07 sec) per iteration: 0.0612973 sec for 235454 iterations 0.261 usec (2.60619e-07 sec) per iteration: 0.0613637 sec for 235454 iterations mean: 0.262775 usec stddev: 0.002923 usec relative stddev: 1.11% RESULT TestClockGetTime: x86-64_with_irt_nnacl_newlib_static= {0.262775, 0.002923} us Measuring clock cycles: first runs (cycles): 5545 1083 1034 1040 1015 1083 988 1526 1052 1349 ... slowest runs (cycles): ... 1202 1205 1220 1260 1273 1285 1349 1514 1526 5545 min: 927 cycles q1: 1004 cycles median: 1059 cycles q3: 1128 cycles max: 5545 cycles RESULT TestClockGetTime_CycleCount: x86-64_with_irt_nnacl_newlib_static= {1059, 124} count Apparent clock speed: 4030 MHz TestTlsVariable: Measuring real time: 0.023 usec (2.264e-08 sec) per iteration: 2.264e-06 sec for 100 iterations 0.011 usec (1.1331e-08 sec) per iteration: 1.1331e-05 sec for 1000 iterations 0.006 usec (6.0235e-09 sec) per iteration: 0.0531595 sec for 8825346 iterations 0.006 usec (6.02469e-09 sec) per iteration: 0.05317 sec for 8825346 iterations 0.006 usec (6.02366e-09 sec) per iteration: 0.0531609 sec for 8825346 iterations 0.006 usec (6.02403e-09 sec) per iteration: 0.0531642 sec for 8825346 iterations 0.006 usec (6.0229e-09 sec) per iteration: 0.0531542 sec for 8825346 iterations mean: 0.006024 usec stddev: 0.000001 usec relative stddev: 0.01% RESULT TestTlsVariable: x86-64_with_irt_nnacl_newlib_static= {0.006024, 0.000001} us Measuring clock cycles: first runs (cycles): 810 95 37 43 30 36 43 40 36 43 ... slowest runs (cycles): ... 52 52 52 52 55 55 55 58 95 810 min: 21 cycles q1: 34 cycles median: 43 cycles q3: 49 cycles max: 810 cycles RESULT TestTlsVariable_CycleCount: x86-64_with_irt_nnacl_newlib_static= {43, 15} count Apparent clock speed: 7138 MHz TestMmapAnonymous: Measuring real time: 3.734 usec (3.73391e-06 sec) per iteration: 0.000373391 sec for 100 iterations 3.277 usec (3.2766e-06 sec) per iteration: 0.0877506 sec for 26781 iterations 3.290 usec (3.29039e-06 sec) per iteration: 0.0881199 sec for 26781 iterations 3.286 usec (3.28601e-06 sec) per iteration: 0.0880026 sec for 26781 iterations 3.285 usec (3.28512e-06 sec) per iteration: 0.0879788 sec for 26781 iterations 3.275 usec (3.27534e-06 sec) per iteration: 0.087717 sec for 26781 iterations mean: 3.282692 usec stddev: 0.005784 usec relative stddev: 0.18% RESULT TestMmapAnonymous: x86-64_with_irt_nnacl_newlib_static= {3.282692, 0.005784} us Measuring clock cycles: first runs (cycles): 37263 18280 18228 16142 17380 17132 17310 17811 16435 15979 ... slowest runs (cycles): ... 16435 17132 17310 17380 17423 17811 18228 18280 34143 37263 min: 8360 cycles q1: 8552 cycles median: 8595 cycles q3: 8745 cycles max: 37263 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_with_irt_nnacl_newlib_static= {8595, 193} count Apparent clock speed: 2618 MHz TestAtomicIncrement: Measuring real time: 0.021 usec (2.104e-08 sec) per iteration: 2.104e-06 sec for 100 iterations 0.009 usec (9.387e-09 sec) per iteration: 9.387e-06 sec for 1000 iterations 0.010 usec (9.6662e-09 sec) per iteration: 9.6662e-05 sec for 10000 iterations 0.010 usec (1.00676e-08 sec) per iteration: 0.104152 sec for 10345327 iterations 0.010 usec (1.00282e-08 sec) per iteration: 0.103745 sec for 10345327 iterations 0.010 usec (1.00346e-08 sec) per iteration: 0.103811 sec for 10345327 iterations 0.010 usec (1.00298e-08 sec) per iteration: 0.103761 sec for 10345327 iterations 0.010 usec (1.00296e-08 sec) per iteration: 0.10376 sec for 10345327 iterations mean: 0.010038 usec stddev: 0.000015 usec relative stddev: 0.15% RESULT TestAtomicIncrement: x86-64_with_irt_nnacl_newlib_static= {0.010038, 0.000015} us Measuring clock cycles: first runs (cycles): 459 65 52 52 55 55 46 61 49 46 ... slowest runs (cycles): ... 52 52 55 55 61 64 65 67 71 459 min: 39 cycles q1: 46 cycles median: 49 cycles q3: 49 cycles max: 459 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_with_irt_nnacl_newlib_static= {49, 3} count Apparent clock speed: 4881 MHz TestUncontendedMutexLock: Measuring real time: 0.031 usec (3.118e-08 sec) per iteration: 3.118e-06 sec for 100 iterations 0.024 usec (2.3865e-08 sec) per iteration: 2.3865e-05 sec for 1000 iterations 0.023 usec (2.34067e-08 sec) per iteration: 0.0980797 sec for 4190236 iterations 0.023 usec (2.34031e-08 sec) per iteration: 0.0980645 sec for 4190236 iterations 0.023 usec (2.34201e-08 sec) per iteration: 0.0981356 sec for 4190236 iterations 0.023 usec (2.34114e-08 sec) per iteration: 0.0980991 sec for 4190236 iterations 0.023 usec (2.34027e-08 sec) per iteration: 0.0980629 sec for 4190236 iterations mean: 0.023409 usec stddev: 0.000006 usec relative stddev: 0.03% RESULT TestUncontendedMutexLock: x86-64_with_irt_nnacl_newlib_static= {0.023409, 0.000006} us Measuring clock cycles: first runs (cycles): 624 116 86 86 89 89 79 79 82 82 ... slowest runs (cycles): ... 89 89 89 89 89 107 107 114 116 624 min: 79 cycles q1: 82 cycles median: 86 cycles q3: 89 cycles max: 624 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_with_irt_nnacl_newlib_static= {86, 7} count Apparent clock speed: 3674 MHz TestCondvarSignalNoOp: Measuring real time: 0.374 usec (3.7449e-07 sec) per iteration: 3.7449e-05 sec for 100 iterations 0.323 usec (3.22994e-07 sec) per iteration: 0.0862487 sec for 267029 iterations 0.323 usec (3.22821e-07 sec) per iteration: 0.0862026 sec for 267029 iterations 0.323 usec (3.23117e-07 sec) per iteration: 0.0862815 sec for 267029 iterations 0.323 usec (3.23025e-07 sec) per iteration: 0.086257 sec for 267029 iterations 0.323 usec (3.22795e-07 sec) per iteration: 0.0861956 sec for 267029 iterations mean: 0.322950 usec stddev: 0.000123 usec relative stddev: 0.04% RESULT TestCondvarSignalNoOp: x86-64_with_irt_nnacl_newlib_static= {0.322950, 0.000123} us Measuring clock cycles: first runs (cycles): 3328 911 841 884 936 982 865 847 832 832 ... slowest runs (cycles): ... 906 909 909 911 918 936 936 936 982 3328 min: 816 cycles q1: 835 cycles median: 841 cycles q3: 847 cycles max: 3328 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {841, 12} count Apparent clock speed: 2604 MHz TestThreadCreateAndJoin: Measuring real time: 49.501 usec (4.95009e-05 sec) per iteration: 0.00495009 sec for 100 iterations 47.951 usec (4.79512e-05 sec) per iteration: 0.0968615 sec for 2020 iterations 49.244 usec (4.92438e-05 sec) per iteration: 0.0994724 sec for 2020 iterations 49.628 usec (4.96279e-05 sec) per iteration: 0.100248 sec for 2020 iterations 48.929 usec (4.89295e-05 sec) per iteration: 0.0988375 sec for 2020 iterations 50.302 usec (5.03019e-05 sec) per iteration: 0.10161 sec for 2020 iterations mean: 49.210848 usec stddev: 0.778623 usec relative stddev: 1.58% RESULT TestThreadCreateAndJoin: x86-64_with_irt_nnacl_newlib_static= {49.210848, 0.778623} us Measuring clock cycles: first runs (cycles): 115100 135962 133970 133952 131550 130728 129382 127886 128385 91379 ... slowest runs (cycles): ... 147906 149405 149793 150531 150880 151170 151638 152565 153103 154709 min: 89599 cycles q1: 128461 cycles median: 129373 cycles q3: 130682 cycles max: 154709 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_with_irt_nnacl_newlib_static= {129373, 2221} count Apparent clock speed: 2629 MHz TestThreadWakeup: Measuring real time: 17.666 usec (1.76661e-05 sec) per iteration: 0.00176661 sec for 100 iterations 16.123 usec (1.61225e-05 sec) per iteration: 0.0912534 sec for 5660 iterations 7.896 usec (7.89588e-06 sec) per iteration: 0.0446907 sec for 5660 iterations 17.342 usec (1.73419e-05 sec) per iteration: 0.0981553 sec for 5660 iterations 16.879 usec (1.68791e-05 sec) per iteration: 0.0955359 sec for 5660 iterations 17.501 usec (1.75011e-05 sec) per iteration: 0.0990564 sec for 5660 iterations mean: 15.148115 usec stddev: 3.657603 usec relative stddev: 24.15% RESULT TestThreadWakeup: x86-64_with_irt_nnacl_newlib_static= {15.148115, 3.657603} us Measuring clock cycles: first runs (cycles): 52854 49149 46136 45390 46870 46818 46690 46412 46069 46378 ... slowest runs (cycles): ... 46378 46394 46412 46455 46690 46818 46870 49149 52854 68105 min: 44362 cycles q1: 44910 cycles median: 45323 cycles q3: 45910 cycles max: 68105 cycles RESULT TestThreadWakeup_CycleCount: x86-64_with_irt_nnacl_newlib_static= {45323, 1000} count Apparent clock speed: 2992 MHz TestCatchingFault: Measuring real time: 2.149 usec (2.14932e-06 sec) per iteration: 0.000214932 sec for 100 iterations 1.964 usec (1.96356e-06 sec) per iteration: 0.0913564 sec for 46526 iterations 1.945 usec (1.94484e-06 sec) per iteration: 0.0904854 sec for 46526 iterations 1.936 usec (1.93594e-06 sec) per iteration: 0.0900716 sec for 46526 iterations 1.939 usec (1.93876e-06 sec) per iteration: 0.0902027 sec for 46526 iterations 1.948 usec (1.94843e-06 sec) per iteration: 0.0906525 sec for 46526 iterations mean: 1.946304 usec stddev: 0.009681 usec relative stddev: 0.50% RESULT TestCatchingFault: x86-64_with_irt_nnacl_newlib_static= {1.946304, 0.009681} us Measuring clock cycles: first runs (cycles): 13694 5646 5282 5234 5059 5019 5050 5017 4980 4962 ... slowest runs (cycles): ... 5035 5050 5059 5075 5081 5081 5234 5282 5646 13694 min: 4928 cycles q1: 4952 cycles median: 4962 cycles q3: 5010 cycles max: 13694 cycles RESULT TestCatchingFault_CycleCount: x86-64_with_irt_nnacl_newlib_static= {4962, 58} count Apparent clock speed: 2549 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 49.984 usec (4.99839e-05 sec) per iteration: 0.00499839 sec for 100 iterations 48.961 usec (4.89606e-05 sec) per iteration: 0.0979212 sec for 2000 iterations 48.650 usec (4.86497e-05 sec) per iteration: 0.0972994 sec for 2000 iterations 48.750 usec (4.875e-05 sec) per iteration: 0.0975001 sec for 2000 iterations 48.955 usec (4.89548e-05 sec) per iteration: 0.0979095 sec for 2000 iterations 48.783 usec (4.87832e-05 sec) per iteration: 0.0975665 sec for 2000 iterations mean: 48.819671 usec stddev: 0.120965 usec relative stddev: 0.25% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_with_irt_nnacl_newlib_static= {48.819671, 0.120965} us Measuring clock cycles: first runs (cycles): 105869 121961 136959 84200 131135 131872 129762 101094 134909 129725 ... slowest runs (cycles): ... 131135 131417 131872 132031 132456 134909 136959 141724 147882 173640 min: 84200 cycles q1: 125084 cycles median: 127581 cycles q3: 129557 cycles max: 173640 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_with_irt_nnacl_newlib_static= {127581, 4473} count Apparent clock speed: 2613 MHz [ OK ] nacl_newlib.run_performance_test_irt (5600 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