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

Running command: /b/s/w/ir/cache/vpython/b574a6/bin/python scons.py --verbose -k -j1 --mode=nacl,opt-host,nacl_irt_test platform=x86-64 --clang breakpad_tools_dir=breakpad-out large_tests_irt scons: Reading SConscript files ... ====================================================================== SCONS ARGS:['scons.py', '--verbose', '-k', '-j1', '--mode=nacl,opt-host,nacl_irt_test', 'platform=x86-64', '--clang', 'breakpad_tools_dir=breakpad-out', 'large_tests_irt'] ====================================================================== ====================================================================== Python Info: ====================================================================== linux2 2.7.14+chromium14 (df075345c7-dirty:df075345c7, Oct 3 2017, 17:29:00) [GCC 4.8.4] ====================================================================== ENV: ====================================================================== PATH /b/s/w/ir/cache/vpython/b574a6/bin:/b/s/w/ir/cipd_bin_packages:/b/s/w/ir/cipd_bin_packages/bin:/b/s/cipd_cache/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin:/b/s/w/ir/kitchen-checkout/depot_tools ====================================================================== OS: ====================================================================== Linux gce-trusty-e833d7b0-us-west1-c-0px6 4.4.0-78-generic #99~14.04.2-Ubuntu SMP Thu Apr 27 18:49:46 UTC 2017 x86_64 x86_64 x86_64 GNU/Linux ====================================================================== CPU: ====================================================================== model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2200.000 model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2200.000 model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2200.000 model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2200.000 model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2200.000 model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2200.000 model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2200.000 model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2200.000 ====================================================================== RAM: ====================================================================== MemTotal: 30882896 kB MemFree: 15449764 kB MemAvailable: 29428624 kB ====================================================================== LOAD: ====================================================================== 1.31 1.78 1.40 1/446 7183 ====================================================================== UPTIME: ====================================================================== 40191.83 311609.80 ====================================================================== 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 [7191,1336764288:10:36:20.658764] Native Client module will be loaded at base address 0x00006cc800000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_with_irt_nnacl_newlib_static= {0.090770, 0.003540} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (1260 ms) /b/s/w/ir/cache/vpython/b574a6/bin/python tools/command_tester.py --name nacl_newlib.run_clock_cputime_test_irt --time_warning 60 --time_error 600 --perf_env_description x86-64_with_irt_nnacl_newlib_static --using_nacl_signal_handler True --subarch 64 --arch x86 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt_test-x86-64/test_results/clock_cputime_test.out scons-out/opt-linux-x86-64/staging/nacl_helper_bootstrap scons-out/opt-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -B scons-out/nacl_irt-x86-64/staging/irt_core.nexe -- scons-out/nacl_irt_test-x86-64/obj/tests/clock/clock_test.nexe -c [ RUN ] nacl_newlib.run_clock_cputime_test_irt scons-out/opt-linux-x86-64/staging/nacl_helper_bootstrap scons-out/opt-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -B scons-out/nacl_irt-x86-64/staging/irt_core.nexe -- scons-out/nacl_irt_test-x86-64/obj/tests/clock/clock_test.nexe -c WARNING: should have taken less than 60.000000 secs [ OK ] nacl_newlib.run_clock_cputime_test_irt (70090 ms) doSizeCheck(["scons-out/nacl_irt_test-x86-64/obj/tests/hello_world/hello_world_size.out"], ["scons-out/nacl_irt_test-x86-64/obj/tests/hello_world/hello_world.nexe"]) RESULT hello_world_size.out: x86-64_with_irt_nnacl_newlib_static= 530.002 KB RESULT hello_world_size.out: ZIPPED_x86-64_with_irt_nnacl_newlib_static= 187.085 KB /b/s/w/ir/cache/vpython/b574a6/bin/python tools/command_tester.py --name nacl_newlib.run_performance_test_irt --time_warning 10 --time_error 100 --perf_env_description x86-64_with_irt_nnacl_newlib_static --using_nacl_signal_handler True --subarch 64 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt_test-x86-64/test_results/performance_test.out scons-out/opt-linux-x86-64/staging/nacl_helper_bootstrap scons-out/opt-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -e -B scons-out/nacl_irt-x86-64/staging/irt_core.nexe -- scons-out/nacl_irt_test-x86-64/obj/tests/performance/performance_test.nexe x86-64_with_irt_nnacl_newlib_static [ RUN ] nacl_newlib.run_performance_test_irt scons-out/opt-linux-x86-64/staging/nacl_helper_bootstrap scons-out/opt-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -e -B scons-out/nacl_irt-x86-64/staging/irt_core.nexe -- scons-out/nacl_irt_test-x86-64/obj/tests/performance/performance_test.nexe x86-64_with_irt_nnacl_newlib_static [7220,2493196160:10:36:35.323363] Native Client module will be loaded at base address 0x000028bd00000000 TestNull: Measuring real time: 0.007 usec (6.58e-09 sec) per iteration: 6.58e-07 sec for 100 iterations 0.002 usec (1.988e-09 sec) per iteration: 1.988e-06 sec for 1000 iterations 0.002 usec (1.814e-09 sec) per iteration: 1.814e-05 sec for 10000 iterations 0.002 usec (1.86331e-09 sec) per iteration: 0.102718 sec for 55126791 iterations 0.002 usec (1.82004e-09 sec) per iteration: 0.100333 sec for 55126791 iterations 0.002 usec (1.81706e-09 sec) per iteration: 0.100169 sec for 55126791 iterations 0.002 usec (1.82218e-09 sec) per iteration: 0.100451 sec for 55126791 iterations 0.002 usec (1.82376e-09 sec) per iteration: 0.100538 sec for 55126791 iterations mean: 0.001829 usec stddev: 0.000017 usec relative stddev: 0.94% RESULT TestNull: x86-64_with_irt_nnacl_newlib_static= {0.001829, 0.000017} us Measuring clock cycles: first runs (cycles): 88 35 19 18 19 19 19 19 19 19 ... slowest runs (cycles): ... 19 19 19 19 19 19 19 19 35 88 min: 18 cycles q1: 19 cycles median: 19 cycles q3: 19 cycles max: 88 cycles RESULT TestNull_CycleCount: x86-64_with_irt_nnacl_newlib_static= {19, 0} count Apparent clock speed: 10387 MHz TestNaClSyscall: Measuring real time: 0.105 usec (1.0503e-07 sec) per iteration: 1.0503e-05 sec for 100 iterations 0.129 usec (1.29331e-07 sec) per iteration: 0.123137 sec for 952108 iterations 0.129 usec (1.28865e-07 sec) per iteration: 0.122693 sec for 952108 iterations 0.129 usec (1.28966e-07 sec) per iteration: 0.122789 sec for 952108 iterations 0.129 usec (1.28834e-07 sec) per iteration: 0.122664 sec for 952108 iterations 0.129 usec (1.29377e-07 sec) per iteration: 0.123181 sec for 952108 iterations mean: 0.129075 usec stddev: 0.000233 usec relative stddev: 0.18% RESULT TestNaClSyscall: x86-64_with_irt_nnacl_newlib_static= {0.129075, 0.000233} us Measuring clock cycles: first runs (cycles): 292 201 173 179 173 182 176 176 179 182 ... slowest runs (cycles): ... 182 182 182 182 183 183 183 183 201 292 min: 172 cycles q1: 176 cycles median: 179 cycles q3: 179 cycles max: 292 cycles RESULT TestNaClSyscall_CycleCount: x86-64_with_irt_nnacl_newlib_static= {179, 3} count Apparent clock speed: 1387 MHz TestSetjmpLongjmp: Measuring real time: 0.017 usec (1.679e-08 sec) per iteration: 1.679e-06 sec for 100 iterations 0.012 usec (1.2388e-08 sec) per iteration: 1.2388e-05 sec for 1000 iterations 0.012 usec (1.23519e-08 sec) per iteration: 0.0997082 sec for 8072328 iterations 0.012 usec (1.23425e-08 sec) per iteration: 0.0996329 sec for 8072328 iterations 0.012 usec (1.24198e-08 sec) per iteration: 0.100257 sec for 8072328 iterations 0.012 usec (1.23575e-08 sec) per iteration: 0.0997535 sec for 8072328 iterations 0.012 usec (1.23525e-08 sec) per iteration: 0.0997138 sec for 8072328 iterations mean: 0.012365 usec stddev: 0.000028 usec relative stddev: 0.23% RESULT TestSetjmpLongjmp: x86-64_with_irt_nnacl_newlib_static= {0.012365, 0.000028} us Measuring clock cycles: first runs (cycles): 406 56 32 25 25 22 25 25 25 22 ... slowest runs (cycles): ... 26 26 26 26 26 26 26 32 56 406 min: 22 cycles q1: 22 cycles median: 25 cycles q3: 25 cycles max: 406 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {25, 3} count Apparent clock speed: 2022 MHz TestClockGetTime: Measuring real time: 0.146 usec (1.4598e-07 sec) per iteration: 1.4598e-05 sec for 100 iterations 0.147 usec (1.47288e-07 sec) per iteration: 0.100896 sec for 685025 iterations 0.147 usec (1.46936e-07 sec) per iteration: 0.100655 sec for 685025 iterations 0.147 usec (1.47042e-07 sec) per iteration: 0.100728 sec for 685025 iterations 0.148 usec (1.47554e-07 sec) per iteration: 0.101078 sec for 685025 iterations 0.147 usec (1.472e-07 sec) per iteration: 0.100836 sec for 685025 iterations mean: 0.147204 usec stddev: 0.000213 usec relative stddev: 0.14% RESULT TestClockGetTime: x86-64_with_irt_nnacl_newlib_static= {0.147204, 0.000213} us Measuring clock cycles: first runs (cycles): 823 371 330 320 318 314 314 314 314 314 ... slowest runs (cycles): ... 321 321 321 321 321 321 321 330 371 823 min: 314 cycles q1: 314 cycles median: 317 cycles q3: 318 cycles max: 823 cycles RESULT TestClockGetTime_CycleCount: x86-64_with_irt_nnacl_newlib_static= {317, 4} count Apparent clock speed: 2153 MHz TestTlsVariable: Measuring real time: 0.012 usec (1.18e-08 sec) per iteration: 1.18e-06 sec for 100 iterations 0.006 usec (5.949e-09 sec) per iteration: 5.949e-06 sec for 1000 iterations 0.008 usec (7.7377e-09 sec) per iteration: 7.7377e-05 sec for 10000 iterations 0.006 usec (5.82717e-09 sec) per iteration: 0.0753088 sec for 12923737 iterations 0.006 usec (5.80663e-09 sec) per iteration: 0.0750433 sec for 12923737 iterations 0.006 usec (5.81906e-09 sec) per iteration: 0.075204 sec for 12923737 iterations 0.006 usec (5.82028e-09 sec) per iteration: 0.0752198 sec for 12923737 iterations 0.006 usec (5.85981e-09 sec) per iteration: 0.0757306 sec for 12923737 iterations mean: 0.005827 usec stddev: 0.000018 usec relative stddev: 0.31% RESULT TestTlsVariable: x86-64_with_irt_nnacl_newlib_static= {0.005827, 0.000018} us Measuring clock cycles: first runs (cycles): 179 63 41 28 19 19 22 22 19 19 ... slowest runs (cycles): ... 22 22 22 22 22 22 28 41 63 179 min: 18 cycles q1: 19 cycles median: 19 cycles q3: 22 cycles max: 179 cycles RESULT TestTlsVariable_CycleCount: x86-64_with_irt_nnacl_newlib_static= {19, 3} count Apparent clock speed: 3261 MHz TestMmapAnonymous: Measuring real time: 2.162 usec (2.16181e-06 sec) per iteration: 0.000216181 sec for 100 iterations 1.969 usec (1.96925e-06 sec) per iteration: 0.0910916 sec for 46257 iterations 1.971 usec (1.97086e-06 sec) per iteration: 0.091166 sec for 46257 iterations 1.967 usec (1.96676e-06 sec) per iteration: 0.0909764 sec for 46257 iterations 1.963 usec (1.9632e-06 sec) per iteration: 0.0908119 sec for 46257 iterations 1.965 usec (1.96527e-06 sec) per iteration: 0.0909073 sec for 46257 iterations mean: 1.967068 usec stddev: 0.002735 usec relative stddev: 0.14% RESULT TestMmapAnonymous: x86-64_with_irt_nnacl_newlib_static= {1.967068, 0.002735} us Measuring clock cycles: first runs (cycles): 7744 4359 4277 4321 4275 4240 4252 4256 4275 4252 ... slowest runs (cycles): ... 4321 4325 4331 4334 4343 4359 4359 4362 4369 7744 min: 4224 cycles q1: 4252 cycles median: 4265 cycles q3: 4280 cycles max: 7744 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_with_irt_nnacl_newlib_static= {4265, 28} count Apparent clock speed: 2168 MHz TestAtomicIncrement: Measuring real time: 0.017 usec (1.711e-08 sec) per iteration: 1.711e-06 sec for 100 iterations 0.011 usec (1.0775e-08 sec) per iteration: 1.0775e-05 sec for 1000 iterations 0.009 usec (9.42053e-09 sec) per iteration: 0.0874295 sec for 9280742 iterations 0.009 usec (9.41592e-09 sec) per iteration: 0.0873867 sec for 9280742 iterations 0.009 usec (9.42651e-09 sec) per iteration: 0.087485 sec for 9280742 iterations 0.009 usec (9.41897e-09 sec) per iteration: 0.0874151 sec for 9280742 iterations 0.009 usec (9.47443e-09 sec) per iteration: 0.0879298 sec for 9280742 iterations mean: 0.009431 usec stddev: 0.000022 usec relative stddev: 0.23% RESULT TestAtomicIncrement: x86-64_with_irt_nnacl_newlib_static= {0.009431, 0.000022} us Measuring clock cycles: first runs (cycles): 173 50 47 47 47 47 47 47 47 47 ... slowest runs (cycles): ... 47 47 47 47 47 47 47 47 50 173 min: 47 cycles q1: 47 cycles median: 47 cycles q3: 47 cycles max: 173 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_with_irt_nnacl_newlib_static= {47, 0} count Apparent clock speed: 4983 MHz TestUncontendedMutexLock: Measuring real time: 0.028 usec (2.757e-08 sec) per iteration: 2.757e-06 sec for 100 iterations 0.023 usec (2.3416e-08 sec) per iteration: 2.3416e-05 sec for 1000 iterations 0.024 usec (2.35795e-08 sec) per iteration: 0.100698 sec for 4270584 iterations 0.024 usec (2.36149e-08 sec) per iteration: 0.100849 sec for 4270584 iterations 0.024 usec (2.35914e-08 sec) per iteration: 0.100749 sec for 4270584 iterations 0.024 usec (2.35543e-08 sec) per iteration: 0.100591 sec for 4270584 iterations 0.024 usec (2.35379e-08 sec) per iteration: 0.100521 sec for 4270584 iterations mean: 0.023576 usec stddev: 0.000027 usec relative stddev: 0.12% RESULT TestUncontendedMutexLock: x86-64_with_irt_nnacl_newlib_static= {0.023576, 0.000027} us Measuring clock cycles: first runs (cycles): 255 100 76 76 75 75 76 75 75 76 ... slowest runs (cycles): ... 76 76 76 76 76 76 76 76 100 255 min: 72 cycles q1: 75 cycles median: 75 cycles q3: 76 cycles max: 255 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_with_irt_nnacl_newlib_static= {75, 1} count Apparent clock speed: 3181 MHz TestCondvarSignalNoOp: Measuring real time: 0.225 usec (2.2503e-07 sec) per iteration: 2.2503e-05 sec for 100 iterations 0.207 usec (2.07235e-07 sec) per iteration: 0.0920923 sec for 444385 iterations 0.207 usec (2.07443e-07 sec) per iteration: 0.0921847 sec for 444385 iterations 0.207 usec (2.07371e-07 sec) per iteration: 0.0921528 sec for 444385 iterations 0.207 usec (2.07246e-07 sec) per iteration: 0.0920968 sec for 444385 iterations 0.208 usec (2.07875e-07 sec) per iteration: 0.0923765 sec for 444385 iterations mean: 0.207434 usec stddev: 0.000234 usec relative stddev: 0.11% RESULT TestCondvarSignalNoOp: x86-64_with_irt_nnacl_newlib_static= {0.207434, 0.000234} us Measuring clock cycles: first runs (cycles): 880 502 484 459 459 465 462 466 459 459 ... slowest runs (cycles): ... 462 462 462 462 465 466 471 484 502 880 min: 458 cycles q1: 459 cycles median: 459 cycles q3: 459 cycles max: 880 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {459, 0} count Apparent clock speed: 2213 MHz TestThreadCreateAndJoin: Measuring real time: 41.942 usec (4.19422e-05 sec) per iteration: 0.00419422 sec for 100 iterations 27.222 usec (2.72221e-05 sec) per iteration: 0.0648975 sec for 2384 iterations 26.012 usec (2.60121e-05 sec) per iteration: 0.0620128 sec for 2384 iterations 25.744 usec (2.57438e-05 sec) per iteration: 0.0613733 sec for 2384 iterations 25.329 usec (2.5329e-05 sec) per iteration: 0.0603844 sec for 2384 iterations 27.116 usec (2.71162e-05 sec) per iteration: 0.064645 sec for 2384 iterations mean: 26.284644 usec stddev: 0.755026 usec relative stddev: 2.87% RESULT TestThreadCreateAndJoin: x86-64_with_irt_nnacl_newlib_static= {26.284644, 0.755026} us Measuring clock cycles: first runs (cycles): 86771 1142146 138144 119206 143631 90467 82358 80448 76855 74439 ... slowest runs (cycles): ... 76855 80448 82358 86771 90467 94600 119206 138144 143631 1142146 min: 48897 cycles q1: 50456 cycles median: 51339 cycles q3: 52838 cycles max: 1142146 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_with_irt_nnacl_newlib_static= {51339, 2382} count Apparent clock speed: 1953 MHz TestThreadWakeup: Measuring real time: 12.574 usec (1.25744e-05 sec) per iteration: 0.00125744 sec for 100 iterations 12.958 usec (1.29576e-05 sec) per iteration: 0.103039 sec for 7952 iterations 13.695 usec (1.36948e-05 sec) per iteration: 0.108901 sec for 7952 iterations 13.307 usec (1.33065e-05 sec) per iteration: 0.105814 sec for 7952 iterations 15.661 usec (1.56612e-05 sec) per iteration: 0.124538 sec for 7952 iterations 15.931 usec (1.59314e-05 sec) per iteration: 0.126687 sec for 7952 iterations mean: 14.310308 usec stddev: 1.238466 usec relative stddev: 8.65% RESULT TestThreadWakeup: x86-64_with_irt_nnacl_newlib_static= {14.310308, 1.238466} us Measuring clock cycles: first runs (cycles): 38519 34744 33971 33582 36527 34323 34002 35693 34182 34169 ... slowest runs (cycles): ... 43174 43431 44704 46112 47410 47441 48922 51062 53180 60082 min: 33503 cycles q1: 34141 cycles median: 34543 cycles q3: 36822 cycles max: 60082 cycles RESULT TestThreadWakeup_CycleCount: x86-64_with_irt_nnacl_newlib_static= {34543, 2681} count Apparent clock speed: 2414 MHz TestCatchingFault: Measuring real time: 2.921 usec (2.9205e-06 sec) per iteration: 0.00029205 sec for 100 iterations 1.585 usec (1.58534e-06 sec) per iteration: 0.054282 sec for 34240 iterations 1.599 usec (1.59908e-06 sec) per iteration: 0.0547525 sec for 34240 iterations 1.584 usec (1.58414e-06 sec) per iteration: 0.0542409 sec for 34240 iterations 1.584 usec (1.58391e-06 sec) per iteration: 0.0542332 sec for 34240 iterations 1.586 usec (1.5862e-06 sec) per iteration: 0.0543113 sec for 34240 iterations mean: 1.587734 usec stddev: 0.005733 usec relative stddev: 0.36% RESULT TestCatchingFault: x86-64_with_irt_nnacl_newlib_static= {1.587734, 0.005733} us Measuring clock cycles: first runs (cycles): 5038 3460 3457 3441 3432 3439 3454 3450 3435 3439 ... slowest runs (cycles): ... 4296 4400 4418 4507 4513 4598 4645 5038 18612 23310 min: 3426 cycles q1: 3444 cycles median: 3451 cycles q3: 3458 cycles max: 23310 cycles RESULT TestCatchingFault_CycleCount: x86-64_with_irt_nnacl_newlib_static= {3451, 14} count Apparent clock speed: 2174 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 35.863 usec (3.58633e-05 sec) per iteration: 0.00358633 sec for 100 iterations 28.366 usec (2.83663e-05 sec) per iteration: 0.0790852 sec for 2788 iterations 25.064 usec (2.5064e-05 sec) per iteration: 0.0698785 sec for 2788 iterations 26.512 usec (2.65121e-05 sec) per iteration: 0.0739156 sec for 2788 iterations 26.741 usec (2.67405e-05 sec) per iteration: 0.0745526 sec for 2788 iterations 25.479 usec (2.54789e-05 sec) per iteration: 0.0710351 sec for 2788 iterations mean: 26.432352 usec stddev: 1.150897 usec relative stddev: 4.35% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_with_irt_nnacl_newlib_static= {26.432352, 1.150897} us Measuring clock cycles: first runs (cycles): 58246 89285 52929 53761 75954 54984 53441 52275 54415 102476 ... slowest runs (cycles): ... 71679 75954 80140 88531 89285 93751 102476 104689 117763 122939 min: 47350 cycles q1: 51427 cycles median: 52254 cycles q3: 53761 cycles max: 122939 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_with_irt_nnacl_newlib_static= {52254, 2334} count Apparent clock speed: 1977 MHz [ OK ] nacl_newlib.run_performance_test_irt (6029 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