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-g34h 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: 13734532 kB MemAvailable: 29421056 kB ====================================================================== LOAD: ====================================================================== 1.12 1.56 1.78 1/439 19203 ====================================================================== UPTIME: ====================================================================== 9893.35 66258.69 ====================================================================== 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='87' BUILDBOT_GOT_REVISION='8e13fe5e85640b063aefcb88a3ccb52abf6e28e8' BUILDBOT_MASTERNAME='client.nacl' BUILDBOT_REVISION='8e13fe5e85640b063aefcb88a3ccb52abf6e28e8' BUILDBOT_SLAVE_TYPE='BuilderTester' BUILDBUCKET_EXPERIMENTAL='FALSE' CIPD_CACHE_DIR='/b/s/cipd_cache/cache' CIPD_PROTOCOL='v2' CYGWIN='nodosfilewarning' DEVSHELL_CLIENT_PORT='46852' 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/8927838581177241648' LOGDOG_STREAM_PROJECT='nacl' LOGDOG_STREAM_SERVER_PATH='unix:/b/s/w/ir/tmp/ld.sock' LOGNAME='chrome-bot' LUCI_CONTEXT='/b/s/w/itQriU2T/luci_context.801948804' 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='19176' 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-g34h' SWARMING_EXTERNAL_BOT_SETUP='1' SWARMING_HEADLESS='1' SWARMING_SERVER='https://chromium-swarm.appspot.com' SWARMING_TASK_ID='419f21cd8223dc11' 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 [19218,4147906304:18:18:16.539020] 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.134050, 0.004120} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (2219 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 (70200 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 [19246,4147865344:18:18:31.474882] Native Client module will be loaded at base address 0x0000000000000000 TestNull: Measuring real time: 0.017 usec (1.704e-08 sec) per iteration: 1.704e-06 sec for 100 iterations 0.004 usec (4.384e-09 sec) per iteration: 4.384e-06 sec for 1000 iterations 0.002 usec (2.4653e-09 sec) per iteration: 2.4653e-05 sec for 10000 iterations 0.002 usec (2.32473e-09 sec) per iteration: 0.0942982 sec for 40563014 iterations 0.002 usec (2.28688e-09 sec) per iteration: 0.0927626 sec for 40563014 iterations 0.002 usec (2.29896e-09 sec) per iteration: 0.0932527 sec for 40563014 iterations 0.002 usec (2.28009e-09 sec) per iteration: 0.0924873 sec for 40563014 iterations 0.002 usec (2.28491e-09 sec) per iteration: 0.0926828 sec for 40563014 iterations mean: 0.002295 usec stddev: 0.000016 usec relative stddev: 0.70% RESULT TestNull: gn_x86-32_with_irt_nnacl_newlib_static= {0.002295, 0.000016} us Measuring clock cycles: first runs (cycles): 88 46 26 24 22 49 22 24 24 24 ... slowest runs (cycles): ... 26 26 26 26 27 27 27 46 49 88 min: 21 cycles q1: 22 cycles median: 24 cycles q3: 24 cycles max: 88 cycles RESULT TestNull_CycleCount: gn_x86-32_with_irt_nnacl_newlib_static= {24, 2} count Apparent clock speed: 10457 MHz TestNaClSyscall: Measuring real time: 0.342 usec (3.4178e-07 sec) per iteration: 3.4178e-05 sec for 100 iterations 0.338 usec (3.38376e-07 sec) per iteration: 0.0990037 sec for 292585 iterations 0.340 usec (3.39883e-07 sec) per iteration: 0.0994447 sec for 292585 iterations 0.341 usec (3.40748e-07 sec) per iteration: 0.0996978 sec for 292585 iterations 0.338 usec (3.37703e-07 sec) per iteration: 0.0988069 sec for 292585 iterations 0.340 usec (3.40152e-07 sec) per iteration: 0.0995233 sec for 292585 iterations mean: 0.339372 usec stddev: 0.001144 usec relative stddev: 0.34% RESULT TestNaClSyscall: gn_x86-32_with_irt_nnacl_newlib_static= {0.339372, 0.001144} us Measuring clock cycles: first runs (cycles): 845 779 735 732 739 774 735 781 732 726 ... slowest runs (cycles): ... 764 770 772 772 774 774 779 779 781 845 min: 726 cycles q1: 730 cycles median: 733 cycles q3: 737 cycles max: 845 cycles RESULT TestNaClSyscall_CycleCount: gn_x86-32_with_irt_nnacl_newlib_static= {733, 7} count Apparent clock speed: 2160 MHz TestSetjmpLongjmp: Measuring real time: 0.021 usec (2.099e-08 sec) per iteration: 2.099e-06 sec for 100 iterations 0.013 usec (1.2879e-08 sec) per iteration: 1.2879e-05 sec for 1000 iterations 0.012 usec (1.23003e-08 sec) per iteration: 0.0955066 sec for 7764577 iterations 0.012 usec (1.23124e-08 sec) per iteration: 0.0956004 sec for 7764577 iterations 0.012 usec (1.22787e-08 sec) per iteration: 0.0953393 sec for 7764577 iterations 0.012 usec (1.22828e-08 sec) per iteration: 0.0953708 sec for 7764577 iterations 0.012 usec (1.22901e-08 sec) per iteration: 0.0954277 sec for 7764577 iterations mean: 0.012293 usec stddev: 0.000012 usec relative stddev: 0.10% RESULT TestSetjmpLongjmp: gn_x86-32_with_irt_nnacl_newlib_static= {0.012293, 0.000012} us Measuring clock cycles: first runs (cycles): 121 66 39 37 36 39 40 40 35 39 ... slowest runs (cycles): ... 41 41 42 42 42 42 42 42 66 121 min: 33 cycles q1: 35 cycles median: 38 cycles q3: 40 cycles max: 121 cycles RESULT TestSetjmpLongjmp_CycleCount: gn_x86-32_with_irt_nnacl_newlib_static= {38, 5} count Apparent clock speed: 3091 MHz TestClockGetTime: Measuring real time: 0.653 usec (6.5291e-07 sec) per iteration: 6.5291e-05 sec for 100 iterations 0.656 usec (6.5596e-07 sec) per iteration: 0.100467 sec for 153160 iterations 0.654 usec (6.54234e-07 sec) per iteration: 0.100203 sec for 153160 iterations 0.664 usec (6.63797e-07 sec) per iteration: 0.101667 sec for 153160 iterations 0.654 usec (6.54142e-07 sec) per iteration: 0.100188 sec for 153160 iterations 0.654 usec (6.53808e-07 sec) per iteration: 0.100137 sec for 153160 iterations mean: 0.656388 usec stddev: 0.003779 usec relative stddev: 0.58% RESULT TestClockGetTime: gn_x86-32_with_irt_nnacl_newlib_static= {0.656388, 0.003779} us Measuring clock cycles: first runs (cycles): 1687 1478 1502 1415 1460 1415 1408 1410 1545 1408 ... slowest runs (cycles): ... 1465 1467 1470 1478 1487 1487 1493 1502 1545 1687 min: 1401 cycles q1: 1412 cycles median: 1416 cycles q3: 1421 cycles max: 1687 cycles RESULT TestClockGetTime_CycleCount: gn_x86-32_with_irt_nnacl_newlib_static= {1416, 9} count Apparent clock speed: 2157 MHz TestTlsVariable: Measuring real time: 0.011 usec (1.143e-08 sec) per iteration: 1.143e-06 sec for 100 iterations 0.004 usec (3.685e-09 sec) per iteration: 3.685e-06 sec for 1000 iterations 0.003 usec (3.05e-09 sec) per iteration: 3.05e-05 sec for 10000 iterations 0.003 usec (3.04623e-09 sec) per iteration: 0.0998764 sec for 32786885 iterations 0.003 usec (3.03706e-09 sec) per iteration: 0.0995759 sec for 32786885 iterations 0.003 usec (3.03516e-09 sec) per iteration: 0.0995134 sec for 32786885 iterations 0.003 usec (3.03751e-09 sec) per iteration: 0.0995904 sec for 32786885 iterations 0.003 usec (3.04317e-09 sec) per iteration: 0.0997761 sec for 32786885 iterations mean: 0.003040 usec stddev: 0.000004 usec relative stddev: 0.14% RESULT TestTlsVariable: gn_x86-32_with_irt_nnacl_newlib_static= {0.003040, 0.000004} us Measuring clock cycles: first runs (cycles): 103 48 28 29 29 26 29 27 28 26 ... slowest runs (cycles): ... 29 29 29 29 29 29 29 29 48 103 min: 26 cycles q1: 27 cycles median: 28 cycles q3: 29 cycles max: 103 cycles RESULT TestTlsVariable_CycleCount: gn_x86-32_with_irt_nnacl_newlib_static= {28, 2} count Apparent clock speed: 9211 MHz TestMmapAnonymous: Measuring real time: 5.806 usec (5.80617e-06 sec) per iteration: 0.000580617 sec for 100 iterations 3.999 usec (3.99857e-06 sec) per iteration: 0.0688673 sec for 17223 iterations 4.007 usec (4.007e-06 sec) per iteration: 0.0690125 sec for 17223 iterations 4.032 usec (4.03243e-06 sec) per iteration: 0.0694505 sec for 17223 iterations 4.000 usec (4.00049e-06 sec) per iteration: 0.0689005 sec for 17223 iterations 3.974 usec (3.974e-06 sec) per iteration: 0.0684442 sec for 17223 iterations mean: 4.002498 usec stddev: 0.018696 usec relative stddev: 0.47% RESULT TestMmapAnonymous: gn_x86-32_with_irt_nnacl_newlib_static= {4.002498, 0.018696} us Measuring clock cycles: first runs (cycles): 11270 8889 8652 8482 8498 8514 8517 8573 8500 8437 ... slowest runs (cycles): ... 8742 8759 8799 8885 8889 8911 8946 9043 11270 18039 min: 8349 cycles q1: 8478 cycles median: 8517 cycles q3: 8585 cycles max: 18039 cycles RESULT TestMmapAnonymous_CycleCount: gn_x86-32_with_irt_nnacl_newlib_static= {8517, 107} count Apparent clock speed: 2128 MHz TestAtomicIncrement: Measuring real time: 0.020 usec (1.969e-08 sec) per iteration: 1.969e-06 sec for 100 iterations 0.010 usec (9.929e-09 sec) per iteration: 9.929e-06 sec for 1000 iterations 0.009 usec (9.3371e-09 sec) per iteration: 9.3371e-05 sec for 10000 iterations 0.010 usec (9.51288e-09 sec) per iteration: 0.101883 sec for 10709963 iterations 0.009 usec (9.48934e-09 sec) per iteration: 0.101631 sec for 10709963 iterations 0.009 usec (9.4767e-09 sec) per iteration: 0.101495 sec for 10709963 iterations 0.009 usec (9.45806e-09 sec) per iteration: 0.101295 sec for 10709963 iterations 0.009 usec (9.48134e-09 sec) per iteration: 0.101545 sec for 10709963 iterations mean: 0.009484 usec stddev: 0.000018 usec relative stddev: 0.19% RESULT TestAtomicIncrement: gn_x86-32_with_irt_nnacl_newlib_static= {0.009484, 0.000018} us Measuring clock cycles: first runs (cycles): 101 69 48 50 51 48 51 50 51 48 ... slowest runs (cycles): ... 51 51 51 51 51 51 51 57 69 101 min: 48 cycles q1: 49 cycles median: 50 cycles q3: 51 cycles max: 101 cycles RESULT TestAtomicIncrement_CycleCount: gn_x86-32_with_irt_nnacl_newlib_static= {50, 2} count Apparent clock speed: 5272 MHz TestUncontendedMutexLock: Measuring real time: 0.032 usec (3.189e-08 sec) per iteration: 3.189e-06 sec for 100 iterations 0.023 usec (2.2573e-08 sec) per iteration: 2.2573e-05 sec for 1000 iterations 0.023 usec (2.25038e-08 sec) per iteration: 0.0996935 sec for 4430071 iterations 0.022 usec (2.23698e-08 sec) per iteration: 0.0990999 sec for 4430071 iterations 0.022 usec (2.23713e-08 sec) per iteration: 0.0991064 sec for 4430071 iterations 0.022 usec (2.23411e-08 sec) per iteration: 0.0989724 sec for 4430071 iterations 0.022 usec (2.24249e-08 sec) per iteration: 0.0993437 sec for 4430071 iterations mean: 0.022402 usec stddev: 0.000058 usec relative stddev: 0.26% RESULT TestUncontendedMutexLock: gn_x86-32_with_irt_nnacl_newlib_static= {0.022402, 0.000058} us Measuring clock cycles: first runs (cycles): 244 150 88 84 79 79 79 80 80 80 ... slowest runs (cycles): ... 82 82 82 82 82 82 84 88 150 244 min: 79 cycles q1: 79 cycles median: 79 cycles q3: 81 cycles max: 244 cycles RESULT TestUncontendedMutexLock_CycleCount: gn_x86-32_with_irt_nnacl_newlib_static= {79, 2} count Apparent clock speed: 3526 MHz TestCondvarSignalNoOp: Measuring real time: 0.578 usec (5.7773e-07 sec) per iteration: 5.7773e-05 sec for 100 iterations 0.534 usec (5.33949e-07 sec) per iteration: 0.0924217 sec for 173091 iterations 0.536 usec (5.36037e-07 sec) per iteration: 0.0927832 sec for 173091 iterations 0.534 usec (5.33763e-07 sec) per iteration: 0.0923896 sec for 173091 iterations 0.535 usec (5.34518e-07 sec) per iteration: 0.0925202 sec for 173091 iterations 0.534 usec (5.33546e-07 sec) per iteration: 0.092352 sec for 173091 iterations mean: 0.534362 usec stddev: 0.000897 usec relative stddev: 0.17% RESULT TestCondvarSignalNoOp: gn_x86-32_with_irt_nnacl_newlib_static= {0.534362, 0.000897} us Measuring clock cycles: first runs (cycles): 1427 1228 1227 1204 1216 1155 1153 1150 1221 1148 ... slowest runs (cycles): ... 1205 1208 1216 1219 1221 1227 1228 1230 1291 1427 min: 1146 cycles q1: 1152 cycles median: 1155 cycles q3: 1164 cycles max: 1427 cycles RESULT TestCondvarSignalNoOp_CycleCount: gn_x86-32_with_irt_nnacl_newlib_static= {1155, 12} count Apparent clock speed: 2161 MHz TestThreadCreateAndJoin: Measuring real time: 194.489 usec (0.000194489 sec) per iteration: 0.0194489 sec for 100 iterations 103.286 usec (0.000103286 sec) per iteration: 0.053089 sec for 514 iterations 174.820 usec (0.00017482 sec) per iteration: 0.0898576 sec for 514 iterations 119.168 usec (0.000119168 sec) per iteration: 0.0612524 sec for 514 iterations 228.553 usec (0.000228553 sec) per iteration: 0.117476 sec for 514 iterations 134.776 usec (0.000134776 sec) per iteration: 0.0692751 sec for 514 iterations mean: 152.120858 usec stddev: 45.008354 usec relative stddev: 29.59% RESULT TestThreadCreateAndJoin: gn_x86-32_with_irt_nnacl_newlib_static= {152.120858, 45.008354} us Measuring clock cycles: first runs (cycles): 244005 339021 260250 653122 239275 230029 517814 186563 165898 158982 ... slowest runs (cycles): ... 244011 258235 260250 275893 311037 324432 339021 435689 517814 653122 min: 118831 cycles q1: 166407 cycles median: 172335 cycles q3: 186563 cycles max: 653122 cycles RESULT TestThreadCreateAndJoin_CycleCount: gn_x86-32_with_irt_nnacl_newlib_static= {172335, 20156} count Apparent clock speed: 1133 MHz TestThreadWakeup: Measuring real time: 66.675 usec (6.66749e-05 sec) per iteration: 0.00666749 sec for 100 iterations 19.395 usec (1.93948e-05 sec) per iteration: 0.0290728 sec for 1499 iterations 23.713 usec (2.37131e-05 sec) per iteration: 0.0355459 sec for 1499 iterations 17.916 usec (1.79156e-05 sec) per iteration: 0.0268554 sec for 1499 iterations 14.081 usec (1.40813e-05 sec) per iteration: 0.0211079 sec for 1499 iterations 18.470 usec (1.84699e-05 sec) per iteration: 0.0276864 sec for 1499 iterations mean: 18.714941 usec stddev: 3.085869 usec relative stddev: 16.49% RESULT TestThreadWakeup: gn_x86-32_with_irt_nnacl_newlib_static= {18.714941, 3.085869} us Measuring clock cycles: first runs (cycles): 60658 48856 40908 39832 39970 39698 39209 39464 39964 42740 ... slowest runs (cycles): ... 43838 43972 44365 46086 46897 48856 50721 56449 60658 383281 min: 38780 cycles q1: 39251 cycles median: 39557 cycles q3: 40156 cycles max: 383281 cycles RESULT TestThreadWakeup_CycleCount: gn_x86-32_with_irt_nnacl_newlib_static= {39557, 905} count Apparent clock speed: 2114 MHz TestCatchingFault: Measuring real time: 3.966 usec (3.96593e-06 sec) per iteration: 0.000396593 sec for 100 iterations 2.562 usec (2.56182e-06 sec) per iteration: 0.0645938 sec for 25214 iterations 2.496 usec (2.49618e-06 sec) per iteration: 0.0629387 sec for 25214 iterations 2.471 usec (2.47096e-06 sec) per iteration: 0.0623028 sec for 25214 iterations 2.482 usec (2.48208e-06 sec) per iteration: 0.0625832 sec for 25214 iterations 2.469 usec (2.4695e-06 sec) per iteration: 0.062266 sec for 25214 iterations mean: 2.496109 usec stddev: 0.034222 usec relative stddev: 1.37% RESULT TestCatchingFault: gn_x86-32_with_irt_nnacl_newlib_static= {2.496109, 0.034222} us Measuring clock cycles: first runs (cycles): 6261 5460 5354 5405 5361 5465 5358 5394 5352 5359 ... slowest runs (cycles): ... 5460 5460 5465 5519 5533 5658 5662 6261 6666 6859 min: 5332 cycles q1: 5349 cycles median: 5358 cycles q3: 5392 cycles max: 6859 cycles RESULT TestCatchingFault_CycleCount: gn_x86-32_with_irt_nnacl_newlib_static= {5358, 43} count Apparent clock speed: 2147 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 191.338 usec (0.000191338 sec) per iteration: 0.0191338 sec for 100 iterations 134.801 usec (0.000134801 sec) per iteration: 0.0703661 sec for 522 iterations 118.259 usec (0.000118259 sec) per iteration: 0.0617311 sec for 522 iterations 108.774 usec (0.000108774 sec) per iteration: 0.05678 sec for 522 iterations 108.651 usec (0.000108651 sec) per iteration: 0.0567159 sec for 522 iterations 98.055 usec (9.80552e-05 sec) per iteration: 0.0511848 sec for 522 iterations mean: 113.707975 usec stddev: 12.333285 usec relative stddev: 10.85% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: gn_x86-32_with_irt_nnacl_newlib_static= {113.707975, 12.333285} us Measuring clock cycles: first runs (cycles): 146617 236409 206749 794802 235680 216174 794466 192348 265140 150573 ... slowest runs (cycles): ... 258908 265140 267809 270873 437723 679980 722411 794466 794802 825428 min: 146617 cycles q1: 180151 cycles median: 185580 cycles q3: 204021 cycles max: 825428 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: gn_x86-32_with_irt_nnacl_newlib_static= {185580, 23870} count Apparent clock speed: 1632 MHz [ OK ] nacl_newlib.run_performance_test_irt (5290 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