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 13 2018, 12:45:42) [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 gce-trusty-16-145e5bab-us-west1-c-d756 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 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: 61847552 kB MemFree: 46700996 kB MemAvailable: 59967732 kB ====================================================================== LOAD: ====================================================================== 1.65 2.61 2.90 1/562 27804 ====================================================================== UPTIME: ====================================================================== 9263.11 120707.84 ====================================================================== 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='linux-pnacl-x86_64' BUILDBOT_BUILDNUMBER='7033' BUILDBOT_GOT_REVISION='067afa275dcb4b3c6eca979ea5e3fe75073c4080' BUILDBOT_MASTERNAME='client.nacl.toolchain' BUILDBOT_REVISION='067afa275dcb4b3c6eca979ea5e3fe75073c4080' BUILDBOT_SLAVE_TYPE='BuilderTester' BUILDBUCKET_EXPERIMENTAL='FALSE' CIPD_CACHE_DIR='/b/s/cipd_cache/cache' CIPD_PROTOCOL='v2' CYGWIN='nodosfilewarning' DEVSHELL_CLIENT_PORT='41073' 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/8916917450172911808' LOGDOG_STREAM_PROJECT='nacl' LOGDOG_STREAM_SERVER_PATH='unix:/b/s/w/ir/tmp/ld.sock' LOGNAME='chrome-bot' LUCI_CONTEXT='/b/s/w/itauT5hi/luci_context.829147174' 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='27777' 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='gce-trusty-16-145e5bab-us-west1-c-d756' SWARMING_EXTERNAL_BOT_SETUP='1' SWARMING_HEADLESS='1' SWARMING_SERVER='https://chromium-swarm.appspot.com' SWARMING_TASK_ID='440bed322540c511' TEMP='/b/s/w/ir/tmp/t/nacl_tmp/linux-pnacl-x86_64' TEMPDIR='/b/s/w/ir/tmp/t' TERM='linux' TMP='/b/s/w/ir/tmp/t/nacl_tmp/linux-pnacl-x86_64' TMPDIR='/b/s/w/ir/tmp/t/nacl_tmp/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 [27819,1458325376:11:53:57.843054] Native Client module will be loaded at base address 0x0000221d00000000 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 [27824,861669248:11:53:58.110544] Native Client module will be loaded at base address 0x00003bfb00000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_nnacl_newlib_static= {0.068370, 0.001550} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test (1640 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 (67140 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 [27860,2076452736:11:54:12.071238] Native Client module will be loaded at base address 0x0000690200000000 TestNull: Measuring real time: 0.006 usec (5.74e-09 sec) per iteration: 5.74e-07 sec for 100 iterations 0.002 usec (1.985e-09 sec) per iteration: 1.985e-06 sec for 1000 iterations 0.002 usec (1.8157e-09 sec) per iteration: 1.8157e-05 sec for 10000 iterations 0.002 usec (1.81479e-09 sec) per iteration: 0.09995 sec for 55075177 iterations 0.002 usec (1.81646e-09 sec) per iteration: 0.100042 sec for 55075177 iterations 0.002 usec (1.8125e-09 sec) per iteration: 0.0998239 sec for 55075177 iterations 0.002 usec (1.81429e-09 sec) per iteration: 0.0999224 sec for 55075177 iterations 0.002 usec (1.8146e-09 sec) per iteration: 0.0999392 sec for 55075177 iterations mean: 0.001815 usec stddev: 0.000001 usec relative stddev: 0.07% RESULT TestNull: x86-64_nnacl_newlib_static= {0.001815, 0.000001} us Measuring clock cycles: first runs (cycles): 81 22 22 18 19 19 19 19 19 19 ... slowest runs (cycles): ... 22 22 22 22 22 22 22 22 22 81 min: 18 cycles q1: 19 cycles median: 19 cycles q3: 19 cycles max: 81 cycles RESULT TestNull_CycleCount: x86-64_nnacl_newlib_static= {19, 0} count Apparent clock speed: 10471 MHz TestNaClSyscall: Measuring real time: 0.129 usec (1.2852e-07 sec) per iteration: 1.2852e-05 sec for 100 iterations 0.130 usec (1.30184e-07 sec) per iteration: 0.101295 sec for 778089 iterations 0.130 usec (1.30122e-07 sec) per iteration: 0.101247 sec for 778089 iterations 0.130 usec (1.30473e-07 sec) per iteration: 0.10152 sec for 778089 iterations 0.131 usec (1.30596e-07 sec) per iteration: 0.101615 sec for 778089 iterations 0.131 usec (1.30663e-07 sec) per iteration: 0.101668 sec for 778089 iterations mean: 0.130408 usec stddev: 0.000217 usec relative stddev: 0.17% RESULT TestNaClSyscall: x86-64_nnacl_newlib_static= {0.130408, 0.000217} us Measuring clock cycles: first runs (cycles): 323 204 163 170 166 170 166 167 169 170 ... slowest runs (cycles): ... 170 170 170 170 170 170 170 170 204 323 min: 163 cycles q1: 167 cycles median: 169 cycles q3: 170 cycles max: 323 cycles RESULT TestNaClSyscall_CycleCount: x86-64_nnacl_newlib_static= {169, 3} count Apparent clock speed: 1296 MHz TestSetjmpLongjmp: Measuring real time: 0.016 usec (1.596e-08 sec) per iteration: 1.596e-06 sec for 100 iterations 0.019 usec (1.9084e-08 sec) per iteration: 1.9084e-05 sec for 1000 iterations 0.012 usec (1.23935e-08 sec) per iteration: 0.0649418 sec for 5239991 iterations 0.012 usec (1.23448e-08 sec) per iteration: 0.0646867 sec for 5239991 iterations 0.012 usec (1.23456e-08 sec) per iteration: 0.0646907 sec for 5239991 iterations 0.012 usec (1.23359e-08 sec) per iteration: 0.06464 sec for 5239991 iterations 0.012 usec (1.2358e-08 sec) per iteration: 0.0647558 sec for 5239991 iterations mean: 0.012356 usec stddev: 0.000020 usec relative stddev: 0.16% RESULT TestSetjmpLongjmp: x86-64_nnacl_newlib_static= {0.012356, 0.000020} us Measuring clock cycles: first runs (cycles): 179 35 22 25 25 25 25 22 25 25 ... slowest runs (cycles): ... 25 25 26 26 26 26 26 26 35 179 min: 22 cycles q1: 22 cycles median: 25 cycles q3: 25 cycles max: 179 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_nnacl_newlib_static= {25, 3} count Apparent clock speed: 2023 MHz TestClockGetTime: Measuring real time: 0.179 usec (1.7914e-07 sec) per iteration: 1.7914e-05 sec for 100 iterations 0.140 usec (1.3953e-07 sec) per iteration: 0.0778888 sec for 558222 iterations 0.139 usec (1.39139e-07 sec) per iteration: 0.0776704 sec for 558222 iterations 0.139 usec (1.38893e-07 sec) per iteration: 0.0775331 sec for 558222 iterations 0.139 usec (1.38819e-07 sec) per iteration: 0.0774918 sec for 558222 iterations 0.139 usec (1.39085e-07 sec) per iteration: 0.0776402 sec for 558222 iterations mean: 0.139093 usec stddev: 0.000248 usec relative stddev: 0.18% RESULT TestClockGetTime: x86-64_nnacl_newlib_static= {0.139093, 0.000248} us Measuring clock cycles: first runs (cycles): 547 349 318 324 321 317 314 314 314 317 ... slowest runs (cycles): ... 315 315 317 317 318 320 321 324 349 547 min: 308 cycles q1: 314 cycles median: 314 cycles q3: 315 cycles max: 547 cycles RESULT TestClockGetTime_CycleCount: x86-64_nnacl_newlib_static= {314, 1} count Apparent clock speed: 2257 MHz TestTlsVariable: Measuring real time: 0.008 usec (8.37e-09 sec) per iteration: 8.37e-07 sec for 100 iterations 0.006 usec (5.58e-09 sec) per iteration: 5.58e-06 sec for 1000 iterations 0.005 usec (5.387e-09 sec) per iteration: 5.387e-05 sec for 10000 iterations 0.006 usec (5.66451e-09 sec) per iteration: 0.105152 sec for 18563207 iterations 0.006 usec (5.73534e-09 sec) per iteration: 0.106466 sec for 18563207 iterations 0.006 usec (5.61992e-09 sec) per iteration: 0.104324 sec for 18563207 iterations 0.005 usec (5.45351e-09 sec) per iteration: 0.101235 sec for 18563207 iterations 0.006 usec (5.57864e-09 sec) per iteration: 0.103557 sec for 18563207 iterations mean: 0.005610 usec stddev: 0.000094 usec relative stddev: 1.68% RESULT TestTlsVariable: x86-64_nnacl_newlib_static= {0.005610, 0.000094} us Measuring clock cycles: first runs (cycles): 148 63 22 22 19 25 19 22 19 22 ... slowest runs (cycles): ... 22 25 25 25 25 25 25 26 63 148 min: 18 cycles q1: 19 cycles median: 19 cycles q3: 22 cycles max: 148 cycles RESULT TestTlsVariable_CycleCount: x86-64_nnacl_newlib_static= {19, 3} count Apparent clock speed: 3387 MHz TestMmapAnonymous: Measuring real time: 2.483 usec (2.48279e-06 sec) per iteration: 0.000248279 sec for 100 iterations 1.897 usec (1.89728e-06 sec) per iteration: 0.0764169 sec for 40277 iterations 1.956 usec (1.95586e-06 sec) per iteration: 0.078776 sec for 40277 iterations 1.966 usec (1.96606e-06 sec) per iteration: 0.0791871 sec for 40277 iterations 1.949 usec (1.94893e-06 sec) per iteration: 0.0784972 sec for 40277 iterations 1.913 usec (1.91324e-06 sec) per iteration: 0.0770597 sec for 40277 iterations mean: 1.936276 usec stddev: 0.026388 usec relative stddev: 1.36% RESULT TestMmapAnonymous: x86-64_nnacl_newlib_static= {1.936276, 0.026388} us Measuring clock cycles: first runs (cycles): 5921 4209 4149 4130 4111 4146 4120 4139 14532 4149 ... slowest runs (cycles): ... 4161 4161 4161 4162 4162 4168 4177 4209 5921 14532 min: 4111 cycles q1: 4136 cycles median: 4148 cycles q3: 4158 cycles max: 14532 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_nnacl_newlib_static= {4148, 22} count Apparent clock speed: 2142 MHz TestAtomicIncrement: Measuring real time: 0.014 usec (1.354e-08 sec) per iteration: 1.354e-06 sec for 100 iterations 0.009 usec (9.458e-09 sec) per iteration: 9.458e-06 sec for 1000 iterations 0.009 usec (9.3111e-09 sec) per iteration: 9.3111e-05 sec for 10000 iterations 0.010 usec (9.59563e-09 sec) per iteration: 0.103056 sec for 10739869 iterations 0.010 usec (9.53908e-09 sec) per iteration: 0.102449 sec for 10739869 iterations 0.010 usec (9.75828e-09 sec) per iteration: 0.104803 sec for 10739869 iterations 0.010 usec (9.76462e-09 sec) per iteration: 0.104871 sec for 10739869 iterations 0.009 usec (9.46545e-09 sec) per iteration: 0.101658 sec for 10739869 iterations mean: 0.009625 usec stddev: 0.000119 usec relative stddev: 1.24% RESULT TestAtomicIncrement: x86-64_nnacl_newlib_static= {0.009625, 0.000119} us Measuring clock cycles: first runs (cycles): 142 53 44 44 44 44 44 50 47 47 ... slowest runs (cycles): ... 47 47 47 47 47 47 47 50 53 142 min: 44 cycles q1: 47 cycles median: 47 cycles q3: 47 cycles max: 142 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_nnacl_newlib_static= {47, 0} count Apparent clock speed: 4883 MHz TestUncontendedMutexLock: Measuring real time: 0.028 usec (2.757e-08 sec) per iteration: 2.757e-06 sec for 100 iterations 0.023 usec (2.3429e-08 sec) per iteration: 2.3429e-05 sec for 1000 iterations 0.024 usec (2.36589e-08 sec) per iteration: 0.100981 sec for 4268214 iterations 0.024 usec (2.37602e-08 sec) per iteration: 0.101414 sec for 4268214 iterations 0.024 usec (2.3587e-08 sec) per iteration: 0.100674 sec for 4268214 iterations 0.024 usec (2.35686e-08 sec) per iteration: 0.100596 sec for 4268214 iterations 0.024 usec (2.36836e-08 sec) per iteration: 0.101087 sec for 4268214 iterations mean: 0.023652 usec stddev: 0.000069 usec relative stddev: 0.29% RESULT TestUncontendedMutexLock: x86-64_nnacl_newlib_static= {0.023652, 0.000069} us Measuring clock cycles: first runs (cycles): 160 76 76 75 75 76 75 75 76 76 ... slowest runs (cycles): ... 76 76 76 76 76 76 76 76 85 160 min: 72 cycles q1: 75 cycles median: 75 cycles q3: 76 cycles max: 160 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_nnacl_newlib_static= {75, 1} count Apparent clock speed: 3171 MHz TestCondvarSignalNoOp: Measuring real time: 0.256 usec (2.5638e-07 sec) per iteration: 2.5638e-05 sec for 100 iterations 0.222 usec (2.21941e-07 sec) per iteration: 0.0865673 sec for 390046 iterations 0.219 usec (2.18691e-07 sec) per iteration: 0.0852996 sec for 390046 iterations 0.222 usec (2.21595e-07 sec) per iteration: 0.0864321 sec for 390046 iterations 0.226 usec (2.26477e-07 sec) per iteration: 0.0883364 sec for 390046 iterations 0.215 usec (2.15206e-07 sec) per iteration: 0.0839401 sec for 390046 iterations mean: 0.220782 usec stddev: 0.003739 usec relative stddev: 1.69% RESULT TestCondvarSignalNoOp: x86-64_nnacl_newlib_static= {0.220782, 0.003739} us Measuring clock cycles: first runs (cycles): 845 531 478 478 481 481 481 481 478 475 ... slowest runs (cycles): ... 481 481 481 481 481 481 481 481 531 845 min: 474 cycles q1: 477 cycles median: 478 cycles q3: 478 cycles max: 845 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_nnacl_newlib_static= {478, 1} count Apparent clock speed: 2165 MHz TestThreadCreateAndJoin: Measuring real time: 44.284 usec (4.42836e-05 sec) per iteration: 0.00442836 sec for 100 iterations 31.789 usec (3.17894e-05 sec) per iteration: 0.0717805 sec for 2258 iterations 23.590 usec (2.35903e-05 sec) per iteration: 0.0532668 sec for 2258 iterations 29.668 usec (2.9668e-05 sec) per iteration: 0.0669903 sec for 2258 iterations 31.737 usec (3.17372e-05 sec) per iteration: 0.0716626 sec for 2258 iterations 28.779 usec (2.87787e-05 sec) per iteration: 0.0649823 sec for 2258 iterations mean: 29.112713 usec stddev: 2.999002 usec relative stddev: 10.30% RESULT TestThreadCreateAndJoin: x86-64_nnacl_newlib_static= {29.112713, 2.999002} us Measuring clock cycles: first runs (cycles): 100346 95763 60858 64869 64114 63703 66675 153268 114447 175283 ... slowest runs (cycles): ... 110073 114447 127591 141558 151309 153268 156416 175283 205348 365222 min: 57178 cycles q1: 89814 cycles median: 91888 cycles q3: 95405 cycles max: 365222 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_nnacl_newlib_static= {91888, 5591} count Apparent clock speed: 3156 MHz TestThreadWakeup: Measuring real time: 11.833 usec (1.18328e-05 sec) per iteration: 0.00118328 sec for 100 iterations 12.195 usec (1.21954e-05 sec) per iteration: 0.103063 sec for 8451 iterations 15.216 usec (1.52163e-05 sec) per iteration: 0.128593 sec for 8451 iterations 16.521 usec (1.6521e-05 sec) per iteration: 0.139619 sec for 8451 iterations 14.725 usec (1.47249e-05 sec) per iteration: 0.12444 sec for 8451 iterations 13.011 usec (1.30114e-05 sec) per iteration: 0.10996 sec for 8451 iterations mean: 14.333814 usec stddev: 1.551578 usec relative stddev: 10.82% RESULT TestThreadWakeup: x86-64_nnacl_newlib_static= {14.333814, 1.551578} us Measuring clock cycles: first runs (cycles): 166414 23618 27673 26626 26981 26941 27299 26683 27161 28072 ... slowest runs (cycles): ... 30586 31319 31400 31878 32007 33871 34632 45984 52178 166414 min: 23618 cycles q1: 26679 cycles median: 27013 cycles q3: 27509 cycles max: 166414 cycles RESULT TestThreadWakeup_CycleCount: x86-64_nnacl_newlib_static= {27013, 830} count Apparent clock speed: 1885 MHz TestCatchingFault: Measuring real time: 2.451 usec (2.45072e-06 sec) per iteration: 0.000245072 sec for 100 iterations 1.605 usec (1.60485e-06 sec) per iteration: 0.0654844 sec for 40804 iterations 1.600 usec (1.60017e-06 sec) per iteration: 0.0652933 sec for 40804 iterations 1.604 usec (1.6045e-06 sec) per iteration: 0.06547 sec for 40804 iterations 1.607 usec (1.6072e-06 sec) per iteration: 0.0655801 sec for 40804 iterations 1.610 usec (1.60993e-06 sec) per iteration: 0.0656916 sec for 40804 iterations mean: 1.605329 usec stddev: 0.003231 usec relative stddev: 0.20% RESULT TestCatchingFault: x86-64_nnacl_newlib_static= {1.605329, 0.003231} us Measuring clock cycles: first runs (cycles): 38078 3390 3329 3323 3326 35153 4148 3450 3447 3454 ... slowest runs (cycles): ... 3469 3470 3473 3523 4148 16319 35153 36715 36907 38078 min: 3316 cycles q1: 3435 cycles median: 3445 cycles q3: 3454 cycles max: 38078 cycles RESULT TestCatchingFault_CycleCount: x86-64_nnacl_newlib_static= {3445, 19} count Apparent clock speed: 2146 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 43.275 usec (4.3275e-05 sec) per iteration: 0.0043275 sec for 100 iterations 30.004 usec (3.00045e-05 sec) per iteration: 0.0693104 sec for 2310 iterations 31.647 usec (3.1647e-05 sec) per iteration: 0.0731045 sec for 2310 iterations 32.854 usec (3.28541e-05 sec) per iteration: 0.0758929 sec for 2310 iterations 32.970 usec (3.29704e-05 sec) per iteration: 0.0761615 sec for 2310 iterations 30.969 usec (3.09686e-05 sec) per iteration: 0.0715374 sec for 2310 iterations mean: 31.688887 usec stddev: 1.127622 usec relative stddev: 3.56% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_nnacl_newlib_static= {31.688887, 1.127622} us Measuring clock cycles: first runs (cycles): 62382 174127 93249 69118 74417 58269 93808 53215 94185 51675 ... slowest runs (cycles): ... 94081 94185 95347 95703 97941 100137 111549 118115 174127 198515 min: 49352 cycles q1: 53843 cycles median: 57307 cycles q3: 89512 cycles max: 198515 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_nnacl_newlib_static= {57307, 35669} count Apparent clock speed: 1808 MHz [ OK ] nacl_newlib.run_performance_test (5979 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