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: 44151932 kB MemAvailable: 59387640 kB ====================================================================== LOAD: ====================================================================== 1.78 1.65 1.90 1/586 22508 ====================================================================== UPTIME: ====================================================================== 26415.69 384882.28 ====================================================================== 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='7030' BUILDBOT_GOT_REVISION='7d24fb15d81f95ed30dde0c04b68584749709657' BUILDBOT_MASTERNAME='client.nacl.toolchain' BUILDBOT_REVISION='7d24fb15d81f95ed30dde0c04b68584749709657' BUILDBOT_SLAVE_TYPE='BuilderTester' BUILDBUCKET_EXPERIMENTAL='FALSE' CIPD_CACHE_DIR='/b/s/cipd_cache/cache' CIPD_PROTOCOL='v2' CYGWIN='nodosfilewarning' DEVSHELL_CLIENT_PORT='44518' 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/8918612060171329952' LOGDOG_STREAM_PROJECT='nacl' LOGDOG_STREAM_SERVER_PATH='unix:/b/s/w/ir/tmp/ld.sock' LOGNAME='chrome-bot' LUCI_CONTEXT='/b/s/w/it0lV5Lt/luci_context.791121765' 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='22481' 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='43ab995b70551d11' 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 [22523,2243860352:19:09:23.544523] Native Client module will be loaded at base address 0x00007f0300000000 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 [22528,4145506176:19:09:23.845688] Native Client module will be loaded at base address 0x00004f0100000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_nnacl_newlib_static= {0.085380, 0.001570} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test (2179 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 (70650 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 [22566,799594368:19:09:38.608919] Native Client module will be loaded at base address 0x0000672000000000 TestNull: Measuring real time: 0.006 usec (6.01e-09 sec) per iteration: 6.01e-07 sec for 100 iterations 0.002 usec (2.017e-09 sec) per iteration: 2.017e-06 sec for 1000 iterations 0.002 usec (2.0196e-09 sec) per iteration: 2.0196e-05 sec for 10000 iterations 0.002 usec (2.03049e-09 sec) per iteration: 0.100539 sec for 49514755 iterations 0.002 usec (1.95482e-09 sec) per iteration: 0.0967922 sec for 49514755 iterations 0.002 usec (1.91472e-09 sec) per iteration: 0.094807 sec for 49514755 iterations 0.002 usec (1.91854e-09 sec) per iteration: 0.0949959 sec for 49514755 iterations 0.002 usec (1.89072e-09 sec) per iteration: 0.0936186 sec for 49514755 iterations mean: 0.001942 usec stddev: 0.000049 usec relative stddev: 2.51% RESULT TestNull: x86-64_nnacl_newlib_static= {0.001942, 0.000049} us Measuring clock cycles: first runs (cycles): 93 24 24 24 27 22 22 24 22 26 ... slowest runs (cycles): ... 27 27 27 27 27 27 27 27 28 93 min: 22 cycles q1: 24 cycles median: 25 cycles q3: 26 cycles max: 93 cycles RESULT TestNull_CycleCount: x86-64_nnacl_newlib_static= {25, 2} count Apparent clock speed: 12874 MHz TestNaClSyscall: Measuring real time: 0.141 usec (1.4062e-07 sec) per iteration: 1.4062e-05 sec for 100 iterations 0.122 usec (1.21949e-07 sec) per iteration: 0.0867223 sec for 711136 iterations 0.128 usec (1.27838e-07 sec) per iteration: 0.0909103 sec for 711136 iterations 0.122 usec (1.21549e-07 sec) per iteration: 0.0864376 sec for 711136 iterations 0.116 usec (1.15816e-07 sec) per iteration: 0.0823609 sec for 711136 iterations 0.124 usec (1.23856e-07 sec) per iteration: 0.0880784 sec for 711136 iterations mean: 0.122202 usec stddev: 0.003893 usec relative stddev: 3.19% RESULT TestNaClSyscall: x86-64_nnacl_newlib_static= {0.122202, 0.003893} us Measuring clock cycles: first runs (cycles): 310 196 172 171 174 172 173 176 176 176 ... slowest runs (cycles): ... 176 176 176 176 176 176 196 209 209 310 min: 171 cycles q1: 174 cycles median: 174 cycles q3: 176 cycles max: 310 cycles RESULT TestNaClSyscall_CycleCount: x86-64_nnacl_newlib_static= {174, 2} count Apparent clock speed: 1424 MHz TestSetjmpLongjmp: Measuring real time: 0.017 usec (1.683e-08 sec) per iteration: 1.683e-06 sec for 100 iterations 0.013 usec (1.2879e-08 sec) per iteration: 1.2879e-05 sec for 1000 iterations 0.013 usec (1.30836e-08 sec) per iteration: 0.101589 sec for 7764577 iterations 0.013 usec (1.30916e-08 sec) per iteration: 0.101651 sec for 7764577 iterations 0.013 usec (1.3179e-08 sec) per iteration: 0.102329 sec for 7764577 iterations 0.013 usec (1.30762e-08 sec) per iteration: 0.101531 sec for 7764577 iterations 0.013 usec (1.3339e-08 sec) per iteration: 0.103572 sec for 7764577 iterations mean: 0.013154 usec stddev: 0.000100 usec relative stddev: 0.76% RESULT TestSetjmpLongjmp: x86-64_nnacl_newlib_static= {0.013154, 0.000100} us Measuring clock cycles: first runs (cycles): 196 84 42 35 33 33 31 29 26 26 ... slowest runs (cycles): ... 31 31 31 31 33 33 35 42 84 196 min: 25 cycles q1: 26 cycles median: 27 cycles q3: 29 cycles max: 196 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_nnacl_newlib_static= {27, 3} count Apparent clock speed: 2053 MHz TestClockGetTime: Measuring real time: 0.144 usec (1.4399e-07 sec) per iteration: 1.4399e-05 sec for 100 iterations 0.155 usec (1.54818e-07 sec) per iteration: 0.10752 sec for 694492 iterations 0.150 usec (1.49787e-07 sec) per iteration: 0.104026 sec for 694492 iterations 0.155 usec (1.54742e-07 sec) per iteration: 0.107467 sec for 694492 iterations 0.148 usec (1.48497e-07 sec) per iteration: 0.10313 sec for 694492 iterations 0.150 usec (1.49942e-07 sec) per iteration: 0.104133 sec for 694492 iterations mean: 0.151557 usec stddev: 0.002679 usec relative stddev: 1.77% RESULT TestClockGetTime: x86-64_nnacl_newlib_static= {0.151557, 0.002679} us Measuring clock cycles: first runs (cycles): 572 383 356 333 328 323 330 330 328 330 ... slowest runs (cycles): ... 333 333 333 333 333 333 333 356 383 572 min: 323 cycles q1: 330 cycles median: 330 cycles q3: 332 cycles max: 572 cycles RESULT TestClockGetTime_CycleCount: x86-64_nnacl_newlib_static= {330, 2} count Apparent clock speed: 2177 MHz TestTlsVariable: Measuring real time: 0.011 usec (1.056e-08 sec) per iteration: 1.056e-06 sec for 100 iterations 0.008 usec (7.525e-09 sec) per iteration: 7.525e-06 sec for 1000 iterations 0.006 usec (5.5767e-09 sec) per iteration: 5.5767e-05 sec for 10000 iterations 0.006 usec (5.67477e-09 sec) per iteration: 0.101758 sec for 17931751 iterations 0.006 usec (5.73569e-09 sec) per iteration: 0.102851 sec for 17931751 iterations 0.006 usec (5.70023e-09 sec) per iteration: 0.102215 sec for 17931751 iterations 0.006 usec (5.6898e-09 sec) per iteration: 0.102028 sec for 17931751 iterations 0.006 usec (5.69784e-09 sec) per iteration: 0.102172 sec for 17931751 iterations mean: 0.005700 usec stddev: 0.000020 usec relative stddev: 0.35% RESULT TestTlsVariable: x86-64_nnacl_newlib_static= {0.005700, 0.000020} us Measuring clock cycles: first runs (cycles): 174 77 35 35 35 35 31 35 35 33 ... slowest runs (cycles): ... 33 35 35 35 35 35 35 35 77 174 min: 24 cycles q1: 27 cycles median: 29 cycles q3: 31 cycles max: 174 cycles RESULT TestTlsVariable_CycleCount: x86-64_nnacl_newlib_static= {29, 4} count Apparent clock speed: 5088 MHz TestMmapAnonymous: Measuring real time: 2.825 usec (2.82477e-06 sec) per iteration: 0.000282477 sec for 100 iterations 1.816 usec (1.81637e-06 sec) per iteration: 0.0643012 sec for 35401 iterations 1.821 usec (1.82052e-06 sec) per iteration: 0.0644484 sec for 35401 iterations 1.818 usec (1.81845e-06 sec) per iteration: 0.0643751 sec for 35401 iterations 1.827 usec (1.82717e-06 sec) per iteration: 0.0646837 sec for 35401 iterations 1.808 usec (1.80831e-06 sec) per iteration: 0.0640158 sec for 35401 iterations mean: 1.818165 usec stddev: 0.006119 usec relative stddev: 0.34% RESULT TestMmapAnonymous: x86-64_nnacl_newlib_static= {1.818165, 0.006119} us Measuring clock cycles: first runs (cycles): 5988 4019 3918 3907 3916 3953 3915 3909 3911 3898 ... slowest runs (cycles): ... 3953 3953 4009 4011 4019 4127 5955 5988 12750 29092 min: 3782 cycles q1: 3819 cycles median: 3837 cycles q3: 3905 cycles max: 29092 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_nnacl_newlib_static= {3837, 86} count Apparent clock speed: 2110 MHz TestAtomicIncrement: Measuring real time: 0.013 usec (1.329e-08 sec) per iteration: 1.329e-06 sec for 100 iterations 0.009 usec (9.44e-09 sec) per iteration: 9.44e-06 sec for 1000 iterations 0.010 usec (1.01102e-08 sec) per iteration: 0.000101102 sec for 10000 iterations 0.009 usec (9.49647e-09 sec) per iteration: 0.0939296 sec for 9891001 iterations 0.009 usec (9.48201e-09 sec) per iteration: 0.0937865 sec for 9891001 iterations 0.010 usec (9.69751e-09 sec) per iteration: 0.0959181 sec for 9891001 iterations 0.009 usec (9.49454e-09 sec) per iteration: 0.0939105 sec for 9891001 iterations 0.010 usec (9.50522e-09 sec) per iteration: 0.0940161 sec for 9891001 iterations mean: 0.009535 usec stddev: 0.000082 usec relative stddev: 0.85% RESULT TestAtomicIncrement: x86-64_nnacl_newlib_static= {0.009535, 0.000082} us Measuring clock cycles: first runs (cycles): 163 53 51 51 53 51 51 51 53 48 ... slowest runs (cycles): ... 53 53 53 53 53 53 53 53 83 163 min: 48 cycles q1: 51 cycles median: 53 cycles q3: 53 cycles max: 163 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_nnacl_newlib_static= {53, 2} count Apparent clock speed: 5558 MHz TestUncontendedMutexLock: Measuring real time: 0.029 usec (2.882e-08 sec) per iteration: 2.882e-06 sec for 100 iterations 0.024 usec (2.438e-08 sec) per iteration: 2.438e-05 sec for 1000 iterations 0.025 usec (2.46688e-08 sec) per iteration: 0.101185 sec for 4101722 iterations 0.025 usec (2.48614e-08 sec) per iteration: 0.101974 sec for 4101722 iterations 0.025 usec (2.47151e-08 sec) per iteration: 0.101374 sec for 4101722 iterations 0.025 usec (2.49007e-08 sec) per iteration: 0.102136 sec for 4101722 iterations 0.025 usec (2.48237e-08 sec) per iteration: 0.10182 sec for 4101722 iterations mean: 0.024794 usec stddev: 0.000088 usec relative stddev: 0.35% RESULT TestUncontendedMutexLock: x86-64_nnacl_newlib_static= {0.024794, 0.000088} us Measuring clock cycles: first runs (cycles): 343 107 83 84 81 81 82 81 81 82 ... slowest runs (cycles): ... 84 84 84 84 84 84 84 84 107 343 min: 79 cycles q1: 81 cycles median: 82 cycles q3: 83 cycles max: 343 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_nnacl_newlib_static= {82, 2} count Apparent clock speed: 3307 MHz TestCondvarSignalNoOp: Measuring real time: 0.263 usec (2.6319e-07 sec) per iteration: 2.6319e-05 sec for 100 iterations 0.218 usec (2.18392e-07 sec) per iteration: 0.0829788 sec for 379953 iterations 0.213 usec (2.1261e-07 sec) per iteration: 0.0807818 sec for 379953 iterations 0.217 usec (2.17368e-07 sec) per iteration: 0.0825896 sec for 379953 iterations 0.220 usec (2.20197e-07 sec) per iteration: 0.0836644 sec for 379953 iterations 0.217 usec (2.17362e-07 sec) per iteration: 0.0825873 sec for 379953 iterations mean: 0.217186 usec stddev: 0.002511 usec relative stddev: 1.16% RESULT TestCondvarSignalNoOp: x86-64_nnacl_newlib_static= {0.217186, 0.002511} us Measuring clock cycles: first runs (cycles): 690 544 471 475 475 478 471 470 470 473 ... slowest runs (cycles): ... 478 478 478 479 480 484 486 508 544 690 min: 468 cycles q1: 473 cycles median: 473 cycles q3: 475 cycles max: 690 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_nnacl_newlib_static= {473, 2} count Apparent clock speed: 2178 MHz TestThreadCreateAndJoin: Measuring real time: 45.429 usec (4.54293e-05 sec) per iteration: 0.00454293 sec for 100 iterations 41.137 usec (4.11365e-05 sec) per iteration: 0.0905415 sec for 2201 iterations 34.109 usec (3.41094e-05 sec) per iteration: 0.0750747 sec for 2201 iterations 31.865 usec (3.18649e-05 sec) per iteration: 0.0701345 sec for 2201 iterations 52.487 usec (5.24866e-05 sec) per iteration: 0.115523 sec for 2201 iterations 55.833 usec (5.58333e-05 sec) per iteration: 0.122889 sec for 2201 iterations mean: 43.086121 usec stddev: 9.603733 usec relative stddev: 22.29% RESULT TestThreadCreateAndJoin: x86-64_nnacl_newlib_static= {43.086121, 9.603733} us Measuring clock cycles: first runs (cycles): 243554 126289 94070 151262 275293 147469 132895 130134 131236 120046 ... slowest runs (cycles): ... 228536 232914 238482 243554 248113 252580 268324 275293 285322 296378 min: 52577 cycles q1: 83204 cycles median: 106610 cycles q3: 154612 cycles max: 296378 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_nnacl_newlib_static= {106610, 71408} count Apparent clock speed: 2474 MHz TestThreadWakeup: Measuring real time: 20.523 usec (2.05227e-05 sec) per iteration: 0.00205227 sec for 100 iterations 22.804 usec (2.2804e-05 sec) per iteration: 0.111101 sec for 4872 iterations 22.984 usec (2.29837e-05 sec) per iteration: 0.111977 sec for 4872 iterations 16.912 usec (1.69117e-05 sec) per iteration: 0.0823938 sec for 4872 iterations 16.151 usec (1.61509e-05 sec) per iteration: 0.0786871 sec for 4872 iterations 17.505 usec (1.75048e-05 sec) per iteration: 0.0852836 sec for 4872 iterations mean: 19.271030 usec stddev: 2.989551 usec relative stddev: 15.51% RESULT TestThreadWakeup: x86-64_nnacl_newlib_static= {19.271030, 2.989551} us Measuring clock cycles: first runs (cycles): 41702 35483 34411 34926 45684 90286 55205 58528 34938 36697 ... slowest runs (cycles): ... 79075 82876 83597 90286 104375 135064 142146 156628 361676 459217 min: 30846 cycles q1: 35461 cycles median: 37729 cycles q3: 43475 cycles max: 459217 cycles RESULT TestThreadWakeup_CycleCount: x86-64_nnacl_newlib_static= {37729, 8014} count Apparent clock speed: 1958 MHz TestCatchingFault: Measuring real time: 3.402 usec (3.40214e-06 sec) per iteration: 0.000340214 sec for 100 iterations 1.760 usec (1.76014e-06 sec) per iteration: 0.0517358 sec for 29393 iterations 1.805 usec (1.80461e-06 sec) per iteration: 0.053043 sec for 29393 iterations 1.758 usec (1.75841e-06 sec) per iteration: 0.051685 sec for 29393 iterations 1.790 usec (1.78968e-06 sec) per iteration: 0.052604 sec for 29393 iterations 1.760 usec (1.75962e-06 sec) per iteration: 0.0517205 sec for 29393 iterations mean: 1.774493 usec stddev: 0.019098 usec relative stddev: 1.08% RESULT TestCatchingFault: x86-64_nnacl_newlib_static= {1.774493, 0.019098} us Measuring clock cycles: first runs (cycles): 5007 3986 3757 3751 3891 3749 3759 3753 3786 3746 ... slowest runs (cycles): ... 3918 3922 3938 3986 4034 4079 4217 4314 5007 25329 min: 3693 cycles q1: 3742 cycles median: 3753 cycles q3: 3775 cycles max: 25329 cycles RESULT TestCatchingFault_CycleCount: x86-64_nnacl_newlib_static= {3753, 33} count Apparent clock speed: 2115 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 55.883 usec (5.58827e-05 sec) per iteration: 0.00558827 sec for 100 iterations 58.704 usec (5.8704e-05 sec) per iteration: 0.105021 sec for 1789 iterations 52.381 usec (5.23808e-05 sec) per iteration: 0.0937093 sec for 1789 iterations 54.182 usec (5.41818e-05 sec) per iteration: 0.0969313 sec for 1789 iterations 51.565 usec (5.1565e-05 sec) per iteration: 0.0922499 sec for 1789 iterations 49.806 usec (4.98063e-05 sec) per iteration: 0.0891035 sec for 1789 iterations mean: 53.327603 usec stddev: 3.034405 usec relative stddev: 5.69% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_nnacl_newlib_static= {53.327603, 3.034405} us Measuring clock cycles: first runs (cycles): 138916 135304 198630 124456 64811 243840 95197 74492 90669 53676 ... slowest runs (cycles): ... 195042 198630 204373 207396 212676 228567 243840 251238 260209 273003 min: 49333 cycles q1: 69868 cycles median: 95015 cycles q3: 131078 cycles max: 273003 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_nnacl_newlib_static= {95015, 61210} count Apparent clock speed: 1782 MHz [ OK ] nacl_newlib.run_performance_test (5960 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