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,dbg-host,nacl_irt_test platform=x86-32 --clang breakpad_tools_dir=breakpad-out large_tests_irt scons: Reading SConscript files ... ====================================================================== SCONS ARGS:['scons.py', '--verbose', '-k', '-j1', '--mode=nacl,dbg-host,nacl_irt_test', 'platform=x86-32', '--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-vmp4 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: 14161672 kB MemAvailable: 29418172 kB ====================================================================== LOAD: ====================================================================== 1.31 1.74 1.32 1/440 21984 ====================================================================== UPTIME: ====================================================================== 10735.05 80102.70 ====================================================================== 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-32 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-32/obj BUILD_TYPE_DESCRIPTION: NaCl module build BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 32 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 32 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 -m32 -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=32 -I/b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-32/include -I/b/s/w/ir/kitchen-workdir -c -o UNKNOWN COMPILER NACL_BUILD_FAMILY: TRUSTED BUILD_TYPE: dbg-linux TARGET_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/dbg-linux-x86-32 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/dbg-linux-x86-32/obj BUILD_TYPE_DESCRIPTION: Linux dbg build BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 32 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 32 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 -m32 -Wall -pedantic -Wextra -Wno-long-long -Wswitch-enum -Wsign-compare -Wundef -fdiagnostics-show-option -fvisibility=hidden -fstack-protector -Werror -Wno-variadic-macros -Wno-covered-switch-default -Wno-c++11-extensions -O0 -g -D_FORTIFY_SOURCE=2 -D_POSIX_C_SOURCE=199506 -D_XOPEN_SOURCE=600 -D_GNU_SOURCE=1 -D_LARGEFILE64_SOURCE=1 -D__STDC_LIMIT_MACROS=1 -D__STDC_FORMAT_MACROS=1 -D_DEBUG -I/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-32 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt_test-x86-32/obj BUILD_TYPE_DESCRIPTION: NaCl tests build with IRT BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 32 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 32 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 -m32 -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=32 -Iscons-out/nacl-x86-32/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-32 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt-x86-32/obj BUILD_TYPE_DESCRIPTION: NaCl IRT build BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 32 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 32 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 -m32 -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=32 -I/b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt-x86-32/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_nacl_sync_cond_test *** 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 There are 22 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-32_with_irt_nnacl_newlib_static --using_nacl_signal_handler True --subarch 32 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt_test-x86-32/test_results/benchmark_test.out scons-out/dbg-linux-x86-32/staging/nacl_helper_bootstrap scons-out/dbg-linux-x86-32/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -B scons-out/nacl_irt-x86-32/staging/irt_core.nexe -- scons-out/nacl_irt_test-x86-32/obj/tests/benchmark/benchmark_test.nexe x86-32_with_irt_nnacl_newlib_static [ RUN ] nacl_newlib.run_benchmark_test_irt scons-out/dbg-linux-x86-32/staging/nacl_helper_bootstrap scons-out/dbg-linux-x86-32/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -B scons-out/nacl_irt-x86-32/staging/irt_core.nexe -- scons-out/nacl_irt_test-x86-32/obj/tests/benchmark/benchmark_test.nexe x86-32_with_irt_nnacl_newlib_static [21992,4146956032:12:22:12.307129] Native Client module will be loaded at base address 0x0000000000000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-32_with_irt_nnacl_newlib_static= {0.101810, 0.020040} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (1820 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-32_with_irt_nnacl_newlib_static --using_nacl_signal_handler True --subarch 32 --arch x86 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt_test-x86-32/test_results/clock_cputime_test.out scons-out/dbg-linux-x86-32/staging/nacl_helper_bootstrap scons-out/dbg-linux-x86-32/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -B scons-out/nacl_irt-x86-32/staging/irt_core.nexe -- scons-out/nacl_irt_test-x86-32/obj/tests/clock/clock_test.nexe -c [ RUN ] nacl_newlib.run_clock_cputime_test_irt scons-out/dbg-linux-x86-32/staging/nacl_helper_bootstrap scons-out/dbg-linux-x86-32/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -B scons-out/nacl_irt-x86-32/staging/irt_core.nexe -- scons-out/nacl_irt_test-x86-32/obj/tests/clock/clock_test.nexe -c WARNING: should have taken less than 60.000000 secs [ OK ] nacl_newlib.run_clock_cputime_test_irt (70640 ms) doSizeCheck(["scons-out/nacl_irt_test-x86-32/obj/tests/hello_world/hello_world_size.out"], ["scons-out/nacl_irt_test-x86-32/obj/tests/hello_world/hello_world.nexe"]) RESULT hello_world_size.out: x86-32_with_irt_nnacl_newlib_static= 509.997 KB RESULT hello_world_size.out: ZIPPED_x86-32_with_irt_nnacl_newlib_static= 170.128 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-32_with_irt_nnacl_newlib_static --using_nacl_signal_handler True --subarch 32 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt_test-x86-32/test_results/performance_test.out scons-out/dbg-linux-x86-32/staging/nacl_helper_bootstrap scons-out/dbg-linux-x86-32/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -e -B scons-out/nacl_irt-x86-32/staging/irt_core.nexe -- scons-out/nacl_irt_test-x86-32/obj/tests/performance/performance_test.nexe x86-32_with_irt_nnacl_newlib_static [ RUN ] nacl_newlib.run_performance_test_irt scons-out/dbg-linux-x86-32/staging/nacl_helper_bootstrap scons-out/dbg-linux-x86-32/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -e -B scons-out/nacl_irt-x86-32/staging/irt_core.nexe -- scons-out/nacl_irt_test-x86-32/obj/tests/performance/performance_test.nexe x86-32_with_irt_nnacl_newlib_static [22020,4147848960:12:22:27.216392] Native Client module will be loaded at base address 0x0000000000000000 TestNull: Measuring real time: 0.016 usec (1.568e-08 sec) per iteration: 1.568e-06 sec for 100 iterations 0.003 usec (2.896e-09 sec) per iteration: 2.896e-06 sec for 1000 iterations 0.002 usec (2.2978e-09 sec) per iteration: 2.2978e-05 sec for 10000 iterations 0.002 usec (2.28543e-09 sec) per iteration: 0.0994616 sec for 43519888 iterations 0.002 usec (2.29779e-09 sec) per iteration: 0.0999994 sec for 43519888 iterations 0.002 usec (2.30035e-09 sec) per iteration: 0.100111 sec for 43519888 iterations 0.002 usec (2.31082e-09 sec) per iteration: 0.100567 sec for 43519888 iterations 0.002 usec (2.29966e-09 sec) per iteration: 0.100081 sec for 43519888 iterations mean: 0.002299 usec stddev: 0.000008 usec relative stddev: 0.35% RESULT TestNull: x86-32_with_irt_nnacl_newlib_static= {0.002299, 0.000008} us Measuring clock cycles: first runs (cycles): 88 46 24 24 24 22 24 22 25 22 ... slowest runs (cycles): ... 25 25 26 26 26 26 27 27 46 88 min: 22 cycles q1: 22 cycles median: 24 cycles q3: 24 cycles max: 88 cycles RESULT TestNull_CycleCount: x86-32_with_irt_nnacl_newlib_static= {24, 2} count Apparent clock speed: 10440 MHz TestNaClSyscall: Measuring real time: 0.339 usec (3.3854e-07 sec) per iteration: 3.3854e-05 sec for 100 iterations 0.334 usec (3.33801e-07 sec) per iteration: 0.0986001 sec for 295386 iterations 0.332 usec (3.31991e-07 sec) per iteration: 0.0980656 sec for 295386 iterations 0.331 usec (3.31356e-07 sec) per iteration: 0.0978779 sec for 295386 iterations 0.333 usec (3.32846e-07 sec) per iteration: 0.098318 sec for 295386 iterations 0.335 usec (3.35323e-07 sec) per iteration: 0.0990496 sec for 295386 iterations mean: 0.333063 usec stddev: 0.001397 usec relative stddev: 0.42% RESULT TestNaClSyscall: x86-32_with_irt_nnacl_newlib_static= {0.333063, 0.001397} us Measuring clock cycles: first runs (cycles): 809 759 728 734 722 737 730 733 730 734 ... slowest runs (cycles): ... 880 882 891 898 929 935 943 961 972 1241 min: 720 cycles q1: 726 cycles median: 731 cycles q3: 737 cycles max: 1241 cycles RESULT TestNaClSyscall_CycleCount: x86-32_with_irt_nnacl_newlib_static= {731, 11} count Apparent clock speed: 2195 MHz TestSetjmpLongjmp: Measuring real time: 0.023 usec (2.267e-08 sec) per iteration: 2.267e-06 sec for 100 iterations 0.013 usec (1.2627e-08 sec) per iteration: 1.2627e-05 sec for 1000 iterations 0.012 usec (1.23434e-08 sec) per iteration: 0.0977537 sec for 7919537 iterations 0.012 usec (1.22123e-08 sec) per iteration: 0.0967158 sec for 7919537 iterations 0.012 usec (1.22166e-08 sec) per iteration: 0.0967496 sec for 7919537 iterations 0.012 usec (1.23627e-08 sec) per iteration: 0.0979071 sec for 7919537 iterations 0.012 usec (1.23571e-08 sec) per iteration: 0.0978629 sec for 7919537 iterations mean: 0.012298 usec stddev: 0.000069 usec relative stddev: 0.56% RESULT TestSetjmpLongjmp: x86-32_with_irt_nnacl_newlib_static= {0.012298, 0.000069} us Measuring clock cycles: first runs (cycles): 134 64 42 33 35 33 41 37 35 37 ... slowest runs (cycles): ... 42 42 42 42 42 44 44 44 64 134 min: 33 cycles q1: 36 cycles median: 37 cycles q3: 40 cycles max: 134 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-32_with_irt_nnacl_newlib_static= {37, 4} count Apparent clock speed: 3009 MHz TestClockGetTime: Measuring real time: 0.634 usec (6.3391e-07 sec) per iteration: 6.3391e-05 sec for 100 iterations 0.642 usec (6.41869e-07 sec) per iteration: 0.101255 sec for 157751 iterations 0.636 usec (6.36431e-07 sec) per iteration: 0.100398 sec for 157751 iterations 0.637 usec (6.36808e-07 sec) per iteration: 0.100457 sec for 157751 iterations 0.635 usec (6.34861e-07 sec) per iteration: 0.10015 sec for 157751 iterations 0.636 usec (6.36493e-07 sec) per iteration: 0.100407 sec for 157751 iterations mean: 0.637292 usec stddev: 0.002386 usec relative stddev: 0.37% RESULT TestClockGetTime: x86-32_with_irt_nnacl_newlib_static= {0.637292, 0.002386} us Measuring clock cycles: first runs (cycles): 1797 1492 1414 1432 1386 1426 1381 1384 1529 1394 ... slowest runs (cycles): ... 1432 1434 1434 1437 1437 1465 1492 1496 1529 1797 min: 1371 cycles q1: 1382 cycles median: 1386 cycles q3: 1401 cycles max: 1797 cycles RESULT TestClockGetTime_CycleCount: x86-32_with_irt_nnacl_newlib_static= {1386, 19} count Apparent clock speed: 2175 MHz TestTlsVariable: Measuring real time: 0.011 usec (1.148e-08 sec) per iteration: 1.148e-06 sec for 100 iterations 0.004 usec (3.691e-09 sec) per iteration: 3.691e-06 sec for 1000 iterations 0.003 usec (3.039e-09 sec) per iteration: 3.039e-05 sec for 10000 iterations 0.003 usec (3.03396e-09 sec) per iteration: 0.0998342 sec for 32905561 iterations 0.003 usec (3.02473e-09 sec) per iteration: 0.0995306 sec for 32905561 iterations 0.003 usec (3.0216e-09 sec) per iteration: 0.0994273 sec for 32905561 iterations 0.003 usec (3.05321e-09 sec) per iteration: 0.100468 sec for 32905561 iterations 0.003 usec (3.06326e-09 sec) per iteration: 0.100798 sec for 32905561 iterations mean: 0.003039 usec stddev: 0.000016 usec relative stddev: 0.53% RESULT TestTlsVariable: x86-32_with_irt_nnacl_newlib_static= {0.003039, 0.000016} us Measuring clock cycles: first runs (cycles): 101 49 26 26 27 27 24 26 25 26 ... slowest runs (cycles): ... 28 29 29 29 29 29 29 29 49 101 min: 24 cycles q1: 26 cycles median: 26 cycles q3: 27 cycles max: 101 cycles RESULT TestTlsVariable_CycleCount: x86-32_with_irt_nnacl_newlib_static= {26, 1} count Apparent clock speed: 8554 MHz TestMmapAnonymous: Measuring real time: 4.777 usec (4.77719e-06 sec) per iteration: 0.000477719 sec for 100 iterations 3.789 usec (3.78918e-06 sec) per iteration: 0.0793151 sec for 20932 iterations 3.841 usec (3.84148e-06 sec) per iteration: 0.0804099 sec for 20932 iterations 3.833 usec (3.83271e-06 sec) per iteration: 0.0802263 sec for 20932 iterations 3.770 usec (3.76984e-06 sec) per iteration: 0.0789102 sec for 20932 iterations 3.753 usec (3.75344e-06 sec) per iteration: 0.0785671 sec for 20932 iterations mean: 3.797331 usec stddev: 0.034496 usec relative stddev: 0.91% RESULT TestMmapAnonymous: x86-32_with_irt_nnacl_newlib_static= {3.797331, 0.034496} us Measuring clock cycles: first runs (cycles): 10495 8146 8591 8069 8171 8285 8171 7968 7963 7968 ... slowest runs (cycles): ... 10398 10495 10645 10962 11025 11272 11461 11617 11733 11765 min: 7902 cycles q1: 7991 cycles median: 8054 cycles q3: 8171 cycles max: 11765 cycles RESULT TestMmapAnonymous_CycleCount: x86-32_with_irt_nnacl_newlib_static= {8054, 180} count Apparent clock speed: 2121 MHz TestAtomicIncrement: Measuring real time: 0.023 usec (2.284e-08 sec) per iteration: 2.284e-06 sec for 100 iterations 0.012 usec (1.1609e-08 sec) per iteration: 1.1609e-05 sec for 1000 iterations 0.009 usec (9.44487e-09 sec) per iteration: 0.0813581 sec for 8614006 iterations 0.009 usec (9.44801e-09 sec) per iteration: 0.0813852 sec for 8614006 iterations 0.009 usec (9.42577e-09 sec) per iteration: 0.0811936 sec for 8614006 iterations 0.009 usec (9.42076e-09 sec) per iteration: 0.0811505 sec for 8614006 iterations 0.009 usec (9.43043e-09 sec) per iteration: 0.0812338 sec for 8614006 iterations mean: 0.009434 usec stddev: 0.000011 usec relative stddev: 0.11% RESULT TestAtomicIncrement: x86-32_with_irt_nnacl_newlib_static= {0.009434, 0.000011} us Measuring clock cycles: first runs (cycles): 101 68 51 50 49 50 48 51 48 51 ... slowest runs (cycles): ... 51 51 51 51 51 51 51 53 68 101 min: 48 cycles q1: 48 cycles median: 50 cycles q3: 51 cycles max: 101 cycles RESULT TestAtomicIncrement_CycleCount: x86-32_with_irt_nnacl_newlib_static= {50, 3} count Apparent clock speed: 5300 MHz TestUncontendedMutexLock: Measuring real time: 0.031 usec (3.062e-08 sec) per iteration: 3.062e-06 sec for 100 iterations 0.026 usec (2.5591e-08 sec) per iteration: 2.5591e-05 sec for 1000 iterations 0.023 usec (2.25464e-08 sec) per iteration: 0.088103 sec for 3907623 iterations 0.023 usec (2.25156e-08 sec) per iteration: 0.0879823 sec for 3907623 iterations 0.023 usec (2.25644e-08 sec) per iteration: 0.0881732 sec for 3907623 iterations 0.022 usec (2.22638e-08 sec) per iteration: 0.0869985 sec for 3907623 iterations 0.022 usec (2.22186e-08 sec) per iteration: 0.0868221 sec for 3907623 iterations mean: 0.022422 usec stddev: 0.000149 usec relative stddev: 0.66% RESULT TestUncontendedMutexLock: x86-32_with_irt_nnacl_newlib_static= {0.022422, 0.000149} us Measuring clock cycles: first runs (cycles): 255 123 77 79 79 80 80 79 81 79 ... slowest runs (cycles): ... 82 82 82 82 82 82 82 82 123 255 min: 77 cycles q1: 79 cycles median: 80 cycles q3: 81 cycles max: 255 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-32_with_irt_nnacl_newlib_static= {80, 2} count Apparent clock speed: 3568 MHz TestCondvarSignalNoOp: Measuring real time: 0.555 usec (5.5494e-07 sec) per iteration: 5.5494e-05 sec for 100 iterations 0.522 usec (5.22444e-07 sec) per iteration: 0.0941438 sec for 180199 iterations 0.520 usec (5.20222e-07 sec) per iteration: 0.0937436 sec for 180199 iterations 0.523 usec (5.22688e-07 sec) per iteration: 0.0941879 sec for 180199 iterations 0.522 usec (5.2201e-07 sec) per iteration: 0.0940656 sec for 180199 iterations 0.523 usec (5.23252e-07 sec) per iteration: 0.0942895 sec for 180199 iterations mean: 0.522123 usec stddev: 0.001032 usec relative stddev: 0.20% RESULT TestCondvarSignalNoOp: x86-32_with_irt_nnacl_newlib_static= {0.522123, 0.001032} us Measuring clock cycles: first runs (cycles): 1523 1218 1131 1174 1144 1131 1177 1138 1135 1172 ... slowest runs (cycles): ... 1179 1179 1184 1187 1195 1214 1215 1218 1236 1523 min: 1126 cycles q1: 1135 cycles median: 1138 cycles q3: 1144 cycles max: 1523 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-32_with_irt_nnacl_newlib_static= {1138, 9} count Apparent clock speed: 2180 MHz TestThreadCreateAndJoin: Measuring real time: 118.066 usec (0.000118066 sec) per iteration: 0.0118066 sec for 100 iterations 95.118 usec (9.51181e-05 sec) per iteration: 0.0804699 sec for 846 iterations 98.626 usec (9.86255e-05 sec) per iteration: 0.0834372 sec for 846 iterations 93.602 usec (9.36016e-05 sec) per iteration: 0.079187 sec for 846 iterations 112.449 usec (0.000112449 sec) per iteration: 0.0951322 sec for 846 iterations 93.038 usec (9.30383e-05 sec) per iteration: 0.0787104 sec for 846 iterations mean: 98.566587 usec stddev: 7.208912 usec relative stddev: 7.31% RESULT TestThreadCreateAndJoin: x86-32_with_irt_nnacl_newlib_static= {98.566587, 7.208912} us Measuring clock cycles: first runs (cycles): 142522 234415 384321 206029 168863 168064 170064 162209 177018 169859 ... slowest runs (cycles): ... 237815 247335 254759 260695 306720 373465 384321 398180 435183 465448 min: 118526 cycles q1: 176494 cycles median: 184700 cycles q3: 204375 cycles max: 465448 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-32_with_irt_nnacl_newlib_static= {184700, 27881} count Apparent clock speed: 1874 MHz TestThreadWakeup: Measuring real time: 14.360 usec (1.43604e-05 sec) per iteration: 0.00143604 sec for 100 iterations 15.104 usec (1.51037e-05 sec) per iteration: 0.105167 sec for 6963 iterations 15.219 usec (1.52193e-05 sec) per iteration: 0.105972 sec for 6963 iterations 15.262 usec (1.52625e-05 sec) per iteration: 0.106273 sec for 6963 iterations 15.030 usec (1.50295e-05 sec) per iteration: 0.10465 sec for 6963 iterations 15.579 usec (1.55787e-05 sec) per iteration: 0.108474 sec for 6963 iterations mean: 15.238728 usec stddev: 0.188962 usec relative stddev: 1.24% RESULT TestThreadWakeup: x86-32_with_irt_nnacl_newlib_static= {15.238728, 0.188962} us Measuring clock cycles: first runs (cycles): 36424 35611 35322 35655 35472 35050 35740 36269 34814 34982 ... slowest runs (cycles): ... 45836 48682 49167 52050 56021 58428 60202 85164 154573 217486 min: 29770 cycles q1: 35272 cycles median: 35655 cycles q3: 38840 cycles max: 217486 cycles RESULT TestThreadWakeup_CycleCount: x86-32_with_irt_nnacl_newlib_static= {35655, 3568} count Apparent clock speed: 2340 MHz TestCatchingFault: Measuring real time: 4.406 usec (4.4062e-06 sec) per iteration: 0.00044062 sec for 100 iterations 2.474 usec (2.47409e-06 sec) per iteration: 0.0561495 sec for 22695 iterations 2.475 usec (2.47458e-06 sec) per iteration: 0.0561606 sec for 22695 iterations 2.472 usec (2.47232e-06 sec) per iteration: 0.0561092 sec for 22695 iterations 2.473 usec (2.4733e-06 sec) per iteration: 0.0561316 sec for 22695 iterations 2.473 usec (2.47316e-06 sec) per iteration: 0.0561283 sec for 22695 iterations mean: 2.473490 usec stddev: 0.000784 usec relative stddev: 0.03% RESULT TestCatchingFault: x86-32_with_irt_nnacl_newlib_static= {2.473490, 0.000784} us Measuring clock cycles: first runs (cycles): 6358 5522 5403 5352 5445 5440 5324 5343 5385 5332 ... slowest runs (cycles): ... 5456 5500 5522 5570 5605 5845 6358 10884 12418 14211 min: 5279 cycles q1: 5313 cycles median: 5330 cycles q3: 5366 cycles max: 14211 cycles RESULT TestCatchingFault_CycleCount: x86-32_with_irt_nnacl_newlib_static= {5330, 53} count Apparent clock speed: 2155 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 102.511 usec (0.000102511 sec) per iteration: 0.0102511 sec for 100 iterations 98.707 usec (9.87073e-05 sec) per iteration: 0.0962396 sec for 975 iterations 90.600 usec (9.06004e-05 sec) per iteration: 0.0883354 sec for 975 iterations 97.117 usec (9.71168e-05 sec) per iteration: 0.0946889 sec for 975 iterations 93.728 usec (9.37277e-05 sec) per iteration: 0.0913845 sec for 975 iterations 85.469 usec (8.54686e-05 sec) per iteration: 0.0833319 sec for 975 iterations mean: 93.124149 usec stddev: 4.742443 usec relative stddev: 5.09% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-32_with_irt_nnacl_newlib_static= {93.124149, 4.742443} us Measuring clock cycles: first runs (cycles): 129196 197556 343373 218106 231044 306015 308323 212970 229668 179493 ... slowest runs (cycles): ... 364536 373665 376703 393969 399482 579294 777609 817246 941748 1194511 min: 129196 cycles q1: 193125 cycles median: 199836 cycles q3: 233509 cycles max: 1194511 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-32_with_irt_nnacl_newlib_static= {199836, 40384} count Apparent clock speed: 2146 MHz [ OK ] nacl_newlib.run_performance_test_irt (5870 ms) scons: done building targets. ********************************************************************** COMMAND EXECUTION REPORT ********************************************************************** 3 command_tester.py 1 doSizeCheck ********************************************************************** ENVIRONMENT USAGE REPORT ********************************************************************** 4 nacl_irt_test-x86-32 Command return code: 0