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-a-sx1w 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: 20053664 kB MemAvailable: 29471120 kB ====================================================================== LOAD: ====================================================================== 1.64 2.82 2.58 1/437 2692 ====================================================================== UPTIME: ====================================================================== 8213.77 61309.67 ====================================================================== 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='9601' 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='34859' 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/8921224548844706048' LOGDOG_STREAM_PROJECT='nacl' LOGDOG_STREAM_SERVER_PATH='unix:/b/s/w/ir/tmp/ld.sock' LOGNAME='chrome-bot' LUCI_CONTEXT='/b/s/w/itGYIUI8/luci_context.788067571' 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='2665' 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-a-sx1w' SWARMING_EXTERNAL_BOT_SETUP='1' SWARMING_HEADLESS='1' SWARMING_SERVER='https://chromium-swarm.appspot.com' SWARMING_TASK_ID='431718c20b646f11' 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 [2707,1068058496:18:08:49.941226] Native Client module will be loaded at base address 0x0000571100000000 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 [2712,3746305920:18:08:50.173446] Native Client module will be loaded at base address 0x000053d800000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_nnacl_newlib_static= {0.100210, 0.005590} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test (1550 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 (74670 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 [2769,3492083584:18:09:06.422740] Native Client module will be loaded at base address 0x000062ab00000000 TestNull: Measuring real time: 0.008 usec (8.04e-09 sec) per iteration: 8.04e-07 sec for 100 iterations 0.003 usec (2.633e-09 sec) per iteration: 2.633e-06 sec for 1000 iterations 0.002 usec (1.8728e-09 sec) per iteration: 1.8728e-05 sec for 10000 iterations 0.002 usec (1.89404e-09 sec) per iteration: 0.101134 sec for 53395984 iterations 0.002 usec (1.8847e-09 sec) per iteration: 0.100635 sec for 53395984 iterations 0.002 usec (1.88836e-09 sec) per iteration: 0.100831 sec for 53395984 iterations 0.002 usec (1.88732e-09 sec) per iteration: 0.100775 sec for 53395984 iterations 0.002 usec (1.8879e-09 sec) per iteration: 0.100806 sec for 53395984 iterations mean: 0.001888 usec stddev: 0.000003 usec relative stddev: 0.16% RESULT TestNull: x86-64_nnacl_newlib_static= {0.001888, 0.000003} us Measuring clock cycles: first runs (cycles): 94 24 22 26 24 24 22 26 24 26 ... slowest runs (cycles): ... 28 28 28 28 29 29 29 29 29 94 min: 22 cycles q1: 24 cycles median: 24 cycles q3: 26 cycles max: 94 cycles RESULT TestNull_CycleCount: x86-64_nnacl_newlib_static= {24, 2} count Apparent clock speed: 12709 MHz TestNaClSyscall: Measuring real time: 0.139 usec (1.3922e-07 sec) per iteration: 1.3922e-05 sec for 100 iterations 0.116 usec (1.16164e-07 sec) per iteration: 0.0834393 sec for 718287 iterations 0.127 usec (1.26857e-07 sec) per iteration: 0.0911195 sec for 718287 iterations 0.124 usec (1.23626e-07 sec) per iteration: 0.0887993 sec for 718287 iterations 0.123 usec (1.23058e-07 sec) per iteration: 0.0883912 sec for 718287 iterations 0.116 usec (1.16174e-07 sec) per iteration: 0.0834466 sec for 718287 iterations mean: 0.121176 usec stddev: 0.004288 usec relative stddev: 3.54% RESULT TestNaClSyscall: x86-64_nnacl_newlib_static= {0.121176, 0.004288} us Measuring clock cycles: first runs (cycles): 300 193 169 172 174 220 172 171 172 172 ... slowest runs (cycles): ... 176 193 207 207 209 209 220 244 300 12427 min: 169 cycles q1: 172 cycles median: 174 cycles q3: 174 cycles max: 12427 cycles RESULT TestNaClSyscall_CycleCount: x86-64_nnacl_newlib_static= {174, 2} count Apparent clock speed: 1436 MHz TestSetjmpLongjmp: Measuring real time: 0.016 usec (1.626e-08 sec) per iteration: 1.626e-06 sec for 100 iterations 0.013 usec (1.2702e-08 sec) per iteration: 1.2702e-05 sec for 1000 iterations 0.013 usec (1.28707e-08 sec) per iteration: 0.101328 sec for 7872775 iterations 0.013 usec (1.29103e-08 sec) per iteration: 0.10164 sec for 7872775 iterations 0.013 usec (1.29307e-08 sec) per iteration: 0.101801 sec for 7872775 iterations 0.013 usec (1.29017e-08 sec) per iteration: 0.101572 sec for 7872775 iterations 0.013 usec (1.28183e-08 sec) per iteration: 0.100915 sec for 7872775 iterations mean: 0.012886 usec stddev: 0.000039 usec relative stddev: 0.30% RESULT TestSetjmpLongjmp: x86-64_nnacl_newlib_static= {0.012886, 0.000039} us Measuring clock cycles: first runs (cycles): 162 58 39 61 37 30 27 27 29 28 ... slowest runs (cycles): ... 29 29 29 29 30 37 39 58 61 162 min: 24 cycles q1: 26 cycles median: 28 cycles q3: 29 cycles max: 162 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_nnacl_newlib_static= {28, 3} count Apparent clock speed: 2173 MHz TestClockGetTime: Measuring real time: 0.144 usec (1.4352e-07 sec) per iteration: 1.4352e-05 sec for 100 iterations 0.149 usec (1.4932e-07 sec) per iteration: 0.104041 sec for 696767 iterations 0.153 usec (1.53093e-07 sec) per iteration: 0.10667 sec for 696767 iterations 0.152 usec (1.51972e-07 sec) per iteration: 0.105889 sec for 696767 iterations 0.152 usec (1.52433e-07 sec) per iteration: 0.106211 sec for 696767 iterations 0.152 usec (1.5155e-07 sec) per iteration: 0.105595 sec for 696767 iterations mean: 0.151674 usec stddev: 0.001283 usec relative stddev: 0.85% RESULT TestClockGetTime: x86-64_nnacl_newlib_static= {0.151674, 0.001283} us Measuring clock cycles: first runs (cycles): 567 409 330 326 330 332 328 326 326 374 ... slowest runs (cycles): ... 330 330 330 330 330 332 332 374 409 567 min: 321 cycles q1: 327 cycles median: 330 cycles q3: 330 cycles max: 567 cycles RESULT TestClockGetTime_CycleCount: x86-64_nnacl_newlib_static= {330, 3} count Apparent clock speed: 2176 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.744e-09 sec) per iteration: 5.744e-06 sec for 1000 iterations 0.006 usec (5.9611e-09 sec) per iteration: 5.9611e-05 sec for 10000 iterations 0.006 usec (5.7043e-09 sec) per iteration: 0.0956921 sec for 16775427 iterations 0.006 usec (5.65647e-09 sec) per iteration: 0.0948898 sec for 16775427 iterations 0.006 usec (5.64356e-09 sec) per iteration: 0.0946732 sec for 16775427 iterations 0.006 usec (5.67617e-09 sec) per iteration: 0.0952201 sec for 16775427 iterations 0.006 usec (5.66898e-09 sec) per iteration: 0.0950995 sec for 16775427 iterations mean: 0.005670 usec stddev: 0.000020 usec relative stddev: 0.36% RESULT TestTlsVariable: x86-64_nnacl_newlib_static= {0.005670, 0.000020} us Measuring clock cycles: first runs (cycles): 151 77 35 36 33 29 27 33 27 30 ... slowest runs (cycles): ... 33 33 33 33 33 33 35 36 77 151 min: 22 cycles q1: 26 cycles median: 29 cycles q3: 31 cycles max: 151 cycles RESULT TestTlsVariable_CycleCount: x86-64_nnacl_newlib_static= {29, 5} count Apparent clock speed: 5115 MHz TestMmapAnonymous: Measuring real time: 2.112 usec (2.11157e-06 sec) per iteration: 0.000211157 sec for 100 iterations 1.904 usec (1.90429e-06 sec) per iteration: 0.0901835 sec for 47358 iterations 1.914 usec (1.91376e-06 sec) per iteration: 0.0906318 sec for 47358 iterations 1.900 usec (1.90023e-06 sec) per iteration: 0.0899913 sec for 47358 iterations 1.905 usec (1.90515e-06 sec) per iteration: 0.0902242 sec for 47358 iterations 1.883 usec (1.88265e-06 sec) per iteration: 0.0891587 sec for 47358 iterations mean: 1.901219 usec stddev: 0.010274 usec relative stddev: 0.54% RESULT TestMmapAnonymous: x86-64_nnacl_newlib_static= {1.901219, 0.010274} us Measuring clock cycles: first runs (cycles): 8454 5266 5113 4826 5433 5366 5383 5647 6320 6196 ... slowest runs (cycles): ... 6544 6553 6683 6688 6708 7222 7372 7466 7500 8454 min: 4035 cycles q1: 4099 cycles median: 5442 cycles q3: 5955 cycles max: 8454 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_nnacl_newlib_static= {5442, 1856} count Apparent clock speed: 2862 MHz TestAtomicIncrement: Measuring real time: 0.015 usec (1.53e-08 sec) per iteration: 1.53e-06 sec for 100 iterations 0.011 usec (1.07e-08 sec) per iteration: 1.07e-05 sec for 1000 iterations 0.009 usec (9.43012e-09 sec) per iteration: 0.0881319 sec for 9345794 iterations 0.009 usec (9.44283e-09 sec) per iteration: 0.0882507 sec for 9345794 iterations 0.009 usec (9.42953e-09 sec) per iteration: 0.0881264 sec for 9345794 iterations 0.009 usec (9.42007e-09 sec) per iteration: 0.0880381 sec for 9345794 iterations 0.009 usec (9.42497e-09 sec) per iteration: 0.0880838 sec for 9345794 iterations mean: 0.009430 usec stddev: 0.000008 usec relative stddev: 0.08% RESULT TestAtomicIncrement: x86-64_nnacl_newlib_static= {0.009430, 0.000008} us Measuring clock cycles: first runs (cycles): 121 53 53 51 57 53 50 53 50 53 ... slowest runs (cycles): ... 55 55 55 55 55 55 57 57 58 121 min: 50 cycles q1: 52 cycles median: 53 cycles q3: 53 cycles max: 121 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_nnacl_newlib_static= {53, 1} count Apparent clock speed: 5621 MHz TestUncontendedMutexLock: Measuring real time: 0.033 usec (3.332e-08 sec) per iteration: 3.332e-06 sec for 100 iterations 0.026 usec (2.6446e-08 sec) per iteration: 2.6446e-05 sec for 1000 iterations 0.024 usec (2.44904e-08 sec) per iteration: 0.0926054 sec for 3781290 iterations 0.024 usec (2.44942e-08 sec) per iteration: 0.0926197 sec for 3781290 iterations 0.025 usec (2.45379e-08 sec) per iteration: 0.0927849 sec for 3781290 iterations 0.025 usec (2.45058e-08 sec) per iteration: 0.0926636 sec for 3781290 iterations 0.024 usec (2.44654e-08 sec) per iteration: 0.0925108 sec for 3781290 iterations mean: 0.024499 usec stddev: 0.000024 usec relative stddev: 0.10% RESULT TestUncontendedMutexLock: x86-64_nnacl_newlib_static= {0.024499, 0.000024} us Measuring clock cycles: first runs (cycles): 247 110 86 84 86 86 85 84 83 83 ... slowest runs (cycles): ... 90 90 92 92 93 94 95 95 110 247 min: 83 cycles q1: 84 cycles median: 86 cycles q3: 86 cycles max: 247 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_nnacl_newlib_static= {86, 2} count Apparent clock speed: 3510 MHz TestCondvarSignalNoOp: Measuring real time: 0.278 usec (2.7823e-07 sec) per iteration: 2.7823e-05 sec for 100 iterations 0.213 usec (2.12991e-07 sec) per iteration: 0.0765519 sec for 359414 iterations 0.213 usec (2.13035e-07 sec) per iteration: 0.0765679 sec for 359414 iterations 0.214 usec (2.13882e-07 sec) per iteration: 0.0768721 sec for 359414 iterations 0.213 usec (2.12883e-07 sec) per iteration: 0.076513 sec for 359414 iterations 0.212 usec (2.11971e-07 sec) per iteration: 0.0761852 sec for 359414 iterations mean: 0.212952 usec stddev: 0.000607 usec relative stddev: 0.28% RESULT TestCondvarSignalNoOp: x86-64_nnacl_newlib_static= {0.212952, 0.000607} us Measuring clock cycles: first runs (cycles): 853 565 512 481 487 521 491 544 497 497 ... slowest runs (cycles): ... 579 581 581 585 585 587 592 598 621 853 min: 475 cycles q1: 493 cycles median: 513 cycles q3: 545 cycles max: 853 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_nnacl_newlib_static= {513, 52} count Apparent clock speed: 2409 MHz TestThreadCreateAndJoin: Measuring real time: 28.414 usec (2.84136e-05 sec) per iteration: 0.00284136 sec for 100 iterations 23.727 usec (2.37273e-05 sec) per iteration: 0.0834962 sec for 3519 iterations 23.011 usec (2.30105e-05 sec) per iteration: 0.0809741 sec for 3519 iterations 23.428 usec (2.34282e-05 sec) per iteration: 0.0824439 sec for 3519 iterations 23.148 usec (2.31483e-05 sec) per iteration: 0.0814587 sec for 3519 iterations 24.358 usec (2.43576e-05 sec) per iteration: 0.0857143 sec for 3519 iterations mean: 23.534367 usec stddev: 0.479505 usec relative stddev: 2.04% RESULT TestThreadCreateAndJoin: x86-64_nnacl_newlib_static= {23.534367, 0.479505} us Measuring clock cycles: first runs (cycles): 74211 45418 55330 95204 56118 93925 56848 134728 41641 44502 ... slowest runs (cycles): ... 97769 99211 99408 100246 103641 111214 113549 117796 121520 134728 min: 37742 cycles q1: 41187 cycles median: 46759 cycles q3: 74211 cycles max: 134728 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_nnacl_newlib_static= {46759, 33024} count Apparent clock speed: 1987 MHz TestThreadWakeup: Measuring real time: 10.805 usec (1.08054e-05 sec) per iteration: 0.00108054 sec for 100 iterations 13.522 usec (1.35219e-05 sec) per iteration: 0.125132 sec for 9254 iterations 15.960 usec (1.59603e-05 sec) per iteration: 0.147697 sec for 9254 iterations 16.060 usec (1.60604e-05 sec) per iteration: 0.148623 sec for 9254 iterations 16.126 usec (1.6126e-05 sec) per iteration: 0.14923 sec for 9254 iterations 17.034 usec (1.70337e-05 sec) per iteration: 0.15763 sec for 9254 iterations mean: 15.740474 usec stddev: 1.174212 usec relative stddev: 7.46% RESULT TestThreadWakeup: x86-64_nnacl_newlib_static= {15.740474, 1.174212} us Measuring clock cycles: first runs (cycles): 38222 36211 36163 37085 37018 48172 38107 36003 37762 37731 ... slowest runs (cycles): ... 41911 41977 42551 42866 43046 43374 43772 43963 48172 50877 min: 32733 cycles q1: 36211 cycles median: 37018 cycles q3: 39526 cycles max: 50877 cycles RESULT TestThreadWakeup_CycleCount: x86-64_nnacl_newlib_static= {37018, 3315} count Apparent clock speed: 2352 MHz TestCatchingFault: Measuring real time: 2.881 usec (2.88064e-06 sec) per iteration: 0.000288064 sec for 100 iterations 1.734 usec (1.73371e-06 sec) per iteration: 0.0601839 sec for 34714 iterations 1.730 usec (1.72975e-06 sec) per iteration: 0.0600465 sec for 34714 iterations 1.733 usec (1.73319e-06 sec) per iteration: 0.0601658 sec for 34714 iterations 1.737 usec (1.73671e-06 sec) per iteration: 0.0602881 sec for 34714 iterations 1.734 usec (1.73412e-06 sec) per iteration: 0.0601983 sec for 34714 iterations mean: 1.733495 usec stddev: 0.002232 usec relative stddev: 0.13% RESULT TestCatchingFault: x86-64_nnacl_newlib_static= {1.733495, 0.002232} us Measuring clock cycles: first runs (cycles): 4369 3803 3799 3770 3819 3766 3766 3761 3806 3770 ... slowest runs (cycles): ... 3777 3777 3777 3777 3799 3803 3806 3814 3819 4369 min: 3751 cycles q1: 3761 cycles median: 3766 cycles q3: 3770 cycles max: 4369 cycles RESULT TestCatchingFault_CycleCount: x86-64_nnacl_newlib_static= {3766, 9} count Apparent clock speed: 2172 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 34.457 usec (3.44572e-05 sec) per iteration: 0.00344572 sec for 100 iterations 30.305 usec (3.03049e-05 sec) per iteration: 0.0879448 sec for 2902 iterations 32.264 usec (3.22639e-05 sec) per iteration: 0.0936298 sec for 2902 iterations 38.901 usec (3.89007e-05 sec) per iteration: 0.11289 sec for 2902 iterations 35.115 usec (3.51149e-05 sec) per iteration: 0.101903 sec for 2902 iterations 38.933 usec (3.89333e-05 sec) per iteration: 0.112985 sec for 2902 iterations mean: 35.103527 usec stddev: 3.469191 usec relative stddev: 9.88% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_nnacl_newlib_static= {35.103527, 3.469191} us Measuring clock cycles: first runs (cycles): 53376 111456 47613 72011 45644 44438 48607 67904 52490 100290 ... slowest runs (cycles): ... 111456 114463 116027 119070 119652 121809 123837 129248 180579 195989 min: 44438 cycles q1: 58946 cycles median: 72189 cycles q3: 100290 cycles max: 195989 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_nnacl_newlib_static= {72189, 41344} count Apparent clock speed: 2056 MHz [ OK ] nacl_newlib.run_performance_test (6390 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