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-east1-b-s3qm 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: 20261756 kB MemAvailable: 29478572 kB ====================================================================== LOAD: ====================================================================== 1.58 1.94 1.16 1/435 7636 ====================================================================== UPTIME: ====================================================================== 645.75 3853.57 ====================================================================== 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 [7644,2489599872:15:41:27.049722] Native Client module will be loaded at base address 0x0000129f00000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_with_irt_nnacl_newlib_static= {0.104170, 0.012090} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (1690 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 (75869 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 [7672,607590272:15:41:43.741471] Native Client module will be loaded at base address 0x0000649900000000 TestNull: Measuring real time: 0.006 usec (6.26e-09 sec) per iteration: 6.26e-07 sec for 100 iterations 0.002 usec (2.083e-09 sec) per iteration: 2.083e-06 sec for 1000 iterations 0.002 usec (1.8751e-09 sec) per iteration: 1.8751e-05 sec for 10000 iterations 0.002 usec (1.90302e-09 sec) per iteration: 0.101489 sec for 53330489 iterations 0.002 usec (1.89629e-09 sec) per iteration: 0.10113 sec for 53330489 iterations 0.002 usec (1.9274e-09 sec) per iteration: 0.102789 sec for 53330489 iterations 0.002 usec (1.90743e-09 sec) per iteration: 0.101724 sec for 53330489 iterations 0.002 usec (1.89596e-09 sec) per iteration: 0.101112 sec for 53330489 iterations mean: 0.001906 usec stddev: 0.000012 usec relative stddev: 0.60% RESULT TestNull: x86-64_with_irt_nnacl_newlib_static= {0.001906, 0.000012} us Measuring clock cycles: first runs (cycles): 95 26 24 27 22 22 26 24 26 24 ... slowest runs (cycles): ... 27 27 27 27 27 27 27 27 27 95 min: 22 cycles q1: 24 cycles median: 25 cycles q3: 26 cycles max: 95 cycles RESULT TestNull_CycleCount: x86-64_with_irt_nnacl_newlib_static= {25, 2} count Apparent clock speed: 13116 MHz TestNaClSyscall: Measuring real time: 0.144 usec (1.4423e-07 sec) per iteration: 1.4423e-05 sec for 100 iterations 0.134 usec (1.34456e-07 sec) per iteration: 0.0932237 sec for 693337 iterations 0.138 usec (1.37802e-07 sec) per iteration: 0.0955429 sec for 693337 iterations 0.131 usec (1.31222e-07 sec) per iteration: 0.0909808 sec for 693337 iterations 0.132 usec (1.32415e-07 sec) per iteration: 0.0918084 sec for 693337 iterations 0.133 usec (1.33439e-07 sec) per iteration: 0.0925182 sec for 693337 iterations mean: 0.133867 usec stddev: 0.002241 usec relative stddev: 1.67% RESULT TestNaClSyscall: x86-64_with_irt_nnacl_newlib_static= {0.133867, 0.002241} us Measuring clock cycles: first runs (cycles): 281 217 203 179 176 180 178 180 181 213 ... slowest runs (cycles): ... 181 181 181 181 189 203 213 215 217 281 min: 176 cycles q1: 178 cycles median: 178 cycles q3: 180 cycles max: 281 cycles RESULT TestNaClSyscall_CycleCount: x86-64_with_irt_nnacl_newlib_static= {178, 2} count Apparent clock speed: 1330 MHz TestSetjmpLongjmp: Measuring real time: 0.015 usec (1.542e-08 sec) per iteration: 1.542e-06 sec for 100 iterations 0.013 usec (1.2758e-08 sec) per iteration: 1.2758e-05 sec for 1000 iterations 0.013 usec (1.28416e-08 sec) per iteration: 0.100655 sec for 7838219 iterations 0.013 usec (1.28917e-08 sec) per iteration: 0.101048 sec for 7838219 iterations 0.013 usec (1.28642e-08 sec) per iteration: 0.100832 sec for 7838219 iterations 0.013 usec (1.29159e-08 sec) per iteration: 0.101238 sec for 7838219 iterations 0.013 usec (1.2863e-08 sec) per iteration: 0.100823 sec for 7838219 iterations mean: 0.012875 usec stddev: 0.000026 usec relative stddev: 0.20% RESULT TestSetjmpLongjmp: x86-64_with_irt_nnacl_newlib_static= {0.012875, 0.000026} us Measuring clock cycles: first runs (cycles): 180 57 57 58 39 33 30 31 29 26 ... slowest runs (cycles): ... 30 30 30 31 33 39 57 57 58 180 min: 26 cycles q1: 27 cycles median: 28 cycles q3: 29 cycles max: 180 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {28, 2} count Apparent clock speed: 2175 MHz TestClockGetTime: Measuring real time: 0.149 usec (1.493e-07 sec) per iteration: 1.493e-05 sec for 100 iterations 0.152 usec (1.52182e-07 sec) per iteration: 0.10193 sec for 669792 iterations 0.154 usec (1.5362e-07 sec) per iteration: 0.102894 sec for 669792 iterations 0.154 usec (1.53896e-07 sec) per iteration: 0.103078 sec for 669792 iterations 0.155 usec (1.55354e-07 sec) per iteration: 0.104055 sec for 669792 iterations 0.154 usec (1.5443e-07 sec) per iteration: 0.103436 sec for 669792 iterations mean: 0.153896 usec stddev: 0.001041 usec relative stddev: 0.68% RESULT TestClockGetTime: x86-64_with_irt_nnacl_newlib_static= {0.153896, 0.001041} us Measuring clock cycles: first runs (cycles): 658 422 418 398 396 398 392 398 396 400 ... slowest runs (cycles): ... 401 401 401 401 401 405 418 422 458 658 min: 392 cycles q1: 396 cycles median: 398 cycles q3: 399 cycles max: 658 cycles RESULT TestClockGetTime_CycleCount: x86-64_with_irt_nnacl_newlib_static= {398, 3} count Apparent clock speed: 2586 MHz TestTlsVariable: Measuring real time: 0.036 usec (3.597e-08 sec) per iteration: 3.597e-06 sec for 100 iterations 0.006 usec (5.785e-09 sec) per iteration: 5.785e-06 sec for 1000 iterations 0.006 usec (5.5799e-09 sec) per iteration: 5.5799e-05 sec for 10000 iterations 0.006 usec (5.68946e-09 sec) per iteration: 0.101963 sec for 17921468 iterations 0.006 usec (5.69795e-09 sec) per iteration: 0.102116 sec for 17921468 iterations 0.006 usec (5.69844e-09 sec) per iteration: 0.102124 sec for 17921468 iterations 0.006 usec (5.78984e-09 sec) per iteration: 0.103763 sec for 17921468 iterations 0.006 usec (5.73227e-09 sec) per iteration: 0.102731 sec for 17921468 iterations mean: 0.005722 usec stddev: 0.000037 usec relative stddev: 0.65% RESULT TestTlsVariable: x86-64_with_irt_nnacl_newlib_static= {0.005722, 0.000037} us Measuring clock cycles: first runs (cycles): 156 77 75 53 31 26 33 26 29 31 ... slowest runs (cycles): ... 33 33 33 33 35 36 53 75 77 156 min: 24 cycles q1: 28 cycles median: 29 cycles q3: 31 cycles max: 156 cycles RESULT TestTlsVariable_CycleCount: x86-64_with_irt_nnacl_newlib_static= {29, 3} count Apparent clock speed: 5069 MHz TestMmapAnonymous: Measuring real time: 2.203 usec (2.20336e-06 sec) per iteration: 0.000220336 sec for 100 iterations 1.928 usec (1.92847e-06 sec) per iteration: 0.0875237 sec for 45385 iterations 2.028 usec (2.02835e-06 sec) per iteration: 0.0920567 sec for 45385 iterations 1.902 usec (1.90233e-06 sec) per iteration: 0.0863375 sec for 45385 iterations 1.915 usec (1.91537e-06 sec) per iteration: 0.086929 sec for 45385 iterations 1.910 usec (1.90955e-06 sec) per iteration: 0.0866649 sec for 45385 iterations mean: 1.936815 usec stddev: 0.046563 usec relative stddev: 2.40% RESULT TestMmapAnonymous: x86-64_with_irt_nnacl_newlib_static= {1.936815, 0.046563} us Measuring clock cycles: first runs (cycles): 5770 4145 4087 4087 4129 4092 4067 4089 4055 4090 ... slowest runs (cycles): ... 4142 4145 4151 4173 4186 4296 4477 4538 5770 12319 min: 4025 cycles q1: 4061 cycles median: 4083 cycles q3: 4105 cycles max: 12319 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_with_irt_nnacl_newlib_static= {4083, 44} count Apparent clock speed: 2108 MHz TestAtomicIncrement: Measuring real time: 0.013 usec (1.328e-08 sec) per iteration: 1.328e-06 sec for 100 iterations 0.009 usec (9.458e-09 sec) per iteration: 9.458e-06 sec for 1000 iterations 0.009 usec (9.2828e-09 sec) per iteration: 9.2828e-05 sec for 10000 iterations 0.009 usec (9.45962e-09 sec) per iteration: 0.101905 sec for 10772611 iterations 0.010 usec (9.5319e-09 sec) per iteration: 0.102683 sec for 10772611 iterations 0.009 usec (9.4693e-09 sec) per iteration: 0.102009 sec for 10772611 iterations 0.009 usec (9.48167e-09 sec) per iteration: 0.102142 sec for 10772611 iterations 0.009 usec (9.44499e-09 sec) per iteration: 0.101747 sec for 10772611 iterations mean: 0.009477 usec stddev: 0.000030 usec relative stddev: 0.31% RESULT TestAtomicIncrement: x86-64_with_irt_nnacl_newlib_static= {0.009477, 0.000030} us Measuring clock cycles: first runs (cycles): 108 52 53 50 51 50 51 51 51 51 ... slowest runs (cycles): ... 53 53 53 53 53 53 53 53 53 108 min: 48 cycles q1: 51 cycles median: 53 cycles q3: 53 cycles max: 108 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_with_irt_nnacl_newlib_static= {53, 2} count Apparent clock speed: 5592 MHz TestUncontendedMutexLock: Measuring real time: 0.029 usec (2.9e-08 sec) per iteration: 2.9e-06 sec for 100 iterations 0.024 usec (2.428e-08 sec) per iteration: 2.428e-05 sec for 1000 iterations 0.025 usec (2.46197e-08 sec) per iteration: 0.101399 sec for 4118616 iterations 0.025 usec (2.46181e-08 sec) per iteration: 0.101393 sec for 4118616 iterations 0.025 usec (2.46976e-08 sec) per iteration: 0.10172 sec for 4118616 iterations 0.025 usec (2.4832e-08 sec) per iteration: 0.102274 sec for 4118616 iterations 0.025 usec (2.46836e-08 sec) per iteration: 0.101662 sec for 4118616 iterations mean: 0.024690 usec stddev: 0.000078 usec relative stddev: 0.32% RESULT TestUncontendedMutexLock: x86-64_with_irt_nnacl_newlib_static= {0.024690, 0.000078} us Measuring clock cycles: first runs (cycles): 240 108 81 84 82 83 92 83 84 83 ... slowest runs (cycles): ... 84 84 84 84 84 84 84 92 108 240 min: 81 cycles q1: 83 cycles median: 84 cycles q3: 84 cycles max: 240 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_with_irt_nnacl_newlib_static= {84, 1} count Apparent clock speed: 3402 MHz TestCondvarSignalNoOp: Measuring real time: 0.247 usec (2.4726e-07 sec) per iteration: 2.4726e-05 sec for 100 iterations 0.215 usec (2.15112e-07 sec) per iteration: 0.0869982 sec for 404432 iterations 0.215 usec (2.1467e-07 sec) per iteration: 0.0868195 sec for 404432 iterations 0.215 usec (2.15308e-07 sec) per iteration: 0.0870776 sec for 404432 iterations 0.214 usec (2.13798e-07 sec) per iteration: 0.0864669 sec for 404432 iterations 0.218 usec (2.18147e-07 sec) per iteration: 0.0882256 sec for 404432 iterations mean: 0.215407 usec stddev: 0.001465 usec relative stddev: 0.68% RESULT TestCondvarSignalNoOp: x86-64_with_irt_nnacl_newlib_static= {0.215407, 0.001465} us Measuring clock cycles: first runs (cycles): 653 508 506 497 480 482 477 479 482 484 ... slowest runs (cycles): ... 484 484 484 484 486 486 497 506 508 653 min: 477 cycles q1: 480 cycles median: 481 cycles q3: 482 cycles max: 653 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {481, 2} count Apparent clock speed: 2233 MHz TestThreadCreateAndJoin: Measuring real time: 95.736 usec (9.57356e-05 sec) per iteration: 0.00957356 sec for 100 iterations 79.613 usec (7.96135e-05 sec) per iteration: 0.0831165 sec for 1044 iterations 29.100 usec (2.90996e-05 sec) per iteration: 0.03038 sec for 1044 iterations 39.605 usec (3.96051e-05 sec) per iteration: 0.0413477 sec for 1044 iterations 28.348 usec (2.83483e-05 sec) per iteration: 0.0295956 sec for 1044 iterations 28.095 usec (2.80953e-05 sec) per iteration: 0.0293315 sec for 1044 iterations mean: 40.952355 usec stddev: 19.804798 usec relative stddev: 48.36% RESULT TestThreadCreateAndJoin: x86-64_with_irt_nnacl_newlib_static= {40.952355, 19.804798} us Measuring clock cycles: first runs (cycles): 98115 165415 71676 68152 50459 264503 88368 52445 48637 49141 ... slowest runs (cycles): ... 85328 88368 91951 98115 101089 106457 107044 113719 165415 264503 min: 45069 cycles q1: 47034 cycles median: 48254 cycles q3: 52445 cycles max: 264503 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_with_irt_nnacl_newlib_static= {48254, 5411} count Apparent clock speed: 1178 MHz TestThreadWakeup: Measuring real time: 16.792 usec (1.67917e-05 sec) per iteration: 0.00167917 sec for 100 iterations 14.567 usec (1.45671e-05 sec) per iteration: 0.0867473 sec for 5955 iterations 14.602 usec (1.46016e-05 sec) per iteration: 0.0869524 sec for 5955 iterations 13.779 usec (1.37788e-05 sec) per iteration: 0.0820525 sec for 5955 iterations 13.923 usec (1.39225e-05 sec) per iteration: 0.0829086 sec for 5955 iterations 14.396 usec (1.43965e-05 sec) per iteration: 0.085731 sec for 5955 iterations mean: 14.253293 usec stddev: 0.339086 usec relative stddev: 2.38% RESULT TestThreadWakeup: x86-64_with_irt_nnacl_newlib_static= {14.253293, 0.339086} us Measuring clock cycles: first runs (cycles): 32350 29967 30118 29422 29730 29053 29776 29717 29382 29792 ... slowest runs (cycles): ... 31695 32035 32350 32491 37733 37843 38633 48708 89736 93894 min: 29002 cycles q1: 29484 cycles median: 29948 cycles q3: 30415 cycles max: 93894 cycles RESULT TestThreadWakeup_CycleCount: x86-64_with_irt_nnacl_newlib_static= {29948, 931} count Apparent clock speed: 2101 MHz TestCatchingFault: Measuring real time: 2.698 usec (2.69768e-06 sec) per iteration: 0.000269768 sec for 100 iterations 1.710 usec (1.71021e-06 sec) per iteration: 0.0633939 sec for 37068 iterations 1.712 usec (1.71199e-06 sec) per iteration: 0.0634602 sec for 37068 iterations 1.711 usec (1.71105e-06 sec) per iteration: 0.0634252 sec for 37068 iterations 1.712 usec (1.71201e-06 sec) per iteration: 0.0634607 sec for 37068 iterations 1.721 usec (1.72122e-06 sec) per iteration: 0.0638022 sec for 37068 iterations mean: 1.713295 usec stddev: 0.004018 usec relative stddev: 0.23% RESULT TestCatchingFault: x86-64_with_irt_nnacl_newlib_static= {1.713295, 0.004018} us Measuring clock cycles: first runs (cycles): 5105 3823 3749 3709 3705 3694 3724 3691 3705 3682 ... slowest runs (cycles): ... 3707 3709 3724 3749 3823 3828 3839 3841 3845 5105 min: 3658 cycles q1: 3685 cycles median: 3694 cycles q3: 3698 cycles max: 5105 cycles RESULT TestCatchingFault_CycleCount: x86-64_with_irt_nnacl_newlib_static= {3694, 13} count Apparent clock speed: 2156 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 83.764 usec (8.37642e-05 sec) per iteration: 0.00837642 sec for 100 iterations 62.211 usec (6.22106e-05 sec) per iteration: 0.0742172 sec for 1193 iterations 53.837 usec (5.38367e-05 sec) per iteration: 0.0642271 sec for 1193 iterations 51.241 usec (5.12414e-05 sec) per iteration: 0.061131 sec for 1193 iterations 52.234 usec (5.22337e-05 sec) per iteration: 0.0623148 sec for 1193 iterations 47.661 usec (4.76612e-05 sec) per iteration: 0.0568599 sec for 1193 iterations mean: 53.436723 usec stddev: 4.832456 usec relative stddev: 9.04% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_with_irt_nnacl_newlib_static= {53.436723, 4.832456} us Measuring clock cycles: first runs (cycles): 146181 157227 64916 324861 167714 73561 58622 69709 56590 93583 ... slowest runs (cycles): ... 276209 324861 335027 337653 354419 383719 385823 405158 419289 452723 min: 53286 cycles q1: 65512 cycles median: 103262 cycles q3: 157227 cycles max: 452723 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_with_irt_nnacl_newlib_static= {103262, 91715} count Apparent clock speed: 1932 MHz [ OK ] nacl_newlib.run_performance_test_irt (5820 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