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 force_sel_ldr=../out_32/sel_ldr force_irt=../out_32/irt_x86/irt_core.nexe perf_prefix=gn_ force_bootstrap=../out_32/nacl_helper_bootstrap 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', 'force_sel_ldr=../out_32/sel_ldr', 'force_irt=../out_32/irt_x86/irt_core.nexe', 'perf_prefix=gn_', 'force_bootstrap=../out_32/nacl_helper_bootstrap'] ====================================================================== ====================================================================== 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: 23005956 kB MemAvailable: 29447976 kB ====================================================================== LOAD: ====================================================================== 1.10 1.53 1.80 1/443 5163 ====================================================================== UPTIME: ====================================================================== 18438.46 140151.46 ====================================================================== 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='5136' 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 gn_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 /b/s/w/ir/kitchen-workdir/out_32/nacl_helper_bootstrap /b/s/w/ir/kitchen-workdir/out_32/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -B /b/s/w/ir/kitchen-workdir/out_32/irt_x86/irt_core.nexe -- scons-out/nacl_irt_test-x86-32/obj/tests/benchmark/benchmark_test.nexe gn_x86-32_with_irt_nnacl_newlib_static [ RUN ] nacl_newlib.run_benchmark_test_irt /b/s/w/ir/kitchen-workdir/out_32/nacl_helper_bootstrap /b/s/w/ir/kitchen-workdir/out_32/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -B /b/s/w/ir/kitchen-workdir/out_32/irt_x86/irt_core.nexe -- scons-out/nacl_irt_test-x86-32/obj/tests/benchmark/benchmark_test.nexe gn_x86-32_with_irt_nnacl_newlib_static [5178,4147889920:18:21:57.622340] Native Client module will be loaded at base address 0x0000000000000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: gn_x86-32_with_irt_nnacl_newlib_static= {0.109450, 0.013240} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (1790 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 gn_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 /b/s/w/ir/kitchen-workdir/out_32/nacl_helper_bootstrap /b/s/w/ir/kitchen-workdir/out_32/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -B /b/s/w/ir/kitchen-workdir/out_32/irt_x86/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 /b/s/w/ir/kitchen-workdir/out_32/nacl_helper_bootstrap /b/s/w/ir/kitchen-workdir/out_32/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -B /b/s/w/ir/kitchen-workdir/out_32/irt_x86/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 (69860 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: gn_x86-32_with_irt_nnacl_newlib_static= 512.889 KB RESULT hello_world_size.out: ZIPPED_gn_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 gn_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 /b/s/w/ir/kitchen-workdir/out_32/nacl_helper_bootstrap /b/s/w/ir/kitchen-workdir/out_32/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -e -B /b/s/w/ir/kitchen-workdir/out_32/irt_x86/irt_core.nexe -- scons-out/nacl_irt_test-x86-32/obj/tests/performance/performance_test.nexe gn_x86-32_with_irt_nnacl_newlib_static [ RUN ] nacl_newlib.run_performance_test_irt /b/s/w/ir/kitchen-workdir/out_32/nacl_helper_bootstrap /b/s/w/ir/kitchen-workdir/out_32/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -e -B /b/s/w/ir/kitchen-workdir/out_32/irt_x86/irt_core.nexe -- scons-out/nacl_irt_test-x86-32/obj/tests/performance/performance_test.nexe gn_x86-32_with_irt_nnacl_newlib_static [5208,4146956032:18:22:12.499725] Native Client module will be loaded at base address 0x0000000000000000 TestNull: Measuring real time: 0.017 usec (1.663e-08 sec) per iteration: 1.663e-06 sec for 100 iterations 0.008 usec (7.974e-09 sec) per iteration: 7.974e-06 sec for 1000 iterations 0.002 usec (2.3045e-09 sec) per iteration: 2.3045e-05 sec for 10000 iterations 0.002 usec (2.26926e-09 sec) per iteration: 0.0984707 sec for 43393360 iterations 0.002 usec (2.28452e-09 sec) per iteration: 0.0991329 sec for 43393360 iterations 0.002 usec (2.26357e-09 sec) per iteration: 0.098224 sec for 43393360 iterations 0.002 usec (2.25935e-09 sec) per iteration: 0.0980409 sec for 43393360 iterations 0.002 usec (2.25943e-09 sec) per iteration: 0.0980445 sec for 43393360 iterations mean: 0.002267 usec stddev: 0.000009 usec relative stddev: 0.41% RESULT TestNull: gn_x86-32_with_irt_nnacl_newlib_static= {0.002267, 0.000009} us Measuring clock cycles: first runs (cycles): 79 46 27 24 24 48 24 24 22 25 ... slowest runs (cycles): ... 26 26 26 26 27 27 27 46 48 79 min: 22 cycles q1: 24 cycles median: 24 cycles q3: 25 cycles max: 79 cycles RESULT TestNull_CycleCount: gn_x86-32_with_irt_nnacl_newlib_static= {24, 1} count Apparent clock speed: 10586 MHz TestNaClSyscall: Measuring real time: 0.389 usec (3.8874e-07 sec) per iteration: 3.8874e-05 sec for 100 iterations 0.334 usec (3.3411e-07 sec) per iteration: 0.0859469 sec for 257241 iterations 0.334 usec (3.34292e-07 sec) per iteration: 0.0859936 sec for 257241 iterations 0.335 usec (3.34932e-07 sec) per iteration: 0.0861583 sec for 257241 iterations 0.335 usec (3.34846e-07 sec) per iteration: 0.0861362 sec for 257241 iterations 0.334 usec (3.34321e-07 sec) per iteration: 0.0860011 sec for 257241 iterations mean: 0.334500 usec stddev: 0.000327 usec relative stddev: 0.10% RESULT TestNaClSyscall: gn_x86-32_with_irt_nnacl_newlib_static= {0.334500, 0.000327} us Measuring clock cycles: first runs (cycles): 822 777 737 732 724 732 735 728 735 730 ... slowest runs (cycles): ... 822 853 854 887 935 968 979 1049 1054 1217 min: 724 cycles q1: 732 cycles median: 734 cycles q3: 746 cycles max: 1217 cycles RESULT TestNaClSyscall_CycleCount: gn_x86-32_with_irt_nnacl_newlib_static= {734, 14} count Apparent clock speed: 2194 MHz TestSetjmpLongjmp: Measuring real time: 0.026 usec (2.646e-08 sec) per iteration: 2.646e-06 sec for 100 iterations 0.015 usec (1.5031e-08 sec) per iteration: 1.5031e-05 sec for 1000 iterations 0.012 usec (1.23286e-08 sec) per iteration: 0.082021 sec for 6652917 iterations 0.012 usec (1.21789e-08 sec) per iteration: 0.0810249 sec for 6652917 iterations 0.012 usec (1.21666e-08 sec) per iteration: 0.0809434 sec for 6652917 iterations 0.012 usec (1.21586e-08 sec) per iteration: 0.0808904 sec for 6652917 iterations 0.012 usec (1.21697e-08 sec) per iteration: 0.0809641 sec for 6652917 iterations mean: 0.012200 usec stddev: 0.000064 usec relative stddev: 0.53% RESULT TestSetjmpLongjmp: gn_x86-32_with_irt_nnacl_newlib_static= {0.012200, 0.000064} us Measuring clock cycles: first runs (cycles): 116 97 70 42 36 35 40 42 39 40 ... slowest runs (cycles): ... 42 42 42 42 42 42 42 70 97 116 min: 33 cycles q1: 36 cycles median: 39 cycles q3: 40 cycles max: 116 cycles RESULT TestSetjmpLongjmp_CycleCount: gn_x86-32_with_irt_nnacl_newlib_static= {39, 4} count Apparent clock speed: 3197 MHz TestClockGetTime: Measuring real time: 0.702 usec (7.0175e-07 sec) per iteration: 7.0175e-05 sec for 100 iterations 0.645 usec (6.45146e-07 sec) per iteration: 0.0919333 sec for 142500 iterations 0.644 usec (6.44322e-07 sec) per iteration: 0.0918159 sec for 142500 iterations 0.645 usec (6.4509e-07 sec) per iteration: 0.0919254 sec for 142500 iterations 0.645 usec (6.44759e-07 sec) per iteration: 0.0918782 sec for 142500 iterations 0.645 usec (6.45051e-07 sec) per iteration: 0.0919198 sec for 142500 iterations mean: 0.644874 usec stddev: 0.000307 usec relative stddev: 0.05% RESULT TestClockGetTime: gn_x86-32_with_irt_nnacl_newlib_static= {0.644874, 0.000307} us Measuring clock cycles: first runs (cycles): 1691 1569 1441 1403 1408 1485 1401 1406 1462 1408 ... slowest runs (cycles): ... 1485 1503 1512 1569 1691 1770 1791 1841 1877 2156 min: 1394 cycles q1: 1404 cycles median: 1408 cycles q3: 1417 cycles max: 2156 cycles RESULT TestClockGetTime_CycleCount: gn_x86-32_with_irt_nnacl_newlib_static= {1408, 13} count Apparent clock speed: 2183 MHz TestTlsVariable: Measuring real time: 0.012 usec (1.152e-08 sec) per iteration: 1.152e-06 sec for 100 iterations 0.007 usec (7.436e-09 sec) per iteration: 7.436e-06 sec for 1000 iterations 0.003 usec (3.0382e-09 sec) per iteration: 3.0382e-05 sec for 10000 iterations 0.003 usec (3.0207e-09 sec) per iteration: 0.0994239 sec for 32914225 iterations 0.003 usec (3.02328e-09 sec) per iteration: 0.0995089 sec for 32914225 iterations 0.003 usec (3.01481e-09 sec) per iteration: 0.0992301 sec for 32914225 iterations 0.003 usec (3.05629e-09 sec) per iteration: 0.100595 sec for 32914225 iterations 0.003 usec (3.01102e-09 sec) per iteration: 0.0991053 sec for 32914225 iterations mean: 0.003025 usec stddev: 0.000016 usec relative stddev: 0.53% RESULT TestTlsVariable: gn_x86-32_with_irt_nnacl_newlib_static= {0.003025, 0.000016} us Measuring clock cycles: first runs (cycles): 84 46 26 29 27 28 26 29 26 28 ... slowest runs (cycles): ... 29 29 29 29 29 29 29 29 46 84 min: 24 cycles q1: 26 cycles median: 26 cycles q3: 27 cycles max: 84 cycles RESULT TestTlsVariable_CycleCount: gn_x86-32_with_irt_nnacl_newlib_static= {26, 1} count Apparent clock speed: 8594 MHz TestMmapAnonymous: Measuring real time: 5.355 usec (5.35487e-06 sec) per iteration: 0.000535487 sec for 100 iterations 3.961 usec (3.96076e-06 sec) per iteration: 0.0739633 sec for 18674 iterations 3.955 usec (3.95453e-06 sec) per iteration: 0.0738468 sec for 18674 iterations 3.896 usec (3.89643e-06 sec) per iteration: 0.0727619 sec for 18674 iterations 4.012 usec (4.01187e-06 sec) per iteration: 0.0749176 sec for 18674 iterations 3.959 usec (3.95927e-06 sec) per iteration: 0.0739354 sec for 18674 iterations mean: 3.956570 usec stddev: 0.036616 usec relative stddev: 0.93% RESULT TestMmapAnonymous: gn_x86-32_with_irt_nnacl_newlib_static= {3.956570, 0.036616} us Measuring clock cycles: first runs (cycles): 10164 8918 8593 8555 8632 8537 8621 8399 8379 8544 ... slowest runs (cycles): ... 8573 8593 8621 8621 8632 8712 8918 8944 10164 17216 min: 8315 cycles q1: 8386 cycles median: 8434 cycles q3: 8496 cycles max: 17216 cycles RESULT TestMmapAnonymous_CycleCount: gn_x86-32_with_irt_nnacl_newlib_static= {8434, 110} count Apparent clock speed: 2132 MHz TestAtomicIncrement: Measuring real time: 0.019 usec (1.882e-08 sec) per iteration: 1.882e-06 sec for 100 iterations 0.010 usec (9.952e-09 sec) per iteration: 9.952e-06 sec for 1000 iterations 0.010 usec (9.706e-09 sec) per iteration: 9.706e-05 sec for 10000 iterations 0.009 usec (9.41686e-09 sec) per iteration: 0.0970211 sec for 10302905 iterations 0.009 usec (9.40767e-09 sec) per iteration: 0.0969263 sec for 10302905 iterations 0.009 usec (9.40494e-09 sec) per iteration: 0.0968982 sec for 10302905 iterations 0.010 usec (9.55724e-09 sec) per iteration: 0.0984673 sec for 10302905 iterations 0.009 usec (9.40809e-09 sec) per iteration: 0.0969307 sec for 10302905 iterations mean: 0.009439 usec stddev: 0.000059 usec relative stddev: 0.63% RESULT TestAtomicIncrement: gn_x86-32_with_irt_nnacl_newlib_static= {0.009439, 0.000059} us Measuring clock cycles: first runs (cycles): 92 69 48 48 51 48 51 48 50 49 ... slowest runs (cycles): ... 51 51 51 51 51 51 51 51 69 92 min: 48 cycles q1: 49 cycles median: 50 cycles q3: 51 cycles max: 92 cycles RESULT TestAtomicIncrement_CycleCount: gn_x86-32_with_irt_nnacl_newlib_static= {50, 2} count Apparent clock speed: 5297 MHz TestUncontendedMutexLock: Measuring real time: 0.031 usec (3.079e-08 sec) per iteration: 3.079e-06 sec for 100 iterations 0.023 usec (2.2588e-08 sec) per iteration: 2.2588e-05 sec for 1000 iterations 0.022 usec (2.22005e-08 sec) per iteration: 0.0982845 sec for 4427129 iterations 0.022 usec (2.21865e-08 sec) per iteration: 0.0982227 sec for 4427129 iterations 0.022 usec (2.21869e-08 sec) per iteration: 0.0982244 sec for 4427129 iterations 0.022 usec (2.21963e-08 sec) per iteration: 0.0982657 sec for 4427129 iterations 0.022 usec (2.21956e-08 sec) per iteration: 0.098263 sec for 4427129 iterations mean: 0.022193 usec stddev: 0.000006 usec relative stddev: 0.02% RESULT TestUncontendedMutexLock: gn_x86-32_with_irt_nnacl_newlib_static= {0.022193, 0.000006} us Measuring clock cycles: first runs (cycles): 227 97 79 79 79 79 88 79 79 79 ... slowest runs (cycles): ... 82 82 82 82 82 82 84 88 97 227 min: 79 cycles q1: 79 cycles median: 79 cycles q3: 81 cycles max: 227 cycles RESULT TestUncontendedMutexLock_CycleCount: gn_x86-32_with_irt_nnacl_newlib_static= {79, 2} count Apparent clock speed: 3560 MHz TestCondvarSignalNoOp: Measuring real time: 0.598 usec (5.9846e-07 sec) per iteration: 5.9846e-05 sec for 100 iterations 0.528 usec (5.2775e-07 sec) per iteration: 0.0881843 sec for 167095 iterations 0.531 usec (5.3102e-07 sec) per iteration: 0.0887307 sec for 167095 iterations 0.527 usec (5.2746e-07 sec) per iteration: 0.088136 sec for 167095 iterations 0.528 usec (5.2832e-07 sec) per iteration: 0.0882796 sec for 167095 iterations 0.528 usec (5.27851e-07 sec) per iteration: 0.0882013 sec for 167095 iterations mean: 0.528480 usec stddev: 0.001299 usec relative stddev: 0.25% RESULT TestCondvarSignalNoOp: gn_x86-32_with_irt_nnacl_newlib_static= {0.528480, 0.001299} us Measuring clock cycles: first runs (cycles): 1388 1309 1307 1175 1195 1151 1153 1201 1199 1149 ... slowest runs (cycles): ... 1196 1199 1201 1201 1207 1214 1219 1307 1309 1388 min: 1142 cycles q1: 1148 cycles median: 1151 cycles q3: 1157 cycles max: 1388 cycles RESULT TestCondvarSignalNoOp_CycleCount: gn_x86-32_with_irt_nnacl_newlib_static= {1151, 9} count Apparent clock speed: 2178 MHz TestThreadCreateAndJoin: Measuring real time: 93.506 usec (9.35061e-05 sec) per iteration: 0.00935061 sec for 100 iterations 99.395 usec (9.93954e-05 sec) per iteration: 0.106254 sec for 1069 iterations 91.336 usec (9.13359e-05 sec) per iteration: 0.0976381 sec for 1069 iterations 89.868 usec (8.98684e-05 sec) per iteration: 0.0960694 sec for 1069 iterations 85.024 usec (8.50235e-05 sec) per iteration: 0.0908902 sec for 1069 iterations 92.004 usec (9.20039e-05 sec) per iteration: 0.0983522 sec for 1069 iterations mean: 91.525449 usec stddev: 4.630814 usec relative stddev: 5.06% RESULT TestThreadCreateAndJoin: gn_x86-32_with_irt_nnacl_newlib_static= {91.525449, 4.630814} us Measuring clock cycles: first runs (cycles): 131403 119840 135279 168008 114946 156243 130532 210961 297391 318897 ... slowest runs (cycles): ... 243676 269210 288606 296304 297391 306136 318897 694249 871406 1057430 min: 114946 cycles q1: 183710 cycles median: 188900 cycles q3: 205297 cycles max: 1057430 cycles RESULT TestThreadCreateAndJoin_CycleCount: gn_x86-32_with_irt_nnacl_newlib_static= {188900, 21587} count Apparent clock speed: 2064 MHz TestThreadWakeup: Measuring real time: 14.574 usec (1.45737e-05 sec) per iteration: 0.00145737 sec for 100 iterations 18.924 usec (1.89237e-05 sec) per iteration: 0.129835 sec for 6861 iterations 19.350 usec (1.93496e-05 sec) per iteration: 0.132758 sec for 6861 iterations 17.373 usec (1.73729e-05 sec) per iteration: 0.119195 sec for 6861 iterations 17.325 usec (1.73253e-05 sec) per iteration: 0.118869 sec for 6861 iterations 17.260 usec (1.72596e-05 sec) per iteration: 0.118418 sec for 6861 iterations mean: 18.046218 usec stddev: 0.901161 usec relative stddev: 4.99% RESULT TestThreadWakeup: gn_x86-32_with_irt_nnacl_newlib_static= {18.046218, 0.901161} us Measuring clock cycles: first runs (cycles): 37340 37535 37386 37119 36668 37179 37229 37716 41138 37144 ... slowest runs (cycles): ... 43679 46033 46159 46633 50288 51816 53873 56033 89178 137147 min: 32320 cycles q1: 36202 cycles median: 37064 cycles q3: 37632 cycles max: 137147 cycles RESULT TestThreadWakeup_CycleCount: gn_x86-32_with_irt_nnacl_newlib_static= {37064, 1430} count Apparent clock speed: 2054 MHz TestCatchingFault: Measuring real time: 4.532 usec (4.53229e-06 sec) per iteration: 0.000453229 sec for 100 iterations 2.479 usec (2.47898e-06 sec) per iteration: 0.0546937 sec for 22063 iterations 2.475 usec (2.47515e-06 sec) per iteration: 0.0546093 sec for 22063 iterations 2.474 usec (2.4738e-06 sec) per iteration: 0.0545795 sec for 22063 iterations 2.472 usec (2.47174e-06 sec) per iteration: 0.0545341 sec for 22063 iterations 2.470 usec (2.47039e-06 sec) per iteration: 0.0545042 sec for 22063 iterations mean: 2.474014 usec stddev: 0.002976 usec relative stddev: 0.12% RESULT TestCatchingFault: gn_x86-32_with_irt_nnacl_newlib_static= {2.474014, 0.002976} us Measuring clock cycles: first runs (cycles): 6395 5524 5418 5481 5416 5370 5385 5418 5376 5427 ... slowest runs (cycles): ... 6402 6575 6701 6780 6826 6841 7070 7407 8043 13512 min: 5312 cycles q1: 5339 cycles median: 5377 cycles q3: 5423 cycles max: 13512 cycles RESULT TestCatchingFault_CycleCount: gn_x86-32_with_irt_nnacl_newlib_static= {5377, 84} count Apparent clock speed: 2173 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 89.724 usec (8.97239e-05 sec) per iteration: 0.00897239 sec for 100 iterations 117.977 usec (0.000117977 sec) per iteration: 0.131426 sec for 1114 iterations 104.847 usec (0.000104847 sec) per iteration: 0.116799 sec for 1114 iterations 91.091 usec (9.10909e-05 sec) per iteration: 0.101475 sec for 1114 iterations 106.279 usec (0.000106279 sec) per iteration: 0.118395 sec for 1114 iterations 130.858 usec (0.000130858 sec) per iteration: 0.145776 sec for 1114 iterations mean: 110.210357 usec stddev: 13.389803 usec relative stddev: 12.15% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: gn_x86-32_with_irt_nnacl_newlib_static= {110.210357, 13.389803} us Measuring clock cycles: first runs (cycles): 156708 163899 177176 163531 174471 199819 120371 328217 121044 351526 ... slowest runs (cycles): ... 446941 449328 472208 506119 543085 547927 585975 613145 987351 1039610 min: 113416 cycles q1: 156727 cycles median: 199819 cycles q3: 274712 cycles max: 1039610 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: gn_x86-32_with_irt_nnacl_newlib_static= {199819, 117985} count Apparent clock speed: 1813 MHz [ OK ] nacl_newlib.run_performance_test_irt (6020 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