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

Running command: /b/s/w/ir/cache/vpython/b574a6/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.14+chromium14 (df075345c7-dirty:df075345c7, Oct 3 2017, 17:29:00) [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-e833d7b0-us-west1-c-s9cj 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 : 2199.998 model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2199.998 model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2199.998 model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2199.998 model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2199.998 model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2199.998 model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2199.998 model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2199.998 ====================================================================== RAM: ====================================================================== MemTotal: 30882896 kB MemFree: 20657312 kB MemAvailable: 29448528 kB ====================================================================== LOAD: ====================================================================== 2.18 3.38 2.77 1/435 28046 ====================================================================== UPTIME: ====================================================================== 3133.68 20837.86 ====================================================================== 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='nacl-precise_64-newlib-x86_64-pnacl' BUILDBOT_BUILDNUMBER='9596' BUILDBOT_GOT_REVISION='db511ff7952658efc9bf67afa8dfdac9faba2f93' 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='39440' 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/8923616730385146144' LOGDOG_STREAM_PROJECT='nacl' LOGDOG_STREAM_SERVER_PATH='unix:/b/s/w/ir/tmp/ld.sock' LOGNAME='chrome-bot' LUCI_CONTEXT='/b/s/w/ithXgtax/luci_context.878317385' 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/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' PPID='28019' 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-west1-c-s9cj' SWARMING_EXTERNAL_BOT_SETUP='1' SWARMING_HEADLESS='1' SWARMING_SERVER='https://chromium-swarm.appspot.com' SWARMING_TASK_ID='428f1dda51f2c811' TEMP='/b/s/w/ir/tmp/t/nacl_tmp/nacl-precise_64-newlib-x86_64-pnacl' TEMPDIR='/b/s/w/ir/tmp/t' TERM='linux' TMP='/b/s/w/ir/tmp/t/nacl_tmp/nacl-precise_64-newlib-x86_64-pnacl' TMPDIR='/b/s/w/ir/tmp/t/nacl_tmp/nacl-precise_64-newlib-x86_64-pnacl' USER='chrome-bot' USERNAME='chrome-bot' VIRTUAL_ENV='/b/s/w/ir/cache/vpython/b574a6' 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 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 *** 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 ... /b/s/w/ir/cache/vpython/b574a6/bin/python /b/s/w/ir/kitchen-workdir/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/kitchen-workdir/native_client/scons-out/nacl-x86-64/test_results/signal_handler_single_step.out /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-64/obj/tests/signal_handler_single_step/step_test_host /b/s/w/ir/kitchen-workdir/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/kitchen-workdir/native_client/scons-out/nacl-x86-64/obj/tests/signal_handler_single_step/step_test_host /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-64/obj/tests/signal_handler_single_step/step_test_guest.nexe x86-64_nnacl_newlib_static [28061,4051425152:08:23:23.942030] Native Client module will be loaded at base address 0x000079e600000000 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) /b/s/w/ir/cache/vpython/b574a6/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/kitchen-workdir/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 [28066,4227880832:08:23:24.158348] Native Client module will be loaded at base address 0x000015c500000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_nnacl_newlib_static= {0.088510, 0.001630} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test (1250 ms) /b/s/w/ir/cache/vpython/b574a6/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/kitchen-workdir/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 (72260 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= 525.291 KB RESULT hello_world_size.out: ZIPPED_x86-64_nnacl_newlib_static= 187.863 KB /b/s/w/ir/cache/vpython/b574a6/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/kitchen-workdir/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 [28106,811042688:08:23:39.326577] Native Client module will be loaded at base address 0x00006b1b00000000 TestNull: Measuring real time: 0.006 usec (5.76e-09 sec) per iteration: 5.76e-07 sec for 100 iterations 0.002 usec (1.964e-09 sec) per iteration: 1.964e-06 sec for 1000 iterations 0.002 usec (1.8092e-09 sec) per iteration: 1.8092e-05 sec for 10000 iterations 0.002 usec (1.91129e-09 sec) per iteration: 0.105643 sec for 55273048 iterations 0.002 usec (1.88074e-09 sec) per iteration: 0.103954 sec for 55273048 iterations 0.002 usec (1.89665e-09 sec) per iteration: 0.104834 sec for 55273048 iterations 0.002 usec (1.89176e-09 sec) per iteration: 0.104563 sec for 55273048 iterations 0.002 usec (1.90534e-09 sec) per iteration: 0.105314 sec for 55273048 iterations mean: 0.001897 usec stddev: 0.000011 usec relative stddev: 0.56% RESULT TestNull: x86-64_nnacl_newlib_static= {0.001897, 0.000011} us Measuring clock cycles: first runs (cycles): 82 22 22 18 19 19 31 19 19 19 ... slowest runs (cycles): ... 19 19 19 19 19 19 22 22 31 82 min: 18 cycles q1: 19 cycles median: 19 cycles q3: 19 cycles max: 82 cycles RESULT TestNull_CycleCount: x86-64_nnacl_newlib_static= {19, 0} count Apparent clock speed: 10015 MHz TestNaClSyscall: Measuring real time: 0.130 usec (1.2974e-07 sec) per iteration: 1.2974e-05 sec for 100 iterations 0.135 usec (1.3453e-07 sec) per iteration: 0.103692 sec for 770772 iterations 0.133 usec (1.33308e-07 sec) per iteration: 0.10275 sec for 770772 iterations 0.130 usec (1.3031e-07 sec) per iteration: 0.100439 sec for 770772 iterations 0.136 usec (1.35865e-07 sec) per iteration: 0.104721 sec for 770772 iterations 0.134 usec (1.34389e-07 sec) per iteration: 0.103583 sec for 770772 iterations mean: 0.133680 usec stddev: 0.001871 usec relative stddev: 1.40% RESULT TestNaClSyscall: x86-64_nnacl_newlib_static= {0.133680, 0.001871} us Measuring clock cycles: first runs (cycles): 296 31812 212 164 279 160 160 164 161 161 ... slowest runs (cycles): ... 164 164 164 164 164 164 212 279 296 31812 min: 160 cycles q1: 161 cycles median: 163 cycles q3: 164 cycles max: 31812 cycles RESULT TestNaClSyscall_CycleCount: x86-64_nnacl_newlib_static= {163, 3} count Apparent clock speed: 1219 MHz TestSetjmpLongjmp: Measuring real time: 0.019 usec (1.877e-08 sec) per iteration: 1.877e-06 sec for 100 iterations 0.014 usec (1.3707e-08 sec) per iteration: 1.3707e-05 sec for 1000 iterations 0.013 usec (1.29932e-08 sec) per iteration: 0.0947926 sec for 7295542 iterations 0.013 usec (1.27888e-08 sec) per iteration: 0.0933015 sec for 7295542 iterations 0.013 usec (1.28094e-08 sec) per iteration: 0.0934512 sec for 7295542 iterations 0.013 usec (1.28113e-08 sec) per iteration: 0.0934652 sec for 7295542 iterations 0.013 usec (1.3013e-08 sec) per iteration: 0.0949367 sec for 7295542 iterations mean: 0.012883 usec stddev: 0.000098 usec relative stddev: 0.76% RESULT TestSetjmpLongjmp: x86-64_nnacl_newlib_static= {0.012883, 0.000098} us Measuring clock cycles: first runs (cycles): 151 54 29 25 25 25 28 25 25 25 ... slowest runs (cycles): ... 26 26 26 26 26 26 28 29 54 151 min: 25 cycles q1: 25 cycles median: 25 cycles q3: 25 cycles max: 151 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_nnacl_newlib_static= {25, 0} count Apparent clock speed: 1941 MHz TestClockGetTime: Measuring real time: 0.189 usec (1.891e-07 sec) per iteration: 1.891e-05 sec for 100 iterations 0.142 usec (1.42301e-07 sec) per iteration: 0.0752517 sec for 528820 iterations 0.142 usec (1.42306e-07 sec) per iteration: 0.0752545 sec for 528820 iterations 0.141 usec (1.40749e-07 sec) per iteration: 0.0744311 sec for 528820 iterations 0.141 usec (1.4128e-07 sec) per iteration: 0.0747118 sec for 528820 iterations 0.142 usec (1.42161e-07 sec) per iteration: 0.0751777 sec for 528820 iterations mean: 0.141760 usec stddev: 0.000633 usec relative stddev: 0.45% RESULT TestClockGetTime: x86-64_nnacl_newlib_static= {0.141760, 0.000633} us Measuring clock cycles: first runs (cycles): 600 327 317 315 308 311 308 314 305 304 ... slowest runs (cycles): ... 308 308 311 311 314 314 315 317 327 600 min: 304 cycles q1: 305 cycles median: 305 cycles q3: 308 cycles max: 600 cycles RESULT TestClockGetTime_CycleCount: x86-64_nnacl_newlib_static= {305, 3} count Apparent clock speed: 2152 MHz TestTlsVariable: Measuring real time: 0.009 usec (8.57e-09 sec) per iteration: 8.57e-07 sec for 100 iterations 0.006 usec (5.533e-09 sec) per iteration: 5.533e-06 sec for 1000 iterations 0.005 usec (5.3818e-09 sec) per iteration: 5.3818e-05 sec for 10000 iterations 0.006 usec (5.6552e-09 sec) per iteration: 0.10508 sec for 18581143 iterations 0.006 usec (5.67704e-09 sec) per iteration: 0.105486 sec for 18581143 iterations 0.006 usec (5.61441e-09 sec) per iteration: 0.104322 sec for 18581143 iterations 0.006 usec (5.62272e-09 sec) per iteration: 0.104477 sec for 18581143 iterations 0.006 usec (5.77803e-09 sec) per iteration: 0.107362 sec for 18581143 iterations mean: 0.005669 usec stddev: 0.000059 usec relative stddev: 1.04% RESULT TestTlsVariable: x86-64_nnacl_newlib_static= {0.005669, 0.000059} us Measuring clock cycles: first runs (cycles): 126 63 63 57 25 22 19 22 19 19 ... slowest runs (cycles): ... 25 25 25 25 25 26 57 63 63 126 min: 18 cycles q1: 19 cycles median: 19 cycles q3: 22 cycles max: 126 cycles RESULT TestTlsVariable_CycleCount: x86-64_nnacl_newlib_static= {19, 3} count Apparent clock speed: 3351 MHz TestMmapAnonymous: Measuring real time: 2.864 usec (2.86422e-06 sec) per iteration: 0.000286422 sec for 100 iterations 1.875 usec (1.87548e-06 sec) per iteration: 0.0654788 sec for 34913 iterations 1.939 usec (1.9387e-06 sec) per iteration: 0.067686 sec for 34913 iterations 1.916 usec (1.91578e-06 sec) per iteration: 0.0668856 sec for 34913 iterations 1.901 usec (1.90124e-06 sec) per iteration: 0.066378 sec for 34913 iterations 1.909 usec (1.9089e-06 sec) per iteration: 0.0666455 sec for 34913 iterations mean: 1.908022 usec stddev: 0.020528 usec relative stddev: 1.08% RESULT TestMmapAnonymous: x86-64_nnacl_newlib_static= {1.908022, 0.020528} us Measuring clock cycles: first runs (cycles): 6833 3908 3854 37173 4205 4023 3979 3998 3985 3969 ... slowest runs (cycles): ... 6418 6443 6524 6531 6641 6644 6757 6833 6899 37173 min: 3854 cycles q1: 3960 cycles median: 3979 cycles q3: 5896 cycles max: 37173 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_nnacl_newlib_static= {3979, 1936} count Apparent clock speed: 2085 MHz TestAtomicIncrement: Measuring real time: 0.016 usec (1.556e-08 sec) per iteration: 1.556e-06 sec for 100 iterations 0.011 usec (1.0769e-08 sec) per iteration: 1.0769e-05 sec for 1000 iterations 0.010 usec (9.70898e-09 sec) per iteration: 0.0901567 sec for 9285913 iterations 0.010 usec (9.90861e-09 sec) per iteration: 0.0920105 sec for 9285913 iterations 0.010 usec (9.78734e-09 sec) per iteration: 0.0908843 sec for 9285913 iterations 0.010 usec (1.00593e-08 sec) per iteration: 0.09341 sec for 9285913 iterations 0.010 usec (9.59665e-09 sec) per iteration: 0.0891137 sec for 9285913 iterations mean: 0.009812 usec stddev: 0.000160 usec relative stddev: 1.63% RESULT TestAtomicIncrement: x86-64_nnacl_newlib_static= {0.009812, 0.000160} us Measuring clock cycles: first runs (cycles): 123 54 47 50 47 47 47 47 47 47 ... slowest runs (cycles): ... 47 47 47 47 47 47 47 50 54 123 min: 47 cycles q1: 47 cycles median: 47 cycles q3: 47 cycles max: 123 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_nnacl_newlib_static= {47, 0} count Apparent clock speed: 4790 MHz TestUncontendedMutexLock: Measuring real time: 0.027 usec (2.713e-08 sec) per iteration: 2.713e-06 sec for 100 iterations 0.023 usec (2.3371e-08 sec) per iteration: 2.3371e-05 sec for 1000 iterations 0.024 usec (2.3733e-08 sec) per iteration: 0.101549 sec for 4278807 iterations 0.024 usec (2.38079e-08 sec) per iteration: 0.101869 sec for 4278807 iterations 0.024 usec (2.36469e-08 sec) per iteration: 0.101181 sec for 4278807 iterations 0.024 usec (2.37145e-08 sec) per iteration: 0.10147 sec for 4278807 iterations 0.024 usec (2.35374e-08 sec) per iteration: 0.100712 sec for 4278807 iterations mean: 0.023688 usec stddev: 0.000091 usec relative stddev: 0.38% RESULT TestUncontendedMutexLock: x86-64_nnacl_newlib_static= {0.023688, 0.000091} us Measuring clock cycles: first runs (cycles): 214 100 75 76 75 75 76 76 75 75 ... slowest runs (cycles): ... 76 76 76 76 76 76 76 79 100 214 min: 72 cycles q1: 75 cycles median: 75 cycles q3: 76 cycles max: 214 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_nnacl_newlib_static= {75, 1} count Apparent clock speed: 3166 MHz TestCondvarSignalNoOp: Measuring real time: 0.222 usec (2.2245e-07 sec) per iteration: 2.2245e-05 sec for 100 iterations 0.204 usec (2.03543e-07 sec) per iteration: 0.0915005 sec for 449539 iterations 0.206 usec (2.05505e-07 sec) per iteration: 0.0923826 sec for 449539 iterations 0.209 usec (2.09344e-07 sec) per iteration: 0.0941083 sec for 449539 iterations 0.208 usec (2.07736e-07 sec) per iteration: 0.0933856 sec for 449539 iterations 0.203 usec (2.03221e-07 sec) per iteration: 0.0913558 sec for 449539 iterations mean: 0.205870 usec stddev: 0.002371 usec relative stddev: 1.15% RESULT TestCondvarSignalNoOp: x86-64_nnacl_newlib_static= {0.205870, 0.002371} us Measuring clock cycles: first runs (cycles): 738 459 446 447 449 453 449 449 450 446 ... slowest runs (cycles): ... 447 449 449 449 449 450 450 453 459 738 min: 446 cycles q1: 446 cycles median: 446 cycles q3: 447 cycles max: 738 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_nnacl_newlib_static= {446, 1} count Apparent clock speed: 2166 MHz TestThreadCreateAndJoin: Measuring real time: 35.202 usec (3.52018e-05 sec) per iteration: 0.00352018 sec for 100 iterations 26.246 usec (2.6246e-05 sec) per iteration: 0.0745388 sec for 2840 iterations 24.972 usec (2.49716e-05 sec) per iteration: 0.0709194 sec for 2840 iterations 25.289 usec (2.52886e-05 sec) per iteration: 0.0718197 sec for 2840 iterations 23.913 usec (2.39131e-05 sec) per iteration: 0.0679133 sec for 2840 iterations 24.651 usec (2.46512e-05 sec) per iteration: 0.0700094 sec for 2840 iterations mean: 25.014131 usec stddev: 0.766639 usec relative stddev: 3.06% RESULT TestThreadCreateAndJoin: x86-64_nnacl_newlib_static= {25.014131, 0.766639} us Measuring clock cycles: first runs (cycles): 55475 48866 52064 50490 49707 76236 49164 49453 52841 89779 ... slowest runs (cycles): ... 64837 70004 70778 76236 78599 80107 89779 108812 109862 112222 min: 46831 cycles q1: 48148 cycles median: 48862 cycles q3: 50490 cycles max: 112222 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_nnacl_newlib_static= {48862, 2342} count Apparent clock speed: 1953 MHz TestThreadWakeup: Measuring real time: 11.850 usec (1.18502e-05 sec) per iteration: 0.00118502 sec for 100 iterations 14.403 usec (1.44032e-05 sec) per iteration: 0.121535 sec for 8438 iterations 15.104 usec (1.51038e-05 sec) per iteration: 0.127446 sec for 8438 iterations 15.164 usec (1.5164e-05 sec) per iteration: 0.127954 sec for 8438 iterations 14.309 usec (1.43091e-05 sec) per iteration: 0.12074 sec for 8438 iterations 13.788 usec (1.37883e-05 sec) per iteration: 0.116346 sec for 8438 iterations mean: 14.553678 usec stddev: 0.518338 usec relative stddev: 3.56% RESULT TestThreadWakeup: x86-64_nnacl_newlib_static= {14.553678, 0.518338} us Measuring clock cycles: first runs (cycles): 31479 27900 27359 28106 27821 27720 27669 27840 27975 28477 ... slowest runs (cycles): ... 33600 33761 34421 34917 34936 35087 36894 37347 40483 46247 min: 23581 cycles q1: 27871 cycles median: 28135 cycles q3: 29401 cycles max: 46247 cycles RESULT TestThreadWakeup_CycleCount: x86-64_nnacl_newlib_static= {28135, 1530} count Apparent clock speed: 1933 MHz TestCatchingFault: Measuring real time: 2.677 usec (2.67681e-06 sec) per iteration: 0.000267681 sec for 100 iterations 1.641 usec (1.6409e-06 sec) per iteration: 0.0612992 sec for 37357 iterations 1.636 usec (1.63593e-06 sec) per iteration: 0.0611134 sec for 37357 iterations 1.676 usec (1.67563e-06 sec) per iteration: 0.0625967 sec for 37357 iterations 1.650 usec (1.65022e-06 sec) per iteration: 0.0616472 sec for 37357 iterations 1.680 usec (1.68008e-06 sec) per iteration: 0.0627628 sec for 37357 iterations mean: 1.656554 usec stddev: 0.018045 usec relative stddev: 1.09% RESULT TestCatchingFault: x86-64_nnacl_newlib_static= {1.656554, 0.018045} us Measuring clock cycles: first runs (cycles): 5010 3517 3476 3479 3469 3467 3482 3466 3473 3501 ... slowest runs (cycles): ... 3495 3495 3495 3495 3495 3495 3498 3501 3517 5010 min: 3460 cycles q1: 3469 cycles median: 3476 cycles q3: 3482 cycles max: 5010 cycles RESULT TestCatchingFault_CycleCount: x86-64_nnacl_newlib_static= {3476, 13} count Apparent clock speed: 2098 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 31.624 usec (3.16239e-05 sec) per iteration: 0.00316239 sec for 100 iterations 24.239 usec (2.4239e-05 sec) per iteration: 0.0766437 sec for 3162 iterations 23.545 usec (2.35453e-05 sec) per iteration: 0.0744502 sec for 3162 iterations 27.049 usec (2.70488e-05 sec) per iteration: 0.0855282 sec for 3162 iterations 24.293 usec (2.42927e-05 sec) per iteration: 0.0768134 sec for 3162 iterations 23.918 usec (2.39179e-05 sec) per iteration: 0.0756283 sec for 3162 iterations mean: 24.608720 usec stddev: 1.248885 usec relative stddev: 5.07% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_nnacl_newlib_static= {24.608720, 1.248885} us Measuring clock cycles: first runs (cycles): 54412 45257 44719 52228 98774 136645 77012 95703 105823 59787 ... slowest runs (cycles): ... 76290 77012 78776 80901 81743 95703 96071 98774 105823 136645 min: 43814 cycles q1: 45257 cycles median: 45964 cycles q3: 47638 cycles max: 136645 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_nnacl_newlib_static= {45964, 2381} count Apparent clock speed: 1868 MHz [ OK ] nacl_newlib.run_performance_test (5980 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