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

Running command: /b/s/w/ir/cache/vpython/e0eb19/bin/python scons.py --verbose -k -j1 --mode=nacl,opt-host,nacl_irt_test platform=x86-64 breakpad_tools_dir=breakpad-out large_tests_irt force_sel_ldr=../out_64/sel_ldr force_irt=../out_64/irt_x64/irt_core.nexe perf_prefix=gn_ force_bootstrap=../out_64/nacl_helper_bootstrap scons: Reading SConscript files ... ====================================================================== SCONS ARGS:['scons.py', '--verbose', '-k', '-j1', '--mode=nacl,opt-host,nacl_irt_test', 'platform=x86-64', 'breakpad_tools_dir=breakpad-out', 'large_tests_irt', 'force_sel_ldr=../out_64/sel_ldr', 'force_irt=../out_64/irt_x64/irt_core.nexe', 'perf_prefix=gn_', 'force_bootstrap=../out_64/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/e0eb19/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-sb00 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: 21619300 kB MemAvailable: 29482088 kB ====================================================================== LOAD: ====================================================================== 1.10 2.08 2.03 1/442 9861 ====================================================================== UPTIME: ====================================================================== 9943.46 75840.89 ====================================================================== 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-64 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-64/obj BUILD_TYPE_DESCRIPTION: NaCl module build BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 64 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 64 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 -m64 -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=64 -I/b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-64/include -I/b/s/w/ir/kitchen-workdir -c -o UNKNOWN COMPILER NACL_BUILD_FAMILY: TRUSTED BUILD_TYPE: opt-linux TARGET_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-linux-x86-64 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-linux-x86-64/obj BUILD_TYPE_DESCRIPTION: Linux opt build BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 64 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 64 CC: gcc ASPPCOM: gcc -fPIE -m64 -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 -O2 -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 -DNDEBUG -I/b/s/w/ir/kitchen-workdir -c -o set BOTO_CONFIG='/home/chrome-bot/.boto' BUILDBOT_BUILDERNAME='linux-64-newlib-opt' BUILDBOT_GOT_REVISION='2ebbdab4bfb1ed9dfe52ce756b34829207dbe25f' BUILDBOT_MASTERNAME='client.nacl' BUILDBOT_REVISION='2ebbdab4bfb1ed9dfe52ce756b34829207dbe25f' BUILDBOT_SLAVE_TYPE='BuilderTester' BUILDBUCKET_EXPERIMENTAL='FALSE' CIPD_CACHE_DIR='/b/s/cipd_cache/cache' CIPD_PROTOCOL='v2' CYGWIN='nodosfilewarning' DEVSHELL_CLIENT_PORT='35396' 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/8934442544737867232' LOGDOG_STREAM_PROJECT='nacl' LOGDOG_STREAM_SERVER_PATH='unix:/b/s/w/ir/tmp/ld.sock' LOGNAME='chrome-bot' LUCI_CONTEXT='/b/s/w/itDinNvt/luci_context.312187504' 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/e0eb19/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='9834' 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-sb00' SWARMING_EXTERNAL_BOT_SETUP='1' SWARMING_HEADLESS='1' SWARMING_SERVER='https://chromium-swarm.appspot.com' SWARMING_TASK_ID='4027bd7d3a4b8e11' TEMP='/b/s/w/ir/tmp/t/nacl_tmp/linux-64-newlib-opt' TEMPDIR='/b/s/w/ir/tmp/t' TERM='linux' TMP='/b/s/w/ir/tmp/t/nacl_tmp/linux-64-newlib-opt' TMPDIR='/b/s/w/ir/tmp/t/nacl_tmp/linux-64-newlib-opt' USER='chrome-bot' USERNAME='chrome-bot' VIRTUAL_ENV='/b/s/w/ir/cache/vpython/e0eb19' 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-64 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt_test-x86-64/obj BUILD_TYPE_DESCRIPTION: NaCl tests build with IRT BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 64 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 64 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 -m64 -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=64 -Iscons-out/nacl-x86-64/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-64 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt-x86-64/obj BUILD_TYPE_DESCRIPTION: NaCl IRT build BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 64 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 64 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 -m64 -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=64 -I/b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt-x86-64/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_vla_test *** BROKEN run_nacl_sync_cond_test SKIPPING test text_overlaps_rodata SKIPPING test text_overlaps_data *** 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 *** BROKEN run_vla_test_irt There are 24 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/e0eb19/bin/python tools/command_tester.py --name nacl_newlib.run_benchmark_test_irt --time_warning 10 --time_error 100 --perf_env_description gn_x86-64_with_irt_nnacl_newlib_static --using_nacl_signal_handler True --subarch 64 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt_test-x86-64/test_results/benchmark_test.out /b/s/w/ir/kitchen-workdir/out_64/nacl_helper_bootstrap /b/s/w/ir/kitchen-workdir/out_64/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -B /b/s/w/ir/kitchen-workdir/out_64/irt_x64/irt_core.nexe -- scons-out/nacl_irt_test-x86-64/obj/tests/benchmark/benchmark_test.nexe gn_x86-64_with_irt_nnacl_newlib_static [ RUN ] nacl_newlib.run_benchmark_test_irt /b/s/w/ir/kitchen-workdir/out_64/nacl_helper_bootstrap /b/s/w/ir/kitchen-workdir/out_64/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -B /b/s/w/ir/kitchen-workdir/out_64/irt_x64/irt_core.nexe -- scons-out/nacl_irt_test-x86-64/obj/tests/benchmark/benchmark_test.nexe gn_x86-64_with_irt_nnacl_newlib_static [9876,377354112:21:29:31.142478] Native Client module will be loaded at base address 0x0000704600000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: gn_x86-64_with_irt_nnacl_newlib_static= {0.108890, 0.002860} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (1760 ms) /b/s/w/ir/cache/vpython/e0eb19/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-64_with_irt_nnacl_newlib_static --using_nacl_signal_handler True --subarch 64 --arch x86 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt_test-x86-64/test_results/clock_cputime_test.out /b/s/w/ir/kitchen-workdir/out_64/nacl_helper_bootstrap /b/s/w/ir/kitchen-workdir/out_64/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -B /b/s/w/ir/kitchen-workdir/out_64/irt_x64/irt_core.nexe -- scons-out/nacl_irt_test-x86-64/obj/tests/clock/clock_test.nexe -c [ RUN ] nacl_newlib.run_clock_cputime_test_irt /b/s/w/ir/kitchen-workdir/out_64/nacl_helper_bootstrap /b/s/w/ir/kitchen-workdir/out_64/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -B /b/s/w/ir/kitchen-workdir/out_64/irt_x64/irt_core.nexe -- scons-out/nacl_irt_test-x86-64/obj/tests/clock/clock_test.nexe -c WARNING: should have taken less than 60.000000 secs [ OK ] nacl_newlib.run_clock_cputime_test_irt (75230 ms) doSizeCheck(["scons-out/nacl_irt_test-x86-64/obj/tests/hello_world/hello_world_size.out"], ["scons-out/nacl_irt_test-x86-64/obj/tests/hello_world/hello_world.nexe"]) RESULT hello_world_size.out: gn_x86-64_with_irt_nnacl_newlib_static= 530.002 KB RESULT hello_world_size.out: ZIPPED_gn_x86-64_with_irt_nnacl_newlib_static= 187.085 KB /b/s/w/ir/cache/vpython/e0eb19/bin/python tools/command_tester.py --name nacl_newlib.run_performance_test_irt --time_warning 10 --time_error 100 --perf_env_description gn_x86-64_with_irt_nnacl_newlib_static --using_nacl_signal_handler True --subarch 64 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt_test-x86-64/test_results/performance_test.out /b/s/w/ir/kitchen-workdir/out_64/nacl_helper_bootstrap /b/s/w/ir/kitchen-workdir/out_64/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -e -B /b/s/w/ir/kitchen-workdir/out_64/irt_x64/irt_core.nexe -- scons-out/nacl_irt_test-x86-64/obj/tests/performance/performance_test.nexe gn_x86-64_with_irt_nnacl_newlib_static [ RUN ] nacl_newlib.run_performance_test_irt /b/s/w/ir/kitchen-workdir/out_64/nacl_helper_bootstrap /b/s/w/ir/kitchen-workdir/out_64/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -e -B /b/s/w/ir/kitchen-workdir/out_64/irt_x64/irt_core.nexe -- scons-out/nacl_irt_test-x86-64/obj/tests/performance/performance_test.nexe gn_x86-64_with_irt_nnacl_newlib_static [9904,954505088:21:29:46.905345] Native Client module will be loaded at base address 0x0000191700000000 TestNull: Measuring real time: 0.006 usec (5.65e-09 sec) per iteration: 5.65e-07 sec for 100 iterations 0.002 usec (2.031e-09 sec) per iteration: 2.031e-06 sec for 1000 iterations 0.002 usec (1.874e-09 sec) per iteration: 1.874e-05 sec for 10000 iterations 0.002 usec (1.90057e-09 sec) per iteration: 0.101418 sec for 53361792 iterations 0.002 usec (1.89372e-09 sec) per iteration: 0.101052 sec for 53361792 iterations 0.002 usec (1.89656e-09 sec) per iteration: 0.101204 sec for 53361792 iterations 0.002 usec (1.89957e-09 sec) per iteration: 0.101365 sec for 53361792 iterations 0.002 usec (1.90297e-09 sec) per iteration: 0.101546 sec for 53361792 iterations mean: 0.001899 usec stddev: 0.000003 usec relative stddev: 0.17% RESULT TestNull: gn_x86-64_with_irt_nnacl_newlib_static= {0.001899, 0.000003} us Measuring clock cycles: first runs (cycles): 93 26 22 22 24 27 20 22 24 22 ... slowest runs (cycles): ... 27 27 27 27 27 27 27 29 29 93 min: 20 cycles q1: 24 cycles median: 25 cycles q3: 26 cycles max: 93 cycles RESULT TestNull_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {25, 2} count Apparent clock speed: 13167 MHz TestNaClSyscall: Measuring real time: 0.144 usec (1.4449e-07 sec) per iteration: 1.4449e-05 sec for 100 iterations 0.125 usec (1.25451e-07 sec) per iteration: 0.0868236 sec for 692089 iterations 0.124 usec (1.23775e-07 sec) per iteration: 0.0856635 sec for 692089 iterations 0.125 usec (1.25467e-07 sec) per iteration: 0.0868344 sec for 692089 iterations 0.125 usec (1.24514e-07 sec) per iteration: 0.0861749 sec for 692089 iterations 0.125 usec (1.25195e-07 sec) per iteration: 0.0866458 sec for 692089 iterations mean: 0.124881 usec stddev: 0.000652 usec relative stddev: 0.52% RESULT TestNaClSyscall: gn_x86-64_with_irt_nnacl_newlib_static= {0.124881, 0.000652} us Measuring clock cycles: first runs (cycles): 334 213 187 182 180 214 178 180 181 180 ... slowest runs (cycles): ... 183 183 183 183 185 187 189 213 214 334 min: 178 cycles q1: 180 cycles median: 181 cycles q3: 182 cycles max: 334 cycles RESULT TestNaClSyscall_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {181, 2} count Apparent clock speed: 1449 MHz TestSetjmpLongjmp: Measuring real time: 0.017 usec (1.7e-08 sec) per iteration: 1.7e-06 sec for 100 iterations 0.013 usec (1.2704e-08 sec) per iteration: 1.2704e-05 sec for 1000 iterations 0.013 usec (1.2805e-08 sec) per iteration: 0.100795 sec for 7871536 iterations 0.013 usec (1.28076e-08 sec) per iteration: 0.100816 sec for 7871536 iterations 0.013 usec (1.27923e-08 sec) per iteration: 0.100695 sec for 7871536 iterations 0.013 usec (1.28492e-08 sec) per iteration: 0.101143 sec for 7871536 iterations 0.013 usec (1.28263e-08 sec) per iteration: 0.100963 sec for 7871536 iterations mean: 0.012816 usec stddev: 0.000020 usec relative stddev: 0.15% RESULT TestSetjmpLongjmp: gn_x86-64_with_irt_nnacl_newlib_static= {0.012816, 0.000020} us Measuring clock cycles: first runs (cycles): 156 59 42 33 31 31 26 26 29 26 ... slowest runs (cycles): ... 29 29 31 31 31 31 33 42 59 156 min: 24 cycles q1: 26 cycles median: 27 cycles q3: 29 cycles max: 156 cycles RESULT TestSetjmpLongjmp_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {27, 3} count Apparent clock speed: 2107 MHz TestClockGetTime: Measuring real time: 0.150 usec (1.4984e-07 sec) per iteration: 1.4984e-05 sec for 100 iterations 0.155 usec (1.54964e-07 sec) per iteration: 0.10342 sec for 667378 iterations 0.157 usec (1.56561e-07 sec) per iteration: 0.104486 sec for 667378 iterations 0.157 usec (1.56754e-07 sec) per iteration: 0.104614 sec for 667378 iterations 0.155 usec (1.54857e-07 sec) per iteration: 0.103348 sec for 667378 iterations 0.156 usec (1.5558e-07 sec) per iteration: 0.10383 sec for 667378 iterations mean: 0.155743 usec stddev: 0.000789 usec relative stddev: 0.51% RESULT TestClockGetTime: gn_x86-64_with_irt_nnacl_newlib_static= {0.155743, 0.000789} us Measuring clock cycles: first runs (cycles): 453 372 369 348 345 341 341 341 337 339 ... slowest runs (cycles): ... 345 345 346 348 369 372 379 383 453 470 min: 334 cycles q1: 338 cycles median: 339 cycles q3: 341 cycles max: 470 cycles RESULT TestClockGetTime_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {339, 3} count Apparent clock speed: 2177 MHz TestTlsVariable: Measuring real time: 0.009 usec (9.39e-09 sec) per iteration: 9.39e-07 sec for 100 iterations 0.006 usec (5.98e-09 sec) per iteration: 5.98e-06 sec for 1000 iterations 0.006 usec (5.5963e-09 sec) per iteration: 5.5963e-05 sec for 10000 iterations 0.006 usec (5.71266e-09 sec) per iteration: 0.102079 sec for 17868949 iterations 0.006 usec (5.68214e-09 sec) per iteration: 0.101534 sec for 17868949 iterations 0.006 usec (5.67485e-09 sec) per iteration: 0.101404 sec for 17868949 iterations 0.006 usec (5.70806e-09 sec) per iteration: 0.101997 sec for 17868949 iterations 0.006 usec (5.69096e-09 sec) per iteration: 0.101691 sec for 17868949 iterations mean: 0.005694 usec stddev: 0.000015 usec relative stddev: 0.26% RESULT TestTlsVariable: gn_x86-64_with_irt_nnacl_newlib_static= {0.005694, 0.000015} us Measuring clock cycles: first runs (cycles): 161 72 53 33 50 30 28 28 28 28 ... slowest runs (cycles): ... 33 33 33 33 33 33 50 53 72 161 min: 24 cycles q1: 28 cycles median: 29 cycles q3: 31 cycles max: 161 cycles RESULT TestTlsVariable_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {29, 3} count Apparent clock speed: 5093 MHz TestMmapAnonymous: Measuring real time: 2.771 usec (2.77059e-06 sec) per iteration: 0.000277059 sec for 100 iterations 1.932 usec (1.93242e-06 sec) per iteration: 0.0697467 sec for 36093 iterations 1.929 usec (1.92896e-06 sec) per iteration: 0.0696219 sec for 36093 iterations 1.915 usec (1.9153e-06 sec) per iteration: 0.0691289 sec for 36093 iterations 1.935 usec (1.93505e-06 sec) per iteration: 0.0698418 sec for 36093 iterations 1.927 usec (1.9268e-06 sec) per iteration: 0.0695442 sec for 36093 iterations mean: 1.927706 usec stddev: 0.006818 usec relative stddev: 0.35% RESULT TestMmapAnonymous: gn_x86-64_with_irt_nnacl_newlib_static= {1.927706, 0.006818} us Measuring clock cycles: first runs (cycles): 6240 4307 4179 4228 4160 4156 4156 4199 4118 4136 ... slowest runs (cycles): ... 4204 4206 4206 4208 4215 4223 4224 4228 4307 6240 min: 4102 cycles q1: 4136 cycles median: 4148 cycles q3: 4171 cycles max: 6240 cycles RESULT TestMmapAnonymous_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {4148, 35} count Apparent clock speed: 2152 MHz TestAtomicIncrement: Measuring real time: 0.013 usec (1.325e-08 sec) per iteration: 1.325e-06 sec for 100 iterations 0.009 usec (9.434e-09 sec) per iteration: 9.434e-06 sec for 1000 iterations 0.009 usec (9.3581e-09 sec) per iteration: 9.3581e-05 sec for 10000 iterations 0.010 usec (9.74325e-09 sec) per iteration: 0.104116 sec for 10685929 iterations 0.009 usec (9.49904e-09 sec) per iteration: 0.101506 sec for 10685929 iterations 0.009 usec (9.46851e-09 sec) per iteration: 0.10118 sec for 10685929 iterations 0.009 usec (9.48003e-09 sec) per iteration: 0.101303 sec for 10685929 iterations 0.009 usec (9.48574e-09 sec) per iteration: 0.101364 sec for 10685929 iterations mean: 0.009535 usec stddev: 0.000104 usec relative stddev: 1.10% RESULT TestAtomicIncrement: gn_x86-64_with_irt_nnacl_newlib_static= {0.009535, 0.000104} us Measuring clock cycles: first runs (cycles): 115 51 51 53 52 53 53 55 50 55 ... slowest runs (cycles): ... 55 55 55 55 55 55 55 55 55 115 min: 50 cycles q1: 52 cycles median: 53 cycles q3: 53 cycles max: 115 cycles RESULT TestAtomicIncrement_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {53, 1} count Apparent clock speed: 5558 MHz TestUncontendedMutexLock: Measuring real time: 0.029 usec (2.855e-08 sec) per iteration: 2.855e-06 sec for 100 iterations 0.024 usec (2.442e-08 sec) per iteration: 2.442e-05 sec for 1000 iterations 0.025 usec (2.46086e-08 sec) per iteration: 0.100772 sec for 4095004 iterations 0.025 usec (2.45903e-08 sec) per iteration: 0.100697 sec for 4095004 iterations 0.025 usec (2.45498e-08 sec) per iteration: 0.100531 sec for 4095004 iterations 0.025 usec (2.47401e-08 sec) per iteration: 0.101311 sec for 4095004 iterations 0.025 usec (2.46828e-08 sec) per iteration: 0.101076 sec for 4095004 iterations mean: 0.024634 usec stddev: 0.000068 usec relative stddev: 0.28% RESULT TestUncontendedMutexLock: gn_x86-64_with_irt_nnacl_newlib_static= {0.024634, 0.000068} us Measuring clock cycles: first runs (cycles): 222 110 81 84 83 81 84 83 81 84 ... slowest runs (cycles): ... 84 84 84 84 84 84 84 92 110 222 min: 81 cycles q1: 82 cycles median: 83 cycles q3: 84 cycles max: 222 cycles RESULT TestUncontendedMutexLock_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {83, 2} count Apparent clock speed: 3369 MHz TestCondvarSignalNoOp: Measuring real time: 0.246 usec (2.4588e-07 sec) per iteration: 2.4588e-05 sec for 100 iterations 0.216 usec (2.15944e-07 sec) per iteration: 0.087825 sec for 406702 iterations 0.215 usec (2.15437e-07 sec) per iteration: 0.0876186 sec for 406702 iterations 0.216 usec (2.15923e-07 sec) per iteration: 0.0878163 sec for 406702 iterations 0.216 usec (2.15681e-07 sec) per iteration: 0.087718 sec for 406702 iterations 0.216 usec (2.1583e-07 sec) per iteration: 0.0877786 sec for 406702 iterations mean: 0.215763 usec stddev: 0.000188 usec relative stddev: 0.09% RESULT TestCondvarSignalNoOp: gn_x86-64_with_irt_nnacl_newlib_static= {0.215763, 0.000188} us Measuring clock cycles: first runs (cycles): 610 502 475 478 515 484 482 482 482 479 ... slowest runs (cycles): ... 484 484 484 484 484 484 484 502 515 610 min: 475 cycles q1: 480 cycles median: 482 cycles q3: 482 cycles max: 610 cycles RESULT TestCondvarSignalNoOp_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {482, 2} count Apparent clock speed: 2234 MHz TestThreadCreateAndJoin: Measuring real time: 35.180 usec (3.51804e-05 sec) per iteration: 0.00351804 sec for 100 iterations 27.831 usec (2.78307e-05 sec) per iteration: 0.0790949 sec for 2842 iterations 26.601 usec (2.66006e-05 sec) per iteration: 0.075599 sec for 2842 iterations 25.780 usec (2.57802e-05 sec) per iteration: 0.0732672 sec for 2842 iterations 25.387 usec (2.53871e-05 sec) per iteration: 0.0721502 sec for 2842 iterations 27.041 usec (2.70406e-05 sec) per iteration: 0.0768495 sec for 2842 iterations mean: 26.527863 usec stddev: 0.874759 usec relative stddev: 3.30% RESULT TestThreadCreateAndJoin: gn_x86-64_with_irt_nnacl_newlib_static= {26.527863, 0.874759} us Measuring clock cycles: first runs (cycles): 52030 86206 52501 69466 52690 836748 126447 77621 99056 69199 ... slowest runs (cycles): ... 72331 74404 77621 86206 98877 99056 101099 126447 160961 836748 min: 46878 cycles q1: 49690 cycles median: 51064 cycles q3: 53746 cycles max: 836748 cycles RESULT TestThreadCreateAndJoin_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {51064, 4056} count Apparent clock speed: 1925 MHz TestThreadWakeup: Measuring real time: 14.821 usec (1.48209e-05 sec) per iteration: 0.00148209 sec for 100 iterations 14.986 usec (1.49865e-05 sec) per iteration: 0.101114 sec for 6747 iterations 14.601 usec (1.46012e-05 sec) per iteration: 0.098514 sec for 6747 iterations 22.241 usec (2.22406e-05 sec) per iteration: 0.150058 sec for 6747 iterations 22.240 usec (2.22404e-05 sec) per iteration: 0.150056 sec for 6747 iterations 15.380 usec (1.53805e-05 sec) per iteration: 0.103772 sec for 6747 iterations mean: 17.889825 usec stddev: 3.560866 usec relative stddev: 19.90% RESULT TestThreadWakeup: gn_x86-64_with_irt_nnacl_newlib_static= {17.889825, 3.560866} us Measuring clock cycles: first runs (cycles): 160971 153840 46937 47687 45842 45213 49887 46588 44696 47083 ... slowest runs (cycles): ... 53052 55625 62536 78973 101656 131144 140630 153840 160971 222869 min: 34432 cycles q1: 41082 cycles median: 42096 cycles q3: 45213 cycles max: 222869 cycles RESULT TestThreadWakeup_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {42096, 4131} count Apparent clock speed: 2353 MHz TestCatchingFault: Measuring real time: 2.762 usec (2.76188e-06 sec) per iteration: 0.000276188 sec for 100 iterations 1.708 usec (1.70775e-06 sec) per iteration: 0.0618326 sec for 36207 iterations 1.706 usec (1.70557e-06 sec) per iteration: 0.0617536 sec for 36207 iterations 1.700 usec (1.6997e-06 sec) per iteration: 0.061541 sec for 36207 iterations 1.699 usec (1.69916e-06 sec) per iteration: 0.0615213 sec for 36207 iterations 1.699 usec (1.69917e-06 sec) per iteration: 0.0615219 sec for 36207 iterations mean: 1.702270 usec stddev: 0.003658 usec relative stddev: 0.21% RESULT TestCatchingFault: gn_x86-64_with_irt_nnacl_newlib_static= {1.702270, 0.003658} us Measuring clock cycles: first runs (cycles): 4177 3790 3724 3729 3693 3731 3689 3726 3715 3718 ... slowest runs (cycles): ... 3740 3746 3755 3760 3790 3883 4050 4177 14273 25914 min: 3661 cycles q1: 3695 cycles median: 3703 cycles q3: 3720 cycles max: 25914 cycles RESULT TestCatchingFault_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {3703, 25} count Apparent clock speed: 2175 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 65.275 usec (6.52751e-05 sec) per iteration: 0.00652751 sec for 100 iterations 58.177 usec (5.81774e-05 sec) per iteration: 0.0890695 sec for 1531 iterations 58.717 usec (5.87169e-05 sec) per iteration: 0.0898955 sec for 1531 iterations 51.965 usec (5.19646e-05 sec) per iteration: 0.0795578 sec for 1531 iterations 53.242 usec (5.32422e-05 sec) per iteration: 0.0815138 sec for 1531 iterations 35.075 usec (3.50749e-05 sec) per iteration: 0.0536997 sec for 1531 iterations mean: 51.435185 usec stddev: 8.598650 usec relative stddev: 16.72% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: gn_x86-64_with_irt_nnacl_newlib_static= {51.435185, 8.598650} us Measuring clock cycles: first runs (cycles): 75594 143788 111482 673751 161717 87830 104436 54667 105107 57485 ... slowest runs (cycles): ... 111482 117809 119320 125759 126750 143788 149228 161717 233182 673751 min: 50325 cycles q1: 54386 cycles median: 60742 cycles q3: 75594 cycles max: 673751 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {60742, 21208} count Apparent clock speed: 1181 MHz [ OK ] nacl_newlib.run_performance_test_irt (6190 ms) scons: done building targets. ********************************************************************** COMMAND EXECUTION REPORT ********************************************************************** 3 command_tester.py 1 doSizeCheck 3 gcc 1 set ********************************************************************** ENVIRONMENT USAGE REPORT ********************************************************************** 4 nacl_irt_test-x86-64 4 opt-linux-x86-64 Command return code: 0