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 luci-flex-try-trusty-0-mlr5 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: 13739436 kB MemAvailable: 29060912 kB ====================================================================== LOAD: ====================================================================== 1.84 2.31 2.75 1/422 25783 ====================================================================== UPTIME: ====================================================================== 28513.68 187260.12 ====================================================================== The following environments have been configured ====================================================================== NACL_BUILD_FAMILY: UNTRUSTED BUILD_TYPE: nacl TARGET_ROOT: /b/s/w/ir/k/native_client/scons-out/nacl-x86-64 OBJ_ROOT: /b/s/w/ir/k/native_client/scons-out/nacl-x86-64/obj BUILD_TYPE_DESCRIPTION: NaCl module build BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 64 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 64 CC: /b/s/w/ir/k/native_client/toolchain/linux_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /b/s/w/ir/k/native_client/toolchain/linux_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang -m64 -D__STDC_LIMIT_MACROS=1 -D__STDC_FORMAT_MACROS=1 -D_GNU_SOURCE=1 -D_POSIX_C_SOURCE=199506 -D_XOPEN_SOURCE=600 -DDYNAMIC_ANNOTATIONS_ENABLED=1 -DDYNAMIC_ANNOTATIONS_PREFIX=NACL_ -DNACL_BUILD_ARCH=x86 -DNACL_BUILD_SUBARCH=64 -I/b/s/w/ir/k/native_client/scons-out/nacl-x86-64/include -I/b/s/w/ir/k -c -o UNKNOWN COMPILER NACL_BUILD_FAMILY: TRUSTED BUILD_TYPE: opt-linux TARGET_ROOT: /b/s/w/ir/k/native_client/scons-out/opt-linux-x86-64 OBJ_ROOT: /b/s/w/ir/k/native_client/scons-out/opt-linux-x86-64/obj BUILD_TYPE_DESCRIPTION: Linux opt build BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 64 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 64 CC: gcc ASPPCOM: gcc -fPIE -m64 -Wall -pedantic -Wextra -Wno-long-long -Wswitch-enum -Wsign-compare -Wundef -fdiagnostics-show-option -fvisibility=hidden -fstack-protector -Werror -Wno-variadic-macros --param ssp-buffer-size=4 -O2 -D_FORTIFY_SOURCE=2 -D_POSIX_C_SOURCE=199506 -D_XOPEN_SOURCE=600 -D_GNU_SOURCE=1 -D_LARGEFILE64_SOURCE=1 -D__STDC_LIMIT_MACROS=1 -D__STDC_FORMAT_MACROS=1 -DNDEBUG -I/b/s/w/ir/k -c -o set BOTO_CONFIG='/home/chrome-bot/.boto' BUILDBOT_BUILDERNAME='nacl-toolchain-linux-pnacl-x86_64' BUILDBOT_BUILDNUMBER='4222' 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='45556' 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/8918893124208863024' LOGDOG_STREAM_PROJECT='nacl' LOGDOG_STREAM_SERVER_PATH='unix:/b/s/w/ir/tmp/ld.sock' LOGNAME='chrome-bot' LUCI_CONTEXT='/b/s/w/itzxusvR/luci_context.364658052' 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='25756' PS1='$ ' PS2='> ' PS4='+ ' PWD='/b/s/w/ir/k/native_client' PYTHONDONTWRITEBYTECODE='1' PYTHONIOENCODING='UTF-8' PYTHONNOUSERSITE='1' PYTHONPATH='/b/s/w/ir/kitchen-checkout/build/scripts:/b/s/w/ir/kitchen-checkout/build/site_config' PYTHONUNBUFFERED='1' QEMU_PREFIX_HOOK='' RUNTEST='/b/s/w/ir/kitchen-checkout/build/scripts/slave/runtest.py' SHELL='/bin/bash' SHLVL='2' SUDO_COMMAND='/usr/bin/python /b/s/swarming_bot.zip start_bot' SUDO_GID='0' SUDO_UID='0' SUDO_USER='root' SWARMING_BOT_ID='luci-flex-try-trusty-0-mlr5' SWARMING_EXTERNAL_BOT_SETUP='1' SWARMING_HEADLESS='1' SWARMING_SERVER='https://chromium-swarm.appspot.com' SWARMING_TASK_ID='439b9f624713af11' TEMP='/b/s/w/ir/tmp/t/nacl_tmp/nacl-toolchain-linux-pnacl-x86_64' TEMPDIR='/b/s/w/ir/tmp/t' TERM='linux' TMP='/b/s/w/ir/tmp/t/nacl_tmp/nacl-toolchain-linux-pnacl-x86_64' TMPDIR='/b/s/w/ir/tmp/t/nacl_tmp/nacl-toolchain-linux-pnacl-x86_64' USER='chrome-bot' USERNAME='chrome-bot' VIRTUAL_ENV='/b/s/w/ir/cache/vpython/b574a6' VPYTHON_VIRTUALENV_ROOT='/b/s/w/ir/cache/vpython' _='buildbot/buildbot_pnacl.py' gcc -v -c Using built-in specs. COLLECT_GCC=gcc Target: x86_64-linux-gnu Configured with: ../src/configure -v --with-pkgversion='Ubuntu 4.8.4-2ubuntu1~14.04.4' --with-bugurl=file:///usr/share/doc/gcc-4.8/README.Bugs --enable-languages=c,c++,java,go,d,fortran,objc,obj-c++ --prefix=/usr --program-suffix=-4.8 --enable-shared --enable-linker-build-id --libexecdir=/usr/lib --without-included-gettext --enable-threads=posix --with-gxx-include-dir=/usr/include/c++/4.8 --libdir=/usr/lib --enable-nls --with-sysroot=/ --enable-clocale=gnu --enable-libstdcxx-debug --enable-libstdcxx-time=yes --enable-gnu-unique-object --disable-libmudflap --enable-plugin --with-system-zlib --disable-browser-plugin --enable-java-awt=gtk --enable-gtk-cairo --with-java-home=/usr/lib/jvm/java-1.5.0-gcj-4.8-amd64/jre --enable-java-home --with-jvm-root-dir=/usr/lib/jvm/java-1.5.0-gcj-4.8-amd64 --with-jvm-jar-dir=/usr/lib/jvm-exports/java-1.5.0-gcj-4.8-amd64 --with-arch-directory=amd64 --with-ecj-jar=/usr/share/java/eclipse-ecj.jar --enable-objc-gc --enable-multiarch --disable-werror --with-arch-32=i686 --with-abi=m64 --with-multilib-list=m32,m64,mx32 --with-tune=generic --enable-checking=release --build=x86_64-linux-gnu --host=x86_64-linux-gnu --target=x86_64-linux-gnu Thread model: posix gcc version 4.8.4 (Ubuntu 4.8.4-2ubuntu1~14.04.4) gcc -print-search-dirs install: /usr/lib/gcc/x86_64-linux-gnu/4.8/ programs: =/usr/lib/gcc/x86_64-linux-gnu/4.8/:/usr/lib/gcc/x86_64-linux-gnu/4.8/:/usr/lib/gcc/x86_64-linux-gnu/:/usr/lib/gcc/x86_64-linux-gnu/4.8/:/usr/lib/gcc/x86_64-linux-gnu/:/usr/lib/gcc/x86_64-linux-gnu/4.8/../../../../x86_64-linux-gnu/bin/x86_64-linux-gnu/4.8/:/usr/lib/gcc/x86_64-linux-gnu/4.8/../../../../x86_64-linux-gnu/bin/x86_64-linux-gnu/:/usr/lib/gcc/x86_64-linux-gnu/4.8/../../../../x86_64-linux-gnu/bin/ libraries: =/usr/lib/gcc/x86_64-linux-gnu/4.8/:/usr/lib/gcc/x86_64-linux-gnu/4.8/../../../../x86_64-linux-gnu/lib/x86_64-linux-gnu/4.8/:/usr/lib/gcc/x86_64-linux-gnu/4.8/../../../../x86_64-linux-gnu/lib/x86_64-linux-gnu/:/usr/lib/gcc/x86_64-linux-gnu/4.8/../../../../x86_64-linux-gnu/lib/../lib/:/usr/lib/gcc/x86_64-linux-gnu/4.8/../../../x86_64-linux-gnu/4.8/:/usr/lib/gcc/x86_64-linux-gnu/4.8/../../../x86_64-linux-gnu/:/usr/lib/gcc/x86_64-linux-gnu/4.8/../../../../lib/:/lib/x86_64-linux-gnu/4.8/:/lib/x86_64-linux-gnu/:/lib/../lib/:/usr/lib/x86_64-linux-gnu/4.8/:/usr/lib/x86_64-linux-gnu/:/usr/lib/../lib/:/usr/lib/gcc/x86_64-linux-gnu/4.8/../../../../x86_64-linux-gnu/lib/:/usr/lib/gcc/x86_64-linux-gnu/4.8/../../../:/lib/:/usr/lib/ gcc -print-libgcc-file-name /usr/lib/gcc/x86_64-linux-gnu/4.8/libgcc.a NACL_BUILD_FAMILY: UNTRUSTED_IRT BUILD_TYPE: nacl_irt TARGET_ROOT: /b/s/w/ir/k/native_client/scons-out/nacl_irt-x86-64 OBJ_ROOT: /b/s/w/ir/k/native_client/scons-out/nacl_irt-x86-64/obj BUILD_TYPE_DESCRIPTION: NaCl IRT build BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 64 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 64 CC: /b/s/w/ir/k/native_client/toolchain/linux_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /b/s/w/ir/k/native_client/toolchain/linux_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang -m64 -D__STDC_LIMIT_MACROS=1 -D__STDC_FORMAT_MACROS=1 -D_GNU_SOURCE=1 -D_POSIX_C_SOURCE=199506 -D_XOPEN_SOURCE=600 -DDYNAMIC_ANNOTATIONS_ENABLED=1 -DDYNAMIC_ANNOTATIONS_PREFIX=NACL_ -DNACL_BUILD_ARCH=x86 -DNACL_BUILD_SUBARCH=64 -I/b/s/w/ir/k/native_client/scons-out/nacl_irt-x86-64/include -I/b/s/w/ir/k -I/b/s/w/ir/k/native_client/src/untrusted/pthread -c -o UNKNOWN COMPILER *** BROKEN run_ncval_annotate_test *** BROKEN run_gdb_invalid_memory_test *** BROKEN run_nanosleep_test *** BROKEN run_timefuncs_test *** BROKEN run_raw_timefuncs_test *** BROKEN run_main_thread_pthread_exit_test2 *** BROKEN run_overflowinline_test *** BROKEN run_stack_frame_noopt_frame_test *** BROKEN run_stack_frame_noopt_noframe_test *** BROKEN run_stack_frame_opt_frame_test *** BROKEN run_stack_frame_opt_noframe_test *** BROKEN run_vla_test *** SKIPPING opt-linux-64 : run_package_version_tests *** SKIPPING opt-linux-64 : run_pynacl_tests *** SKIPPING opt-linux-64 : run_gio_test *** SKIPPING opt-linux-64 : run_gprintf_test *** SKIPPING opt-linux-64 : run_sigpipe_test *** SKIPPING opt-linux-64 : run_platform_tests *** SKIPPING opt-linux-64 : run_nacl_semaphore_test *** SKIPPING opt-linux-64 : run_nacl_host_dir_test *** SKIPPING opt-linux-64 : run_nacl_clock_test *** SKIPPING opt-linux-64 : run_nacl_clock_cputime_test *** SKIPPING opt-linux-64 : run_nacl_sync_lock_lock_test *** SKIPPING opt-linux-64 : run_nacl_sync_lock_trylock_test *** SKIPPING opt-linux-64 : run_nacl_sync_trylock_lock_test *** SKIPPING opt-linux-64 : run_nacl_sync_trylock_trylock_test *** SKIPPING opt-linux-64 : run_nacl_host_desc_mmap_test *** SKIPPING opt-linux-64 : run_nacl_host_desc_pread_pwrite_test *** SKIPPING opt-linux-64 : run_nacl_host_desc_big_file_test *** SKIPPING opt-linux-64 : run_cpu_x86_test *** SKIPPING opt-linux-64 : run_gdb_rsp_tests *** SKIPPING opt-linux-64 : run_transport_ipc_test *** SKIPPING opt-linux-64 : run_nacl_desc_quota_test *** SKIPPING opt-linux-64 : run_metadata_test *** SKIPPING opt-linux-64 : run_nacl_desc_io_alloc_ctor_test *** SKIPPING opt-linux-64 : run_nacl_fi_test *** SKIPPING opt-linux-64 : run_nacl_interval_list_test *** SKIPPING opt-linux-64 : run_nacl_interval_tree_test *** SKIPPING opt-linux-64 : run_nacl_interval_list_random_test *** SKIPPING opt-linux-64 : run_nacl_interval_tree_random_test *** SKIPPING opt-linux-64 : run_nacl_interval_multi_test *** SKIPPING opt-linux-64 : run_nacl_interval_multi_reg_test *** SKIPPING opt-linux-64 : run_nacl_base_test *** SKIPPING opt-linux-64 : run_nacl_min_max_test *** SKIPPING opt-linux-64 : run_nacl_min_max_cpp_test *** SKIPPING opt-linux-64 : run_nacl_perf_counter_test *** SKIPPING opt-linux-64 : run_platform_qual_test *** SKIPPING opt-linux-64 : run_cpuwhitelist_test *** SKIPPING opt-linux-64 : run_service_runtime_tests *** SKIPPING opt-linux-64 : run_format_string_test *** SKIPPING opt-linux-64 : run_trusted_mmap_test *** SKIPPING opt-linux-64 : run_nacl_bootstrap_prereservation_test *** BROKEN run_nacl_sync_cond_test *** SKIPPING opt-linux-64 : run_env_cleanser_test *** SKIPPING opt-linux-64 : run_nacl_resource_test *** SKIPPING opt-linux-64 : run_nacl_signal_test *** SKIPPING opt-linux-64 : run_signal_frame_test *** SKIPPING opt-linux-64 : run_check_test *** SKIPPING opt-linux-64 : run_check_test_death *** SKIPPING opt-linux-64 : run_dcheck_test_death *** SKIPPING opt-linux-64 : run_check_test_always_death *** SKIPPING opt-linux-64 : run_check_test_always_death_abort *** SKIPPING opt-linux-64 : run_dcheck_test_never_death *** SKIPPING opt-linux-64 : run_dcheck_test_always_death *** SKIPPING opt-linux-64 : run_nacl_tls_unittest *** SKIPPING opt-linux-64 : run_sel_ldr_exe_not_found_test *** SKIPPING opt-linux-64 : run_fuzz_nullptr_test *** SKIPPING opt-linux-64 : run_unaligned_data_test *** SKIPPING opt-linux-64 : run_unaligned_data_irt_test *** SKIPPING opt-linux-64 : run_hello_x32_test *** SKIPPING opt-linux-64 : run_service_runtime_hello_world_test *** SKIPPING opt-linux-64 : run_old_abi_death_test *** SKIPPING opt-linux-64 : run_integer_overflow_while_madvising_death_test *** SKIPPING opt-linux-64 : run_negative_hole_death_test *** SKIPPING opt-linux-64 : run_rodata_data_overlap_death_test *** SKIPPING opt-linux-64 : run_data_not_last_death_test SKIPPING test text_overlaps_rodata SKIPPING test text_overlaps_data *** SKIPPING opt-linux-64 : run_text_too_big_death_test *** SKIPPING opt-linux-64 : run_sel_ldr_thread_death_test *** SKIPPING opt-linux-64 : run_nacl_error_gio_test *** SKIPPING opt-linux-64 : run_nacl_error_log_test *** SKIPPING opt-linux-64 : run_dyn_array_test *** SKIPPING opt-linux-64 : run_validation_cache_test *** SKIPPING opt-linux-64 : run_validator_debug_stub_test *** SKIPPING opt-linux-64 : run_disable_nontemporals_test *** SKIPPING opt-linux-64 : run_validation_rewrite_test *** SKIPPING opt-linux-64 : run_gen_dfa_test *** SKIPPING opt-linux-64 : run_trie_test *** SKIPPING opt-linux-64 : run_proof_tools_test *** SKIPPING opt-linux-64 : run_validator_py_test *** SKIPPING opt-linux-64 : run_rdfa_targeted_tests_32 *** SKIPPING opt-linux-64 : run_dis_section_test_32 *** SKIPPING opt-linux-64 : run_spec_val_test_32 *** SKIPPING opt-linux-64 : run_rdfa_targeted_tests_64 *** SKIPPING opt-linux-64 : run_dis_section_test_64 *** SKIPPING opt-linux-64 : run_spec_val_test_64 *** SKIPPING opt-linux-64 : run_nacl_file_lock_all_test *** SKIPPING opt-linux-64 : run_nacl_file_lock_any_test *** SKIPPING opt-linux-64 : run_nacl_file_lock_t3f2_test *** SKIPPING opt-linux-64 : run_nacl_file_lock_t3f3_test *** SKIPPING opt-linux-64 : run_nacl_file_lock_all_real_test *** SKIPPING opt-linux-64 : run_nacl_file_lock_any_real_test *** SKIPPING opt-linux-64 : run_nacl_file_lock_t3f2_real_test *** SKIPPING opt-linux-64 : run_nacl_file_lock_t3f3_real_test *** SKIPPING opt-linux-64 : run_trusted_performance_test *** SKIPPING opt-linux-64 : run_python_version_test *** SKIPPING opt-linux-64 : run_seccomp_open_errno_test *** SKIPPING opt-linux-64 : run_seccomp_check_arch_test *** SKIPPING opt-linux-64 : run_tool_tests *** SKIPPING opt-linux-64 : run_nacl_close_test *** SKIPPING opt-linux-64 : run_double_nacl_close_test *** SKIPPING opt-linux-64 : run_nacl_create_memory_object_test *** SKIPPING opt-linux-64 : run_nacl_thread_create_joinable_test *** SKIPPING opt-linux-64 : run_atomic_ops_test *** SKIPPING opt-linux-64 : run_bits_test *** SKIPPING opt-linux-64 : run_kernel_version_test *** SKIPPING opt-linux-64 : run_nacl_memory_test *** SKIPPING opt-linux-64 : run_toolchain_python_tests *** SKIPPING nacl_irt-64 : run_irt_thread_test There are 13 broken tests. ====================================================================== B U I L D - O U T P U T: ====================================================================== scons: done reading SConscript files. scons: Building targets ... /usr/bin/python /b/s/w/ir/k/native_client/tools/command_tester.py --name nacl_newlib.run_signal_handler_single_step_test --time_warning 2 --time_error 20 --perf_env_description x86-64_nnacl_newlib_static --subarch 64 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/k/native_client/scons-out/nacl-x86-64/test_results/signal_handler_single_step.out /b/s/w/ir/k/native_client/scons-out/nacl-x86-64/obj/tests/signal_handler_single_step/step_test_host /b/s/w/ir/k/native_client/scons-out/nacl-x86-64/obj/tests/signal_handler_single_step/step_test_guest.nexe x86-64_nnacl_newlib_static [ RUN ] nacl_newlib.run_signal_handler_single_step_test /b/s/w/ir/k/native_client/scons-out/nacl-x86-64/obj/tests/signal_handler_single_step/step_test_host /b/s/w/ir/k/native_client/scons-out/nacl-x86-64/obj/tests/signal_handler_single_step/step_test_guest.nexe x86-64_nnacl_newlib_static [25798,1868785536:16:28:17.941481] Native Client module will be loaded at base address 0x0000614500000000 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 [25803,1824143232:16:28:18.224241] Native Client module will be loaded at base address 0x0000237500000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_nnacl_newlib_static= {0.101660, 0.006580} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test (1600 ms) /usr/bin/python tools/command_tester.py --name nacl_newlib.run_clock_cputime_test --time_warning 60 --time_error 600 --perf_env_description x86-64_nnacl_newlib_static --using_nacl_signal_handler True --subarch 64 --arch x86 --output_stamp /b/s/w/ir/k/native_client/scons-out/nacl-x86-64/test_results/clock_cputime_test.out scons-out/opt-linux-x86-64/staging/nacl_helper_bootstrap scons-out/opt-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -- scons-out/nacl-x86-64/obj/tests/clock/clock_test.nexe -c [ RUN ] nacl_newlib.run_clock_cputime_test scons-out/opt-linux-x86-64/staging/nacl_helper_bootstrap scons-out/opt-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -- scons-out/nacl-x86-64/obj/tests/clock/clock_test.nexe -c WARNING: should have taken less than 60.000000 secs [ OK ] nacl_newlib.run_clock_cputime_test (75430 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 [25833,2678978432:16:28:33.807173] Native Client module will be loaded at base address 0x0000461200000000 TestNull: Measuring real time: 0.006 usec (5.56e-09 sec) per iteration: 5.56e-07 sec for 100 iterations 0.002 usec (2.046e-09 sec) per iteration: 2.046e-06 sec for 1000 iterations 0.002 usec (1.8747e-09 sec) per iteration: 1.8747e-05 sec for 10000 iterations 0.002 usec (1.88636e-09 sec) per iteration: 0.100622 sec for 53341868 iterations 0.002 usec (1.88809e-09 sec) per iteration: 0.100714 sec for 53341868 iterations 0.002 usec (1.88221e-09 sec) per iteration: 0.100401 sec for 53341868 iterations 0.002 usec (1.88319e-09 sec) per iteration: 0.100453 sec for 53341868 iterations 0.002 usec (1.94243e-09 sec) per iteration: 0.103613 sec for 53341868 iterations mean: 0.001896 usec stddev: 0.000023 usec relative stddev: 1.22% RESULT TestNull: x86-64_nnacl_newlib_static= {0.001896, 0.000023} us Measuring clock cycles: first runs (cycles): 110 26 24 26 27 25 27 22 22 24 ... slowest runs (cycles): ... 27 27 27 28 28 28 28 29 29 110 min: 22 cycles q1: 24 cycles median: 25 cycles q3: 26 cycles max: 110 cycles RESULT TestNull_CycleCount: x86-64_nnacl_newlib_static= {25, 2} count Apparent clock speed: 13182 MHz TestNaClSyscall: Measuring real time: 0.128 usec (1.2772e-07 sec) per iteration: 1.2772e-05 sec for 100 iterations 0.126 usec (1.26327e-07 sec) per iteration: 0.0989093 sec for 782962 iterations 0.131 usec (1.30642e-07 sec) per iteration: 0.102288 sec for 782962 iterations 0.125 usec (1.25188e-07 sec) per iteration: 0.0980171 sec for 782962 iterations 0.121 usec (1.21417e-07 sec) per iteration: 0.0950648 sec for 782962 iterations 0.120 usec (1.19762e-07 sec) per iteration: 0.0937689 sec for 782962 iterations mean: 0.124667 usec stddev: 0.003830 usec relative stddev: 3.07% RESULT TestNaClSyscall: x86-64_nnacl_newlib_static= {0.124667, 0.003830} us Measuring clock cycles: first runs (cycles): 304 196 170 171 176 174 173 174 174 176 ... slowest runs (cycles): ... 176 176 176 176 176 178 183 196 212 304 min: 169 cycles q1: 172 cycles median: 174 cycles q3: 174 cycles max: 304 cycles RESULT TestNaClSyscall_CycleCount: x86-64_nnacl_newlib_static= {174, 2} count Apparent clock speed: 1396 MHz TestSetjmpLongjmp: Measuring real time: 0.015 usec (1.542e-08 sec) per iteration: 1.542e-06 sec for 100 iterations 0.013 usec (1.2754e-08 sec) per iteration: 1.2754e-05 sec for 1000 iterations 0.013 usec (1.28193e-08 sec) per iteration: 0.100512 sec for 7840677 iterations 0.013 usec (1.28391e-08 sec) per iteration: 0.100668 sec for 7840677 iterations 0.013 usec (1.2822e-08 sec) per iteration: 0.100533 sec for 7840677 iterations 0.013 usec (1.28542e-08 sec) per iteration: 0.100786 sec for 7840677 iterations 0.013 usec (1.28343e-08 sec) per iteration: 0.10063 sec for 7840677 iterations mean: 0.012834 usec stddev: 0.000013 usec relative stddev: 0.10% RESULT TestSetjmpLongjmp: x86-64_nnacl_newlib_static= {0.012834, 0.000013} us Measuring clock cycles: first runs (cycles): 209 58 39 33 35 33 31 26 26 29 ... slowest runs (cycles): ... 29 29 31 31 33 33 35 39 58 209 min: 24 cycles q1: 26 cycles median: 27 cycles q3: 29 cycles max: 209 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_nnacl_newlib_static= {27, 3} count Apparent clock speed: 2104 MHz TestClockGetTime: Measuring real time: 0.159 usec (1.5858e-07 sec) per iteration: 1.5858e-05 sec for 100 iterations 0.154 usec (1.5443e-07 sec) per iteration: 0.0973828 sec for 630596 iterations 0.153 usec (1.5265e-07 sec) per iteration: 0.0962602 sec for 630596 iterations 0.152 usec (1.51576e-07 sec) per iteration: 0.0955835 sec for 630596 iterations 0.152 usec (1.52478e-07 sec) per iteration: 0.0961523 sec for 630596 iterations 0.153 usec (1.52741e-07 sec) per iteration: 0.0963181 sec for 630596 iterations mean: 0.152775 usec stddev: 0.000925 usec relative stddev: 0.61% RESULT TestClockGetTime: x86-64_nnacl_newlib_static= {0.152775, 0.000925} us Measuring clock cycles: first runs (cycles): 686 418 407 451 412 401 407 410 403 405 ... slowest runs (cycles): ... 403 404 405 407 407 410 412 418 451 686 min: 387 cycles q1: 389 cycles median: 390 cycles q3: 392 cycles max: 686 cycles RESULT TestClockGetTime_CycleCount: x86-64_nnacl_newlib_static= {390, 3} count Apparent clock speed: 2553 MHz TestTlsVariable: Measuring real time: 0.009 usec (8.77e-09 sec) per iteration: 8.77e-07 sec for 100 iterations 0.006 usec (5.759e-09 sec) per iteration: 5.759e-06 sec for 1000 iterations 0.006 usec (5.581e-09 sec) per iteration: 5.581e-05 sec for 10000 iterations 0.006 usec (5.66636e-09 sec) per iteration: 0.101529 sec for 17917935 iterations 0.006 usec (5.66353e-09 sec) per iteration: 0.101479 sec for 17917935 iterations 0.006 usec (5.64675e-09 sec) per iteration: 0.101178 sec for 17917935 iterations 0.006 usec (5.66366e-09 sec) per iteration: 0.101481 sec for 17917935 iterations 0.006 usec (5.81871e-09 sec) per iteration: 0.104259 sec for 17917935 iterations mean: 0.005692 usec stddev: 0.000064 usec relative stddev: 1.12% RESULT TestTlsVariable: x86-64_nnacl_newlib_static= {0.005692, 0.000064} us Measuring clock cycles: first runs (cycles): 160 77 57 62 26 33 33 26 31 25 ... slowest runs (cycles): ... 33 33 33 33 33 33 57 62 77 160 min: 24 cycles q1: 26 cycles median: 28 cycles q3: 31 cycles max: 160 cycles RESULT TestTlsVariable_CycleCount: x86-64_nnacl_newlib_static= {28, 5} count Apparent clock speed: 4919 MHz TestMmapAnonymous: Measuring real time: 2.098 usec (2.09801e-06 sec) per iteration: 0.000209801 sec for 100 iterations 1.812 usec (1.81172e-06 sec) per iteration: 0.086354 sec for 47664 iterations 1.854 usec (1.85397e-06 sec) per iteration: 0.0883676 sec for 47664 iterations 1.802 usec (1.80194e-06 sec) per iteration: 0.0858878 sec for 47664 iterations 1.803 usec (1.80287e-06 sec) per iteration: 0.0859322 sec for 47664 iterations 1.800 usec (1.80034e-06 sec) per iteration: 0.0858116 sec for 47664 iterations mean: 1.814171 usec stddev: 0.020289 usec relative stddev: 1.12% RESULT TestMmapAnonymous: x86-64_nnacl_newlib_static= {1.814171, 0.020289} us Measuring clock cycles: first runs (cycles): 6184 4087 4028 3937 3945 3973 3953 3953 3966 3955 ... slowest runs (cycles): ... 4028 4048 4052 4087 4096 4111 4294 5680 5999 6184 min: 3833 cycles q1: 3887 cycles median: 3920 cycles q3: 3953 cycles max: 6184 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_nnacl_newlib_static= {3920, 66} count Apparent clock speed: 2161 MHz TestAtomicIncrement: Measuring real time: 0.018 usec (1.842e-08 sec) per iteration: 1.842e-06 sec for 100 iterations 0.011 usec (1.0689e-08 sec) per iteration: 1.0689e-05 sec for 1000 iterations 0.009 usec (9.44235e-09 sec) per iteration: 0.088337 sec for 9355412 iterations 0.009 usec (9.40378e-09 sec) per iteration: 0.0879763 sec for 9355412 iterations 0.009 usec (9.44363e-09 sec) per iteration: 0.088349 sec for 9355412 iterations 0.009 usec (9.41286e-09 sec) per iteration: 0.0880612 sec for 9355412 iterations 0.009 usec (9.40423e-09 sec) per iteration: 0.0879804 sec for 9355412 iterations mean: 0.009421 usec stddev: 0.000018 usec relative stddev: 0.19% RESULT TestAtomicIncrement: x86-64_nnacl_newlib_static= {0.009421, 0.000018} us Measuring clock cycles: first runs (cycles): 115 53 51 53 50 50 52 52 50 51 ... slowest runs (cycles): ... 53 53 53 53 53 53 53 53 55 115 min: 50 cycles q1: 51 cycles median: 53 cycles q3: 53 cycles max: 115 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_nnacl_newlib_static= {53, 2} count Apparent clock speed: 5626 MHz TestUncontendedMutexLock: Measuring real time: 0.035 usec (3.506e-08 sec) per iteration: 3.506e-06 sec for 100 iterations 0.026 usec (2.5982e-08 sec) per iteration: 2.5982e-05 sec for 1000 iterations 0.024 usec (2.44804e-08 sec) per iteration: 0.0942208 sec for 3848818 iterations 0.024 usec (2.44337e-08 sec) per iteration: 0.0940409 sec for 3848818 iterations 0.025 usec (2.4506e-08 sec) per iteration: 0.0943193 sec for 3848818 iterations 0.024 usec (2.44485e-08 sec) per iteration: 0.0940978 sec for 3848818 iterations 0.024 usec (2.44596e-08 sec) per iteration: 0.0941406 sec for 3848818 iterations mean: 0.024466 usec stddev: 0.000025 usec relative stddev: 0.10% RESULT TestUncontendedMutexLock: x86-64_nnacl_newlib_static= {0.024466, 0.000025} us Measuring clock cycles: first runs (cycles): 235 95 82 81 82 82 83 81 84 81 ... slowest runs (cycles): ... 83 83 83 83 84 84 84 90 95 235 min: 81 cycles q1: 81 cycles median: 82 cycles q3: 82 cycles max: 235 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_nnacl_newlib_static= {82, 1} count Apparent clock speed: 3352 MHz TestCondvarSignalNoOp: Measuring real time: 0.245 usec (2.4532e-07 sec) per iteration: 2.4532e-05 sec for 100 iterations 0.212 usec (2.12382e-07 sec) per iteration: 0.0865733 sec for 407630 iterations 0.212 usec (2.11685e-07 sec) per iteration: 0.0862891 sec for 407630 iterations 0.213 usec (2.12548e-07 sec) per iteration: 0.086641 sec for 407630 iterations 0.212 usec (2.11954e-07 sec) per iteration: 0.0863989 sec for 407630 iterations 0.212 usec (2.11614e-07 sec) per iteration: 0.0862602 sec for 407630 iterations mean: 0.212037 usec stddev: 0.000371 usec relative stddev: 0.18% RESULT TestCondvarSignalNoOp: x86-64_nnacl_newlib_static= {0.212037, 0.000371} us Measuring clock cycles: first runs (cycles): 785 590 524 523 524 524 523 521 524 528 ... slowest runs (cycles): ... 528 528 528 528 528 528 530 530 590 785 min: 521 cycles q1: 524 cycles median: 526 cycles q3: 526 cycles max: 785 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_nnacl_newlib_static= {526, 2} count Apparent clock speed: 2481 MHz TestThreadCreateAndJoin: Measuring real time: 34.151 usec (3.41509e-05 sec) per iteration: 0.00341509 sec for 100 iterations 31.266 usec (3.12663e-05 sec) per iteration: 0.0915478 sec for 2928 iterations 32.031 usec (3.20315e-05 sec) per iteration: 0.0937882 sec for 2928 iterations 27.076 usec (2.7076e-05 sec) per iteration: 0.0792784 sec for 2928 iterations 24.939 usec (2.49395e-05 sec) per iteration: 0.0730228 sec for 2928 iterations 25.894 usec (2.58937e-05 sec) per iteration: 0.0758166 sec for 2928 iterations mean: 28.241380 usec stddev: 2.873598 usec relative stddev: 10.18% RESULT TestThreadCreateAndJoin: x86-64_nnacl_newlib_static= {28.241380, 2.873598} us Measuring clock cycles: first runs (cycles): 79048 88762 52169 313529 68597 59795 50585 45833 46060 54395 ... slowest runs (cycles): ... 61498 67845 68597 73588 79048 80416 83245 88762 195637 313529 min: 40226 cycles q1: 42282 cycles median: 43407 cycles q3: 48356 cycles max: 313529 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_nnacl_newlib_static= {43407, 6074} count Apparent clock speed: 1537 MHz TestThreadWakeup: Measuring real time: 12.539 usec (1.25393e-05 sec) per iteration: 0.00125393 sec for 100 iterations 12.686 usec (1.26855e-05 sec) per iteration: 0.101154 sec for 7974 iterations 14.639 usec (1.46387e-05 sec) per iteration: 0.116729 sec for 7974 iterations 14.860 usec (1.48602e-05 sec) per iteration: 0.118495 sec for 7974 iterations 15.610 usec (1.56103e-05 sec) per iteration: 0.124477 sec for 7974 iterations 16.102 usec (1.61022e-05 sec) per iteration: 0.128399 sec for 7974 iterations mean: 14.779395 usec stddev: 1.170547 usec relative stddev: 7.92% RESULT TestThreadWakeup: x86-64_nnacl_newlib_static= {14.779395, 1.170547} us Measuring clock cycles: first runs (cycles): 38393 37270 36684 39229 37659 37663 37245 36684 39146 40120 ... slowest runs (cycles): ... 39902 40120 40455 40767 41918 43580 44405 47782 48235 53961 min: 33431 cycles q1: 34540 cycles median: 35087 cycles q3: 37659 cycles max: 53961 cycles RESULT TestThreadWakeup_CycleCount: x86-64_nnacl_newlib_static= {35087, 3119} count Apparent clock speed: 2374 MHz TestCatchingFault: Measuring real time: 2.853 usec (2.85339e-06 sec) per iteration: 0.000285339 sec for 100 iterations 1.737 usec (1.73747e-06 sec) per iteration: 0.0608913 sec for 35046 iterations 1.732 usec (1.73186e-06 sec) per iteration: 0.0606947 sec for 35046 iterations 1.818 usec (1.81825e-06 sec) per iteration: 0.0637223 sec for 35046 iterations 1.750 usec (1.75039e-06 sec) per iteration: 0.0613442 sec for 35046 iterations 1.767 usec (1.76682e-06 sec) per iteration: 0.06192 sec for 35046 iterations mean: 1.760957 usec stddev: 0.031070 usec relative stddev: 1.76% RESULT TestCatchingFault: x86-64_nnacl_newlib_static= {1.760957, 0.031070} us Measuring clock cycles: first runs (cycles): 5204 3931 3821 3823 3821 3823 3835 3832 3821 3821 ... slowest runs (cycles): ... 3832 3832 3833 3834 3834 3834 3835 3835 3931 5204 min: 3816 cycles q1: 3821 cycles median: 3825 cycles q3: 3830 cycles max: 5204 cycles RESULT TestCatchingFault_CycleCount: x86-64_nnacl_newlib_static= {3825, 9} count Apparent clock speed: 2172 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 57.170 usec (5.71702e-05 sec) per iteration: 0.00571702 sec for 100 iterations 44.570 usec (4.45701e-05 sec) per iteration: 0.0779531 sec for 1749 iterations 48.063 usec (4.80632e-05 sec) per iteration: 0.0840626 sec for 1749 iterations 46.721 usec (4.67211e-05 sec) per iteration: 0.0817151 sec for 1749 iterations 43.024 usec (4.30236e-05 sec) per iteration: 0.0752482 sec for 1749 iterations 45.495 usec (4.54946e-05 sec) per iteration: 0.0795701 sec for 1749 iterations mean: 45.574505 usec stddev: 1.733831 usec relative stddev: 3.80% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_nnacl_newlib_static= {45.574505, 1.733831} us Measuring clock cycles: first runs (cycles): 75087 135463 151257 73171 217190 121611 359779 134026 46352 37837 ... slowest runs (cycles): ... 129547 134026 135463 136723 137489 151257 217190 299897 304522 359779 min: 33606 cycles q1: 38063 cycles median: 43733 cycles q3: 72172 cycles max: 359779 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_nnacl_newlib_static= {43733, 34109} count Apparent clock speed: 960 MHz [ OK ] nacl_newlib.run_performance_test (6250 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