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 --asan 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', '--asan', '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-b-nxxd 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: 21818712 kB MemAvailable: 29483848 kB ====================================================================== LOAD: ====================================================================== 1.24 1.65 1.13 1/434 13318 ====================================================================== UPTIME: ====================================================================== 755.78 4561.98 ====================================================================== 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 -fsanitize=address -gline-tables-only -fno-omit-frame-pointer -DADDRESS_SANITIZER ASPPCOM: /b/s/w/ir/kitchen-workdir/third_party/llvm-build/Release+Asserts/bin/clang -fsanitize=address -gline-tables-only -fno-omit-frame-pointer -DADDRESS_SANITIZER -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 Skipping death test "nacl_text_small_pad_ro_test.out" under AddressSanitizer Skipping death test "nacl_text_large_pad_ro_test.out" under AddressSanitizer *** BROKEN run_ncval_annotate_test Skipping death test "run_crash_in_syscall_test.out" under AddressSanitizer Skipping death test "run_log_fatal_in_syscall_test.out" under AddressSanitizer Skipping death test "run_check_failure_in_syscall_test.out" under AddressSanitizer Skipping death test "run_jump_to_zero_in_syscall_test.out" under AddressSanitizer Skipping death test "run_jump_into_sandbox_in_syscall_crash_test.out" under AddressSanitizer Skipping death test "execute_data.out" under AddressSanitizer Skipping death test "write_to_dyncode_before_alloc.out" under AddressSanitizer Skipping death test "write_to_dyncode_after_alloc.out" under AddressSanitizer Skipping death test "dyncode_demand_alloc_test.out" under AddressSanitizer Skipping death test "exception_test.out" under AddressSanitizer Skipping death test "exception_test_bad_handler.out" under AddressSanitizer Skipping death test "exception_test_stack_outside_sandbox.out" under AddressSanitizer Skipping death test "exception_test_stack_in_rwdata.out" under AddressSanitizer Skipping death test "exception_test_stack_in_rodata.out" under AddressSanitizer Skipping death test "exception_test_stack_in_code.out" under AddressSanitizer Skipping death test "exception_test_crash_in_syscall.out" under AddressSanitizer Skipping death test "exceptions_disabled_test.out" under AddressSanitizer *** BROKEN run_gdb_invalid_memory_test Skipping death test "mmap_race_socketpair_test.out" under AddressSanitizer Skipping death test "mmap_race_connect_test.out" under AddressSanitizer *** BROKEN run_nanosleep_test Skipping death test "nullptr.out" under AddressSanitizer *** BROKEN run_divide_by_zero_test Skipping death test "write_to_rodata.out" under AddressSanitizer Skipping death test "untrusted_crash_test.out" under AddressSanitizer Skipping death test "trusted_crash_test.out" under AddressSanitizer Skipping death test "crash_in_syscall_test.out" under AddressSanitizer *** BROKEN run_timefuncs_test *** BROKEN run_raw_timefuncs_test *** BROKEN run_nacl_thread_capture_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_untrusted_check_test *** BROKEN run_nacl_sync_cond_test Skipping death test "nacl_signal_frame_test.out" under AddressSanitizer SKIPPING test text_overlaps_rodata SKIPPING test text_overlaps_data Skipping death test "sel_ldr_thread_death_test.out" under AddressSanitizer Skipping death test "nacl_error_log_test.out" under AddressSanitizer *** BROKEN run_seccomp_open_errno_test *** BROKEN run_seccomp_check_arch_test Skipping death test "execute_data.out" under AddressSanitizer Skipping death test "write_to_dyncode_before_alloc.out" under AddressSanitizer Skipping death test "write_to_dyncode_after_alloc.out" under AddressSanitizer Skipping death test "dyncode_demand_alloc_test.out" under AddressSanitizer Skipping death test "exception_test.out" under AddressSanitizer Skipping death test "exception_test_bad_handler.out" under AddressSanitizer Skipping death test "exception_test_stack_outside_sandbox.out" under AddressSanitizer Skipping death test "exception_test_stack_in_rwdata.out" under AddressSanitizer Skipping death test "exception_test_stack_in_rodata.out" under AddressSanitizer Skipping death test "exception_test_stack_in_code.out" under AddressSanitizer Skipping death test "exception_test_crash_in_syscall.out" under AddressSanitizer Skipping death test "exceptions_disabled_test.out" under AddressSanitizer *** BROKEN run_gdb_invalid_memory_test_irt Skipping death test "mmap_race_socketpair_test.out" under AddressSanitizer Skipping death test "mmap_race_connect_test.out" under AddressSanitizer *** BROKEN run_nanosleep_test_irt Skipping death test "nullptr.out" under AddressSanitizer *** BROKEN run_divide_by_zero_test_irt Skipping death test "write_to_rodata.out" under AddressSanitizer Skipping death test "untrusted_crash_test.out" under AddressSanitizer Skipping death test "trusted_crash_test.out" under AddressSanitizer Skipping death test "crash_in_syscall_test.out" under AddressSanitizer *** BROKEN run_timefuncs_test_irt *** BROKEN run_raw_timefuncs_test_irt *** BROKEN run_nacl_thread_capture_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 *** BROKEN run_untrusted_check_test_irt There are 32 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 --osenv ASAN_OPTIONS=handle_segv=0:handle_sigfpe=0:abort_on_error=0:detect_leaks=0 --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 ====================================================================== setting environment ====================================================================== [ASAN_OPTIONS] = [handle_segv=0:handle_sigfpe=0:abort_on_error=0:detect_leaks=0] ASAN_OPTIONS=handle_segv=0:handle_sigfpe=0:abort_on_error=0:detect_leaks=0 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 [13558,2752915456:12:57:13.770933] Native Client module will be loaded at base address 0x0000111900000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_with_irt_nnacl_newlib_static= {0.107970, 0.001050} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (1730 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 --osenv ASAN_OPTIONS=handle_segv=0:handle_sigfpe=0:abort_on_error=0:detect_leaks=0 --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 ====================================================================== setting environment ====================================================================== [ASAN_OPTIONS] = [handle_segv=0:handle_sigfpe=0:abort_on_error=0:detect_leaks=0] ASAN_OPTIONS=handle_segv=0:handle_sigfpe=0:abort_on_error=0:detect_leaks=0 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 (75710 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 --osenv ASAN_OPTIONS=handle_segv=0:handle_sigfpe=0:abort_on_error=0:detect_leaks=0 --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 ====================================================================== setting environment ====================================================================== [ASAN_OPTIONS] = [handle_segv=0:handle_sigfpe=0:abort_on_error=0:detect_leaks=0] ASAN_OPTIONS=handle_segv=0:handle_sigfpe=0:abort_on_error=0:detect_leaks=0 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 [13586,3153258496:12:57:29.686277] Native Client module will be loaded at base address 0x0000659900000000 TestNull: Measuring real time: 0.011 usec (1.129e-08 sec) per iteration: 1.129e-06 sec for 100 iterations 0.002 usec (2.216e-09 sec) per iteration: 2.216e-06 sec for 1000 iterations 0.002 usec (1.8968e-09 sec) per iteration: 1.8968e-05 sec for 10000 iterations 0.002 usec (1.9278e-09 sec) per iteration: 0.101635 sec for 52720371 iterations 0.002 usec (1.92878e-09 sec) per iteration: 0.101686 sec for 52720371 iterations 0.002 usec (1.91956e-09 sec) per iteration: 0.1012 sec for 52720371 iterations 0.002 usec (1.92332e-09 sec) per iteration: 0.101398 sec for 52720371 iterations 0.002 usec (1.93092e-09 sec) per iteration: 0.101799 sec for 52720371 iterations mean: 0.001926 usec stddev: 0.000004 usec relative stddev: 0.21% RESULT TestNull: x86-64_with_irt_nnacl_newlib_static= {0.001926, 0.000004} us Measuring clock cycles: first runs (cycles): 213 51 24 24 24 27 22 24 24 26 ... slowest runs (cycles): ... 27 27 27 27 27 27 27 28 51 213 min: 22 cycles q1: 24 cycles median: 25 cycles q3: 26 cycles max: 213 cycles RESULT TestNull_CycleCount: x86-64_with_irt_nnacl_newlib_static= {25, 2} count Apparent clock speed: 12980 MHz TestNaClSyscall: Measuring real time: 0.138 usec (1.3754e-07 sec) per iteration: 1.3754e-05 sec for 100 iterations 0.162 usec (1.6191e-07 sec) per iteration: 0.117719 sec for 727061 iterations 0.161 usec (1.60682e-07 sec) per iteration: 0.116825 sec for 727061 iterations 0.164 usec (1.64259e-07 sec) per iteration: 0.119427 sec for 727061 iterations 0.160 usec (1.59695e-07 sec) per iteration: 0.116108 sec for 727061 iterations 0.159 usec (1.59042e-07 sec) per iteration: 0.115633 sec for 727061 iterations mean: 0.161118 usec stddev: 0.001845 usec relative stddev: 1.15% RESULT TestNaClSyscall: x86-64_with_irt_nnacl_newlib_static= {0.161118, 0.001845} us Measuring clock cycles: first runs (cycles): 800 282 268 222 227 229 226 227 229 229 ... slowest runs (cycles): ... 231 231 231 231 235 236 268 282 366 800 min: 222 cycles q1: 227 cycles median: 227 cycles q3: 229 cycles max: 800 cycles RESULT TestNaClSyscall_CycleCount: x86-64_with_irt_nnacl_newlib_static= {227, 2} count Apparent clock speed: 1409 MHz TestSetjmpLongjmp: Measuring real time: 0.018 usec (1.787e-08 sec) per iteration: 1.787e-06 sec for 100 iterations 0.013 usec (1.2854e-08 sec) per iteration: 1.2854e-05 sec for 1000 iterations 0.013 usec (1.30687e-08 sec) per iteration: 0.10167 sec for 7779679 iterations 0.013 usec (1.31161e-08 sec) per iteration: 0.102039 sec for 7779679 iterations 0.013 usec (1.30965e-08 sec) per iteration: 0.101886 sec for 7779679 iterations 0.013 usec (1.30028e-08 sec) per iteration: 0.101158 sec for 7779679 iterations 0.013 usec (1.30554e-08 sec) per iteration: 0.101567 sec for 7779679 iterations mean: 0.013068 usec stddev: 0.000039 usec relative stddev: 0.30% RESULT TestSetjmpLongjmp: x86-64_with_irt_nnacl_newlib_static= {0.013068, 0.000039} us Measuring clock cycles: first runs (cycles): 222 58 57 55 51 57 55 60 55 58 ... slowest runs (cycles): ... 59 59 59 59 59 60 60 60 60 222 min: 48 cycles q1: 55 cycles median: 55 cycles q3: 57 cycles max: 222 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {55, 2} count Apparent clock speed: 4209 MHz TestClockGetTime: Measuring real time: 0.242 usec (2.4194e-07 sec) per iteration: 2.4194e-05 sec for 100 iterations 0.243 usec (2.42922e-07 sec) per iteration: 0.100406 sec for 413325 iterations 0.243 usec (2.42985e-07 sec) per iteration: 0.100432 sec for 413325 iterations 0.244 usec (2.4404e-07 sec) per iteration: 0.100868 sec for 413325 iterations 0.252 usec (2.51768e-07 sec) per iteration: 0.104062 sec for 413325 iterations 0.245 usec (2.44849e-07 sec) per iteration: 0.101202 sec for 413325 iterations mean: 0.245313 usec stddev: 0.003306 usec relative stddev: 1.35% RESULT TestClockGetTime: x86-64_with_irt_nnacl_newlib_static= {0.245313, 0.003306} us Measuring clock cycles: first runs (cycles): 992 627 554 541 550 546 527 528 539 535 ... slowest runs (cycles): ... 545 546 546 547 548 550 554 627 728 992 min: 519 cycles q1: 530 cycles median: 534 cycles q3: 537 cycles max: 992 cycles RESULT TestClockGetTime_CycleCount: x86-64_with_irt_nnacl_newlib_static= {534, 7} count Apparent clock speed: 2177 MHz TestTlsVariable: Measuring real time: 0.011 usec (1.143e-08 sec) per iteration: 1.143e-06 sec for 100 iterations 0.006 usec (5.891e-09 sec) per iteration: 5.891e-06 sec for 1000 iterations 0.006 usec (5.6124e-09 sec) per iteration: 5.6124e-05 sec for 10000 iterations 0.006 usec (5.99681e-09 sec) per iteration: 0.106849 sec for 17817689 iterations 0.006 usec (5.94643e-09 sec) per iteration: 0.105952 sec for 17817689 iterations 0.006 usec (6.09649e-09 sec) per iteration: 0.108625 sec for 17817689 iterations 0.006 usec (5.94077e-09 sec) per iteration: 0.105851 sec for 17817689 iterations 0.006 usec (5.8366e-09 sec) per iteration: 0.103995 sec for 17817689 iterations mean: 0.005963 usec stddev: 0.000085 usec relative stddev: 1.42% RESULT TestTlsVariable: x86-64_with_irt_nnacl_newlib_static= {0.005963, 0.000085} us Measuring clock cycles: first runs (cycles): 198 75 73 36 33 33 33 33 33 30 ... slowest runs (cycles): ... 33 33 33 33 33 33 36 73 75 198 min: 30 cycles q1: 33 cycles median: 33 cycles q3: 33 cycles max: 198 cycles RESULT TestTlsVariable_CycleCount: x86-64_with_irt_nnacl_newlib_static= {33, 0} count Apparent clock speed: 5534 MHz TestMmapAnonymous: Measuring real time: 4.019 usec (4.01903e-06 sec) per iteration: 0.000401903 sec for 100 iterations 3.176 usec (3.17574e-06 sec) per iteration: 0.0790155 sec for 24881 iterations 3.228 usec (3.22766e-06 sec) per iteration: 0.0803074 sec for 24881 iterations 3.196 usec (3.19608e-06 sec) per iteration: 0.0795217 sec for 24881 iterations 3.127 usec (3.12671e-06 sec) per iteration: 0.0777957 sec for 24881 iterations 3.123 usec (3.12296e-06 sec) per iteration: 0.0777023 sec for 24881 iterations mean: 3.169830 usec stddev: 0.040311 usec relative stddev: 1.27% RESULT TestMmapAnonymous: x86-64_with_irt_nnacl_newlib_static= {3.169830, 0.040311} us Measuring clock cycles: first runs (cycles): 8866 6485 6364 6395 6307 6345 6386 6316 6412 6499 ... slowest runs (cycles): ... 6652 6666 6703 6740 6773 8866 12552 14403 23768 71864 min: 6239 cycles q1: 6322 cycles median: 6360 cycles q3: 6402 cycles max: 71864 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_with_irt_nnacl_newlib_static= {6360, 80} count Apparent clock speed: 2006 MHz TestAtomicIncrement: Measuring real time: 0.016 usec (1.567e-08 sec) per iteration: 1.567e-06 sec for 100 iterations 0.010 usec (9.559e-09 sec) per iteration: 9.559e-06 sec for 1000 iterations 0.009 usec (9.3147e-09 sec) per iteration: 9.3147e-05 sec for 10000 iterations 0.010 usec (9.62182e-09 sec) per iteration: 0.103297 sec for 10735718 iterations 0.010 usec (9.72197e-09 sec) per iteration: 0.104372 sec for 10735718 iterations 0.010 usec (1.00332e-08 sec) per iteration: 0.107713 sec for 10735718 iterations 0.010 usec (9.79859e-09 sec) per iteration: 0.105195 sec for 10735718 iterations 0.012 usec (1.18145e-08 sec) per iteration: 0.126837 sec for 10735718 iterations mean: 0.010198 usec stddev: 0.000820 usec relative stddev: 8.04% RESULT TestAtomicIncrement: x86-64_with_irt_nnacl_newlib_static= {0.010198, 0.000820} us Measuring clock cycles: first runs (cycles): 244 53 51 53 51 53 50 53 50 53 ... slowest runs (cycles): ... 53 53 53 53 55 66 185 244 4734 5013 min: 48 cycles q1: 51 cycles median: 51 cycles q3: 53 cycles max: 5013 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_with_irt_nnacl_newlib_static= {51, 2} count Apparent clock speed: 5001 MHz TestUncontendedMutexLock: Measuring real time: 0.030 usec (3.043e-08 sec) per iteration: 3.043e-06 sec for 100 iterations 0.024 usec (2.4419e-08 sec) per iteration: 2.4419e-05 sec for 1000 iterations 0.034 usec (3.36325e-08 sec) per iteration: 0.137731 sec for 4095171 iterations 0.035 usec (3.50621e-08 sec) per iteration: 0.143585 sec for 4095171 iterations 0.026 usec (2.6139e-08 sec) per iteration: 0.107044 sec for 4095171 iterations 0.025 usec (2.50264e-08 sec) per iteration: 0.102487 sec for 4095171 iterations 0.025 usec (2.49553e-08 sec) per iteration: 0.102196 sec for 4095171 iterations mean: 0.028963 usec stddev: 0.004439 usec relative stddev: 15.33% RESULT TestUncontendedMutexLock: x86-64_with_irt_nnacl_newlib_static= {0.028963, 0.004439} us Measuring clock cycles: first runs (cycles): 237 110 82 83 83 84 83 83 84 83 ... slowest runs (cycles): ... 84 84 84 84 84 84 84 84 110 237 min: 79 cycles q1: 81 cycles median: 82 cycles q3: 83 cycles max: 237 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_with_irt_nnacl_newlib_static= {82, 2} count Apparent clock speed: 2831 MHz TestCondvarSignalNoOp: Measuring real time: 0.332 usec (3.3197e-07 sec) per iteration: 3.3197e-05 sec for 100 iterations 0.265 usec (2.65444e-07 sec) per iteration: 0.0799603 sec for 301232 iterations 0.265 usec (2.64627e-07 sec) per iteration: 0.0797141 sec for 301232 iterations 0.265 usec (2.65039e-07 sec) per iteration: 0.0798384 sec for 301232 iterations 0.265 usec (2.64851e-07 sec) per iteration: 0.0797817 sec for 301232 iterations 0.265 usec (2.65237e-07 sec) per iteration: 0.0798978 sec for 301232 iterations mean: 0.265040 usec stddev: 0.000286 usec relative stddev: 0.11% RESULT TestCondvarSignalNoOp: x86-64_with_irt_nnacl_newlib_static= {0.265040, 0.000286} us Measuring clock cycles: first runs (cycles): 898 605 579 576 612 576 568 574 565 574 ... slowest runs (cycles): ... 577 579 580 581 581 605 612 633 704 898 min: 561 cycles q1: 568 cycles median: 572 cycles q3: 574 cycles max: 898 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {572, 6} count Apparent clock speed: 2158 MHz TestThreadCreateAndJoin: Measuring real time: 241.241 usec (0.000241241 sec) per iteration: 0.0241241 sec for 100 iterations 184.963 usec (0.000184963 sec) per iteration: 0.0765745 sec for 414 iterations 200.021 usec (0.000200021 sec) per iteration: 0.0828087 sec for 414 iterations 235.530 usec (0.00023553 sec) per iteration: 0.0975092 sec for 414 iterations 212.662 usec (0.000212662 sec) per iteration: 0.0880421 sec for 414 iterations 212.409 usec (0.000212409 sec) per iteration: 0.0879373 sec for 414 iterations mean: 209.116845 usec stddev: 16.656508 usec relative stddev: 7.97% RESULT TestThreadCreateAndJoin: x86-64_with_irt_nnacl_newlib_static= {209.116845, 16.656508} us Measuring clock cycles: first runs (cycles): 258798 646027 330972 321544 414816 234949 509034 272073 303960 329244 ... slowest runs (cycles): ... 685387 691547 706491 707396 728343 787147 798863 817689 1634627 1641800 min: 231290 cycles q1: 349056 cycles median: 452074 cycles q3: 551746 cycles max: 1641800 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_with_irt_nnacl_newlib_static= {452074, 202690} count Apparent clock speed: 2162 MHz TestThreadWakeup: Measuring real time: 20.488 usec (2.0488e-05 sec) per iteration: 0.0020488 sec for 100 iterations 22.239 usec (2.22392e-05 sec) per iteration: 0.108527 sec for 4880 iterations 23.381 usec (2.3381e-05 sec) per iteration: 0.114099 sec for 4880 iterations 20.234 usec (2.02344e-05 sec) per iteration: 0.0987441 sec for 4880 iterations 15.562 usec (1.5562e-05 sec) per iteration: 0.0759426 sec for 4880 iterations 15.639 usec (1.56387e-05 sec) per iteration: 0.076317 sec for 4880 iterations mean: 19.411089 usec stddev: 3.270556 usec relative stddev: 16.85% RESULT TestThreadWakeup: x86-64_with_irt_nnacl_newlib_static= {19.411089, 3.270556} us Measuring clock cycles: first runs (cycles): 34083 31555 31567 32398 31791 25800 31263 31778 33993 28645 ... slowest runs (cycles): ... 38397 38793 40243 40285 42890 43787 48764 52558 63531 126233 min: 25800 cycles q1: 32121 cycles median: 32735 cycles q3: 35157 cycles max: 126233 cycles RESULT TestThreadWakeup_CycleCount: x86-64_with_irt_nnacl_newlib_static= {32735, 3036} count Apparent clock speed: 1686 MHz TestCatchingFault: Measuring real time: 3.709 usec (3.70947e-06 sec) per iteration: 0.000370947 sec for 100 iterations 1.868 usec (1.86815e-06 sec) per iteration: 0.0503617 sec for 26958 iterations 1.862 usec (1.86184e-06 sec) per iteration: 0.0501914 sec for 26958 iterations 1.864 usec (1.86406e-06 sec) per iteration: 0.0502512 sec for 26958 iterations 1.860 usec (1.86034e-06 sec) per iteration: 0.0501511 sec for 26958 iterations 1.860 usec (1.86009e-06 sec) per iteration: 0.0501444 sec for 26958 iterations mean: 1.862896 usec stddev: 0.002983 usec relative stddev: 0.16% RESULT TestCatchingFault: x86-64_with_irt_nnacl_newlib_static= {1.862896, 0.002983} us Measuring clock cycles: first runs (cycles): 5421 4180 4052 4048 4052 4058 4054 4061 4046 4061 ... slowest runs (cycles): ... 4067 4067 4068 4070 4072 4085 4180 4217 5421 12720 min: 4030 cycles q1: 4039 cycles median: 4046 cycles q3: 4054 cycles max: 12720 cycles RESULT TestCatchingFault_CycleCount: x86-64_with_irt_nnacl_newlib_static= {4046, 15} count Apparent clock speed: 2172 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 219.011 usec (0.000219011 sec) per iteration: 0.0219011 sec for 100 iterations 244.177 usec (0.000244177 sec) per iteration: 0.111345 sec for 456 iterations 213.485 usec (0.000213485 sec) per iteration: 0.0973493 sec for 456 iterations 220.434 usec (0.000220434 sec) per iteration: 0.100518 sec for 456 iterations 176.624 usec (0.000176624 sec) per iteration: 0.0805407 sec for 456 iterations 188.645 usec (0.000188645 sec) per iteration: 0.0860219 sec for 456 iterations mean: 208.672964 usec stddev: 23.875732 usec relative stddev: 11.44% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_with_irt_nnacl_newlib_static= {208.672964, 23.875732} us Measuring clock cycles: first runs (cycles): 317307 837411 573993 436382 812688 653602 370457 326216 341645 357226 ... slowest runs (cycles): ... 745533 757286 762807 778717 790075 804977 812688 837411 882110 1085095 min: 254731 cycles q1: 332433 cycles median: 410517 cycles q3: 566924 cycles max: 1085095 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_with_irt_nnacl_newlib_static= {410517, 234491} count Apparent clock speed: 1967 MHz [ OK ] nacl_newlib.run_performance_test_irt (7400 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