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

Running command: /b/s/w/ir/cache/vpython/4b93a2/bin/python scons.py --verbose -k -j1 --mode=nacl,dbg-host,nacl_irt_test platform=x86-32 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', '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/4b93a2/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-0r4g 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: 23012180 kB MemAvailable: 29452588 kB ====================================================================== LOAD: ====================================================================== 1.16 1.98 1.99 1/443 21211 ====================================================================== UPTIME: ====================================================================== 18147.27 138222.94 ====================================================================== 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: gcc ASPPCOM: gcc -fPIE -m32 -Wall -pedantic -Wextra -Wno-long-long -Wswitch-enum -Wsign-compare -Wundef -fdiagnostics-show-option -fvisibility=hidden -fstack-protector -Werror -Wno-variadic-macros --param ssp-buffer-size=4 -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 set BOTO_CONFIG='/home/chrome-bot/.boto' BUILDBOT_BUILDERNAME='linux-32-newlib-dbg' BUILDBOT_BUILDNUMBER='84' BUILDBOT_GOT_REVISION='f701a90597fc85979319447c0cd44c3b52201c78' BUILDBOT_MASTERNAME='client.nacl' BUILDBOT_REVISION='f701a90597fc85979319447c0cd44c3b52201c78' BUILDBOT_SLAVE_TYPE='BuilderTester' BUILDBUCKET_EXPERIMENTAL='FALSE' CIPD_CACHE_DIR='/b/s/cipd_cache/cache' CIPD_PROTOCOL='v2' CYGWIN='nodosfilewarning' DEVSHELL_CLIENT_PORT='38474' DOCKER_CONFIG='/b/s/w/ir/tmp/docker_cfg_task' DOCKER_TMPDIR='/b/s/w/ir/tmp/docker_tmp_task' GIT_CONFIG_NOSYSTEM='1' GIT_TERMINAL_PROMPT='0' GOMA_DIR='/b/s/w/ir/cache/goma/client' GOMA_TMP_DIR='/b/s/w/ir/tmp/rt/goma' GSUTIL='/b/s/w/ir/kitchen-checkout/depot_tools/gsutil.py' HOME='/home/chrome-bot' IFS=' ' INFRA_GIT_WRAPPER_HOME='/b/s/w/ir/tmp/git_home_task' LOGDOG_COORDINATOR_HOST='logs.chromium.org' LOGDOG_STREAM_PREFIX='buildbucket/cr-buildbucket.appspot.com/8929197227161560512' LOGDOG_STREAM_PROJECT='nacl' LOGDOG_STREAM_SERVER_PATH='unix:/b/s/w/ir/tmp/ld.sock' LOGNAME='chrome-bot' LUCI_CONTEXT='/b/s/w/itsgD4Kr/luci_context.131800827' MAC_CHROMIUM_TMPDIR='/b/s/w/ir/tmp/t' MAIL='/var/mail/chrome-bot' NATIVE_PYTHON='python' NOCONTROL_GOMA='1' NO_GCE_CHECK='False' OPTIND='1' PATH='/b/s/w/ir/cache/vpython/4b93a2/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' PPID='21184' PS1='$ ' PS2='> ' PS4='+ ' PWD='/b/s/w/ir/kitchen-workdir/native_client' PYTHONDONTWRITEBYTECODE='1' PYTHONIOENCODING='UTF-8' PYTHONNOUSERSITE='1' PYTHONPATH='/b/s/w/ir/kitchen-checkout/build/scripts:/b/s/w/ir/kitchen-checkout/build/site_config' PYTHONUNBUFFERED='1' QEMU_PREFIX_HOOK='' RUNTEST='/b/s/w/ir/kitchen-checkout/build/scripts/slave/runtest.py' SHELL='/bin/bash' SHLVL='1' SUDO_COMMAND='/usr/bin/python /b/s/swarming_bot.zip start_bot' SUDO_GID='0' SUDO_UID='0' SUDO_USER='root' SWARMING_BOT_ID='gce-trusty-e833d7b0-us-east1-b-0r4g' SWARMING_EXTERNAL_BOT_SETUP='1' SWARMING_HEADLESS='1' SWARMING_SERVER='https://chromium-swarm.appspot.com' SWARMING_TASK_ID='4151e6e603871f11' TEMP='/b/s/w/ir/tmp/t/nacl_tmp/linux-32-newlib-dbg' TEMPDIR='/b/s/w/ir/tmp/t' TERM='linux' TMP='/b/s/w/ir/tmp/t/nacl_tmp/linux-32-newlib-dbg' TMPDIR='/b/s/w/ir/tmp/t/nacl_tmp/linux-32-newlib-dbg' USER='chrome-bot' USERNAME='chrome-bot' VIRTUAL_ENV='/b/s/w/ir/cache/vpython/4b93a2' VPYTHON_VIRTUALENV_ROOT='/b/s/w/ir/cache/vpython' _='/b/s/w/ir/cipd_bin_packages/vpython' gcc -v -c Using built-in specs. COLLECT_GCC=gcc Target: x86_64-linux-gnu Configured with: ../src/configure -v --with-pkgversion='Ubuntu 4.8.4-2ubuntu1~14.04.4' --with-bugurl=file:///usr/share/doc/gcc-4.8/README.Bugs --enable-languages=c,c++,java,go,d,fortran,objc,obj-c++ --prefix=/usr --program-suffix=-4.8 --enable-shared --enable-linker-build-id --libexecdir=/usr/lib --without-included-gettext --enable-threads=posix --with-gxx-include-dir=/usr/include/c++/4.8 --libdir=/usr/lib --enable-nls --with-sysroot=/ --enable-clocale=gnu --enable-libstdcxx-debug --enable-libstdcxx-time=yes --enable-gnu-unique-object --disable-libmudflap --enable-plugin --with-system-zlib --disable-browser-plugin --enable-java-awt=gtk --enable-gtk-cairo --with-java-home=/usr/lib/jvm/java-1.5.0-gcj-4.8-amd64/jre --enable-java-home --with-jvm-root-dir=/usr/lib/jvm/java-1.5.0-gcj-4.8-amd64 --with-jvm-jar-dir=/usr/lib/jvm-exports/java-1.5.0-gcj-4.8-amd64 --with-arch-directory=amd64 --with-ecj-jar=/usr/share/java/eclipse-ecj.jar --enable-objc-gc --enable-multiarch --disable-werror --with-arch-32=i686 --with-abi=m64 --with-multilib-list=m32,m64,mx32 --with-tune=generic --enable-checking=release --build=x86_64-linux-gnu --host=x86_64-linux-gnu --target=x86_64-linux-gnu Thread model: posix gcc version 4.8.4 (Ubuntu 4.8.4-2ubuntu1~14.04.4) gcc -print-search-dirs install: /usr/lib/gcc/x86_64-linux-gnu/4.8/ programs: =/usr/lib/gcc/x86_64-linux-gnu/4.8/:/usr/lib/gcc/x86_64-linux-gnu/4.8/:/usr/lib/gcc/x86_64-linux-gnu/:/usr/lib/gcc/x86_64-linux-gnu/4.8/:/usr/lib/gcc/x86_64-linux-gnu/:/usr/lib/gcc/x86_64-linux-gnu/4.8/../../../../x86_64-linux-gnu/bin/x86_64-linux-gnu/4.8/:/usr/lib/gcc/x86_64-linux-gnu/4.8/../../../../x86_64-linux-gnu/bin/x86_64-linux-gnu/:/usr/lib/gcc/x86_64-linux-gnu/4.8/../../../../x86_64-linux-gnu/bin/ libraries: =/usr/lib/gcc/x86_64-linux-gnu/4.8/:/usr/lib/gcc/x86_64-linux-gnu/4.8/../../../../x86_64-linux-gnu/lib/x86_64-linux-gnu/4.8/:/usr/lib/gcc/x86_64-linux-gnu/4.8/../../../../x86_64-linux-gnu/lib/x86_64-linux-gnu/:/usr/lib/gcc/x86_64-linux-gnu/4.8/../../../../x86_64-linux-gnu/lib/../lib/:/usr/lib/gcc/x86_64-linux-gnu/4.8/../../../x86_64-linux-gnu/4.8/:/usr/lib/gcc/x86_64-linux-gnu/4.8/../../../x86_64-linux-gnu/:/usr/lib/gcc/x86_64-linux-gnu/4.8/../../../../lib/:/lib/x86_64-linux-gnu/4.8/:/lib/x86_64-linux-gnu/:/lib/../lib/:/usr/lib/x86_64-linux-gnu/4.8/:/usr/lib/x86_64-linux-gnu/:/usr/lib/../lib/:/usr/lib/gcc/x86_64-linux-gnu/4.8/../../../../x86_64-linux-gnu/lib/:/usr/lib/gcc/x86_64-linux-gnu/4.8/../../../:/lib/:/usr/lib/ gcc -print-libgcc-file-name /usr/lib/gcc/x86_64-linux-gnu/4.8/libgcc.a 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/4b93a2/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 [21231,4147545856:18:17:10.913128] 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.109950, 0.007590} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (1880 ms) /b/s/w/ir/cache/vpython/4b93a2/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 (69930 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/4b93a2/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 [21259,4147701504:18:17:25.553046] Native Client module will be loaded at base address 0x0000000000000000 TestNull: Measuring real time: 0.013 usec (1.311e-08 sec) per iteration: 1.311e-06 sec for 100 iterations 0.003 usec (2.893e-09 sec) per iteration: 2.893e-06 sec for 1000 iterations 0.003 usec (2.6643e-09 sec) per iteration: 2.6643e-05 sec for 10000 iterations 0.002 usec (2.26678e-09 sec) per iteration: 0.0850797 sec for 37533310 iterations 0.002 usec (2.26512e-09 sec) per iteration: 0.0850174 sec for 37533310 iterations 0.002 usec (2.26582e-09 sec) per iteration: 0.0850437 sec for 37533310 iterations 0.002 usec (2.26251e-09 sec) per iteration: 0.0849194 sec for 37533310 iterations 0.002 usec (2.26355e-09 sec) per iteration: 0.0849587 sec for 37533310 iterations mean: 0.002265 usec stddev: 0.000002 usec relative stddev: 0.07% RESULT TestNull: x86-32_with_irt_nnacl_newlib_static= {0.002265, 0.000002} us Measuring clock cycles: first runs (cycles): 95 49 24 25 25 25 22 24 22 24 ... slowest runs (cycles): ... 25 25 26 26 26 27 27 27 49 95 min: 22 cycles q1: 22 cycles median: 24 cycles q3: 24 cycles max: 95 cycles RESULT TestNull_CycleCount: x86-32_with_irt_nnacl_newlib_static= {24, 2} count Apparent clock speed: 10597 MHz TestNaClSyscall: Measuring real time: 0.325 usec (3.2451e-07 sec) per iteration: 3.2451e-05 sec for 100 iterations 0.317 usec (3.17462e-07 sec) per iteration: 0.0978279 sec for 308156 iterations 0.317 usec (3.17484e-07 sec) per iteration: 0.0978346 sec for 308156 iterations 0.317 usec (3.17367e-07 sec) per iteration: 0.0977985 sec for 308156 iterations 0.317 usec (3.17379e-07 sec) per iteration: 0.0978024 sec for 308156 iterations 0.318 usec (3.17736e-07 sec) per iteration: 0.0979122 sec for 308156 iterations mean: 0.317486 usec stddev: 0.000133 usec relative stddev: 0.04% RESULT TestNaClSyscall: x86-32_with_irt_nnacl_newlib_static= {0.317486, 0.000133} us Measuring clock cycles: first runs (cycles): 850 739 761 704 702 695 699 704 702 706 ... slowest runs (cycles): ... 704 704 706 706 706 708 729 739 761 850 min: 693 cycles q1: 697 cycles median: 699 cycles q3: 702 cycles max: 850 cycles RESULT TestNaClSyscall_CycleCount: x86-32_with_irt_nnacl_newlib_static= {699, 5} count Apparent clock speed: 2202 MHz TestSetjmpLongjmp: Measuring real time: 0.022 usec (2.213e-08 sec) per iteration: 2.213e-06 sec for 100 iterations 0.013 usec (1.2839e-08 sec) per iteration: 1.2839e-05 sec for 1000 iterations 0.012 usec (1.21645e-08 sec) per iteration: 0.0947468 sec for 7788768 iterations 0.012 usec (1.22937e-08 sec) per iteration: 0.0957526 sec for 7788768 iterations 0.012 usec (1.2162e-08 sec) per iteration: 0.0947274 sec for 7788768 iterations 0.012 usec (1.21626e-08 sec) per iteration: 0.0947315 sec for 7788768 iterations 0.012 usec (1.21635e-08 sec) per iteration: 0.0947384 sec for 7788768 iterations mean: 0.012189 usec stddev: 0.000052 usec relative stddev: 0.43% RESULT TestSetjmpLongjmp: x86-32_with_irt_nnacl_newlib_static= {0.012189, 0.000052} us Measuring clock cycles: first runs (cycles): 101 42 38 42 33 42 40 37 37 35 ... slowest runs (cycles): ... 42 42 42 42 42 42 44 44 44 101 min: 32 cycles q1: 35 cycles median: 38 cycles q3: 40 cycles max: 101 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-32_with_irt_nnacl_newlib_static= {38, 5} count Apparent clock speed: 3117 MHz TestClockGetTime: Measuring real time: 0.650 usec (6.4969e-07 sec) per iteration: 6.4969e-05 sec for 100 iterations 0.605 usec (6.05079e-07 sec) per iteration: 0.0931331 sec for 153919 iterations 0.605 usec (6.04858e-07 sec) per iteration: 0.0930991 sec for 153919 iterations 0.607 usec (6.06601e-07 sec) per iteration: 0.0933674 sec for 153919 iterations 0.605 usec (6.04996e-07 sec) per iteration: 0.0931204 sec for 153919 iterations 0.606 usec (6.05522e-07 sec) per iteration: 0.0932014 sec for 153919 iterations mean: 0.605411 usec stddev: 0.000635 usec relative stddev: 0.10% RESULT TestClockGetTime: x86-32_with_irt_nnacl_newlib_static= {0.605411, 0.000635} us Measuring clock cycles: first runs (cycles): 1716 1419 1375 1327 1359 1388 1324 1324 1327 1325 ... slowest runs (cycles): ... 1367 1368 1370 1373 1375 1375 1385 1388 1419 1716 min: 1311 cycles q1: 1320 cycles median: 1324 cycles q3: 1329 cycles max: 1716 cycles RESULT TestClockGetTime_CycleCount: x86-32_with_irt_nnacl_newlib_static= {1324, 9} count Apparent clock speed: 2187 MHz TestTlsVariable: Measuring real time: 0.012 usec (1.183e-08 sec) per iteration: 1.183e-06 sec for 100 iterations 0.004 usec (3.646e-09 sec) per iteration: 3.646e-06 sec for 1000 iterations 0.004 usec (3.7708e-09 sec) per iteration: 3.7708e-05 sec for 10000 iterations 0.003 usec (3.06183e-09 sec) per iteration: 0.0811985 sec for 26519571 iterations 0.003 usec (3.01294e-09 sec) per iteration: 0.0799017 sec for 26519571 iterations 0.003 usec (3.014e-09 sec) per iteration: 0.07993 sec for 26519571 iterations 0.003 usec (3.01435e-09 sec) per iteration: 0.0799393 sec for 26519571 iterations 0.003 usec (3.01227e-09 sec) per iteration: 0.079884 sec for 26519571 iterations mean: 0.003023 usec stddev: 0.000019 usec relative stddev: 0.64% RESULT TestTlsVariable: x86-32_with_irt_nnacl_newlib_static= {0.003023, 0.000019} us Measuring clock cycles: first runs (cycles): 90 27 28 26 27 26 26 27 26 26 ... slowest runs (cycles): ... 27 27 27 27 27 28 28 28 29 90 min: 24 cycles q1: 26 cycles median: 26 cycles q3: 27 cycles max: 90 cycles RESULT TestTlsVariable_CycleCount: x86-32_with_irt_nnacl_newlib_static= {26, 1} count Apparent clock speed: 8601 MHz TestMmapAnonymous: Measuring real time: 3.848 usec (3.84757e-06 sec) per iteration: 0.000384757 sec for 100 iterations 3.595 usec (3.59478e-06 sec) per iteration: 0.0934284 sec for 25990 iterations 3.643 usec (3.6427e-06 sec) per iteration: 0.0946737 sec for 25990 iterations 3.634 usec (3.63383e-06 sec) per iteration: 0.0944431 sec for 25990 iterations 3.608 usec (3.60835e-06 sec) per iteration: 0.093781 sec for 25990 iterations 3.607 usec (3.60698e-06 sec) per iteration: 0.0937455 sec for 25990 iterations mean: 3.617328 usec stddev: 0.017954 usec relative stddev: 0.50% RESULT TestMmapAnonymous: x86-32_with_irt_nnacl_newlib_static= {3.617328, 0.017954} us Measuring clock cycles: first runs (cycles): 10601 7789 7799 7723 7697 7704 7721 7717 7706 7686 ... slowest runs (cycles): ... 11302 11311 11321 11338 11428 11433 11619 12336 12374 26055 min: 7644 cycles q1: 7693 cycles median: 7739 cycles q3: 10245 cycles max: 26055 cycles RESULT TestMmapAnonymous_CycleCount: x86-32_with_irt_nnacl_newlib_static= {7739, 2552} count Apparent clock speed: 2139 MHz TestAtomicIncrement: Measuring real time: 0.020 usec (2.005e-08 sec) per iteration: 2.005e-06 sec for 100 iterations 0.011 usec (1.0967e-08 sec) per iteration: 1.0967e-05 sec for 1000 iterations 0.009 usec (9.42416e-09 sec) per iteration: 0.085932 sec for 9118263 iterations 0.009 usec (9.40693e-09 sec) per iteration: 0.0857749 sec for 9118263 iterations 0.009 usec (9.40976e-09 sec) per iteration: 0.0858007 sec for 9118263 iterations 0.009 usec (9.41109e-09 sec) per iteration: 0.0858128 sec for 9118263 iterations 0.010 usec (9.51134e-09 sec) per iteration: 0.0867269 sec for 9118263 iterations mean: 0.009433 usec stddev: 0.000040 usec relative stddev: 0.42% RESULT TestAtomicIncrement: x86-32_with_irt_nnacl_newlib_static= {0.009433, 0.000040} us Measuring clock cycles: first runs (cycles): 101 68 51 50 51 51 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: 5301 MHz TestUncontendedMutexLock: Measuring real time: 0.032 usec (3.162e-08 sec) per iteration: 3.162e-06 sec for 100 iterations 0.023 usec (2.2529e-08 sec) per iteration: 2.2529e-05 sec for 1000 iterations 0.022 usec (2.22842e-08 sec) per iteration: 0.0989133 sec for 4438723 iterations 0.022 usec (2.22164e-08 sec) per iteration: 0.0986125 sec for 4438723 iterations 0.022 usec (2.22077e-08 sec) per iteration: 0.0985739 sec for 4438723 iterations 0.022 usec (2.22056e-08 sec) per iteration: 0.0985643 sec for 4438723 iterations 0.022 usec (2.22437e-08 sec) per iteration: 0.0987338 sec for 4438723 iterations mean: 0.022232 usec stddev: 0.000030 usec relative stddev: 0.13% RESULT TestUncontendedMutexLock: x86-32_with_irt_nnacl_newlib_static= {0.022232, 0.000030} us Measuring clock cycles: first runs (cycles): 255 150 83 79 80 80 79 79 79 79 ... slowest runs (cycles): ... 82 82 82 82 82 82 83 84 150 255 min: 79 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: 3598 MHz TestCondvarSignalNoOp: Measuring real time: 0.614 usec (6.1396e-07 sec) per iteration: 6.1396e-05 sec for 100 iterations 0.507 usec (5.06695e-07 sec) per iteration: 0.0825289 sec for 162877 iterations 0.505 usec (5.04865e-07 sec) per iteration: 0.082231 sec for 162877 iterations 0.505 usec (5.05237e-07 sec) per iteration: 0.0822914 sec for 162877 iterations 0.512 usec (5.11869e-07 sec) per iteration: 0.0833717 sec for 162877 iterations 0.505 usec (5.0465e-07 sec) per iteration: 0.0821959 sec for 162877 iterations mean: 0.506663 usec stddev: 0.002699 usec relative stddev: 0.53% RESULT TestCondvarSignalNoOp: x86-32_with_irt_nnacl_newlib_static= {0.506663, 0.002699} us Measuring clock cycles: first runs (cycles): 1383 1208 1100 1098 1097 1102 1096 1095 1104 1107 ... slowest runs (cycles): ... 1131 1132 1142 1144 1155 1168 1208 1232 1246 1383 min: 1091 cycles q1: 1098 cycles median: 1100 cycles q3: 1102 cycles max: 1383 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-32_with_irt_nnacl_newlib_static= {1100, 4} count Apparent clock speed: 2171 MHz TestThreadCreateAndJoin: Measuring real time: 109.368 usec (0.000109368 sec) per iteration: 0.0109368 sec for 100 iterations 97.393 usec (9.73933e-05 sec) per iteration: 0.0890175 sec for 914 iterations 91.337 usec (9.13371e-05 sec) per iteration: 0.0834821 sec for 914 iterations 84.583 usec (8.45834e-05 sec) per iteration: 0.0773092 sec for 914 iterations 89.099 usec (8.90988e-05 sec) per iteration: 0.0814363 sec for 914 iterations 89.282 usec (8.92822e-05 sec) per iteration: 0.0816039 sec for 914 iterations mean: 90.338940 usec stddev: 4.160315 usec relative stddev: 4.61% RESULT TestThreadCreateAndJoin: x86-32_with_irt_nnacl_newlib_static= {90.338940, 4.160315} us Measuring clock cycles: first runs (cycles): 148247 184505 501282 310912 237084 193072 297495 116726 202402 176877 ... slowest runs (cycles): ... 225625 232154 232205 237084 249238 297495 310912 450590 464473 501282 min: 116726 cycles q1: 180100 cycles median: 185091 cycles q3: 198611 cycles max: 501282 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-32_with_irt_nnacl_newlib_static= {185091, 18511} count Apparent clock speed: 2049 MHz TestThreadWakeup: Measuring real time: 15.391 usec (1.53909e-05 sec) per iteration: 0.00153909 sec for 100 iterations 12.780 usec (1.27797e-05 sec) per iteration: 0.0830299 sec for 6497 iterations 13.639 usec (1.36391e-05 sec) per iteration: 0.0886132 sec for 6497 iterations 13.130 usec (1.31296e-05 sec) per iteration: 0.0853029 sec for 6497 iterations 13.206 usec (1.32057e-05 sec) per iteration: 0.0857971 sec for 6497 iterations 15.105 usec (1.51051e-05 sec) per iteration: 0.098138 sec for 6497 iterations mean: 13.571836 usec stddev: 0.813958 usec relative stddev: 6.00% RESULT TestThreadWakeup: x86-32_with_irt_nnacl_newlib_static= {13.571836, 0.813958} us Measuring clock cycles: first runs (cycles): 51394 32337 32467 32572 32322 32651 32366 37443 40362 35786 ... slowest runs (cycles): ... 37443 37698 38012 40362 45149 51394 84272 124221 217752 306182 min: 26569 cycles q1: 32322 cycles median: 33092 cycles q3: 35590 cycles max: 306182 cycles RESULT TestThreadWakeup_CycleCount: x86-32_with_irt_nnacl_newlib_static= {33092, 3268} count Apparent clock speed: 2438 MHz TestCatchingFault: Measuring real time: 4.181 usec (4.18145e-06 sec) per iteration: 0.000418145 sec for 100 iterations 2.477 usec (2.47669e-06 sec) per iteration: 0.05923 sec for 23915 iterations 2.468 usec (2.46756e-06 sec) per iteration: 0.0590116 sec for 23915 iterations 2.472 usec (2.47213e-06 sec) per iteration: 0.0591211 sec for 23915 iterations 2.467 usec (2.46737e-06 sec) per iteration: 0.0590073 sec for 23915 iterations 2.463 usec (2.46349e-06 sec) per iteration: 0.0589144 sec for 23915 iterations mean: 2.469449 usec stddev: 0.004538 usec relative stddev: 0.18% RESULT TestCatchingFault: x86-32_with_irt_nnacl_newlib_static= {2.469449, 0.004538} us Measuring clock cycles: first runs (cycles): 6408 5592 5462 5394 5363 5366 5354 6179 6756 5487 ... slowest runs (cycles): ... 6342 6408 6437 6441 6459 6536 6645 6647 6756 23676 min: 5337 cycles q1: 5363 cycles median: 5603 cycles q3: 5768 cycles max: 23676 cycles RESULT TestCatchingFault_CycleCount: x86-32_with_irt_nnacl_newlib_static= {5603, 405} count Apparent clock speed: 2269 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 98.047 usec (9.80469e-05 sec) per iteration: 0.00980469 sec for 100 iterations 88.584 usec (8.85839e-05 sec) per iteration: 0.090267 sec for 1019 iterations 86.764 usec (8.67645e-05 sec) per iteration: 0.088413 sec for 1019 iterations 126.131 usec (0.000126131 sec) per iteration: 0.128528 sec for 1019 iterations 133.574 usec (0.000133574 sec) per iteration: 0.136112 sec for 1019 iterations 116.375 usec (0.000116375 sec) per iteration: 0.118586 sec for 1019 iterations mean: 110.285752 usec stddev: 19.259923 usec relative stddev: 17.46% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-32_with_irt_nnacl_newlib_static= {110.285752, 19.259923} us Measuring clock cycles: first runs (cycles): 183941 190404 490429 651221 707461 598694 463272 294574 254539 227459 ... slowest runs (cycles): ... 490429 524581 545470 565563 573724 586777 598694 625751 651221 707461 min: 124942 cycles q1: 204190 cycles median: 247155 cycles q3: 364699 cycles max: 707461 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-32_with_irt_nnacl_newlib_static= {247155, 160509} count Apparent clock speed: 2241 MHz [ OK ] nacl_newlib.run_performance_test_irt (5750 ms) scons: done building targets. ********************************************************************** COMMAND EXECUTION REPORT ********************************************************************** 3 command_tester.py 1 doSizeCheck 3 gcc 1 set ********************************************************************** ENVIRONMENT USAGE REPORT ********************************************************************** 4 dbg-linux-x86-32 4 nacl_irt_test-x86-32 Command return code: 0