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-west1-c-f7lk 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: 19893716 kB MemAvailable: 29465248 kB ====================================================================== LOAD: ====================================================================== 1.16 1.69 0.98 1/438 24447 ====================================================================== UPTIME: ====================================================================== 24659.13 195138.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-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 [24457,4147238656:05:12:04.245590] 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.100340, 0.005060} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (1720 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 (64720 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= 512.889 KB RESULT hello_world_size.out: ZIPPED_x86-32_with_irt_nnacl_newlib_static= 170.219 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 [24485,4147009280:05:12:17.763986] Native Client module will be loaded at base address 0x0000000000000000 TestNull: Measuring real time: 0.012 usec (1.192e-08 sec) per iteration: 1.192e-06 sec for 100 iterations 0.003 usec (2.791e-09 sec) per iteration: 2.791e-06 sec for 1000 iterations 0.002 usec (2.224e-09 sec) per iteration: 2.224e-05 sec for 10000 iterations 0.002 usec (2.22769e-09 sec) per iteration: 0.100166 sec for 44964028 iterations 0.002 usec (2.18996e-09 sec) per iteration: 0.0984694 sec for 44964028 iterations 0.002 usec (2.17477e-09 sec) per iteration: 0.0977863 sec for 44964028 iterations 0.002 usec (2.17503e-09 sec) per iteration: 0.0977981 sec for 44964028 iterations 0.002 usec (2.17724e-09 sec) per iteration: 0.0978975 sec for 44964028 iterations mean: 0.002189 usec stddev: 0.000020 usec relative stddev: 0.92% RESULT TestNull: x86-32_with_irt_nnacl_newlib_static= {0.002189, 0.000020} us Measuring clock cycles: first runs (cycles): 72 19 19 18 19 19 22 18 22 19 ... slowest runs (cycles): ... 22 22 22 22 22 22 22 22 22 72 min: 18 cycles q1: 19 cycles median: 19 cycles q3: 22 cycles max: 72 cycles RESULT TestNull_CycleCount: x86-32_with_irt_nnacl_newlib_static= {19, 3} count Apparent clock speed: 8680 MHz TestNaClSyscall: Measuring real time: 0.396 usec (3.9603e-07 sec) per iteration: 3.9603e-05 sec for 100 iterations 0.320 usec (3.19636e-07 sec) per iteration: 0.0807099 sec for 252506 iterations 0.319 usec (3.18984e-07 sec) per iteration: 0.0805455 sec for 252506 iterations 0.318 usec (3.18186e-07 sec) per iteration: 0.0803438 sec for 252506 iterations 0.318 usec (3.17711e-07 sec) per iteration: 0.080224 sec for 252506 iterations 0.317 usec (3.17351e-07 sec) per iteration: 0.0801331 sec for 252506 iterations mean: 0.318374 usec stddev: 0.000835 usec relative stddev: 0.26% RESULT TestNaClSyscall: x86-32_with_irt_nnacl_newlib_static= {0.318374, 0.000835} us Measuring clock cycles: first runs (cycles): 776 729 726 723 711 704 701 704 704 710 ... slowest runs (cycles): ... 711 711 713 723 726 729 757 774 776 776 min: 697 cycles q1: 701 cycles median: 704 cycles q3: 707 cycles max: 776 cycles RESULT TestNaClSyscall_CycleCount: x86-32_with_irt_nnacl_newlib_static= {704, 6} count Apparent clock speed: 2211 MHz TestSetjmpLongjmp: Measuring real time: 0.021 usec (2.13e-08 sec) per iteration: 2.13e-06 sec for 100 iterations 0.013 usec (1.2753e-08 sec) per iteration: 1.2753e-05 sec for 1000 iterations 0.012 usec (1.24256e-08 sec) per iteration: 0.0974329 sec for 7841292 iterations 0.012 usec (1.24322e-08 sec) per iteration: 0.0974841 sec for 7841292 iterations 0.012 usec (1.24288e-08 sec) per iteration: 0.0974575 sec for 7841292 iterations 0.012 usec (1.23837e-08 sec) per iteration: 0.097104 sec for 7841292 iterations 0.012 usec (1.23958e-08 sec) per iteration: 0.0971994 sec for 7841292 iterations mean: 0.012413 usec stddev: 0.000020 usec relative stddev: 0.16% RESULT TestSetjmpLongjmp: x86-32_with_irt_nnacl_newlib_static= {0.012413, 0.000020} us Measuring clock cycles: first runs (cycles): 129 66 28 22 22 25 28 22 25 22 ... slowest runs (cycles): ... 25 25 26 26 26 28 28 66 129 254 min: 22 cycles q1: 22 cycles median: 22 cycles q3: 25 cycles max: 254 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-32_with_irt_nnacl_newlib_static= {22, 3} count Apparent clock speed: 1772 MHz TestClockGetTime: Measuring real time: 0.706 usec (7.0602e-07 sec) per iteration: 7.0602e-05 sec for 100 iterations 0.618 usec (6.18136e-07 sec) per iteration: 0.0875521 sec for 141639 iterations 0.618 usec (6.18333e-07 sec) per iteration: 0.0875801 sec for 141639 iterations 0.618 usec (6.17591e-07 sec) per iteration: 0.0874749 sec for 141639 iterations 0.617 usec (6.1729e-07 sec) per iteration: 0.0874324 sec for 141639 iterations 0.619 usec (6.19023e-07 sec) per iteration: 0.0876779 sec for 141639 iterations mean: 0.618075 usec stddev: 0.000603 usec relative stddev: 0.10% RESULT TestClockGetTime: x86-32_with_irt_nnacl_newlib_static= {0.618075, 0.000603} us Measuring clock cycles: first runs (cycles): 1662 1405 1361 1471 1354 1345 1342 1345 1348 1339 ... slowest runs (cycles): ... 1383 1393 1402 1405 1417 1446 1464 1471 1487 1662 min: 1326 cycles q1: 1339 cycles median: 1342 cycles q3: 1346 cycles max: 1662 cycles RESULT TestClockGetTime_CycleCount: x86-32_with_irt_nnacl_newlib_static= {1342, 7} count Apparent clock speed: 2171 MHz TestTlsVariable: Measuring real time: 0.012 usec (1.152e-08 sec) per iteration: 1.152e-06 sec for 100 iterations 0.004 usec (3.879e-09 sec) per iteration: 3.879e-06 sec for 1000 iterations 0.003 usec (3.3017e-09 sec) per iteration: 3.3017e-05 sec for 10000 iterations 0.003 usec (3.27018e-09 sec) per iteration: 0.0990455 sec for 30287427 iterations 0.003 usec (3.28263e-09 sec) per iteration: 0.0994225 sec for 30287427 iterations 0.003 usec (3.26546e-09 sec) per iteration: 0.0989024 sec for 30287427 iterations 0.003 usec (3.28555e-09 sec) per iteration: 0.0995109 sec for 30287427 iterations 0.003 usec (3.26089e-09 sec) per iteration: 0.0987641 sec for 30287427 iterations mean: 0.003273 usec stddev: 0.000010 usec relative stddev: 0.29% RESULT TestTlsVariable: x86-32_with_irt_nnacl_newlib_static= {0.003273, 0.000010} us Measuring clock cycles: first runs (cycles): 85 22 19 22 19 19 19 19 18 19 ... slowest runs (cycles): ... 19 19 19 19 19 19 19 22 22 85 min: 18 cycles q1: 19 cycles median: 19 cycles q3: 19 cycles max: 85 cycles RESULT TestTlsVariable_CycleCount: x86-32_with_irt_nnacl_newlib_static= {19, 0} count Apparent clock speed: 5805 MHz TestMmapAnonymous: Measuring real time: 5.218 usec (5.21829e-06 sec) per iteration: 0.000521829 sec for 100 iterations 3.715 usec (3.7154e-06 sec) per iteration: 0.0711983 sec for 19163 iterations 3.741 usec (3.74064e-06 sec) per iteration: 0.0716818 sec for 19163 iterations 3.746 usec (3.74592e-06 sec) per iteration: 0.0717831 sec for 19163 iterations 3.741 usec (3.74059e-06 sec) per iteration: 0.071681 sec for 19163 iterations 3.742 usec (3.74208e-06 sec) per iteration: 0.0717094 sec for 19163 iterations mean: 3.736926 usec stddev: 0.010936 usec relative stddev: 0.29% RESULT TestMmapAnonymous: x86-32_with_irt_nnacl_newlib_static= {3.736926, 0.010936} us Measuring clock cycles: first runs (cycles): 11541 8234 8115 8030 8216 8061 8017 8162 8052 8105 ... slowest runs (cycles): ... 12160 12201 12415 12559 12574 12789 12904 13024 13049 23118 min: 7989 cycles q1: 8061 cycles median: 8166 cycles q3: 11474 cycles max: 23118 cycles RESULT TestMmapAnonymous_CycleCount: x86-32_with_irt_nnacl_newlib_static= {8166, 3413} count Apparent clock speed: 2185 MHz TestAtomicIncrement: Measuring real time: 0.020 usec (2.02e-08 sec) per iteration: 2.02e-06 sec for 100 iterations 0.010 usec (9.937e-09 sec) per iteration: 9.937e-06 sec for 1000 iterations 0.011 usec (1.08126e-08 sec) per iteration: 0.000108126 sec for 10000 iterations 0.009 usec (9.41388e-09 sec) per iteration: 0.087064 sec for 9248469 iterations 0.009 usec (9.42317e-09 sec) per iteration: 0.0871499 sec for 9248469 iterations 0.009 usec (9.41445e-09 sec) per iteration: 0.0870693 sec for 9248469 iterations 0.009 usec (9.42774e-09 sec) per iteration: 0.0871922 sec for 9248469 iterations 0.009 usec (9.4262e-09 sec) per iteration: 0.0871779 sec for 9248469 iterations mean: 0.009421 usec stddev: 0.000006 usec relative stddev: 0.06% RESULT TestAtomicIncrement: x86-32_with_irt_nnacl_newlib_static= {0.009421, 0.000006} us Measuring clock cycles: first runs (cycles): 78 51 44 47 47 47 47 47 47 47 ... slowest runs (cycles): ... 47 47 47 47 47 47 47 47 51 78 min: 44 cycles q1: 47 cycles median: 47 cycles q3: 47 cycles max: 78 cycles RESULT TestAtomicIncrement_CycleCount: x86-32_with_irt_nnacl_newlib_static= {47, 0} count Apparent clock speed: 4989 MHz TestUncontendedMutexLock: Measuring real time: 0.031 usec (3.113e-08 sec) per iteration: 3.113e-06 sec for 100 iterations 0.023 usec (2.2819e-08 sec) per iteration: 2.2819e-05 sec for 1000 iterations 0.023 usec (2.25344e-08 sec) per iteration: 0.0987529 sec for 4382312 iterations 0.023 usec (2.25299e-08 sec) per iteration: 0.098733 sec for 4382312 iterations 0.022 usec (2.24718e-08 sec) per iteration: 0.0984785 sec for 4382312 iterations 0.022 usec (2.24739e-08 sec) per iteration: 0.0984877 sec for 4382312 iterations 0.022 usec (2.24672e-08 sec) per iteration: 0.0984583 sec for 4382312 iterations mean: 0.022495 usec stddev: 0.000030 usec relative stddev: 0.13% RESULT TestUncontendedMutexLock: x86-32_with_irt_nnacl_newlib_static= {0.022495, 0.000030} us Measuring clock cycles: first runs (cycles): 205 78 76 73 75 72 75 73 76 72 ... slowest runs (cycles): ... 76 76 76 76 76 76 76 76 78 205 min: 72 cycles q1: 72 cycles median: 75 cycles q3: 75 cycles max: 205 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-32_with_irt_nnacl_newlib_static= {75, 3} count Apparent clock speed: 3334 MHz TestCondvarSignalNoOp: Measuring real time: 0.532 usec (5.3231e-07 sec) per iteration: 5.3231e-05 sec for 100 iterations 0.505 usec (5.04874e-07 sec) per iteration: 0.0948457 sec for 187860 iterations 0.504 usec (5.04031e-07 sec) per iteration: 0.0946872 sec for 187860 iterations 0.505 usec (5.05062e-07 sec) per iteration: 0.094881 sec for 187860 iterations 0.506 usec (5.05527e-07 sec) per iteration: 0.0949683 sec for 187860 iterations 0.509 usec (5.09043e-07 sec) per iteration: 0.0956288 sec for 187860 iterations mean: 0.505707 usec stddev: 0.001737 usec relative stddev: 0.34% RESULT TestCondvarSignalNoOp: x86-32_with_irt_nnacl_newlib_static= {0.505707, 0.001737} us Measuring clock cycles: first runs (cycles): 1443 1175 1116 1100 1096 1100 1097 1116 1100 1116 ... slowest runs (cycles): ... 1116 1116 1150 1154 1156 1160 1166 1175 1207 1443 min: 1090 cycles q1: 1097 cycles median: 1103 cycles q3: 1106 cycles max: 1443 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-32_with_irt_nnacl_newlib_static= {1103, 9} count Apparent clock speed: 2181 MHz TestThreadCreateAndJoin: Measuring real time: 104.011 usec (0.000104011 sec) per iteration: 0.0104011 sec for 100 iterations 95.139 usec (9.51393e-05 sec) per iteration: 0.0914288 sec for 961 iterations 86.446 usec (8.64461e-05 sec) per iteration: 0.0830747 sec for 961 iterations 88.699 usec (8.86987e-05 sec) per iteration: 0.0852395 sec for 961 iterations 87.613 usec (8.76131e-05 sec) per iteration: 0.0841961 sec for 961 iterations 85.846 usec (8.58455e-05 sec) per iteration: 0.0824975 sec for 961 iterations mean: 88.748537 usec stddev: 3.342503 usec relative stddev: 3.77% RESULT TestThreadCreateAndJoin: x86-32_with_irt_nnacl_newlib_static= {88.748537, 3.342503} us Measuring clock cycles: first runs (cycles): 132428 193270 209437 213362 215204 227914 195118 128116 238244 193408 ... slowest runs (cycles): ... 222156 227914 229982 230673 238244 238992 239766 259075 275679 478189 min: 128116 cycles q1: 195574 cycles median: 198713 cycles q3: 204431 cycles max: 478189 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-32_with_irt_nnacl_newlib_static= {198713, 8857} count Apparent clock speed: 2239 MHz TestThreadWakeup: Measuring real time: 13.741 usec (1.37414e-05 sec) per iteration: 0.00137414 sec for 100 iterations 11.786 usec (1.1786e-05 sec) per iteration: 0.0857664 sec for 7277 iterations 12.649 usec (1.26485e-05 sec) per iteration: 0.0920433 sec for 7277 iterations 12.202 usec (1.22019e-05 sec) per iteration: 0.0887932 sec for 7277 iterations 13.545 usec (1.35451e-05 sec) per iteration: 0.0985679 sec for 7277 iterations 13.162 usec (1.31621e-05 sec) per iteration: 0.0957809 sec for 7277 iterations mean: 12.668728 usec stddev: 0.633903 usec relative stddev: 5.00% RESULT TestThreadWakeup: x86-32_with_irt_nnacl_newlib_static= {12.668728, 0.633903} us Measuring clock cycles: first runs (cycles): 63030 33406 33239 32815 33644 32796 32830 33034 35873 33543 ... slowest runs (cycles): ... 46501 46649 47511 47885 47986 48026 59139 63030 79973 96989 min: 27412 cycles q1: 32966 cycles median: 33456 cycles q3: 36810 cycles max: 96989 cycles RESULT TestThreadWakeup_CycleCount: x86-32_with_irt_nnacl_newlib_static= {33456, 3844} count Apparent clock speed: 2641 MHz TestCatchingFault: Measuring real time: 4.048 usec (4.04772e-06 sec) per iteration: 0.000404772 sec for 100 iterations 2.367 usec (2.36675e-06 sec) per iteration: 0.0584705 sec for 24705 iterations 2.367 usec (2.36725e-06 sec) per iteration: 0.0584829 sec for 24705 iterations 2.368 usec (2.3684e-06 sec) per iteration: 0.0585114 sec for 24705 iterations 2.348 usec (2.3482e-06 sec) per iteration: 0.0580123 sec for 24705 iterations 2.349 usec (2.34868e-06 sec) per iteration: 0.0580241 sec for 24705 iterations mean: 2.359856 usec stddev: 0.009338 usec relative stddev: 0.40% RESULT TestCatchingFault: x86-32_with_irt_nnacl_newlib_static= {2.359856, 0.009338} us Measuring clock cycles: first runs (cycles): 6672 5245 5117 5132 5132 5132 5135 5211 5132 5202 ... slowest runs (cycles): ... 6453 6481 6600 6603 6653 6672 6886 6971 14690 21908 min: 5101 cycles q1: 5129 cycles median: 5173 cycles q3: 5239 cycles max: 21908 cycles RESULT TestCatchingFault_CycleCount: x86-32_with_irt_nnacl_newlib_static= {5173, 110} count Apparent clock speed: 2192 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 93.448 usec (9.34482e-05 sec) per iteration: 0.00934482 sec for 100 iterations 85.971 usec (8.59708e-05 sec) per iteration: 0.0919888 sec for 1070 iterations 85.217 usec (8.52171e-05 sec) per iteration: 0.0911823 sec for 1070 iterations 91.723 usec (9.17232e-05 sec) per iteration: 0.0981439 sec for 1070 iterations 89.020 usec (8.90201e-05 sec) per iteration: 0.0952515 sec for 1070 iterations 91.963 usec (9.19628e-05 sec) per iteration: 0.0984002 sec for 1070 iterations mean: 88.778809 usec stddev: 2.808445 usec relative stddev: 3.16% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-32_with_irt_nnacl_newlib_static= {88.778809, 2.808445} us Measuring clock cycles: first runs (cycles): 142874 205848 253095 246906 242843 190290 206178 187641 211929 186157 ... slowest runs (cycles): ... 244156 246906 247088 247821 249357 253095 273963 281156 288989 360184 min: 122965 cycles q1: 185960 cycles median: 204911 cycles q3: 218271 cycles max: 360184 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-32_with_irt_nnacl_newlib_static= {204911, 32311} count Apparent clock speed: 2308 MHz [ OK ] nacl_newlib.run_performance_test_irt (5770 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