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

Running command: /usr/bin/python scons.py --verbose -k -j1 --mode=nacl,opt-host platform=x86-64 nacl_clang=1 skip_trusted_tests=1 large_tests scons: Reading SConscript files ... ====================================================================== SCONS ARGS:['scons.py', '--verbose', '-k', '-j1', '--mode=nacl,opt-host', 'platform=x86-64', 'nacl_clang=1', 'skip_trusted_tests=1', 'large_tests'] ====================================================================== ====================================================================== Python Info: ====================================================================== linux2 2.7.6 (default, Nov 23 2017, 15:49:48) [GCC 4.8.4] ====================================================================== ENV: ====================================================================== PATH /b/s/w/ir/cache/vpython/b574a6/bin:/b/s/w/ir/cipd_bin_packages:/b/s/w/ir/cipd_bin_packages/bin:/b/s/cipd_cache/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin:/b/s/w/ir/kitchen-checkout/depot_tools ====================================================================== OS: ====================================================================== Linux luci-flex-try-trusty-15-zt4c 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: 13392228 kB MemAvailable: 29083060 kB ====================================================================== LOAD: ====================================================================== 1.98 2.74 3.11 1/423 20669 ====================================================================== UPTIME: ====================================================================== 46539.32 333400.52 ====================================================================== The following environments have been configured ====================================================================== NACL_BUILD_FAMILY: UNTRUSTED BUILD_TYPE: nacl TARGET_ROOT: /b/s/w/ir/k/native_client/scons-out/nacl-x86-64 OBJ_ROOT: /b/s/w/ir/k/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/k/native_client/toolchain/linux_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /b/s/w/ir/k/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/k/native_client/scons-out/nacl-x86-64/include -I/b/s/w/ir/k -c -o UNKNOWN COMPILER NACL_BUILD_FAMILY: TRUSTED BUILD_TYPE: opt-linux TARGET_ROOT: /b/s/w/ir/k/native_client/scons-out/opt-linux-x86-64 OBJ_ROOT: /b/s/w/ir/k/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/k -c -o set BOTO_CONFIG='/home/chrome-bot/.boto' BUILDBOT_BUILDERNAME='nacl-toolchain-linux-pnacl-x86_64' BUILDBOT_BUILDNUMBER='4230' BUILDBOT_GOT_REVISION='1ed9878b8eb68c1e0d0268754dafcf83e747fa8f' BUILDBOT_MASTERNAME='tryserver.nacl' BUILDBOT_REVISION='' BUILDBOT_SLAVE_TYPE='Trybot' BUILDBUCKET_EXPERIMENTAL='FALSE' CIPD_CACHE_DIR='/b/s/cipd_cache/cache' CIPD_PROTOCOL='v2' CYGWIN='nodosfilewarning' DEVSHELL_CLIENT_PORT='34733' 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' HOST_ARCH='x86_64' 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/8918740835268554704' LOGDOG_STREAM_PROJECT='nacl' LOGDOG_STREAM_SERVER_PATH='unix:/b/s/w/ir/tmp/ld.sock' LOGNAME='chrome-bot' LUCI_CONTEXT='/b/s/w/it3wumSn/luci_context.688821543' MAC_CHROMIUM_TMPDIR='/b/s/w/ir/tmp/t' MAIL='/var/mail/chrome-bot' NATIVE_PYTHON='python' NOCONTROL_GOMA='1' NO_GCE_CHECK='False' OLDPWD='/b/s/w/ir/k/native_client' OPTIND='1' PATH='/b/s/w/ir/cache/vpython/b574a6/bin:/b/s/w/ir/cipd_bin_packages:/b/s/w/ir/cipd_bin_packages/bin:/b/s/cipd_cache/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin:/b/s/w/ir/kitchen-checkout/depot_tools' PNACL_BUILDBOT='true' PNACL_TOOLCHAIN_DIR='linux_x86/pnacl_newlib' PNACL_VERBOSE='true' PPID='20642' PS1='$ ' PS2='> ' PS4='+ ' PWD='/b/s/w/ir/k/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='2' SUDO_COMMAND='/usr/bin/python /b/s/swarming_bot.zip start_bot' SUDO_GID='0' SUDO_UID='0' SUDO_USER='root' SWARMING_BOT_ID='luci-flex-try-trusty-15-zt4c' SWARMING_EXTERNAL_BOT_SETUP='1' SWARMING_HEADLESS='1' SWARMING_SERVER='https://chromium-swarm.appspot.com' SWARMING_TASK_ID='43a4477c69bc5511' TEMP='/b/s/w/ir/tmp/t/nacl_tmp/nacl-toolchain-linux-pnacl-x86_64' TEMPDIR='/b/s/w/ir/tmp/t' TERM='linux' TMP='/b/s/w/ir/tmp/t/nacl_tmp/nacl-toolchain-linux-pnacl-x86_64' TMPDIR='/b/s/w/ir/tmp/t/nacl_tmp/nacl-toolchain-linux-pnacl-x86_64' USER='chrome-bot' USERNAME='chrome-bot' VIRTUAL_ENV='/b/s/w/ir/cache/vpython/b574a6' VPYTHON_VIRTUALENV_ROOT='/b/s/w/ir/cache/vpython' _='buildbot/buildbot_pnacl.py' 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 BUILD_TYPE: nacl_irt TARGET_ROOT: /b/s/w/ir/k/native_client/scons-out/nacl_irt-x86-64 OBJ_ROOT: /b/s/w/ir/k/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/k/native_client/toolchain/linux_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /b/s/w/ir/k/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/k/native_client/scons-out/nacl_irt-x86-64/include -I/b/s/w/ir/k -I/b/s/w/ir/k/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 *** SKIPPING opt-linux-64 : run_package_version_tests *** SKIPPING opt-linux-64 : run_pynacl_tests *** SKIPPING opt-linux-64 : run_gio_test *** SKIPPING opt-linux-64 : run_gprintf_test *** SKIPPING opt-linux-64 : run_sigpipe_test *** SKIPPING opt-linux-64 : run_platform_tests *** SKIPPING opt-linux-64 : run_nacl_semaphore_test *** SKIPPING opt-linux-64 : run_nacl_host_dir_test *** SKIPPING opt-linux-64 : run_nacl_clock_test *** SKIPPING opt-linux-64 : run_nacl_clock_cputime_test *** SKIPPING opt-linux-64 : run_nacl_sync_lock_lock_test *** SKIPPING opt-linux-64 : run_nacl_sync_lock_trylock_test *** SKIPPING opt-linux-64 : run_nacl_sync_trylock_lock_test *** SKIPPING opt-linux-64 : run_nacl_sync_trylock_trylock_test *** SKIPPING opt-linux-64 : run_nacl_host_desc_mmap_test *** SKIPPING opt-linux-64 : run_nacl_host_desc_pread_pwrite_test *** SKIPPING opt-linux-64 : run_nacl_host_desc_big_file_test *** SKIPPING opt-linux-64 : run_cpu_x86_test *** SKIPPING opt-linux-64 : run_gdb_rsp_tests *** SKIPPING opt-linux-64 : run_transport_ipc_test *** SKIPPING opt-linux-64 : run_nacl_desc_quota_test *** SKIPPING opt-linux-64 : run_metadata_test *** SKIPPING opt-linux-64 : run_nacl_desc_io_alloc_ctor_test *** SKIPPING opt-linux-64 : run_nacl_fi_test *** SKIPPING opt-linux-64 : run_nacl_interval_list_test *** SKIPPING opt-linux-64 : run_nacl_interval_tree_test *** SKIPPING opt-linux-64 : run_nacl_interval_list_random_test *** SKIPPING opt-linux-64 : run_nacl_interval_tree_random_test *** SKIPPING opt-linux-64 : run_nacl_interval_multi_test *** SKIPPING opt-linux-64 : run_nacl_interval_multi_reg_test *** SKIPPING opt-linux-64 : run_nacl_base_test *** SKIPPING opt-linux-64 : run_nacl_min_max_test *** SKIPPING opt-linux-64 : run_nacl_min_max_cpp_test *** SKIPPING opt-linux-64 : run_nacl_perf_counter_test *** SKIPPING opt-linux-64 : run_platform_qual_test *** SKIPPING opt-linux-64 : run_cpuwhitelist_test *** SKIPPING opt-linux-64 : run_service_runtime_tests *** SKIPPING opt-linux-64 : run_format_string_test *** SKIPPING opt-linux-64 : run_trusted_mmap_test *** SKIPPING opt-linux-64 : run_nacl_bootstrap_prereservation_test *** BROKEN run_nacl_sync_cond_test *** SKIPPING opt-linux-64 : run_env_cleanser_test *** SKIPPING opt-linux-64 : run_nacl_resource_test *** SKIPPING opt-linux-64 : run_nacl_signal_test *** SKIPPING opt-linux-64 : run_signal_frame_test *** SKIPPING opt-linux-64 : run_check_test *** SKIPPING opt-linux-64 : run_check_test_death *** SKIPPING opt-linux-64 : run_dcheck_test_death *** SKIPPING opt-linux-64 : run_check_test_always_death *** SKIPPING opt-linux-64 : run_check_test_always_death_abort *** SKIPPING opt-linux-64 : run_dcheck_test_never_death *** SKIPPING opt-linux-64 : run_dcheck_test_always_death *** SKIPPING opt-linux-64 : run_nacl_tls_unittest *** SKIPPING opt-linux-64 : run_sel_ldr_exe_not_found_test *** SKIPPING opt-linux-64 : run_fuzz_nullptr_test *** SKIPPING opt-linux-64 : run_unaligned_data_test *** SKIPPING opt-linux-64 : run_unaligned_data_irt_test *** SKIPPING opt-linux-64 : run_hello_x32_test *** SKIPPING opt-linux-64 : run_service_runtime_hello_world_test *** SKIPPING opt-linux-64 : run_old_abi_death_test *** SKIPPING opt-linux-64 : run_integer_overflow_while_madvising_death_test *** SKIPPING opt-linux-64 : run_negative_hole_death_test *** SKIPPING opt-linux-64 : run_rodata_data_overlap_death_test *** SKIPPING opt-linux-64 : run_data_not_last_death_test SKIPPING test text_overlaps_rodata SKIPPING test text_overlaps_data *** SKIPPING opt-linux-64 : run_text_too_big_death_test *** SKIPPING opt-linux-64 : run_sel_ldr_thread_death_test *** SKIPPING opt-linux-64 : run_nacl_error_gio_test *** SKIPPING opt-linux-64 : run_nacl_error_log_test *** SKIPPING opt-linux-64 : run_dyn_array_test *** SKIPPING opt-linux-64 : run_validation_cache_test *** SKIPPING opt-linux-64 : run_validator_debug_stub_test *** SKIPPING opt-linux-64 : run_disable_nontemporals_test *** SKIPPING opt-linux-64 : run_validation_rewrite_test *** SKIPPING opt-linux-64 : run_gen_dfa_test *** SKIPPING opt-linux-64 : run_trie_test *** SKIPPING opt-linux-64 : run_proof_tools_test *** SKIPPING opt-linux-64 : run_validator_py_test *** SKIPPING opt-linux-64 : run_rdfa_targeted_tests_32 *** SKIPPING opt-linux-64 : run_dis_section_test_32 *** SKIPPING opt-linux-64 : run_spec_val_test_32 *** SKIPPING opt-linux-64 : run_rdfa_targeted_tests_64 *** SKIPPING opt-linux-64 : run_dis_section_test_64 *** SKIPPING opt-linux-64 : run_spec_val_test_64 *** SKIPPING opt-linux-64 : run_nacl_file_lock_all_test *** SKIPPING opt-linux-64 : run_nacl_file_lock_any_test *** SKIPPING opt-linux-64 : run_nacl_file_lock_t3f2_test *** SKIPPING opt-linux-64 : run_nacl_file_lock_t3f3_test *** SKIPPING opt-linux-64 : run_nacl_file_lock_all_real_test *** SKIPPING opt-linux-64 : run_nacl_file_lock_any_real_test *** SKIPPING opt-linux-64 : run_nacl_file_lock_t3f2_real_test *** SKIPPING opt-linux-64 : run_nacl_file_lock_t3f3_real_test *** SKIPPING opt-linux-64 : run_trusted_performance_test *** SKIPPING opt-linux-64 : run_python_version_test *** SKIPPING opt-linux-64 : run_seccomp_open_errno_test *** SKIPPING opt-linux-64 : run_seccomp_check_arch_test *** SKIPPING opt-linux-64 : run_tool_tests *** SKIPPING opt-linux-64 : run_nacl_close_test *** SKIPPING opt-linux-64 : run_double_nacl_close_test *** SKIPPING opt-linux-64 : run_nacl_create_memory_object_test *** SKIPPING opt-linux-64 : run_nacl_thread_create_joinable_test *** SKIPPING opt-linux-64 : run_atomic_ops_test *** SKIPPING opt-linux-64 : run_bits_test *** SKIPPING opt-linux-64 : run_kernel_version_test *** SKIPPING opt-linux-64 : run_nacl_memory_test *** SKIPPING opt-linux-64 : run_toolchain_python_tests *** SKIPPING nacl_irt-64 : run_irt_thread_test There are 13 broken tests. ====================================================================== B U I L D - O U T P U T: ====================================================================== scons: done reading SConscript files. scons: Building targets ... /usr/bin/python /b/s/w/ir/k/native_client/tools/command_tester.py --name nacl_newlib.run_signal_handler_single_step_test --time_warning 2 --time_error 20 --perf_env_description x86-64_nnacl_newlib_static --subarch 64 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/k/native_client/scons-out/nacl-x86-64/test_results/signal_handler_single_step.out /b/s/w/ir/k/native_client/scons-out/nacl-x86-64/obj/tests/signal_handler_single_step/step_test_host /b/s/w/ir/k/native_client/scons-out/nacl-x86-64/obj/tests/signal_handler_single_step/step_test_guest.nexe x86-64_nnacl_newlib_static [ RUN ] nacl_newlib.run_signal_handler_single_step_test /b/s/w/ir/k/native_client/scons-out/nacl-x86-64/obj/tests/signal_handler_single_step/step_test_host /b/s/w/ir/k/native_client/scons-out/nacl-x86-64/obj/tests/signal_handler_single_step/step_test_guest.nexe x86-64_nnacl_newlib_static [20684,224638848:08:18:04.434818] Native Client module will be loaded at base address 0x00006f7100000000 Switching to untrusted: since previous switch: 137 instructions, 464 instruction bytes, 20 jumps Switching to trusted: since previous switch: 11 instructions, 48 instruction bytes, 2 jumps Switching to untrusted: since previous switch: 173 instructions, 584 instruction bytes, 16 jumps Switching to trusted: since previous switch: 9 instructions, 32 instruction bytes, 3 jumps Switching to untrusted: since previous switch: 173 instructions, 584 instruction bytes, 16 jumps Switching to trusted: since previous switch: 9 instructions, 32 instruction bytes, 3 jumps Switching to untrusted: since previous switch: 173 instructions, 584 instruction bytes, 16 jumps Switching to trusted: since previous switch: 9 instructions, 32 instruction bytes, 3 jumps Switching to untrusted: since previous switch: 173 instructions, 584 instruction bytes, 16 jumps RESULT InstructionsPerSyscall: x86-64_nnacl_newlib_static= 173 count RESULT InstructionBytesPerSyscall: x86-64_nnacl_newlib_static= 584 count RESULT JumpsPerSyscall: x86-64_nnacl_newlib_static= 16 count Switching to trusted: since previous switch: 9 instructions, 32 instruction bytes, 3 jumps [ OK ] nacl_newlib.run_signal_handler_single_step_test (0 ms) /usr/bin/python tools/command_tester.py --name nacl_newlib.run_benchmark_test --time_warning 10 --time_error 100 --perf_env_description x86-64_nnacl_newlib_static --using_nacl_signal_handler True --subarch 64 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/k/native_client/scons-out/nacl-x86-64/test_results/benchmark_test.out scons-out/opt-linux-x86-64/staging/nacl_helper_bootstrap scons-out/opt-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -- scons-out/nacl-x86-64/obj/tests/benchmark/benchmark_test.nexe x86-64_nnacl_newlib_static [ RUN ] nacl_newlib.run_benchmark_test scons-out/opt-linux-x86-64/staging/nacl_helper_bootstrap scons-out/opt-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -- scons-out/nacl-x86-64/obj/tests/benchmark/benchmark_test.nexe x86-64_nnacl_newlib_static [20689,2732148608:08:18:04.703252] Native Client module will be loaded at base address 0x0000447100000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_nnacl_newlib_static= {0.103230, 0.008720} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test (1600 ms) /usr/bin/python tools/command_tester.py --name nacl_newlib.run_clock_cputime_test --time_warning 60 --time_error 600 --perf_env_description x86-64_nnacl_newlib_static --using_nacl_signal_handler True --subarch 64 --arch x86 --output_stamp /b/s/w/ir/k/native_client/scons-out/nacl-x86-64/test_results/clock_cputime_test.out scons-out/opt-linux-x86-64/staging/nacl_helper_bootstrap scons-out/opt-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -- scons-out/nacl-x86-64/obj/tests/clock/clock_test.nexe -c [ RUN ] nacl_newlib.run_clock_cputime_test scons-out/opt-linux-x86-64/staging/nacl_helper_bootstrap scons-out/opt-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -- scons-out/nacl-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 (74610 ms) doSizeCheck(["scons-out/nacl-x86-64/obj/tests/hello_world/hello_world_size.out"], ["scons-out/nacl-x86-64/obj/tests/hello_world/hello_world.nexe"]) RESULT hello_world_size.out: x86-64_nnacl_newlib_static= 522.842 KB RESULT hello_world_size.out: ZIPPED_x86-64_nnacl_newlib_static= 187.712 KB /usr/bin/python tools/command_tester.py --name nacl_newlib.run_performance_test --time_warning 10 --time_error 100 --perf_env_description x86-64_nnacl_newlib_static --using_nacl_signal_handler True --subarch 64 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/k/native_client/scons-out/nacl-x86-64/test_results/performance_test.out scons-out/opt-linux-x86-64/staging/nacl_helper_bootstrap scons-out/opt-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -e -- scons-out/nacl-x86-64/obj/tests/performance/performance_test.nexe x86-64_nnacl_newlib_static [ RUN ] nacl_newlib.run_performance_test scons-out/opt-linux-x86-64/staging/nacl_helper_bootstrap scons-out/opt-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -e -- scons-out/nacl-x86-64/obj/tests/performance/performance_test.nexe x86-64_nnacl_newlib_static [20719,3583240064:08:18:19.930570] Native Client module will be loaded at base address 0x00006a3c00000000 TestNull: Measuring real time: 0.007 usec (7.28e-09 sec) per iteration: 7.28e-07 sec for 100 iterations 0.003 usec (2.516e-09 sec) per iteration: 2.516e-06 sec for 1000 iterations 0.002 usec (2.362e-09 sec) per iteration: 2.362e-05 sec for 10000 iterations 0.002 usec (1.88936e-09 sec) per iteration: 0.0799899 sec for 42337002 iterations 0.002 usec (1.8863e-09 sec) per iteration: 0.0798604 sec for 42337002 iterations 0.002 usec (1.88798e-09 sec) per iteration: 0.0799316 sec for 42337002 iterations 0.002 usec (1.88816e-09 sec) per iteration: 0.0799389 sec for 42337002 iterations 0.002 usec (1.88913e-09 sec) per iteration: 0.07998 sec for 42337002 iterations mean: 0.001888 usec stddev: 0.000001 usec relative stddev: 0.06% RESULT TestNull: x86-64_nnacl_newlib_static= {0.001888, 0.000001} us Measuring clock cycles: first runs (cycles): 108 27 25 24 26 28 22 24 24 20 ... slowest runs (cycles): ... 36 37 37 37 38 38 39 42 44 108 min: 20 cycles q1: 26 cycles median: 27 cycles q3: 29 cycles max: 108 cycles RESULT TestNull_CycleCount: x86-64_nnacl_newlib_static= {27, 3} count Apparent clock speed: 14299 MHz TestNaClSyscall: Measuring real time: 0.103 usec (1.0265e-07 sec) per iteration: 1.0265e-05 sec for 100 iterations 0.123 usec (1.23057e-07 sec) per iteration: 0.11988 sec for 974184 iterations 0.118 usec (1.18065e-07 sec) per iteration: 0.115017 sec for 974184 iterations 0.120 usec (1.20365e-07 sec) per iteration: 0.117258 sec for 974184 iterations 0.117 usec (1.17321e-07 sec) per iteration: 0.114292 sec for 974184 iterations 0.122 usec (1.21802e-07 sec) per iteration: 0.118658 sec for 974184 iterations mean: 0.120122 usec stddev: 0.002171 usec relative stddev: 1.81% RESULT TestNaClSyscall: x86-64_nnacl_newlib_static= {0.120122, 0.002171} us Measuring clock cycles: first runs (cycles): 361 198 176 178 178 189 192 187 173 179 ... slowest runs (cycles): ... 194 198 200 214 218 224 226 229 239 361 min: 173 cycles q1: 178 cycles median: 181 cycles q3: 187 cycles max: 361 cycles RESULT TestNaClSyscall_CycleCount: x86-64_nnacl_newlib_static= {181, 9} count Apparent clock speed: 1507 MHz TestSetjmpLongjmp: Measuring real time: 0.022 usec (2.19e-08 sec) per iteration: 2.19e-06 sec for 100 iterations 0.014 usec (1.4456e-08 sec) per iteration: 1.4456e-05 sec for 1000 iterations 0.013 usec (1.28434e-08 sec) per iteration: 0.0888448 sec for 6917542 iterations 0.013 usec (1.2784e-08 sec) per iteration: 0.088434 sec for 6917542 iterations 0.013 usec (1.28012e-08 sec) per iteration: 0.0885531 sec for 6917542 iterations 0.013 usec (1.28019e-08 sec) per iteration: 0.0885576 sec for 6917542 iterations 0.013 usec (1.28772e-08 sec) per iteration: 0.0890783 sec for 6917542 iterations mean: 0.012822 usec stddev: 0.000034 usec relative stddev: 0.27% RESULT TestSetjmpLongjmp: x86-64_nnacl_newlib_static= {0.012822, 0.000034} us Measuring clock cycles: first runs (cycles): 231 63 62 44 46 44 46 48 44 44 ... slowest runs (cycles): ... 46 47 47 48 50 50 51 62 63 231 min: 30 cycles q1: 33 cycles median: 37 cycles q3: 42 cycles max: 231 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_nnacl_newlib_static= {37, 9} count Apparent clock speed: 2886 MHz TestClockGetTime: Measuring real time: 0.182 usec (1.8222e-07 sec) per iteration: 1.8222e-05 sec for 100 iterations 0.152 usec (1.52272e-07 sec) per iteration: 0.0835651 sec for 548787 iterations 0.150 usec (1.50253e-07 sec) per iteration: 0.0824566 sec for 548787 iterations 0.150 usec (1.50287e-07 sec) per iteration: 0.0824758 sec for 548787 iterations 0.151 usec (1.50712e-07 sec) per iteration: 0.0827089 sec for 548787 iterations 0.149 usec (1.49029e-07 sec) per iteration: 0.0817851 sec for 548787 iterations mean: 0.150511 usec stddev: 0.001045 usec relative stddev: 0.69% RESULT TestClockGetTime: x86-64_nnacl_newlib_static= {0.150511, 0.001045} us Measuring clock cycles: first runs (cycles): 677 427 372 391 370 360 354 363 352 361 ... slowest runs (cycles): ... 453 453 453 466 469 482 488 546 677 15346 min: 344 cycles q1: 356 cycles median: 368 cycles q3: 398 cycles max: 15346 cycles RESULT TestClockGetTime_CycleCount: x86-64_nnacl_newlib_static= {368, 42} count Apparent clock speed: 2445 MHz TestTlsVariable: Measuring real time: 0.015 usec (1.478e-08 sec) per iteration: 1.478e-06 sec for 100 iterations 0.009 usec (9.27e-09 sec) per iteration: 9.27e-06 sec for 1000 iterations 0.009 usec (8.9291e-09 sec) per iteration: 8.9291e-05 sec for 10000 iterations 0.006 usec (5.67337e-09 sec) per iteration: 0.0635379 sec for 11199336 iterations 0.006 usec (5.66388e-09 sec) per iteration: 0.0634317 sec for 11199336 iterations 0.006 usec (5.67366e-09 sec) per iteration: 0.0635412 sec for 11199336 iterations 0.006 usec (5.66034e-09 sec) per iteration: 0.063392 sec for 11199336 iterations 0.006 usec (5.65739e-09 sec) per iteration: 0.063359 sec for 11199336 iterations mean: 0.005666 usec stddev: 0.000007 usec relative stddev: 0.12% RESULT TestTlsVariable: x86-64_nnacl_newlib_static= {0.005666, 0.000007} us Measuring clock cycles: first runs (cycles): 185 96 36 33 35 35 33 30 28 28 ... slowest runs (cycles): ... 44 44 49 49 53 57 57 61 96 185 min: 24 cycles q1: 29 cycles median: 33 cycles q3: 37 cycles max: 185 cycles RESULT TestTlsVariable_CycleCount: x86-64_nnacl_newlib_static= {33, 8} count Apparent clock speed: 5824 MHz TestMmapAnonymous: Measuring real time: 2.365 usec (2.36471e-06 sec) per iteration: 0.000236471 sec for 100 iterations 1.791 usec (1.79135e-06 sec) per iteration: 0.0757528 sec for 42288 iterations 1.792 usec (1.79181e-06 sec) per iteration: 0.0757719 sec for 42288 iterations 1.791 usec (1.79131e-06 sec) per iteration: 0.0757509 sec for 42288 iterations 1.794 usec (1.79364e-06 sec) per iteration: 0.0758496 sec for 42288 iterations 1.793 usec (1.79337e-06 sec) per iteration: 0.0758379 sec for 42288 iterations mean: 1.792296 usec stddev: 0.001007 usec relative stddev: 0.06% RESULT TestMmapAnonymous: x86-64_nnacl_newlib_static= {1.792296, 0.001007} us Measuring clock cycles: first runs (cycles): 8258 5625 5513 5270 5361 5497 5975 6369 5889 5442 ... slowest runs (cycles): ... 6557 6606 6686 6697 7121 7266 7354 7437 7448 8258 min: 3824 cycles q1: 3876 cycles median: 5442 cycles q3: 5959 cycles max: 8258 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_nnacl_newlib_static= {5442, 2083} count Apparent clock speed: 3036 MHz TestAtomicIncrement: Measuring real time: 0.015 usec (1.456e-08 sec) per iteration: 1.456e-06 sec for 100 iterations 0.010 usec (1.0256e-08 sec) per iteration: 1.0256e-05 sec for 1000 iterations 0.010 usec (9.53373e-09 sec) per iteration: 0.0929576 sec for 9750390 iterations 0.009 usec (9.42094e-09 sec) per iteration: 0.0918579 sec for 9750390 iterations 0.009 usec (9.42414e-09 sec) per iteration: 0.091889 sec for 9750390 iterations 0.009 usec (9.43512e-09 sec) per iteration: 0.0919961 sec for 9750390 iterations 0.009 usec (9.41799e-09 sec) per iteration: 0.0918291 sec for 9750390 iterations mean: 0.009446 usec stddev: 0.000044 usec relative stddev: 0.47% RESULT TestAtomicIncrement: x86-64_nnacl_newlib_static= {0.009446, 0.000044} us Measuring clock cycles: first runs (cycles): 106 53 52 51 53 53 50 51 50 53 ... slowest runs (cycles): ... 53 53 53 53 53 53 53 53 53 106 min: 49 cycles q1: 50 cycles median: 51 cycles q3: 53 cycles max: 106 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_nnacl_newlib_static= {51, 3} count Apparent clock speed: 5399 MHz TestUncontendedMutexLock: Measuring real time: 0.027 usec (2.693e-08 sec) per iteration: 2.693e-06 sec for 100 iterations 0.024 usec (2.4274e-08 sec) per iteration: 2.4274e-05 sec for 1000 iterations 0.024 usec (2.44848e-08 sec) per iteration: 0.100868 sec for 4119634 iterations 0.024 usec (2.44877e-08 sec) per iteration: 0.10088 sec for 4119634 iterations 0.024 usec (2.44731e-08 sec) per iteration: 0.10082 sec for 4119634 iterations 0.024 usec (2.44684e-08 sec) per iteration: 0.100801 sec for 4119634 iterations 0.025 usec (2.48764e-08 sec) per iteration: 0.102481 sec for 4119634 iterations mean: 0.024558 usec stddev: 0.000159 usec relative stddev: 0.65% RESULT TestUncontendedMutexLock: x86-64_nnacl_newlib_static= {0.024558, 0.000159} us Measuring clock cycles: first runs (cycles): 233 108 84 81 81 82 81 81 82 82 ... slowest runs (cycles): ... 84 84 84 84 84 84 84 84 108 233 min: 79 cycles q1: 81 cycles median: 82 cycles q3: 82 cycles max: 233 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_nnacl_newlib_static= {82, 1} count Apparent clock speed: 3339 MHz TestCondvarSignalNoOp: Measuring real time: 0.245 usec (2.4489e-07 sec) per iteration: 2.4489e-05 sec for 100 iterations 0.214 usec (2.13687e-07 sec) per iteration: 0.0872583 sec for 408346 iterations 0.212 usec (2.1197e-07 sec) per iteration: 0.0865569 sec for 408346 iterations 0.213 usec (2.12876e-07 sec) per iteration: 0.086927 sec for 408346 iterations 0.213 usec (2.12598e-07 sec) per iteration: 0.0868135 sec for 408346 iterations 0.212 usec (2.12408e-07 sec) per iteration: 0.0867358 sec for 408346 iterations mean: 0.212708 usec stddev: 0.000572 usec relative stddev: 0.27% RESULT TestCondvarSignalNoOp: x86-64_nnacl_newlib_static= {0.212708, 0.000572} us Measuring clock cycles: first runs (cycles): 770 502 473 543 475 478 473 473 475 475 ... slowest runs (cycles): ... 478 478 478 478 478 478 483 502 543 770 min: 470 cycles q1: 473 cycles median: 475 cycles q3: 476 cycles max: 770 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_nnacl_newlib_static= {475, 3} count Apparent clock speed: 2233 MHz TestThreadCreateAndJoin: Measuring real time: 37.501 usec (3.75007e-05 sec) per iteration: 0.00375007 sec for 100 iterations 28.049 usec (2.80489e-05 sec) per iteration: 0.0747783 sec for 2666 iterations 26.568 usec (2.65679e-05 sec) per iteration: 0.07083 sec for 2666 iterations 25.788 usec (2.57876e-05 sec) per iteration: 0.0687497 sec for 2666 iterations 23.907 usec (2.39067e-05 sec) per iteration: 0.0637351 sec for 2666 iterations 26.628 usec (2.66285e-05 sec) per iteration: 0.0709916 sec for 2666 iterations mean: 26.187902 usec stddev: 1.353966 usec relative stddev: 5.17% RESULT TestThreadCreateAndJoin: x86-64_nnacl_newlib_static= {26.187902, 1.353966} us Measuring clock cycles: first runs (cycles): 71648 60398 112686 107648 76250 56003 101260 100561 115433 55163 ... slowest runs (cycles): ... 100561 101186 101260 107648 108944 112686 115433 116837 117146 118250 min: 39297 cycles q1: 44016 cycles median: 46494 cycles q3: 55827 cycles max: 118250 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_nnacl_newlib_static= {46494, 11811} count Apparent clock speed: 1775 MHz TestThreadWakeup: Measuring real time: 12.565 usec (1.25647e-05 sec) per iteration: 0.00125647 sec for 100 iterations 13.337 usec (1.33374e-05 sec) per iteration: 0.106139 sec for 7958 iterations 13.786 usec (1.37861e-05 sec) per iteration: 0.10971 sec for 7958 iterations 14.033 usec (1.4033e-05 sec) per iteration: 0.111675 sec for 7958 iterations 13.986 usec (1.39862e-05 sec) per iteration: 0.111302 sec for 7958 iterations 14.130 usec (1.41296e-05 sec) per iteration: 0.112443 sec for 7958 iterations mean: 13.854490 usec stddev: 0.281759 usec relative stddev: 2.03% RESULT TestThreadWakeup: x86-64_nnacl_newlib_static= {13.854490, 0.281759} us Measuring clock cycles: first runs (cycles): 34372 32671 34240 32362 33719 33974 31900 28224 31810 30846 ... slowest runs (cycles): ... 39276 39337 39581 39709 39977 40200 40239 40664 52343 58319 min: 28224 cycles q1: 31757 cycles median: 32162 cycles q3: 34007 cycles max: 58319 cycles RESULT TestThreadWakeup_CycleCount: x86-64_nnacl_newlib_static= {32162, 2250} count Apparent clock speed: 2321 MHz TestCatchingFault: Measuring real time: 2.270 usec (2.27008e-06 sec) per iteration: 0.000227008 sec for 100 iterations 1.729 usec (1.72873e-06 sec) per iteration: 0.0761521 sec for 44051 iterations 1.722 usec (1.7225e-06 sec) per iteration: 0.0758778 sec for 44051 iterations 1.721 usec (1.72076e-06 sec) per iteration: 0.0758013 sec for 44051 iterations 1.722 usec (1.72171e-06 sec) per iteration: 0.0758431 sec for 44051 iterations 1.722 usec (1.72225e-06 sec) per iteration: 0.0758669 sec for 44051 iterations mean: 1.723190 usec stddev: 0.002831 usec relative stddev: 0.16% RESULT TestCatchingFault: x86-64_nnacl_newlib_static= {1.723190, 0.002831} us Measuring clock cycles: first runs (cycles): 4569 3777 3757 3769 3740 3716 3733 3731 3742 3731 ... slowest runs (cycles): ... 3750 3751 3753 3755 3755 3755 3757 3769 3777 4569 min: 3698 cycles q1: 3731 cycles median: 3736 cycles q3: 3742 cycles max: 4569 cycles RESULT TestCatchingFault_CycleCount: x86-64_nnacl_newlib_static= {3736, 11} count Apparent clock speed: 2168 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 42.073 usec (4.20734e-05 sec) per iteration: 0.00420734 sec for 100 iterations 25.843 usec (2.58433e-05 sec) per iteration: 0.0614036 sec for 2376 iterations 28.836 usec (2.88357e-05 sec) per iteration: 0.0685136 sec for 2376 iterations 28.568 usec (2.85676e-05 sec) per iteration: 0.0678765 sec for 2376 iterations 25.958 usec (2.59582e-05 sec) per iteration: 0.0616767 sec for 2376 iterations 22.506 usec (2.25062e-05 sec) per iteration: 0.0534747 sec for 2376 iterations mean: 26.342178 usec stddev: 2.292651 usec relative stddev: 8.70% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_nnacl_newlib_static= {26.342178, 2.292651} us Measuring clock cycles: first runs (cycles): 195698 117172 54052 44766 103568 215559 61619 48097 41353 42757 ... slowest runs (cycles): ... 100888 103251 103568 105430 108399 117172 121113 152014 195698 215559 min: 36407 cycles q1: 41099 cycles median: 42757 cycles q3: 49982 cycles max: 215559 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_nnacl_newlib_static= {42757, 8883} count Apparent clock speed: 1623 MHz [ OK ] nacl_newlib.run_performance_test (5670 ms) scons: done building targets. ********************************************************************** COMMAND EXECUTION REPORT ********************************************************************** 4 command_tester.py 1 doSizeCheck 3 gcc 1 set ********************************************************************** ENVIRONMENT USAGE REPORT ********************************************************************** 5 nacl-x86-64 4 opt-linux-x86-64 Command return code: 0