builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0080 starttime: 1469553985.12 results: failure (2) buildid: 20160726081124 builduid: 6e8ef183439b443d93c4813c5902f422 revision: a5eb9b47ea45aa1fbde63e49a5aa75ba207ebc67 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-26 10:26:25.118933) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-26 10:26:25.119386) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-26 10:26:25.119657) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-26 10:26:25.143456) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 10:26:25.143829) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KWCGVYbzI9/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.RUBWGtJenq/Listeners TMPDIR=/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-07-26 10:26:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 12.8M=0.001s 2016-07-26 10:26:25 (12.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.126451 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 10:26:25.292297) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 10:26:25.292607) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KWCGVYbzI9/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.RUBWGtJenq/Listeners TMPDIR=/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.020169 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 10:26:25.340470) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-26 10:26:25.340825) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev a5eb9b47ea45aa1fbde63e49a5aa75ba207ebc67 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev a5eb9b47ea45aa1fbde63e49a5aa75ba207ebc67 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KWCGVYbzI9/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.RUBWGtJenq/Listeners TMPDIR=/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-07-26 10:26:25,412 truncating revision to first 12 chars 2016-07-26 10:26:25,413 Setting DEBUG logging. 2016-07-26 10:26:25,413 attempt 1/10 2016-07-26 10:26:25,413 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/a5eb9b47ea45?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-26 10:26:26,906 unpacking tar archive at: autoland-a5eb9b47ea45/testing/mozharness/ program finished with exit code 0 elapsedTime=1.762758 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-26 10:26:27.178332) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-26 10:26:27.178645) ========= script_repo_revision: a5eb9b47ea45aa1fbde63e49a5aa75ba207ebc67 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-26 10:26:27.179039) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-26 10:26:27.179338) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-26 10:26:27.198049) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 2, elapsed: 1 hrs, 20 mins, 17 secs) (at 2016-07-26 10:26:27.198428) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KWCGVYbzI9/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.RUBWGtJenq/Listeners TMPDIR=/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 10:26:27 INFO - MultiFileLogger online at 20160726 10:26:27 in /builds/slave/test 10:26:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 10:26:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:26:27 INFO - {'append_to_log': False, 10:26:27 INFO - 'base_work_dir': '/builds/slave/test', 10:26:27 INFO - 'blob_upload_branch': 'Autoland', 10:26:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:26:27 INFO - 'branch': 'Autoland', 10:26:27 INFO - 'buildbot_json_path': 'buildprops.json', 10:26:27 INFO - 'config_files': ('talos/mac_config.py',), 10:26:27 INFO - 'default_actions': ('clobber', 10:26:27 INFO - 'read-buildbot-config', 10:26:27 INFO - 'download-and-extract', 10:26:27 INFO - 'populate-webroot', 10:26:27 INFO - 'create-virtualenv', 10:26:27 INFO - 'install', 10:26:27 INFO - 'run-tests'), 10:26:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:26:27 INFO - 'download_minidump_stackwalk': True, 10:26:27 INFO - 'download_symbols': 'ondemand', 10:26:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:26:27 INFO - 'tooltool.py': '/tools/tooltool.py', 10:26:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:26:27 INFO - '/tools/misc-python/virtualenv.py')}, 10:26:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:26:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:26:27 INFO - 'installer_path': 'installer.exe', 10:26:27 INFO - 'log_level': 'info', 10:26:27 INFO - 'log_name': 'talos', 10:26:27 INFO - 'log_to_console': True, 10:26:27 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:26:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:26:27 INFO - 'opt_config_files': (), 10:26:27 INFO - 'pip_index': False, 10:26:27 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:26:27 INFO - 'cmd': ('bash', 10:26:27 INFO - '-c', 10:26:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:26:27 INFO - 'enabled': True, 10:26:27 INFO - 'halt_on_failure': False, 10:26:27 INFO - 'name': 'check_screen_resolution'},), 10:26:27 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:26:27 INFO - 'cmd': ('bash', 10:26:27 INFO - '-c', 10:26:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:26:27 INFO - 'enabled': True, 10:26:27 INFO - 'halt_on_failure': False, 10:26:27 INFO - 'name': 'check_screen_resolution'},), 10:26:27 INFO - 'run_cmd_checks_enabled': True, 10:26:27 INFO - 'sps_profile': False, 10:26:27 INFO - 'sps_profile_interval': 0, 10:26:27 INFO - 'suite': 'dromaeojs-e10s', 10:26:27 INFO - 'system_bits': '32', 10:26:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:26:27 INFO - 'title': 't-yosemite-r7-0080', 10:26:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:26:27 INFO - 'use_talos_json': True, 10:26:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:26:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:26:27 INFO - 'work_dir': 'build'} 10:26:27 INFO - [mozharness: 2016-07-26 17:26:27.387505Z] Running clobber step. 10:26:27 INFO - Running pre-action listener: _resource_record_pre_action 10:26:27 INFO - Running main action method: clobber 10:26:27 INFO - rmtree: /builds/slave/test/build 10:26:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:26:28 INFO - Running post-action listener: _resource_record_post_action 10:26:28 INFO - [mozharness: 2016-07-26 17:26:28.002067Z] Finished clobber step (success) 10:26:28 INFO - [mozharness: 2016-07-26 17:26:28.002360Z] Running read-buildbot-config step. 10:26:28 INFO - Running pre-action listener: _resource_record_pre_action 10:26:28 INFO - Running main action method: read_buildbot_config 10:26:28 INFO - Using buildbot properties: 10:26:28 INFO - { 10:26:28 INFO - "project": "", 10:26:28 INFO - "product": "firefox", 10:26:28 INFO - "script_repo_revision": "production", 10:26:28 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 10:26:28 INFO - "repository": "", 10:26:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 10:26:28 INFO - "buildid": "20160726081124", 10:26:28 INFO - "pgo_build": "False", 10:26:28 INFO - "basedir": "/builds/slave/test", 10:26:28 INFO - "buildnumber": 54, 10:26:28 INFO - "slavename": "t-yosemite-r7-0080", 10:26:28 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 10:26:28 INFO - "platform": "yosemite_r7", 10:26:28 INFO - "scheduler": "tests-autoland-macosx64-talos", 10:26:28 INFO - "branch": "autoland", 10:26:28 INFO - "repo_path": "integration/autoland", 10:26:28 INFO - "revision": "a5eb9b47ea45aa1fbde63e49a5aa75ba207ebc67", 10:26:28 INFO - "stage_platform": "macosx64", 10:26:28 INFO - "builduid": "6e8ef183439b443d93c4813c5902f422", 10:26:28 INFO - "slavebuilddir": "test" 10:26:28 INFO - } 10:26:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/UZFvWBzUQc-M6bUEkzz58A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 10:26:28 INFO - Running post-action listener: _resource_record_post_action 10:26:28 INFO - [mozharness: 2016-07-26 17:26:28.008047Z] Finished read-buildbot-config step (success) 10:26:28 INFO - [mozharness: 2016-07-26 17:26:28.008229Z] Running download-and-extract step. 10:26:28 INFO - Running pre-action listener: _resource_record_pre_action 10:26:28 INFO - Running main action method: download_and_extract 10:26:28 INFO - mkdir: /builds/slave/test/build/tests 10:26:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:26:28 INFO - https://queue.taskcluster.net/v1/task/UZFvWBzUQc-M6bUEkzz58A/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 10:26:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UZFvWBzUQc-M6bUEkzz58A/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 10:26:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UZFvWBzUQc-M6bUEkzz58A/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 10:26:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UZFvWBzUQc-M6bUEkzz58A/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 10:26:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UZFvWBzUQc-M6bUEkzz58A/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 10:26:30 INFO - Downloaded 1199 bytes. 10:26:30 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 10:26:30 INFO - Using the following test package requirements: 10:26:30 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 10:26:30 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:26:30 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 10:26:30 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:26:30 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 10:26:30 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:26:30 INFO - u'jsshell-mac.zip'], 10:26:30 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:26:30 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 10:26:30 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 10:26:30 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:26:30 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 10:26:30 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:26:30 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 10:26:30 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:26:30 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 10:26:30 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:26:30 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 10:26:30 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:26:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:26:30 INFO - https://queue.taskcluster.net/v1/task/UZFvWBzUQc-M6bUEkzz58A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:26:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UZFvWBzUQc-M6bUEkzz58A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:26:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UZFvWBzUQc-M6bUEkzz58A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:26:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UZFvWBzUQc-M6bUEkzz58A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:26:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UZFvWBzUQc-M6bUEkzz58A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:26:31 INFO - Downloaded 18811892 bytes. 10:26:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:26:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 10:26:34 INFO - Return code: 0 10:26:34 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 10:26:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:26:34 INFO - https://queue.taskcluster.net/v1/task/UZFvWBzUQc-M6bUEkzz58A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:26:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UZFvWBzUQc-M6bUEkzz58A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:26:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UZFvWBzUQc-M6bUEkzz58A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:26:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UZFvWBzUQc-M6bUEkzz58A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:26:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UZFvWBzUQc-M6bUEkzz58A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:26:35 INFO - Downloaded 18811892 bytes. 10:26:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:26:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 10:26:39 INFO - Return code: 0 10:26:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:26:39 INFO - https://queue.taskcluster.net/v1/task/UZFvWBzUQc-M6bUEkzz58A/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 10:26:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UZFvWBzUQc-M6bUEkzz58A/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 10:26:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UZFvWBzUQc-M6bUEkzz58A/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 10:26:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UZFvWBzUQc-M6bUEkzz58A/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 10:26:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UZFvWBzUQc-M6bUEkzz58A/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:26:41 INFO - Downloaded 14413725 bytes. 10:26:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:26:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 10:26:41 INFO - Return code: 0 10:26:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:26:41 INFO - https://queue.taskcluster.net/v1/task/UZFvWBzUQc-M6bUEkzz58A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 10:26:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UZFvWBzUQc-M6bUEkzz58A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:26:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UZFvWBzUQc-M6bUEkzz58A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:26:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UZFvWBzUQc-M6bUEkzz58A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 10:26:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UZFvWBzUQc-M6bUEkzz58A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 10:26:44 INFO - Downloaded 113014456 bytes. 10:26:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UZFvWBzUQc-M6bUEkzz58A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:26:44 INFO - mkdir: /builds/slave/test/properties 10:26:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:26:44 INFO - Writing to file /builds/slave/test/properties/build_url 10:26:44 INFO - Contents: 10:26:44 INFO - build_url:https://queue.taskcluster.net/v1/task/UZFvWBzUQc-M6bUEkzz58A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:26:45 INFO - Running post-action listener: _resource_record_post_action 10:26:45 INFO - Running post-action listener: set_extra_try_arguments 10:26:45 INFO - [mozharness: 2016-07-26 17:26:45.797611Z] Finished download-and-extract step (success) 10:26:45 INFO - [mozharness: 2016-07-26 17:26:45.797761Z] Running populate-webroot step. 10:26:45 INFO - Running pre-action listener: _resource_record_pre_action 10:26:45 INFO - Running main action method: populate_webroot 10:26:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:26:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:26:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:26:45 INFO - '--tppagecycles', 10:26:45 INFO - '7'], 10:26:45 INFO - 'tests': ['tsvgm']}}, 10:26:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:26:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:26:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:26:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:26:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:26:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:26:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:26:45 INFO - 'talos_options': ['--disable-e10s'], 10:26:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:26:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:26:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:26:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:26:45 INFO - 'talos_options': ['--disable-e10s'], 10:26:45 INFO - 'tests': ['damp', 'tps']}, 10:26:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:26:45 INFO - 'tests': ['damp', 'tps']}, 10:26:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:26:45 INFO - 'tests': ['dromaeo_dom']}, 10:26:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:26:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:26:45 INFO - 'tests': ['basic_compositor_video']}, 10:26:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:26:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:26:45 INFO - 'tests': ['a11yr', 10:26:45 INFO - 'ts_paint', 10:26:45 INFO - 'tpaint', 10:26:45 INFO - 'sessionrestore', 10:26:45 INFO - 'sessionrestore_no_auto_restore', 10:26:45 INFO - 'tabpaint']}, 10:26:45 INFO - 'other-e10s': {'tests': ['a11yr', 10:26:45 INFO - 'ts_paint', 10:26:45 INFO - 'tpaint', 10:26:45 INFO - 'sessionrestore', 10:26:45 INFO - 'sessionrestore_no_auto_restore', 10:26:45 INFO - 'tabpaint']}, 10:26:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:26:45 INFO - 'ts_paint', 10:26:45 INFO - 'tpaint', 10:26:45 INFO - 'sessionrestore', 10:26:45 INFO - 'sessionrestore_no_auto_restore', 10:26:45 INFO - 'tabpaint']}, 10:26:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:26:45 INFO - 'ts_paint', 10:26:45 INFO - 'tpaint', 10:26:45 INFO - 'sessionrestore', 10:26:45 INFO - 'sessionrestore_no_auto_restore', 10:26:45 INFO - 'tabpaint']}, 10:26:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:26:45 INFO - 'tests': ['a11yr', 10:26:45 INFO - 'ts_paint', 10:26:45 INFO - 'tpaint', 10:26:45 INFO - 'sessionrestore', 10:26:45 INFO - 'sessionrestore_no_auto_restore', 10:26:45 INFO - 'tabpaint']}, 10:26:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:26:45 INFO - 'tests': ['a11yr', 10:26:45 INFO - 'ts_paint', 10:26:45 INFO - 'tpaint', 10:26:45 INFO - 'sessionrestore', 10:26:45 INFO - 'sessionrestore_no_auto_restore', 10:26:45 INFO - 'tabpaint']}, 10:26:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:26:45 INFO - 'tests': ['tsvgx', 10:26:45 INFO - 'tsvgr_opacity', 10:26:45 INFO - 'tart', 10:26:45 INFO - 'tscrollx', 10:26:45 INFO - 'cart']}, 10:26:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:26:45 INFO - 'tsvgr_opacity', 10:26:45 INFO - 'tart', 10:26:45 INFO - 'tscrollx', 10:26:45 INFO - 'cart']}, 10:26:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:26:45 INFO - 'talos_options': ['--disable-e10s'], 10:26:45 INFO - 'tests': ['tp5o']}, 10:26:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:26:45 INFO - 'tests': ['tp5o']}, 10:26:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:26:45 INFO - 'talos_options': ['--disable-e10s', 10:26:45 INFO - '--xperf_path', 10:26:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:26:45 INFO - 'tests': ['tp5n']}, 10:26:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:26:45 INFO - 'talos_options': ['--xperf_path', 10:26:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:26:45 INFO - 'tests': ['tp5n']}}, 10:26:45 INFO - 'talos.zip': {'path': '', 10:26:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:26:45 INFO - Running post-action listener: _resource_record_post_action 10:26:45 INFO - [mozharness: 2016-07-26 17:26:45.816864Z] Finished populate-webroot step (success) 10:26:45 INFO - [mozharness: 2016-07-26 17:26:45.816992Z] Running create-virtualenv step. 10:26:45 INFO - Running pre-action listener: _resource_record_pre_action 10:26:45 INFO - Running main action method: create_virtualenv 10:26:45 INFO - Creating virtualenv /builds/slave/test/build/venv 10:26:45 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 10:26:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:26:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:26:45 INFO - Using real prefix '/tools/python27' 10:26:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:26:46 INFO - Installing distribute.............................................................................................................................................................................................done. 10:26:49 INFO - Installing pip.................done. 10:26:49 INFO - Return code: 0 10:26:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:26:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:26:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:26:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109ad2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b67618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb198454d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a36d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10979bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a2eb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KWCGVYbzI9/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RUBWGtJenq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:26:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:26:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:26:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KWCGVYbzI9/Render', 10:26:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:26:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:26:49 INFO - 'HOME': '/Users/cltbld', 10:26:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:26:49 INFO - 'LOGNAME': 'cltbld', 10:26:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:49 INFO - 'MOZ_NO_REMOTE': '1', 10:26:49 INFO - 'NO_EM_RESTART': '1', 10:26:49 INFO - 'PAGER': '/bin/cat', 10:26:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:26:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:49 INFO - 'PWD': '/builds/slave/test', 10:26:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:26:49 INFO - 'SHELL': '/bin/bash', 10:26:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RUBWGtJenq/Listeners', 10:26:49 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 10:26:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:26:49 INFO - 'USER': 'cltbld', 10:26:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:26:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:26:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:49 INFO - 'XPC_FLAGS': '0x0', 10:26:49 INFO - 'XPC_SERVICE_NAME': '0', 10:26:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:26:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:49 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:26:49 INFO - Cleaning up... 10:26:49 INFO - Return code: 0 10:26:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:26:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:26:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:26:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109ad2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b67618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb198454d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a36d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10979bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a2eb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KWCGVYbzI9/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RUBWGtJenq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:26:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:26:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:26:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KWCGVYbzI9/Render', 10:26:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:26:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:26:49 INFO - 'HOME': '/Users/cltbld', 10:26:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:26:49 INFO - 'LOGNAME': 'cltbld', 10:26:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:49 INFO - 'MOZ_NO_REMOTE': '1', 10:26:49 INFO - 'NO_EM_RESTART': '1', 10:26:49 INFO - 'PAGER': '/bin/cat', 10:26:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:26:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:49 INFO - 'PWD': '/builds/slave/test', 10:26:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:26:49 INFO - 'SHELL': '/bin/bash', 10:26:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RUBWGtJenq/Listeners', 10:26:49 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 10:26:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:26:49 INFO - 'USER': 'cltbld', 10:26:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:26:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:26:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:49 INFO - 'XPC_FLAGS': '0x0', 10:26:49 INFO - 'XPC_SERVICE_NAME': '0', 10:26:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:26:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:50 INFO - Downloading/unpacking psutil>=3.1.1 10:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:26:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:26:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:26:53 INFO - Installing collected packages: psutil 10:26:53 INFO - Running setup.py install for psutil 10:26:53 INFO - building 'psutil._psutil_osx' extension 10:26:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 10:26:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 10:26:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 10:26:53 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 10:26:53 INFO - building 'psutil._psutil_posix' extension 10:26:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 10:26:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:26:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:26:53 INFO - ^ 10:26:53 INFO - 1 warning generated. 10:26:53 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 10:26:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:26:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:26:53 INFO - Successfully installed psutil 10:26:53 INFO - Cleaning up... 10:26:54 INFO - Return code: 0 10:26:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:26:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:26:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:26:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109ad2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b67618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb198454d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a36d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10979bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a2eb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KWCGVYbzI9/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RUBWGtJenq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:26:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:26:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:26:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KWCGVYbzI9/Render', 10:26:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:26:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:26:54 INFO - 'HOME': '/Users/cltbld', 10:26:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:26:54 INFO - 'LOGNAME': 'cltbld', 10:26:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:54 INFO - 'MOZ_NO_REMOTE': '1', 10:26:54 INFO - 'NO_EM_RESTART': '1', 10:26:54 INFO - 'PAGER': '/bin/cat', 10:26:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:26:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:54 INFO - 'PWD': '/builds/slave/test', 10:26:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:26:54 INFO - 'SHELL': '/bin/bash', 10:26:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RUBWGtJenq/Listeners', 10:26:54 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 10:26:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:26:54 INFO - 'USER': 'cltbld', 10:26:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:26:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:26:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:54 INFO - 'XPC_FLAGS': '0x0', 10:26:54 INFO - 'XPC_SERVICE_NAME': '0', 10:26:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:26:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:26:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:26:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:26:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:26:56 INFO - Installing collected packages: mozsystemmonitor 10:26:56 INFO - Running setup.py install for mozsystemmonitor 10:26:56 INFO - Successfully installed mozsystemmonitor 10:26:56 INFO - Cleaning up... 10:26:57 INFO - Return code: 0 10:26:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:26:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:26:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:26:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109ad2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b67618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb198454d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a36d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10979bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a2eb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KWCGVYbzI9/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RUBWGtJenq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:26:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:26:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:26:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KWCGVYbzI9/Render', 10:26:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:26:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:26:57 INFO - 'HOME': '/Users/cltbld', 10:26:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:26:57 INFO - 'LOGNAME': 'cltbld', 10:26:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:57 INFO - 'MOZ_NO_REMOTE': '1', 10:26:57 INFO - 'NO_EM_RESTART': '1', 10:26:57 INFO - 'PAGER': '/bin/cat', 10:26:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:26:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:57 INFO - 'PWD': '/builds/slave/test', 10:26:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:26:57 INFO - 'SHELL': '/bin/bash', 10:26:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RUBWGtJenq/Listeners', 10:26:57 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 10:26:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:26:57 INFO - 'USER': 'cltbld', 10:26:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:26:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:26:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:57 INFO - 'XPC_FLAGS': '0x0', 10:26:57 INFO - 'XPC_SERVICE_NAME': '0', 10:26:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:26:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:57 INFO - Downloading/unpacking jsonschema==2.5.1 10:26:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:26:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:26:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:27:00 INFO - Downloading functools32-3.2.3-2.tar.gz 10:27:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:27:00 INFO - warning: no files found matching '*.txt' 10:27:00 INFO - no previously-included directories found matching 'build' 10:27:00 INFO - no previously-included directories found matching 'dist' 10:27:00 INFO - no previously-included directories found matching '.git*' 10:27:00 INFO - Installing collected packages: jsonschema, functools32 10:27:00 INFO - Running setup.py install for functools32 10:27:00 INFO - warning: no files found matching '*.txt' 10:27:00 INFO - no previously-included directories found matching 'build' 10:27:00 INFO - no previously-included directories found matching 'dist' 10:27:00 INFO - no previously-included directories found matching '.git*' 10:27:00 INFO - Successfully installed jsonschema functools32 10:27:00 INFO - Cleaning up... 10:27:00 INFO - Return code: 0 10:27:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:27:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:27:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:27:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:27:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:27:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:27:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:27:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109ad2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b67618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb198454d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a36d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10979bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a2eb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KWCGVYbzI9/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RUBWGtJenq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:27:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:27:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:27:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KWCGVYbzI9/Render', 10:27:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:27:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:27:00 INFO - 'HOME': '/Users/cltbld', 10:27:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:27:00 INFO - 'LOGNAME': 'cltbld', 10:27:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:27:00 INFO - 'MOZ_NO_REMOTE': '1', 10:27:00 INFO - 'NO_EM_RESTART': '1', 10:27:00 INFO - 'PAGER': '/bin/cat', 10:27:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:27:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:27:00 INFO - 'PWD': '/builds/slave/test', 10:27:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:27:00 INFO - 'SHELL': '/bin/bash', 10:27:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RUBWGtJenq/Listeners', 10:27:00 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 10:27:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:27:00 INFO - 'USER': 'cltbld', 10:27:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:27:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:27:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:27:00 INFO - 'XPC_FLAGS': '0x0', 10:27:00 INFO - 'XPC_SERVICE_NAME': '0', 10:27:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:27:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:27:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:27:00 INFO - Cleaning up... 10:27:00 INFO - Return code: 0 10:27:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:27:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:27:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:27:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:27:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:27:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:27:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:27:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109ad2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b67618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb198454d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a36d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10979bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a2eb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KWCGVYbzI9/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RUBWGtJenq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:27:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:27:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:27:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KWCGVYbzI9/Render', 10:27:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:27:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:27:00 INFO - 'HOME': '/Users/cltbld', 10:27:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:27:00 INFO - 'LOGNAME': 'cltbld', 10:27:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:27:00 INFO - 'MOZ_NO_REMOTE': '1', 10:27:00 INFO - 'NO_EM_RESTART': '1', 10:27:00 INFO - 'PAGER': '/bin/cat', 10:27:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:27:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:27:00 INFO - 'PWD': '/builds/slave/test', 10:27:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:27:00 INFO - 'SHELL': '/bin/bash', 10:27:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RUBWGtJenq/Listeners', 10:27:00 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 10:27:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:27:00 INFO - 'USER': 'cltbld', 10:27:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:27:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:27:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:27:00 INFO - 'XPC_FLAGS': '0x0', 10:27:00 INFO - 'XPC_SERVICE_NAME': '0', 10:27:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:27:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:27:01 INFO - Downloading/unpacking blobuploader==1.2.4 10:27:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:27:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:27:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:27:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:27:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:27:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:27:03 INFO - Downloading blobuploader-1.2.4.tar.gz 10:27:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:27:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:27:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:27:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:27:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:27:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:27:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:27:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:27:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:27:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:27:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:27:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:27:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:27:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:27:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:27:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:27:04 INFO - Downloading docopt-0.6.1.tar.gz 10:27:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:27:04 INFO - Installing collected packages: blobuploader, requests, docopt 10:27:04 INFO - Running setup.py install for blobuploader 10:27:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:27:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:27:04 INFO - Running setup.py install for requests 10:27:05 INFO - Running setup.py install for docopt 10:27:05 INFO - Successfully installed blobuploader requests docopt 10:27:05 INFO - Cleaning up... 10:27:05 INFO - Return code: 0 10:27:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:27:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:27:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:27:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:27:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:27:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:27:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109ad2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b67618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb198454d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a36d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10979bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a2eb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KWCGVYbzI9/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RUBWGtJenq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:27:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:27:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:27:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KWCGVYbzI9/Render', 10:27:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:27:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:27:05 INFO - 'HOME': '/Users/cltbld', 10:27:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:27:05 INFO - 'LOGNAME': 'cltbld', 10:27:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:27:05 INFO - 'MOZ_NO_REMOTE': '1', 10:27:05 INFO - 'NO_EM_RESTART': '1', 10:27:05 INFO - 'PAGER': '/bin/cat', 10:27:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:27:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:27:05 INFO - 'PWD': '/builds/slave/test', 10:27:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:27:05 INFO - 'SHELL': '/bin/bash', 10:27:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RUBWGtJenq/Listeners', 10:27:05 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 10:27:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:27:05 INFO - 'USER': 'cltbld', 10:27:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:27:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:27:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:27:05 INFO - 'XPC_FLAGS': '0x0', 10:27:05 INFO - 'XPC_SERVICE_NAME': '0', 10:27:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:27:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:27:05 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-1m4JGi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:27:06 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-8c9NFx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:27:06 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-SHpvbm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:27:06 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-gMvXUg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:27:06 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-CGuwah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:27:06 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-0_F2RV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:27:06 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-INzj7O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:27:06 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-_GDj1W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:27:06 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-Hz4l6Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:27:06 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-AA9YSb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:27:06 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-HXR3sv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:27:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:27:07 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-RZ5DQv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:27:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:27:07 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-2EzeBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:27:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:27:07 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-QFDjbB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:27:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:27:07 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-XruOCb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:27:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:27:07 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-1fKKvb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:27:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:27:07 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-GV50Ze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:27:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:27:07 INFO - Running setup.py install for manifestparser 10:27:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:27:07 INFO - Running setup.py install for mozcrash 10:27:08 INFO - Running setup.py install for mozdebug 10:27:08 INFO - Running setup.py install for mozdevice 10:27:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:27:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:27:08 INFO - Running setup.py install for mozfile 10:27:08 INFO - Running setup.py install for mozhttpd 10:27:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:27:08 INFO - Running setup.py install for mozinfo 10:27:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:27:08 INFO - Running setup.py install for mozInstall 10:27:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:27:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:27:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:27:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:27:08 INFO - Running setup.py install for mozleak 10:27:09 INFO - Running setup.py install for mozlog 10:27:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:27:09 INFO - Running setup.py install for moznetwork 10:27:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:27:09 INFO - Running setup.py install for mozprocess 10:27:09 INFO - Running setup.py install for mozprofile 10:27:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:27:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:27:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:27:09 INFO - Running setup.py install for mozrunner 10:27:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:27:09 INFO - Running setup.py install for mozscreenshot 10:27:10 INFO - Running setup.py install for moztest 10:27:10 INFO - Running setup.py install for mozversion 10:27:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:27:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:27:10 INFO - Cleaning up... 10:27:10 INFO - Return code: 0 10:27:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:27:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:27:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:27:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:27:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:27:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:27:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:27:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109ad2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b67618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb198454d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a36d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10979bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a2eb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KWCGVYbzI9/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RUBWGtJenq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:27:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:27:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:27:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KWCGVYbzI9/Render', 10:27:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:27:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:27:10 INFO - 'HOME': '/Users/cltbld', 10:27:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:27:10 INFO - 'LOGNAME': 'cltbld', 10:27:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:27:10 INFO - 'MOZ_NO_REMOTE': '1', 10:27:10 INFO - 'NO_EM_RESTART': '1', 10:27:10 INFO - 'PAGER': '/bin/cat', 10:27:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:27:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:27:10 INFO - 'PWD': '/builds/slave/test', 10:27:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:27:10 INFO - 'SHELL': '/bin/bash', 10:27:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RUBWGtJenq/Listeners', 10:27:10 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 10:27:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:27:10 INFO - 'USER': 'cltbld', 10:27:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:27:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:27:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:27:10 INFO - 'XPC_FLAGS': '0x0', 10:27:10 INFO - 'XPC_SERVICE_NAME': '0', 10:27:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:27:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:27:10 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-K180Sg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:27:10 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 10:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:27:10 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-zUT59J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:27:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:27:10 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-3RQSva-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:27:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:27:10 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-LHPrxS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:27:11 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-e9fMTT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:27:11 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-Y4Po5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 10:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:27:11 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-WTfiKS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 10:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:27:11 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-tN8BcE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:27:11 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-PHxaq1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 10:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:27:11 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-mn88yA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:27:11 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-yY4CYw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 10:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:27:11 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-7JqHly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:27:11 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-uZUZJx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:27:11 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-aMeFkL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:27:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:27:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:27:12 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-JHyijd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:27:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 10:27:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:27:12 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-rSWmN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:27:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:27:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:27:12 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-39_j2h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:27:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 10:27:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:27:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:27:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:27:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:27:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:27:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:27:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:27:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:27:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:27:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:27:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:27:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:27:14 INFO - Downloading blessings-1.6.tar.gz 10:27:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:27:14 INFO - Installing collected packages: blessings 10:27:14 INFO - Running setup.py install for blessings 10:27:15 INFO - Successfully installed blessings 10:27:15 INFO - Cleaning up... 10:27:15 INFO - Return code: 0 10:27:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:27:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:27:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:27:15 INFO - Reading from file tmpfile_stdout 10:27:15 INFO - Current package versions: 10:27:15 INFO - blessings == 1.6 10:27:15 INFO - blobuploader == 1.2.4 10:27:15 INFO - docopt == 0.6.1 10:27:15 INFO - functools32 == 3.2.3-2 10:27:15 INFO - jsonschema == 2.5.1 10:27:15 INFO - manifestparser == 1.1 10:27:15 INFO - mozInstall == 1.12 10:27:15 INFO - mozcrash == 0.17 10:27:15 INFO - mozdebug == 0.1 10:27:15 INFO - mozdevice == 0.48 10:27:15 INFO - mozfile == 1.2 10:27:15 INFO - mozhttpd == 0.7 10:27:15 INFO - mozinfo == 0.9 10:27:15 INFO - mozleak == 0.1 10:27:15 INFO - mozlog == 3.3 10:27:15 INFO - moznetwork == 0.27 10:27:15 INFO - mozprocess == 0.23 10:27:15 INFO - mozprofile == 0.28 10:27:15 INFO - mozrunner == 6.12 10:27:15 INFO - mozscreenshot == 0.1 10:27:15 INFO - mozsystemmonitor == 0.3 10:27:15 INFO - moztest == 0.7 10:27:15 INFO - mozversion == 1.4 10:27:15 INFO - psutil == 3.1.1 10:27:15 INFO - requests == 1.2.3 10:27:15 INFO - wsgiref == 0.1.2 10:27:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:27:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:27:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:27:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:27:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:27:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:27:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:27:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109ad2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b67618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb198454d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a36d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10979bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a2eb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KWCGVYbzI9/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RUBWGtJenq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:27:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:27:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:27:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KWCGVYbzI9/Render', 10:27:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:27:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:27:15 INFO - 'HOME': '/Users/cltbld', 10:27:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:27:15 INFO - 'LOGNAME': 'cltbld', 10:27:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:27:15 INFO - 'MOZ_NO_REMOTE': '1', 10:27:15 INFO - 'NO_EM_RESTART': '1', 10:27:15 INFO - 'PAGER': '/bin/cat', 10:27:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:27:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:27:15 INFO - 'PWD': '/builds/slave/test', 10:27:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:27:15 INFO - 'SHELL': '/bin/bash', 10:27:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RUBWGtJenq/Listeners', 10:27:15 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 10:27:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:27:15 INFO - 'USER': 'cltbld', 10:27:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:27:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:27:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:27:15 INFO - 'XPC_FLAGS': '0x0', 10:27:15 INFO - 'XPC_SERVICE_NAME': '0', 10:27:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:27:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:27:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:27:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:27:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:27:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:27:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:27:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:27:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:27:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:27:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:27:15 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:27:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:27:15 INFO - Cleaning up... 10:27:15 INFO - Return code: 0 10:27:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:27:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:27:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:27:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:27:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:27:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:27:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:27:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109ad2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b67618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb198454d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a36d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10979bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a2eb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KWCGVYbzI9/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RUBWGtJenq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:27:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:27:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:27:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KWCGVYbzI9/Render', 10:27:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:27:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:27:15 INFO - 'HOME': '/Users/cltbld', 10:27:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:27:15 INFO - 'LOGNAME': 'cltbld', 10:27:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:27:15 INFO - 'MOZ_NO_REMOTE': '1', 10:27:15 INFO - 'NO_EM_RESTART': '1', 10:27:15 INFO - 'PAGER': '/bin/cat', 10:27:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:27:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:27:15 INFO - 'PWD': '/builds/slave/test', 10:27:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:27:15 INFO - 'SHELL': '/bin/bash', 10:27:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RUBWGtJenq/Listeners', 10:27:15 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 10:27:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:27:15 INFO - 'USER': 'cltbld', 10:27:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:27:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:27:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:27:15 INFO - 'XPC_FLAGS': '0x0', 10:27:15 INFO - 'XPC_SERVICE_NAME': '0', 10:27:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:27:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:27:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:27:16 INFO - Cleaning up... 10:27:16 INFO - Return code: 0 10:27:16 INFO - Running post-action listener: _resource_record_post_action 10:27:16 INFO - Running post-action listener: _start_resource_monitoring 10:27:16 INFO - Starting resource monitoring. 10:27:16 INFO - [mozharness: 2016-07-26 17:27:16.075449Z] Finished create-virtualenv step (success) 10:27:16 INFO - [mozharness: 2016-07-26 17:27:16.076478Z] Running install step. 10:27:16 INFO - Running pre-action listener: _resource_record_pre_action 10:27:16 INFO - Running main action method: install 10:27:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:27:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:27:16 INFO - Reading from file tmpfile_stdout 10:27:16 INFO - Detecting whether we're running mozinstall >=1.0... 10:27:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:27:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:27:16 INFO - Reading from file tmpfile_stdout 10:27:16 INFO - Output received: 10:27:16 INFO - Usage: mozinstall [options] installer 10:27:16 INFO - Options: 10:27:16 INFO - -h, --help show this help message and exit 10:27:16 INFO - -d DEST, --destination=DEST 10:27:16 INFO - Directory to install application into. [default: 10:27:16 INFO - "/builds/slave/test"] 10:27:16 INFO - --app=APP Application being installed. [default: firefox] 10:27:16 INFO - mkdir: /builds/slave/test/build/application 10:27:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:27:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 10:27:51 INFO - Reading from file tmpfile_stdout 10:27:51 INFO - Output received: 10:27:51 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:27:51 INFO - Running post-action listener: _resource_record_post_action 10:27:51 INFO - [mozharness: 2016-07-26 17:27:51.237137Z] Finished install step (success) 10:27:51 INFO - [mozharness: 2016-07-26 17:27:51.237237Z] Running run-tests step. 10:27:51 INFO - Running pre-action listener: _resource_record_pre_action 10:27:51 INFO - Running main action method: run_tests 10:27:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:27:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:27:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:27:51 INFO - 2016-07-26 10:27:51.252 screenresolution[7106:118479] starting screenresolution argv=screenresolution get 10:27:51 INFO - 2016-07-26 10:27:51.255 screenresolution[7106:118479] Display 0: 1600x1200x32@60 10:27:51 INFO - 2016-07-26 10:27:51.261 screenresolution[7107:118481] starting screenresolution argv=screenresolution list 10:27:51 INFO - Available Modes on Display 0 10:27:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:27:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:27:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:27:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:27:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:27:51 INFO - Intel Iris: 10:27:51 INFO - Chipset Model: Intel Iris 10:27:51 INFO - Type: GPU 10:27:51 INFO - Bus: Built-In 10:27:51 INFO - VRAM (Dynamic, Max): 1536 MB 10:27:51 INFO - Vendor: Intel (0x8086) 10:27:51 INFO - Device ID: 0x0a2e 10:27:51 INFO - Revision ID: 0x0009 10:27:51 INFO - Displays: 10:27:51 INFO - Display: 10:27:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:27:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:27:51 INFO - Display Serial Number: 1600x1200 60 10:27:51 INFO - Main Display: Yes 10:27:51 INFO - Mirror: Off 10:27:51 INFO - Online: Yes 10:27:51 INFO - Rotation: Supported 10:27:51 INFO - Return code: 0 10:27:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:27:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:27:51 INFO - Python 2.7.3 10:27:51 INFO - Return code: 0 10:27:51 INFO - grabbing minidump binary from tooltool 10:27:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:27:51 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a36d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10979bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a2eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:27:51 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 10:27:51 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 10:27:51 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 10:27:51 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:27:51 INFO - Return code: 0 10:27:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:27:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:27:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:27:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:27:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:27:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UZFvWBzUQc-M6bUEkzz58A/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0080', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 10:27:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UZFvWBzUQc-M6bUEkzz58A/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0080 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 10:27:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KWCGVYbzI9/Render', 10:27:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:27:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:27:51 INFO - 'HOME': '/Users/cltbld', 10:27:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:27:51 INFO - 'LOGNAME': 'cltbld', 10:27:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:27:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:27:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:27:51 INFO - 'MOZ_NO_REMOTE': '1', 10:27:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:27:51 INFO - 'NO_EM_RESTART': '1', 10:27:51 INFO - 'PAGER': '/bin/cat', 10:27:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:27:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:27:51 INFO - 'PWD': '/builds/slave/test', 10:27:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:27:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:27:51 INFO - 'SHELL': '/bin/bash', 10:27:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RUBWGtJenq/Listeners', 10:27:51 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 10:27:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:27:51 INFO - 'USER': 'cltbld', 10:27:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:27:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:27:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:27:51 INFO - 'XPC_FLAGS': '0x0', 10:27:51 INFO - 'XPC_SERVICE_NAME': '0', 10:27:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:27:51 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UZFvWBzUQc-M6bUEkzz58A/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0080', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 10:27:51 INFO - mozversion application_buildid: 20160726081124 10:27:51 INFO - mozversion application_changeset: a5eb9b47ea45aa1fbde63e49a5aa75ba207ebc67 10:27:51 INFO - mozversion application_display_name: Nightly 10:27:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:27:51 INFO - mozversion application_name: Firefox 10:27:51 INFO - mozversion application_remotingname: firefox 10:27:51 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 10:27:51 INFO - mozversion application_vendor: Mozilla 10:27:51 INFO - mozversion application_version: 50.0a1 10:27:51 INFO - mozversion platform_buildid: 20160726081124 10:27:51 INFO - mozversion platform_changeset: a5eb9b47ea45aa1fbde63e49a5aa75ba207ebc67 10:27:51 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 10:27:51 INFO - mozversion platform_version: 50.0a1 10:27:51 INFO - using testdate: 1469554071 10:27:51 INFO - actual date: 1469554071 10:27:51 INFO - starting webserver on 'localhost:62115' 10:27:51 INFO - SUITE-START | Running 2 tests 10:27:51 INFO - TEST-START | dromaeo_css 10:27:51 INFO - operating with platform_type : mac_ 10:27:51 INFO - Initialising browser for dromaeo_css test... 10:27:51 INFO - TEST-INFO | started process 7117 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/tmpkXOM6i/profile http://localhost:62115/getInfo.html) 10:27:54 INFO - PROCESS | 7117 | JavaScript error: , line 0: uncaught exception: undefined 10:27:54 INFO - PROCESS | 7117 | __metrics Screen width/height:1600/1200 10:27:54 INFO - PROCESS | 7117 | colorDepth:24 10:27:54 INFO - PROCESS | 7117 | Browser inner width/height: 1024/689 10:27:54 INFO - PROCESS | 7117 | __metrics 10:27:54 ERROR - PROCESS | 7117 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2968: TypeError: this.tabs is undefined 10:27:55 INFO - TEST-INFO | 7117: exit 0 10:27:55 INFO - Browser initialized. 10:27:55 INFO - Running cycle 1/1 for dromaeo_css test... 10:27:55 INFO - TEST-INFO | started process 7120 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/tmpkXOM6i/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:28:07 INFO - PROCESS | 7120 | RSS: Main: 257142784 10:28:07 INFO - PROCESS | 7120 | 10:28:57 INFO - PROCESS | 7120 | 1469554137284 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 10:31:08 INFO - PROCESS | 7120 | Cycle 1(1): loaded http://localhost:62115/tests/dromaeo/cssquery-dojo.html (next: http://localhost:62115/tests/dromaeo/cssquery-ext.html) 10:31:09 INFO - PROCESS | 7120 | RSS: Main: 270135296 10:31:09 INFO - PROCESS | 7120 | 10:34:12 INFO - PROCESS | 7120 | Cycle 1(1): loaded http://localhost:62115/tests/dromaeo/cssquery-ext.html (next: http://localhost:62115/tests/dromaeo/cssquery-jquery.html) 10:34:12 INFO - PROCESS | 7120 | RSS: Main: 282386432 10:34:12 INFO - PROCESS | 7120 | 10:37:14 INFO - PROCESS | 7120 | Cycle 1(1): loaded http://localhost:62115/tests/dromaeo/cssquery-jquery.html (next: http://localhost:62115/tests/dromaeo/cssquery-mootools.html) 10:37:15 INFO - PROCESS | 7120 | RSS: Main: 294699008 10:37:15 INFO - PROCESS | 7120 | 10:40:17 INFO - PROCESS | 7120 | Cycle 1(1): loaded http://localhost:62115/tests/dromaeo/cssquery-mootools.html (next: http://localhost:62115/tests/dromaeo/cssquery-prototype.html) 10:40:17 INFO - PROCESS | 7120 | RSS: Main: 292990976 10:40:17 INFO - PROCESS | 7120 | 10:43:20 INFO - PROCESS | 7120 | Cycle 1(1): loaded http://localhost:62115/tests/dromaeo/cssquery-prototype.html (next: http://localhost:62115/tests/dromaeo/cssquery-yui.html) 10:43:21 INFO - PROCESS | 7120 | RSS: Main: 297312256 10:43:21 INFO - PROCESS | 7120 | 10:46:23 INFO - PROCESS | 7120 | Cycle 1(1): loaded http://localhost:62115/tests/dromaeo/cssquery-yui.html (next: http://localhost:62115/tests/dromaeo/cssquery-dojo.html) 10:46:24 INFO - PROCESS | 7120 | RSS: Main: 297775104 10:46:24 INFO - PROCESS | 7120 | 10:46:24 INFO - PROCESS | 7120 | __start_tp_report 10:46:24 INFO - PROCESS | 7120 | _x_x_mozilla_page_load 10:46:24 INFO - PROCESS | 7120 | _x_x_mozilla_page_load_details 10:46:24 INFO - PROCESS | 7120 | |i|pagename|runs| 10:46:24 INFO - PROCESS | 7120 | |0;dojo.html;2936.5007948648727;3188.3463889902573;3381.239221175236;3449.655034496549;3456.429689101298;5786.942130578693;5916.142159386892;5928.733207005685;6005.519558435326;6034.8189554313385;5653.180288858113;5913.586048976574;5927.940720592804;5932.911006334908;6045.123457098729;5273.789048438058;5351.866203344928;5371.811986580451;5466.972665136669;5496.258005169311;5408.621396502246;5433.565314774828;5478.04134276502;5503.147012213093;5518.6688798672;4352.738835669854;4526.4794548627;5016.2224855147515;5359.437259087778;5361.517463428271;5321.095413779667;5358.866028349284;5382.058139825514;5394.91907621386;5447;4833.275008748681;5369.812056578001;5391.137418013138;5399.19012148177;5407.621466497347;4559.430071241095;5834.708264586754;5949.226600541945;5976.9402305977255;5981.700914954235;6231.532635052344;6239.251289845202;6255.468285195719;6273.62358258506;6292.590981586182;6210.378962103799;6293.433590976834;6350.650714210717;6385.680715964183;6413.134226879358;6033.034714445667;6085.056816193497;6090.056041313604;6118.938810611881;6128.9693551531955;6479.514036447285;6548.3124271950755;6553.868922621521;6577.736890524421;6588.308227636125;6120.908186377208;6145;6210.658413787195;6224.439800417984;6249.96875015622;5427.728613569385;6245.28179259391;6261.530385221126;6367.808965731036;6387.968060159762;6190.597611155261;6213.099100630383;6276;6287.1512345832725;6299.874002519924;5282.898570937543;5326.893462130735;6298.527610429236;6310.305866354697;6330.620162790247;4975.90048199034;5028.572571331405;5063.569596584258;5098.413682426569;5128.3076784633595;4504.594586487232;4517.88705282364;4564.2240819060835;4564.680472366969;4595.586397224266;5982.551308651781;6044.758209671564;6051.425114614105;6059.606125601822;6072.362401947732;6190.721417536221;6257.374262573706;6281.8743625127245;6328.71520781566;6393.456556192683;6010.759569617167;6030.095485677183;6033.42682445167;6047.576669633084;6056.182415373956;4683.718976861399;4909.386326709161;5588.972055139699;5596.944030559643;5607.859803504945;6413.4869210463985;6502;6511.34886511345;6590.077389165616;6609.834754131185;5467.617266791445;5550.306211723535;5868.735906884286;6331.366863313632;6392.552521323462;5652.349979752216;6566.507511936719;6585.5719378242175;6617.470602351704;6642.800715978528;6175.96912015437;6208.1308616792785;6219.720112594853;6285;6318.6840657965995;5199.76601052946;6346.2067241594805;6357.364263573606;6379.298277189413;6389.297177310399;6226.688665566794;6235.282942461675;6264.2796078451565;6287.779927702509;6306.4639505642535;6105.156502995126;6143.266246520434;6168.1056246844855;6182.814515564541;6263.686815659108;6029.457348838626;6095.116208149882;6096.02463605821;6118.541109416722;6142.877142457037;6157.599756015845;6188.237169270024;6195.287541932736;6276.6547839869245;6279.403456671609;6031.517478601789;6102.792231004566;6113.658745237643;6121.785737499345;6197.504199664107 10:46:24 INFO - PROCESS | 7120 | |1;ext.html;497.3286063813854;522.2975098492528;535.4404647143734;546.464464824472;561.4862400903176;40284.79857600712;43186.13627727443;43336.783316083456;43415.565844341545;43508;44257.33613995793;44461.11077778442;44714.55285447145;44723.10553788931;44771.77614111933;9716.56275467602;9866.60533578656;9927.453990030546;9943.900560994405;9946.452945088018;9855.605775768996;9922.206223502102;9935;9964.601415943354;9980.30137890348;8379.790505237357;9794.902050979506;9840.507974601278;9898.208143348515;9900.900990990105;8936.46381217129;9675.951620241924;9950.55222514985;9950.701478955607;9995.75010624733;9679.951600242024;9700;9771.90228097717;9778.07108324708;9824.115829575301;41276.79361603203;42009.78995105005;42147.57852421498;42267.36598951018;42440.1511969762;3466.636003219651;3485.8082805445692;3517.5954765202073;3587.157018100744;3678.668919797231;32893.50659740106;33768;33870.98387048393;33889.491657625156;33989.830050849836;3225.4194245035883;3362.5460562824196;3376.1053320869987;3402.846871890746;3408.199073217792;459653;465046;465714;467110.664446679;469562;64494.35505644977;64754;64880.70238595202;65453;65497.34502655008;61922.380776192564;69461.3053869465;69546.9567956481;69685.65157174283;69800.65099674469;16757.748633770505;16818;17011.65976680484;17016.149192540324;17024.82975170257;12983.285919274342;13037.217766934014;13098.934505327603;13112.606621801362;13140.211587304791;16953.830461695226;17209.913950430167;18161.36435224787;18826.246950122117;18869.528261793566;5933.792317268875;6680.663867226574;7451.962740186264;7641.617919104023;7933.603319833986;881.5371929736812;903.620479398641;962.3840741925173;996.2876543271595;1159.1190695071807;23845.642315365283;26719.73280267211;31697.683023169935;31782.841085794884;32319;1040.1834559870465;1056.6354607660344;1092.4810714910354;1098.0447011100234;1217.227060816388;16184.514464566082;16649.25078371452;16667.3333066676;16667.416235664543;16694.24875880564;146137;173028;173053.2694673037;173535;173644;63602;64217.678911606075;64346;64620.353796462376;64635;1245.4644502863855;1391.7912313152906;1421.1686163594413;1452.4553292515307;1454.0911930043724;30955;31525.842370788;31768.523472147474;32032.67967320297;32208.101270885734;54641.18038229509;55738;55742.16386754283;56346;56450.43549564452;599777;600522;604219;605099;605581;24095.51808963846;24770.876145619157;24848.627270590594;25638.487230254916;44781.32828007648;50803.49196508135;51404;51496.97006059783;51819.740901295256;53059.469405305455;41971;42201.94495137646;42214.788926056404;42225.155496890504;43557.911052222684;32137.678623214404;41660.1667966645;41745.37381939334;41910.37134443047;43193;40282;41115;41146.794266028475;41290.79354603208;42869.78565107154;31943;32707.509387359685;33957.830210848784;36562.45156322711;36749.89750307388;25186;26736.465270694865;27063.323416913947;27372.041978530655;27484.900603975617 10:46:24 INFO - PROCESS | 7120 | |2;jquery.html;373.1901773152259;388.5085367010732;390.7674933414619;401.9577944315845;407.87355919664907;19565.608687826236;21874.453138671543;21992.560148797016;22167.113315467363;22467.662985055216;19465.51336216592;21638.675419868716;22166.667499987514;22172.778272217274;22236.55526889465;5873.089671100986;6093.78671746489;6176.752929882799;6178.691065446732;6200.10098535712;4196.349565817288;6078.574499784994;6510.446612037982;6810.795676129724;6858.382745552899;5592.133219351007;6827.385535301822;6850.588964662136;6867.072945152416;6884.690188941485;5678.914816277759;5843.883122337551;5969.492593129589;5973.790917317873;5984.982552965985;4508.61676757476;4968.105740966625;5287.339082614673;5329.893402131975;5371.623986320959;13526.661833454144;13610;13647;14347.497837575738;22492.662610060863;1979.267670961739;2041.836653067751;2220.8334374921947;2255.4474153832252;2654.5354562951406;10202.89797102027;10378.896211037869;10502.369857808479;15092.773608395883;16069.158655755953;1758.9648207035916;1767.927298702331;1872.456987473631;1877.1458986161401;1924.0187504372732;121519.3924030383;124831.12753308707;127470.08794868084;128343.35828320892;129489;11210.66368008961;11591.710207244887;11629.825552616718;11727.824082638766;11878.524859005543;14624.488142914946;14629.853701463062;14634.560963171121;14636.926815365856;14704.411823527153;8470.195331443503;8596.957015214884;8662.91337086621;8687.6524939003;8735.126487351341;8598.398112132216;8601.182887625666;8620.094890036582;8744.606492707844;8773.298136149093;3783.4376566711376;4081.9591804082174;4377.190219809346;4635.3742244797195;4668;1581.5018269245152;1666.6000159961523;1687.7721507596564;1695.6015336395938;1918.4340619517277;833.3749687734199;958.0611001218705;969.7721035556639;1054.5887104029434;1067.4022547373495;21822.890885545472;22606.66090008651;22636.547269054856;22695;22790.430239244153;880.903100658927;964.8986856380119;982.7101005203482;982.9066238707311;996.5615129343066;9669.758256043655;10683.25217234786;11633.709157271136;11833.763324733629;11867.762644747057;611872;613735;626321;627360;631058;81133.18866811374;88469;110736;139634.30182849022;143510.2824485871;937.2033771294482;998.9500524973868;1090.9018188363086;1092.0771947704177;1093.5953697132113;19633.509162271057;19727.60544789125;20111.19555217775;20172.79827201709;20294.391168264974;38764;50194.749026254634;53973.46026539841;55476.44523554713;56180.48268625426;61062.69468652628;89370;89472;89765.55117224372;90477;7505.324520793155;7524.548527088392;7629.618519073913;7703.191164927602;7844.80387990282;12124.878751212374;12181;12935.417906194432;13329.400176991865;13715.245661488714;9788.412695238023;11370.772584548427;11546.076313895037;11586.42067896585;11888.108391870472;9180.540972951192;11412.657620271406;11461.255018423775;11805.173637845268;11846.822297665369;11688.824667630162;11729.706757330794;11919.165658404176;11984;12119.636410907688;7535.208803075599;7621.466497345133;7669.808254793675;7732.4598794515605;7820.687172513036;10720.035196832323;11656.941715291368;11783.587574434854;11838.585649502225;11958.58144964922 10:46:24 INFO - PROCESS | 7120 | |3;mootools.html;1540.8151021877377;1545.48998830386;1565.3034399692137;1867.4771064102056;1884.7267146263785;6530.2163740351225;7639.19788422216;8763.123687631241;9231.584578693966;9232.446053236803;8878.733637990854;9063.184313411792;9081.59132839024;9091.18179363857;9103.54482275887;2366.6331718583647;2384.7972922301624;2401.903923843044;2406.7713567211085;2418.5404773093146;2352.8941197646145;2376.275236053003;2379.916702915398;2400.7119145702454;2402.447437089462;2404.9158279460135;2405.1581946318825;2406.759324067597;2425.551273014498;2427.8664673442954;2384.78536931677;2398.0767404549288;2401.13559118718;2409.132712223598;2413.1312727418117;2366.337425520857;2394.760523947609;2396.436837343223;2398.304491697406;2409.0484258717884;9249.167574918223;9293.442393456347;9297.860532092023;9300.02349753273;9359.251259899193;5345.1447768356875;5440.401555828856;5495.945040549584;5515.61390702651;5527.115661494142;3599.8740044098595;3708.147126160998;3741.7942013189263;3764.228333191689;3789.696824254053;1094.9980767597638;1099.8946059210443;1105.2981356838386;1107.5403707461394;1116.2799994003904;11995.460204290828;11995.520179192821;12047.638570842888;12060.879391206063;12155.149139560235;4455.866324010285;4572.588467037983;4722.362481065081;4727.57451829337;4743.501932297128;9229.630814767334;9493.857592136124;9508.42949423023;9508.572114254875;9512.33413661037;5220.530152286313;5354.384245811703;5360.0351936650995;5363.731813409314;5379.919301210485;4499.235130027903;4848.272759086165;4972.378452693413;4985.501449855058;5027.82402615914;3020.4714175019285;3629.25600251953;3818.5417749870194;3958.802059896994;3974.82113304902;1069.0227715811584;1176.65876895701;1183.71590818203;1196.084994978842;1197.3055627735891;818.9140140285303;872.2367928062945;936.7283487788601;966.9226461883173;972.557486343726;4701.929471057937;6027.9752442084955;6033.547483938634;6064.666443345658;6096.664683442454;834.739543289637;950.3964982236191;957.9042095790504;964.8986856380119;1031.6801791444677;3175.539546765706;3963.623455771697;3973.145773658677;3994.08136128692;4145.17096580685;10797.56809727602;10888.67333979982;11126.109911207246;11205.495752691148;11239.60661376848;11009;11015.504302306415;11034.393108379116;11036.061934735637;11104;8072.394570407243;8473.745787626258;8480.787980300418;8489.108643592333;8575.957120214483;5951.494122999594;6007.609505382136;6158.753649853956;6221.657808820558;6353.745850165941;7487.812804679927;10221.182305415698;10427.895721042692;10431.739206519595;10558.208134390105;10074.69775906724;10228.693139205836;10434.68695939123;10592.417417041828;10618.73453163677;7205.567665940061;7651.464397492122;7737.690492380242;7771.028621422322;7861.410394220571;7006.159260888726;7267.30960558746;8651.52416617092;8779.429337093068;8783.5169065702;7727.265909738566;7814.101378341336;7824.882626760489;7923.801904952192;8002.679892804455;6292.4651404629285;7376.336129748348;7533.530961462462;7590.8102297443;7661.425393095428;7212.675429605785;7232.819179520554;7312.268773122858;7319.5242309249725;7333.816654583678;5035.295058691713;5134.460881607378;5212.837703812439;5227.268182454383;5282.8943421132135;4508.278675411918;4836.879078023077;5048.697078175322;5187.377514698261;5263.894722105613 10:46:24 INFO - PROCESS | 7120 | |4;prototype.html;304.9009072051584;305.40750943170247;317.6109266148967;320.1883226022033;322.6144757015368;4962.330085438465;5519.530839878616;5594.86012849679;5756.517307029245;5777.537796976242;4468.642508599312;5705.686187259699;5760.407816482579;5767.625104368223;5793.406813126392;4319.913601727963;4326.545712700153;4331.610155086041;4354.586314300136;4356.368326592642;3690.5017822593873;3693.002889219909;4023.155137421145;4250.362445633149;4294.828206871722;3598.6581274778987;3926.7447615905016;4152.792360381984;4290.292101803198;4295.806688699016;3595.586507551639;4247.405363249148;4265.168292182994;4289.549597292286;4318.114786468798;3696.9260614787822;3706.610805865372;3710.5510363160456;3721.5348081489815;3744.812759362021;5075.898482030376;5262.394824595144;5513.4438287304865;5594;5616.297962754656;4890.339804126433;4893.632977526664;4953.950460495421;4985.5513003829465;4985.576226020793;4670.243606510099;5788.842231553706;5982.910256346115;6082.756689732405;6084.6349219047;3529.4176460883923;4033.475648165749;4057.89855253621;4069.552349241581;4081.510218773766;5999.730012149507;6066.211392518944;6218.595791273553;6252.779373922584;6269.373062693694;3449.9482507762405;3731.9440208396895;4134.441850350225;4151.813168407429;4152.522459917148;3305.9173520662175;3986.6412022918075;4092.877213683594;4102.569230230843;4113;4017.457643218128;4794.880127996758;4819.3012013257885;4854.5873600744335;4881.414230292387;5201.45384734605;5384.461553844662;5833.862396832576;5844.707764611752;6077.237601095721;1326.8739469750358;1381.6269607205998;1385.9099158554661;1392.6587985943509;1413.7808639660868;735.8043179832772;817.9591020449072;839.2530647723531;858.3776761847611;858.6608289725524;4147.776405960189;4569.337446070368;4575.336576196433;4677.345171675968;4680.923387620798;4176.016392213735;4487.858499050678;4971.806766376044;5106.825430151086;5121.820736274212;4249.32010878258;4277.529471758104;4650.6046986005895;4703.95296047042;4750.61995040396;2247.9662805057937;2327.615943369359;2639.2185274939725;2684.5167869783627;2706.2950341284863;4383.912321753547;4945.373199912135;5055.34280543527;5338.599605029638;5410;4414.381986521889;4449.911001780011;4856.708597484162;5077.1876499759865;5284.150547308488;4550.522195169526;4580.519045500241;4885.316055752198;5003.899922001612;5066.758644132137;5125.9487405126965;5143.459936706741;5169.663971841818;5185.274061631299;5221.947780522298;4960.214322843845;5037.345145131109;5128.410232823273;5130.435652078343;5164.302819119371;5518.944810551843;5621.634593751393;5890.646561206341;6519.8044058678315;6573.835654108686;3372.915677108092;3938.369860822255;3947.111899822517;4059.127287633173;4069.7558146511305;5924.7926322578505;6025.794841031723;6227.346128656426;6255.18682571281;6318.682691586953;5513.696746678971;5869.442402971711;6023.45788879003;6029.8191054268445;6043.2748070231855;5637.774489020558;5955.076963070731;5981.521478281814;5999.6700181488695;6016.518678505797;5578.442155784389;5873.353931067493;5910.517582615226;5970.940290597038;6014.40618236159;5773.826785196451;5935.883661077969;5994.850128746816;6091.817245482643;6097.323236110105;4786.521347865186;5846.503047241032;5885.17607534954;5910.70446477683;5921.6743079131065 10:46:24 INFO - PROCESS | 7120 | |5;yui.html;729.5258082246536;757.6893473675792;792.0178978067195;843.3927572148059;863.2877875752506;15161;15657.608559786011;15882.285297161627;15889.046657200575;15890;14204.644883877909;14241.857581424183;14400.423983040691;14431;14443.638909027279;709.0604948443307;877.8595424732044;896.9641214351418;916.8303863785221;916.8716379706847;889.8487257166265;907.7549061753356;908.9091090890925;910.499225426013;912.1289168843738;815.8898548695939;955.4076472587005;967.2890425537224;972.7033254857301;974.878140232471;946.7680418297526;964.8793900762405;965.2953344058841;965.3918031640092;966.2415004221723;702.1679310017646;717.2683862460261;718.5150023733959;718.6909628859588;720.1178556268582;15101.924490377587;15144.015638983541;15145.242737863173;15149.469768558047;15205.08769473824;14604;14691.853081469155;14751.631209219748;14775.335109919972;14948.701025979528;11814.881851181464;12206.389680516028;12426.5650702225;12442.937785311105;12460.501579936792;3543.8228088595724;3831.63599458051;3862.5364956205153;3961;4056.127932494528;81439.5928020362;113125;113494.43252783766;115634.42182789033;116749;11497.942510287563;11586.536538538365;11664.708382290512;11796.05631549474;11855.58505452305;18993.24027038903;19867;24030.639540406908;24133.276001719976;24271.514569708506;8213.301869341172;8270.958645206736;8336.201521386707;8383.538905360141;8469.237768600855;4480.103979204172;4562.8631141065825;4563.383943167696;4612.723236605747;4623.722576645412;3499.370113379616;3585.2650206707563;3587.0852932502044;3615.295017471568;3621.8913432597064;665.195113912162;674.6491824251361;675.6081472745796;678.1658559971263;680.7243066558052;596.501920896048;629.7827249598881;676.6751959059679;688.3116883116883;709.5990765217635;7246.710131594783;8207.507549547046;9298.209652179505;9571.904280957102;9764.462954537568;559.5216090242865;646.5033447568957;665.6638397609273;711.3597762014125;730.4229658569776;4269.018125831014;4380.518143004267;4520.977395113069;4522.072975040109;4575.95424045762;105101.94898051074;122628.38685806692;140148;151264;162696.18651906902;125689.37155314165;127195;127808;129715;130661;111750;112793.87206127998;113473.73052538902;114550;114734;7085.6811443486195;9011.414258073204;9036.32227582947;9053.818923621358;9127.202574898642;60341.69829150826;63738.68130659317;65996.34003659902;67057;69123.32273481616;74551.62724186345;96861;97536.58621948198;98445;99681.50159249158;11349.205555611028;11747.647570572897;11923.940380298043;12223.816642750187;12450.81323780126;14911;15586.454474093352;15619.062856228662;15874.68250635004;16099.67800644004;13788.172709637454;14453.566393008226;14633.414663413345;14721.26393680333;15076.698466030399;14085.577432677037;14088.295585220492;14175.574732758034;14236.644083897985;14495.78256326135;13870.65323366924;13961.860381396056;14138.010339276152;14279.71440571162;14555.636109096933;10975.780484390427;11185.552577896793;11252.099832013255;11532.94233528827;11610.303381797119;11531.827022594836;11568.18499510744;11776.76446471083;11859.52561897549;11866.584669536525 10:46:24 INFO - PROCESS | 7120 | __end_tp_report 10:46:24 INFO - PROCESS | 7120 | __start_cc_report 10:46:24 INFO - PROCESS | 7120 | _x_x_mozilla_cycle_collect,419 10:46:24 INFO - PROCESS | 7120 | __end_cc_report 10:46:24 INFO - PROCESS | 7120 | __startTimestamp1469555184076__endTimestamp 10:46:24 INFO - PROCESS | 7120 | 10:46:24 INFO - PROCESS | 7120 | ------- Summary: start ------- 10:46:24 INFO - PROCESS | 7120 | Number of tests: 6 10:46:24 INFO - PROCESS | 7120 | 10:46:24 INFO - PROCESS | 7120 | [#0] dojo.html Cycles:165 Average:5827.10 Median:6090.06 stddev:695.00 (11.4%) stddev-sans-first:659.10 10:46:24 INFO - PROCESS | 7120 | Values: 2936.5 3188.3 3381.2 3449.7 3456.4 5786.9 5916.1 5928.7 6005.5 6034.8 5653.2 5913.6 5927.9 5932.9 6045.1 5273.8 5351.9 5371.8 5467.0 5496.3 5408.6 5433.6 5478.0 5503.1 5518.7 4352.7 4526.5 5016.2 5359.4 5361.5 5321.1 5358.9 5382.1 5394.9 5447.0 4833.3 5369.8 5391.1 5399.2 5407.6 4559.4 5834.7 5949.2 5976.9 5981.7 6231.5 6239.3 6255.5 6273.6 6292.6 6210.4 6293.4 6350.7 6385.7 6413.1 6033.0 6085.1 6090.1 6118.9 6129.0 6479.5 6548.3 6553.9 6577.7 6588.3 6120.9 6145.0 6210.7 6224.4 6250.0 5427.7 6245.3 6261.5 6367.8 6388.0 6190.6 6213.1 6276.0 6287.2 6299.9 5282.9 5326.9 6298.5 6310.3 6330.6 4975.9 5028.6 5063.6 5098.4 5128.3 4504.6 4517.9 4564.2 4564.7 4595.6 5982.6 6044.8 6051.4 6059.6 6072.4 6190.7 6257.4 6281.9 6328.7 6393.5 6010.8 6030.1 6033.4 6047.6 6056.2 4683.7 4909.4 5589.0 5596.9 5607.9 6413.5 6502.0 6511.3 6590.1 6609.8 5467.6 5550.3 5868.7 6331.4 6392.6 5652.3 6566.5 6585.6 6617.5 6642.8 6176.0 6208.1 6219.7 6285.0 6318.7 5199.8 6346.2 6357.4 6379.3 6389.3 6226.7 6235.3 6264.3 6287.8 6306.5 6105.2 6143.3 6168.1 6182.8 6263.7 6029.5 6095.1 6096.0 6118.5 6142.9 6157.6 6188.2 6195.3 6276.7 6279.4 6031.5 6102.8 6113.7 6121.8 6197.5 10:46:24 INFO - PROCESS | 7120 | 10:46:24 INFO - PROCESS | 7120 | [#1] ext.html Cycles:180 Average:58626.65 Median:42246.26 stddev:120727.08 (285.8%) stddev-sans-first:120986.87 10:46:24 INFO - PROCESS | 7120 | Values: 497.3 522.3 535.4 546.5 561.5 40284.8 43186.1 43336.8 43415.6 43508.0 44257.3 44461.1 44714.6 44723.1 44771.8 9716.6 9866.6 9927.5 9943.9 9946.5 9855.6 9922.2 9935.0 9964.6 9980.3 8379.8 9794.9 9840.5 9898.2 9900.9 8936.5 9676.0 9950.6 9950.7 9995.8 9680.0 9700.0 9771.9 9778.1 9824.1 41276.8 42009.8 42147.6 42267.4 42440.2 3466.6 3485.8 3517.6 3587.2 3678.7 32893.5 33768.0 33871.0 33889.5 33989.8 3225.4 3362.5 3376.1 3402.8 3408.2 459653.0 465046.0 465714.0 467110.7 469562.0 64494.4 64754.0 64880.7 65453.0 65497.3 61922.4 69461.3 69547.0 69685.7 69800.7 16757.7 16818.0 17011.7 17016.1 17024.8 12983.3 13037.2 13098.9 13112.6 13140.2 16953.8 17209.9 18161.4 18826.2 18869.5 5933.8 6680.7 7452.0 7641.6 7933.6 881.5 903.6 962.4 996.3 1159.1 23845.6 26719.7 31697.7 31782.8 32319.0 1040.2 1056.6 1092.5 1098.0 1217.2 16184.5 16649.3 16667.3 16667.4 16694.2 146137.0 173028.0 173053.3 173535.0 173644.0 63602.0 64217.7 64346.0 64620.4 64635.0 1245.5 1391.8 1421.2 1452.5 1454.1 30955.0 31525.8 31768.5 32032.7 32208.1 54641.2 55738.0 55742.2 56346.0 56450.4 599777.0 600522.0 604219.0 605099.0 605581.0 24095.5 24770.9 24848.6 25638.5 44781.3 50803.5 51404.0 51497.0 51819.7 53059.5 41971.0 42201.9 42214.8 42225.2 43557.9 32137.7 41660.2 41745.4 41910.4 43193.0 40282.0 41115.0 41146.8 41290.8 42869.8 31943.0 32707.5 33957.8 36562.5 36749.9 25186.0 26736.5 27063.3 27372.0 27484.9 10:46:24 INFO - PROCESS | 7120 | 10:46:24 INFO - PROCESS | 7120 | [#2] jquery.html Cycles:180 Average:35600.90 Median:22621.60 stddev:103628.63 (458.1%) stddev-sans-first:103885.57 10:46:24 INFO - PROCESS | 7120 | Values: 373.2 388.5 390.8 402.0 407.9 19565.6 21874.5 21992.6 22167.1 22467.7 19465.5 21638.7 22166.7 22172.8 22236.6 5873.1 6093.8 6176.8 6178.7 6200.1 4196.3 6078.6 6510.4 6810.8 6858.4 5592.1 6827.4 6850.6 6867.1 6884.7 5678.9 5843.9 5969.5 5973.8 5985.0 4508.6 4968.1 5287.3 5329.9 5371.6 13526.7 13610.0 13647.0 14347.5 22492.7 1979.3 2041.8 2220.8 2255.4 2654.5 10202.9 10378.9 10502.4 15092.8 16069.2 1759.0 1767.9 1872.5 1877.1 1924.0 121519.4 124831.1 127470.1 128343.4 129489.0 11210.7 11591.7 11629.8 11727.8 11878.5 14624.5 14629.9 14634.6 14636.9 14704.4 8470.2 8597.0 8662.9 8687.7 8735.1 8598.4 8601.2 8620.1 8744.6 8773.3 3783.4 4082.0 4377.2 4635.4 4668.0 1581.5 1666.6 1687.8 1695.6 1918.4 833.4 958.1 969.8 1054.6 1067.4 21822.9 22606.7 22636.5 22695.0 22790.4 880.9 964.9 982.7 982.9 996.6 9669.8 10683.3 11633.7 11833.8 11867.8 611872.0 613735.0 626321.0 627360.0 631058.0 81133.2 88469.0 110736.0 139634.3 143510.3 937.2 999.0 1090.9 1092.1 1093.6 19633.5 19727.6 20111.2 20172.8 20294.4 38764.0 50194.7 53973.5 55476.4 56180.5 61062.7 89370.0 89472.0 89765.6 90477.0 7505.3 7524.5 7629.6 7703.2 7844.8 12124.9 12181.0 12935.4 13329.4 13715.2 9788.4 11370.8 11546.1 11586.4 11888.1 9180.5 11412.7 11461.3 11805.2 11846.8 11688.8 11729.7 11919.2 11984.0 12119.6 7535.2 7621.5 7669.8 7732.5 7820.7 10720.0 11656.9 11783.6 11838.6 11958.6 10:46:24 INFO - PROCESS | 7120 | 10:46:24 INFO - PROCESS | 7120 | [#3] mootools.html Cycles:180 Average:5790.45 Median:4978.94 stddev:3276.26 (65.8%) stddev-sans-first:3269.88 10:46:24 INFO - PROCESS | 7120 | Values: 1540.8 1545.5 1565.3 1867.5 1884.7 6530.2 7639.2 8763.1 9231.6 9232.4 8878.7 9063.2 9081.6 9091.2 9103.5 2366.6 2384.8 2401.9 2406.8 2418.5 2352.9 2376.3 2379.9 2400.7 2402.4 2404.9 2405.2 2406.8 2425.6 2427.9 2384.8 2398.1 2401.1 2409.1 2413.1 2366.3 2394.8 2396.4 2398.3 2409.0 9249.2 9293.4 9297.9 9300.0 9359.3 5345.1 5440.4 5495.9 5515.6 5527.1 3599.9 3708.1 3741.8 3764.2 3789.7 1095.0 1099.9 1105.3 1107.5 1116.3 11995.5 11995.5 12047.6 12060.9 12155.1 4455.9 4572.6 4722.4 4727.6 4743.5 9229.6 9493.9 9508.4 9508.6 9512.3 5220.5 5354.4 5360.0 5363.7 5379.9 4499.2 4848.3 4972.4 4985.5 5027.8 3020.5 3629.3 3818.5 3958.8 3974.8 1069.0 1176.7 1183.7 1196.1 1197.3 818.9 872.2 936.7 966.9 972.6 4701.9 6028.0 6033.5 6064.7 6096.7 834.7 950.4 957.9 964.9 1031.7 3175.5 3963.6 3973.1 3994.1 4145.2 10797.6 10888.7 11126.1 11205.5 11239.6 11009.0 11015.5 11034.4 11036.1 11104.0 8072.4 8473.7 8480.8 8489.1 8576.0 5951.5 6007.6 6158.8 6221.7 6353.7 7487.8 10221.2 10427.9 10431.7 10558.2 10074.7 10228.7 10434.7 10592.4 10618.7 7205.6 7651.5 7737.7 7771.0 7861.4 7006.2 7267.3 8651.5 8779.4 8783.5 7727.3 7814.1 7824.9 7923.8 8002.7 6292.5 7376.3 7533.5 7590.8 7661.4 7212.7 7232.8 7312.3 7319.5 7333.8 5035.3 5134.5 5212.8 5227.3 5282.9 4508.3 4836.9 5048.7 5187.4 5263.9 10:46:24 INFO - PROCESS | 7120 | 10:46:24 INFO - PROCESS | 7120 | [#4] prototype.html Cycles:180 Average:4577.93 Median:4957.08 stddev:1438.34 (29.0%) stddev-sans-first:1406.17 10:46:24 INFO - PROCESS | 7120 | Values: 304.9 305.4 317.6 320.2 322.6 4962.3 5519.5 5594.9 5756.5 5777.5 4468.6 5705.7 5760.4 5767.6 5793.4 4319.9 4326.5 4331.6 4354.6 4356.4 3690.5 3693.0 4023.2 4250.4 4294.8 3598.7 3926.7 4152.8 4290.3 4295.8 3595.6 4247.4 4265.2 4289.5 4318.1 3696.9 3706.6 3710.6 3721.5 3744.8 5075.9 5262.4 5513.4 5594.0 5616.3 4890.3 4893.6 4954.0 4985.6 4985.6 4670.2 5788.8 5982.9 6082.8 6084.6 3529.4 4033.5 4057.9 4069.6 4081.5 5999.7 6066.2 6218.6 6252.8 6269.4 3449.9 3731.9 4134.4 4151.8 4152.5 3305.9 3986.6 4092.9 4102.6 4113.0 4017.5 4794.9 4819.3 4854.6 4881.4 5201.5 5384.5 5833.9 5844.7 6077.2 1326.9 1381.6 1385.9 1392.7 1413.8 735.8 818.0 839.3 858.4 858.7 4147.8 4569.3 4575.3 4677.3 4680.9 4176.0 4487.9 4971.8 5106.8 5121.8 4249.3 4277.5 4650.6 4704.0 4750.6 2248.0 2327.6 2639.2 2684.5 2706.3 4383.9 4945.4 5055.3 5338.6 5410.0 4414.4 4449.9 4856.7 5077.2 5284.2 4550.5 4580.5 4885.3 5003.9 5066.8 5125.9 5143.5 5169.7 5185.3 5221.9 4960.2 5037.3 5128.4 5130.4 5164.3 5518.9 5621.6 5890.6 6519.8 6573.8 3372.9 3938.4 3947.1 4059.1 4069.8 5924.8 6025.8 6227.3 6255.2 6318.7 5513.7 5869.4 6023.5 6029.8 6043.3 5637.8 5955.1 5981.5 5999.7 6016.5 5578.4 5873.4 5910.5 5970.9 6014.4 5773.8 5935.9 5994.9 6091.8 6097.3 4786.5 5846.5 5885.2 5910.7 5921.7 10:46:24 INFO - PROCESS | 7120 | 10:46:24 INFO - PROCESS | 7120 | [#5] yui.html Cycles:180 Average:24830.56 Median:3564.54 stddev:38957.39 (1092.9%) stddev-sans-first:39024.65 10:46:24 INFO - PROCESS | 7120 | Values: 729.5 757.7 792.0 843.4 863.3 15161.0 15657.6 15882.3 15889.0 15890.0 14204.6 14241.9 14400.4 14431.0 14443.6 709.1 877.9 897.0 916.8 916.9 889.8 907.8 908.9 910.5 912.1 815.9 955.4 967.3 972.7 974.9 946.8 964.9 965.3 965.4 966.2 702.2 717.3 718.5 718.7 720.1 15101.9 15144.0 15145.2 15149.5 15205.1 14604.0 14691.9 14751.6 14775.3 14948.7 11814.9 12206.4 12426.6 12442.9 12460.5 3543.8 3831.6 3862.5 3961.0 4056.1 81439.6 113125.0 113494.4 115634.4 116749.0 11497.9 11586.5 11664.7 11796.1 11855.6 18993.2 19867.0 24030.6 24133.3 24271.5 8213.3 8271.0 8336.2 8383.5 8469.2 4480.1 4562.9 4563.4 4612.7 4623.7 3499.4 3585.3 3587.1 3615.3 3621.9 665.2 674.6 675.6 678.2 680.7 596.5 629.8 676.7 688.3 709.6 7246.7 8207.5 9298.2 9571.9 9764.5 559.5 646.5 665.7 711.4 730.4 4269.0 4380.5 4521.0 4522.1 4576.0 105101.9 122628.4 140148.0 151264.0 162696.2 125689.4 127195.0 127808.0 129715.0 130661.0 111750.0 112793.9 113473.7 114550.0 114734.0 7085.7 9011.4 9036.3 9053.8 9127.2 60341.7 63738.7 65996.3 67057.0 69123.3 74551.6 96861.0 97536.6 98445.0 99681.5 11349.2 11747.6 11923.9 12223.8 12450.8 14911.0 15586.5 15619.1 15874.7 16099.7 13788.2 14453.6 14633.4 14721.3 15076.7 14085.6 14088.3 14175.6 14236.6 14495.8 13870.7 13961.9 14138.0 14279.7 14555.6 10975.8 11185.6 11252.1 11532.9 11610.3 11531.8 11568.2 11776.8 11859.5 11866.6 10:46:24 INFO - PROCESS | 7120 | -------- Summary: end -------- 10:46:24 INFO - PROCESS | 7120 | 10:46:24 INFO - TEST-INFO | 7120: exit 0 10:46:24 INFO - TEST-OK | dromaeo_css | took 1112847ms 10:46:24 INFO - TEST-START | kraken 10:46:24 INFO - operating with platform_type : mac_ 10:46:24 INFO - Initialising browser for kraken test... 10:46:24 INFO - TEST-INFO | started process 7125 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/tmp6S_glj/profile http://localhost:62115/getInfo.html) 10:46:26 INFO - PROCESS | 7125 | JavaScript error: , line 0: uncaught exception: undefined 10:46:27 INFO - PROCESS | 7125 | __metrics Screen width/height:1600/1200 10:46:27 INFO - PROCESS | 7125 | colorDepth:24 10:46:27 INFO - PROCESS | 7125 | Browser inner width/height: 1024/689 10:46:27 INFO - PROCESS | 7125 | __metrics 10:46:27 ERROR - PROCESS | 7125 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2968: TypeError: this.tabs is undefined 10:46:28 INFO - TEST-INFO | 7125: exit 0 10:46:28 INFO - Browser initialized. 10:46:28 INFO - Running cycle 1/1 for kraken test... 10:46:28 INFO - TEST-INFO | started process 7127 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/tmp6S_glj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:46:40 INFO - PROCESS | 7127 | RSS: Main: 257212416 10:46:40 INFO - PROCESS | 7127 | 10:47:29 INFO - PROCESS | 7127 | 1469555249856 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 11:46:28 INFO - Terminating psutil.Process(pid=7127, name='firefox') 11:46:28 INFO - PROCESS | 7127 | [Child 7128] ###!!! ABORT: Aborting on channel error.: file /builds/slave/autoland-m64-00000000000000000/build/src/ipc/glue/MessageChannel.cpp, line 2056 11:46:28 INFO - PROCESS | 7127 | [Child 7128] ###!!! ABORT: Aborting on channel error.: file /builds/slave/autoland-m64-00000000000000000/build/src/ipc/glue/MessageChannel.cpp, line 2056 11:46:28 INFO - TEST-UNEXPECTED-ERROR | kraken | timeout 11:46:28 ERROR - Traceback (most recent call last): 11:46:28 INFO - File "/builds/slave/test/build/tests/talos/talos/run_tests.py", line 202, in run_tests 11:46:28 INFO - talos_results.add(mytest.runTest(browser_config, test)) 11:46:28 INFO - File "/builds/slave/test/build/tests/talos/talos/ttest.py", line 70, in runTest 11:46:28 INFO - return self._runTest(browser_config, test_config, setup) 11:46:28 INFO - File "/builds/slave/test/build/tests/talos/talos/ttest.py", line 174, in _runTest 11:46:28 INFO - if counter_management else None), 11:46:28 INFO - File "/builds/slave/test/build/tests/talos/talos/talos_process.py", line 117, in run_browser 11:46:28 INFO - raise TalosError("timeout") 11:46:28 INFO - TalosError: timeout 11:46:28 INFO - TEST-INFO took 3603573ms 11:46:28 INFO - SUITE-END | took 4716s 11:46:28 ERROR - Return code: 2 11:46:28 WARNING - setting return code to 2 11:46:28 ERROR - # TBPL FAILURE # 11:46:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:46:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:46:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:46:28 INFO - 2016-07-26 11:46:28.679 screenresolution[7136:122970] starting screenresolution argv=screenresolution get 11:46:28 INFO - 2016-07-26 11:46:28.682 screenresolution[7136:122970] Display 0: 1600x1200x32@60 11:46:28 INFO - 2016-07-26 11:46:28.690 screenresolution[7137:122973] starting screenresolution argv=screenresolution list 11:46:28 INFO - Available Modes on Display 0 11:46:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:46:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:46:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:46:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:46:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:46:28 INFO - Intel Iris: 11:46:28 INFO - Chipset Model: Intel Iris 11:46:28 INFO - Type: GPU 11:46:28 INFO - Bus: Built-In 11:46:28 INFO - VRAM (Dynamic, Max): 1536 MB 11:46:28 INFO - Vendor: Intel (0x8086) 11:46:28 INFO - Device ID: 0x0a2e 11:46:28 INFO - Revision ID: 0x0009 11:46:28 INFO - Displays: 11:46:28 INFO - Display: 11:46:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:46:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:46:28 INFO - Display Serial Number: 1600x1200 60 11:46:28 INFO - Main Display: Yes 11:46:28 INFO - Mirror: Off 11:46:28 INFO - Online: Yes 11:46:28 INFO - Rotation: Supported 11:46:28 INFO - Return code: 0 11:46:28 INFO - Running post-action listener: _resource_record_post_action 11:46:28 INFO - [mozharness: 2016-07-26 18:46:28.962728Z] Finished run-tests step (success) 11:46:28 INFO - Running post-run listener: _resource_record_post_run 11:46:29 INFO - Total resource usage - Wall time: 4753s; CPU: 41.0%; Read bytes: 610304; Write bytes: 518233600; Read time: 8; Write time: 1681 11:46:29 INFO - TinderboxPrint: CPU usage
41.2% 11:46:29 INFO - TinderboxPrint: I/O read bytes / time
610,304 / 8 11:46:29 INFO - TinderboxPrint: I/O write bytes / time
518,233,600 / 1,681 11:46:29 INFO - TinderboxPrint: CPU idle
11,178.2 (58.8%) 11:46:29 INFO - TinderboxPrint: CPU system
4,265.4 (22.4%) 11:46:29 INFO - TinderboxPrint: CPU user
3,566.4 (18.8%) 11:46:29 INFO - TinderboxPrint: Swap in / out
408,346,624 / 0 11:46:29 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 409108992; Write bytes: 411839488; Read time: 31110; Write time: 909 11:46:29 INFO - run-tests - Wall time: 4718s; CPU: 41.0%; Read bytes: 552960; Write bytes: 104927744; Read time: 7; Write time: 764 11:46:31 INFO - Running post-run listener: _upload_blobber_files 11:46:31 INFO - Blob upload gear active. 11:46:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:46:31 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:46:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:46:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:46:31 INFO - (blobuploader) - INFO - Open directory for files ... 11:46:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:46:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:46:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:46:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:46:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:46:32 INFO - (blobuploader) - INFO - Done attempting. 11:46:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:46:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:46:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:46:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:46:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:46:33 INFO - (blobuploader) - INFO - Done attempting. 11:46:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:46:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:46:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:46:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:46:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:46:44 INFO - (blobuploader) - INFO - Done attempting. 11:46:44 INFO - (blobuploader) - INFO - Iteration through files over. 11:46:44 INFO - Return code: 0 11:46:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:46:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:46:44 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/661f2fce40a7e0bad6b45efe2006f1daaac568032c68c2dcf247b8ff2d3733846891f1cad65cabd0f1f1cbf45552e2f7f55b2a5c97abe6d5550ff9c7a2fda6a3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3e625a71e5207326122272942b30b0611514036e7604559707135e40d0b599caa01a3c4743b906567e23349749e6087f9dcb52fd866996ac732d7a008a43c149", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8b630833fc63b7a871a8aaa2b3752ccd68fdb3eacfb933cea9bdbb33421c2550b4b3c6cb2097213525d3cb84d714b0c4203dbe249580c824d010a14b31ae66f3"} 11:46:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:46:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:46:44 INFO - Contents: 11:46:44 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/661f2fce40a7e0bad6b45efe2006f1daaac568032c68c2dcf247b8ff2d3733846891f1cad65cabd0f1f1cbf45552e2f7f55b2a5c97abe6d5550ff9c7a2fda6a3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3e625a71e5207326122272942b30b0611514036e7604559707135e40d0b599caa01a3c4743b906567e23349749e6087f9dcb52fd866996ac732d7a008a43c149", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8b630833fc63b7a871a8aaa2b3752ccd68fdb3eacfb933cea9bdbb33421c2550b4b3c6cb2097213525d3cb84d714b0c4203dbe249580c824d010a14b31ae66f3"} 11:46:44 INFO - Running post-run listener: copy_logs_to_upload_dir 11:46:44 INFO - Copying logs to upload dir... 11:46:44 INFO - mkdir: /builds/slave/test/build/upload/logs 11:46:44 INFO - Copying logs to upload dir... 11:46:44 WARNING - returning nonzero exit status 2 program finished with exit code 2 elapsedTime=4817.018474 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 2, elapsed: 1 hrs, 20 mins, 17 secs) (at 2016-07-26 11:46:44.266319) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-26 11:46:44.269790) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KWCGVYbzI9/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.RUBWGtJenq/Listeners TMPDIR=/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/661f2fce40a7e0bad6b45efe2006f1daaac568032c68c2dcf247b8ff2d3733846891f1cad65cabd0f1f1cbf45552e2f7f55b2a5c97abe6d5550ff9c7a2fda6a3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3e625a71e5207326122272942b30b0611514036e7604559707135e40d0b599caa01a3c4743b906567e23349749e6087f9dcb52fd866996ac732d7a008a43c149", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8b630833fc63b7a871a8aaa2b3752ccd68fdb3eacfb933cea9bdbb33421c2550b4b3c6cb2097213525d3cb84d714b0c4203dbe249580c824d010a14b31ae66f3"} build_url:https://queue.taskcluster.net/v1/task/UZFvWBzUQc-M6bUEkzz58A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011339 build_url: 'https://queue.taskcluster.net/v1/task/UZFvWBzUQc-M6bUEkzz58A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/661f2fce40a7e0bad6b45efe2006f1daaac568032c68c2dcf247b8ff2d3733846891f1cad65cabd0f1f1cbf45552e2f7f55b2a5c97abe6d5550ff9c7a2fda6a3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3e625a71e5207326122272942b30b0611514036e7604559707135e40d0b599caa01a3c4743b906567e23349749e6087f9dcb52fd866996ac732d7a008a43c149", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8b630833fc63b7a871a8aaa2b3752ccd68fdb3eacfb933cea9bdbb33421c2550b4b3c6cb2097213525d3cb84d714b0c4203dbe249580c824d010a14b31ae66f3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-26 11:46:44.304132) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 11:46:44.304552) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KWCGVYbzI9/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.RUBWGtJenq/Listeners TMPDIR=/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005319 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 11:46:44.353782) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-26 11:46:44.354103) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-26 11:46:44.354435) ========= ========= Total master_lag: 0.24 =========