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 luci-flex-try-trusty-7-mna2 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: 18877048 kB MemAvailable: 29430800 kB ====================================================================== LOAD: ====================================================================== 0.98 1.79 1.80 1/415 3680 ====================================================================== UPTIME: ====================================================================== 2432.38 15702.52 ====================================================================== The following environments have been configured ====================================================================== NACL_BUILD_FAMILY: UNTRUSTED BUILD_TYPE: nacl TARGET_ROOT: /b/s/w/ir/k/native_client/scons-out/nacl-x86-64 OBJ_ROOT: /b/s/w/ir/k/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/k/native_client/toolchain/linux_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /b/s/w/ir/k/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/k/native_client/scons-out/nacl-x86-64/include -I/b/s/w/ir/k -c -o UNKNOWN COMPILER NACL_BUILD_FAMILY: TRUSTED BUILD_TYPE: opt-linux TARGET_ROOT: /b/s/w/ir/k/native_client/scons-out/opt-linux-x86-64 OBJ_ROOT: /b/s/w/ir/k/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/k/third_party/llvm-build/Release+Asserts/bin/clang ASPPCOM: /b/s/w/ir/k/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/k -c -o UNKNOWN COMPILER NACL_BUILD_FAMILY: UNTRUSTED_IRT_TESTS BUILD_TYPE: nacl_irt_test TARGET_ROOT: /b/s/w/ir/k/native_client/scons-out/nacl_irt_test-x86-64 OBJ_ROOT: /b/s/w/ir/k/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/k/native_client/toolchain/linux_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /b/s/w/ir/k/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/k -c -o UNKNOWN COMPILER NACL_BUILD_FAMILY: UNTRUSTED_IRT BUILD_TYPE: nacl_irt TARGET_ROOT: /b/s/w/ir/k/native_client/scons-out/nacl_irt-x86-64 OBJ_ROOT: /b/s/w/ir/k/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/k/native_client/toolchain/linux_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /b/s/w/ir/k/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/k/native_client/scons-out/nacl_irt-x86-64/include -I/b/s/w/ir/k -I/b/s/w/ir/k/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/k/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 [3688,3153753984:13:54:25.921958] Native Client module will be loaded at base address 0x0000131e00000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_with_irt_nnacl_newlib_static= {0.102500, 0.001630} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (1660 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/k/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 (74760 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= 526.650 KB RESULT hello_world_size.out: ZIPPED_x86-64_with_irt_nnacl_newlib_static= 187.021 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/k/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 [3716,1530054528:13:54:41.619081] Native Client module will be loaded at base address 0x0000148400000000 TestNull: Measuring real time: 0.006 usec (6.04e-09 sec) per iteration: 6.04e-07 sec for 100 iterations 0.002 usec (2.054e-09 sec) per iteration: 2.054e-06 sec for 1000 iterations 0.002 usec (1.8757e-09 sec) per iteration: 1.8757e-05 sec for 10000 iterations 0.002 usec (1.92636e-09 sec) per iteration: 0.102701 sec for 53313429 iterations 0.002 usec (1.91222e-09 sec) per iteration: 0.101947 sec for 53313429 iterations 0.002 usec (1.92371e-09 sec) per iteration: 0.10256 sec for 53313429 iterations 0.002 usec (1.93789e-09 sec) per iteration: 0.103315 sec for 53313429 iterations 0.002 usec (1.93637e-09 sec) per iteration: 0.103235 sec for 53313429 iterations mean: 0.001927 usec stddev: 0.000009 usec relative stddev: 0.48% RESULT TestNull: x86-64_with_irt_nnacl_newlib_static= {0.001927, 0.000009} us Measuring clock cycles: first runs (cycles): 112 26 25 26 26 24 26 49 24 28 ... slowest runs (cycles): ... 27 27 27 28 28 29 29 29 49 112 min: 20 cycles q1: 24 cycles median: 24 cycles q3: 26 cycles max: 112 cycles RESULT TestNull_CycleCount: x86-64_with_irt_nnacl_newlib_static= {24, 2} count Apparent clock speed: 12453 MHz TestNaClSyscall: Measuring real time: 0.142 usec (1.4203e-07 sec) per iteration: 1.4203e-05 sec for 100 iterations 0.128 usec (1.28446e-07 sec) per iteration: 0.0904356 sec for 704076 iterations 0.128 usec (1.2769e-07 sec) per iteration: 0.0899035 sec for 704076 iterations 0.127 usec (1.27115e-07 sec) per iteration: 0.0894988 sec for 704076 iterations 0.126 usec (1.26306e-07 sec) per iteration: 0.0889288 sec for 704076 iterations 0.128 usec (1.28453e-07 sec) per iteration: 0.090441 sec for 704076 iterations mean: 0.127602 usec stddev: 0.000820 usec relative stddev: 0.64% RESULT TestNaClSyscall: x86-64_with_irt_nnacl_newlib_static= {0.127602, 0.000820} us Measuring clock cycles: first runs (cycles): 414 213 198 176 180 181 217 220 178 176 ... slowest runs (cycles): ... 182 182 183 183 198 213 217 220 222 414 min: 175 cycles q1: 178 cycles median: 178 cycles q3: 180 cycles max: 414 cycles RESULT TestNaClSyscall_CycleCount: x86-64_with_irt_nnacl_newlib_static= {178, 2} count Apparent clock speed: 1395 MHz TestSetjmpLongjmp: Measuring real time: 0.015 usec (1.503e-08 sec) per iteration: 1.503e-06 sec for 100 iterations 0.013 usec (1.2755e-08 sec) per iteration: 1.2755e-05 sec for 1000 iterations 0.013 usec (1.30071e-08 sec) per iteration: 0.101977 sec for 7840062 iterations 0.013 usec (1.29709e-08 sec) per iteration: 0.101693 sec for 7840062 iterations 0.013 usec (1.30518e-08 sec) per iteration: 0.102327 sec for 7840062 iterations 0.013 usec (1.30565e-08 sec) per iteration: 0.102364 sec for 7840062 iterations 0.013 usec (1.30627e-08 sec) per iteration: 0.102413 sec for 7840062 iterations mean: 0.013030 usec stddev: 0.000035 usec relative stddev: 0.27% RESULT TestSetjmpLongjmp: x86-64_with_irt_nnacl_newlib_static= {0.013030, 0.000035} us Measuring clock cycles: first runs (cycles): 211 57 40 33 33 35 31 26 27 26 ... slowest runs (cycles): ... 31 31 31 31 33 33 35 40 57 211 min: 24 cycles q1: 26 cycles median: 27 cycles q3: 29 cycles max: 211 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {27, 3} count Apparent clock speed: 2072 MHz TestClockGetTime: Measuring real time: 0.151 usec (1.506e-07 sec) per iteration: 1.506e-05 sec for 100 iterations 0.157 usec (1.57067e-07 sec) per iteration: 0.104294 sec for 664010 iterations 0.157 usec (1.56566e-07 sec) per iteration: 0.103962 sec for 664010 iterations 0.159 usec (1.5889e-07 sec) per iteration: 0.105505 sec for 664010 iterations 0.157 usec (1.56611e-07 sec) per iteration: 0.103991 sec for 664010 iterations 0.158 usec (1.57678e-07 sec) per iteration: 0.1047 sec for 664010 iterations mean: 0.157362 usec stddev: 0.000862 usec relative stddev: 0.55% RESULT TestClockGetTime: x86-64_with_irt_nnacl_newlib_static= {0.157362, 0.000862} us Measuring clock cycles: first runs (cycles): 669 411 346 344 346 338 378 335 334 334 ... slowest runs (cycles): ... 341 341 343 344 346 346 378 381 411 669 min: 332 cycles q1: 335 cycles median: 337 cycles q3: 339 cycles max: 669 cycles RESULT TestClockGetTime_CycleCount: x86-64_with_irt_nnacl_newlib_static= {337, 4} count Apparent clock speed: 2142 MHz TestTlsVariable: Measuring real time: 0.010 usec (9.52e-09 sec) per iteration: 9.52e-07 sec for 100 iterations 0.006 usec (5.806e-09 sec) per iteration: 5.806e-06 sec for 1000 iterations 0.006 usec (5.5795e-09 sec) per iteration: 5.5795e-05 sec for 10000 iterations 0.006 usec (5.9627e-09 sec) per iteration: 0.106868 sec for 17922752 iterations 0.006 usec (5.95449e-09 sec) per iteration: 0.106721 sec for 17922752 iterations 0.006 usec (5.91081e-09 sec) per iteration: 0.105938 sec for 17922752 iterations 0.006 usec (6.17393e-09 sec) per iteration: 0.110654 sec for 17922752 iterations 0.006 usec (6.00185e-09 sec) per iteration: 0.10757 sec for 17922752 iterations mean: 0.006001 usec stddev: 0.000091 usec relative stddev: 1.52% RESULT TestTlsVariable: x86-64_with_irt_nnacl_newlib_static= {0.006001, 0.000091} us Measuring clock cycles: first runs (cycles): 187 75 53 33 29 29 29 31 33 29 ... slowest runs (cycles): ... 33 33 33 33 33 33 35 53 75 187 min: 26 cycles q1: 28 cycles median: 29 cycles q3: 31 cycles max: 187 cycles RESULT TestTlsVariable_CycleCount: x86-64_with_irt_nnacl_newlib_static= {29, 3} count Apparent clock speed: 4833 MHz TestMmapAnonymous: Measuring real time: 2.821 usec (2.82126e-06 sec) per iteration: 0.000282126 sec for 100 iterations 2.091 usec (2.09084e-06 sec) per iteration: 0.0741099 sec for 35445 iterations 2.056 usec (2.05561e-06 sec) per iteration: 0.072861 sec for 35445 iterations 2.094 usec (2.09368e-06 sec) per iteration: 0.0742104 sec for 35445 iterations 2.060 usec (2.06035e-06 sec) per iteration: 0.0730293 sec for 35445 iterations 2.070 usec (2.06989e-06 sec) per iteration: 0.0733671 sec for 35445 iterations mean: 2.074073 usec stddev: 0.015571 usec relative stddev: 0.75% RESULT TestMmapAnonymous: x86-64_with_irt_nnacl_newlib_static= {2.074073, 0.015571} us Measuring clock cycles: first runs (cycles): 5808 4481 4525 4320 4312 4264 4318 4278 4318 4292 ... slowest runs (cycles): ... 4357 4358 4366 4404 4481 4499 4525 4549 4558 5808 min: 4237 cycles q1: 4259 cycles median: 4300 cycles q3: 4314 cycles max: 5808 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_with_irt_nnacl_newlib_static= {4300, 55} count Apparent clock speed: 2073 MHz TestAtomicIncrement: Measuring real time: 0.016 usec (1.577e-08 sec) per iteration: 1.577e-06 sec for 100 iterations 0.009 usec (9.443e-09 sec) per iteration: 9.443e-06 sec for 1000 iterations 0.009 usec (9.3834e-09 sec) per iteration: 9.3834e-05 sec for 10000 iterations 0.010 usec (9.55003e-09 sec) per iteration: 0.101776 sec for 10657117 iterations 0.010 usec (9.5161e-09 sec) per iteration: 0.101414 sec for 10657117 iterations 0.010 usec (9.52998e-09 sec) per iteration: 0.101562 sec for 10657117 iterations 0.010 usec (9.53526e-09 sec) per iteration: 0.101618 sec for 10657117 iterations 0.010 usec (9.56294e-09 sec) per iteration: 0.101913 sec for 10657117 iterations mean: 0.009539 usec stddev: 0.000016 usec relative stddev: 0.17% RESULT TestAtomicIncrement: x86-64_with_irt_nnacl_newlib_static= {0.009539, 0.000016} us Measuring clock cycles: first runs (cycles): 125 51 50 51 53 50 51 53 51 51 ... slowest runs (cycles): ... 51 51 51 51 51 51 53 53 53 125 min: 48 cycles q1: 50 cycles median: 51 cycles q3: 51 cycles max: 125 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_with_irt_nnacl_newlib_static= {51, 1} count Apparent clock speed: 5347 MHz TestUncontendedMutexLock: Measuring real time: 0.028 usec (2.772e-08 sec) per iteration: 2.772e-06 sec for 100 iterations 0.024 usec (2.4287e-08 sec) per iteration: 2.4287e-05 sec for 1000 iterations 0.025 usec (2.48386e-08 sec) per iteration: 0.102271 sec for 4117429 iterations 0.025 usec (2.48685e-08 sec) per iteration: 0.102394 sec for 4117429 iterations 0.025 usec (2.48265e-08 sec) per iteration: 0.102221 sec for 4117429 iterations 0.025 usec (2.49925e-08 sec) per iteration: 0.102905 sec for 4117429 iterations 0.025 usec (2.50798e-08 sec) per iteration: 0.103264 sec for 4117429 iterations mean: 0.024921 usec stddev: 0.000099 usec relative stddev: 0.40% RESULT TestUncontendedMutexLock: x86-64_with_irt_nnacl_newlib_static= {0.024921, 0.000099} us Measuring clock cycles: first runs (cycles): 251 119 81 83 82 81 81 82 82 81 ... slowest runs (cycles): ... 84 84 84 84 84 84 84 93 119 251 min: 79 cycles q1: 81 cycles median: 82 cycles q3: 83 cycles max: 251 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_with_irt_nnacl_newlib_static= {82, 2} count Apparent clock speed: 3290 MHz TestCondvarSignalNoOp: Measuring real time: 0.249 usec (2.4906e-07 sec) per iteration: 2.4906e-05 sec for 100 iterations 0.218 usec (2.18272e-07 sec) per iteration: 0.0876384 sec for 401509 iterations 0.219 usec (2.19068e-07 sec) per iteration: 0.0879577 sec for 401509 iterations 0.218 usec (2.1817e-07 sec) per iteration: 0.0875972 sec for 401509 iterations 0.218 usec (2.1761e-07 sec) per iteration: 0.0873725 sec for 401509 iterations 0.218 usec (2.17798e-07 sec) per iteration: 0.087448 sec for 401509 iterations mean: 0.218184 usec stddev: 0.000503 usec relative stddev: 0.23% RESULT TestCondvarSignalNoOp: x86-64_with_irt_nnacl_newlib_static= {0.218184, 0.000503} us Measuring clock cycles: first runs (cycles): 876 490 473 475 473 480 479 479 480 484 ... slowest runs (cycles): ... 484 484 484 486 486 486 486 490 515 876 min: 473 cycles q1: 484 cycles median: 484 cycles q3: 484 cycles max: 876 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {484, 0} count Apparent clock speed: 2218 MHz TestThreadCreateAndJoin: Measuring real time: 79.265 usec (7.92653e-05 sec) per iteration: 0.00792653 sec for 100 iterations 30.444 usec (3.04441e-05 sec) per iteration: 0.03839 sec for 1261 iterations 33.559 usec (3.35594e-05 sec) per iteration: 0.0423185 sec for 1261 iterations 30.393 usec (3.03932e-05 sec) per iteration: 0.0383258 sec for 1261 iterations 29.440 usec (2.94402e-05 sec) per iteration: 0.0371241 sec for 1261 iterations 26.951 usec (2.69512e-05 sec) per iteration: 0.0339855 sec for 1261 iterations mean: 30.157623 usec stddev: 2.121594 usec relative stddev: 7.04% RESULT TestThreadCreateAndJoin: x86-64_with_irt_nnacl_newlib_static= {30.157623, 2.121594} us Measuring clock cycles: first runs (cycles): 187788 180025 59881 119959 252030 94870 102129 128313 62301 51751 ... slowest runs (cycles): ... 102129 103247 103811 112565 119959 128313 165396 180025 187788 252030 min: 46689 cycles q1: 48583 cycles median: 50270 cycles q3: 54287 cycles max: 252030 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_with_irt_nnacl_newlib_static= {50270, 5704} count Apparent clock speed: 1667 MHz TestThreadWakeup: Measuring real time: 17.735 usec (1.77352e-05 sec) per iteration: 0.00177352 sec for 100 iterations 13.768 usec (1.37678e-05 sec) per iteration: 0.0776227 sec for 5638 iterations 14.399 usec (1.43986e-05 sec) per iteration: 0.0811791 sec for 5638 iterations 14.866 usec (1.48655e-05 sec) per iteration: 0.0838118 sec for 5638 iterations 14.804 usec (1.48036e-05 sec) per iteration: 0.0834626 sec for 5638 iterations 15.047 usec (1.50465e-05 sec) per iteration: 0.0848322 sec for 5638 iterations mean: 14.576388 usec stddev: 0.456443 usec relative stddev: 3.13% RESULT TestThreadWakeup: x86-64_with_irt_nnacl_newlib_static= {14.576388, 0.456443} us Measuring clock cycles: first runs (cycles): 32516 30234 28816 27254 27287 29166 31567 28494 28877 28052 ... slowest runs (cycles): ... 31378 31552 31557 31567 31655 31871 32516 42384 74558 101801 min: 22736 cycles q1: 23354 cycles median: 25687 cycles q3: 29828 cycles max: 101801 cycles RESULT TestThreadWakeup_CycleCount: x86-64_with_irt_nnacl_newlib_static= {25687, 6474} count Apparent clock speed: 1762 MHz TestCatchingFault: Measuring real time: 2.863 usec (2.86291e-06 sec) per iteration: 0.000286291 sec for 100 iterations 1.764 usec (1.76398e-06 sec) per iteration: 0.061614 sec for 34929 iterations 1.766 usec (1.76628e-06 sec) per iteration: 0.0616942 sec for 34929 iterations 1.767 usec (1.76704e-06 sec) per iteration: 0.0617208 sec for 34929 iterations 1.766 usec (1.76594e-06 sec) per iteration: 0.0616824 sec for 34929 iterations 1.756 usec (1.75626e-06 sec) per iteration: 0.0613445 sec for 34929 iterations mean: 1.763898 usec stddev: 0.003948 usec relative stddev: 0.22% RESULT TestCatchingFault: x86-64_with_irt_nnacl_newlib_static= {1.763898, 0.003948} us Measuring clock cycles: first runs (cycles): 6093 4921 4694 4369 4553 4846 4375 58403 4630 4527 ... slowest runs (cycles): ... 5083 5084 5112 5115 5179 5207 5295 5391 6093 58403 min: 3984 cycles q1: 4290 cycles median: 4518 cycles q3: 4846 cycles max: 58403 cycles RESULT TestCatchingFault_CycleCount: x86-64_with_irt_nnacl_newlib_static= {4518, 556} count Apparent clock speed: 2561 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 45.363 usec (4.53631e-05 sec) per iteration: 0.00453631 sec for 100 iterations 58.090 usec (5.80905e-05 sec) per iteration: 0.128031 sec for 2204 iterations 74.001 usec (7.40011e-05 sec) per iteration: 0.163098 sec for 2204 iterations 55.068 usec (5.50675e-05 sec) per iteration: 0.121369 sec for 2204 iterations 48.159 usec (4.81588e-05 sec) per iteration: 0.106142 sec for 2204 iterations 37.328 usec (3.7328e-05 sec) per iteration: 0.0822709 sec for 2204 iterations mean: 54.529187 usec stddev: 12.071387 usec relative stddev: 22.14% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_with_irt_nnacl_newlib_static= {54.529187, 12.071387} us Measuring clock cycles: first runs (cycles): 58038 52573 52030 49197 57189 51998 54567 92599 50862 52074 ... slowest runs (cycles): ... 64769 70828 89417 89933 90133 90243 92599 110827 123102 131083 min: 47107 cycles q1: 51329 cycles median: 52875 cycles q3: 55354 cycles max: 131083 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_with_irt_nnacl_newlib_static= {52875, 4025} count Apparent clock speed: 970 MHz [ OK ] nacl_newlib.run_performance_test_irt (5960 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