builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0078 starttime: 1469452647.71 results: success (0) buildid: 20160725020629 builduid: e9cffd69a2054ead90893fbe00f05894 revision: 517663b29d7ffc2ce91f90dc7beaac4fa35e52e5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-25 06:17:27.708694) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-25 06:17:27.709111) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-25 06:17:27.709583) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-25 06:17:27.815008) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 06:17:27.815262) ========= 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.zlmAHSJLNl/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.2YZznXWMHK/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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-25 06:17:27-- 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% 135M=0s 2016-07-25 06:17:27 (135 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.113267 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 06:17:27.955458) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 06:17:27.955883) ========= 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.zlmAHSJLNl/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.2YZznXWMHK/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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.030840 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 06:17:28.007571) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-25 06:17:28.007919) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 517663b29d7ffc2ce91f90dc7beaac4fa35e52e5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 517663b29d7ffc2ce91f90dc7beaac4fa35e52e5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zlmAHSJLNl/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.2YZznXWMHK/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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-25 06:17:28,079 truncating revision to first 12 chars 2016-07-25 06:17:28,079 Setting DEBUG logging. 2016-07-25 06:17:28,079 attempt 1/10 2016-07-25 06:17:28,079 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/517663b29d7f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-25 06:17:29,162 unpacking tar archive at: mozilla-aurora-517663b29d7f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.356086 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-25 06:17:29.386261) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-25 06:17:29.386582) ========= script_repo_revision: 517663b29d7ffc2ce91f90dc7beaac4fa35e52e5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-25 06:17:29.387014) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-25 06:17:29.387284) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-25 06:17:29.405966) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 46 secs) (at 2016-07-25 06:17:29.406324) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zlmAHSJLNl/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.2YZznXWMHK/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 06:17:29 INFO - MultiFileLogger online at 20160725 06:17:29 in /builds/slave/test 06:17:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 06:17:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:17:29 INFO - {'append_to_log': False, 06:17:29 INFO - 'base_work_dir': '/builds/slave/test', 06:17:29 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 06:17:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:17:29 INFO - 'branch': 'Mozilla-Aurora', 06:17:29 INFO - 'buildbot_json_path': 'buildprops.json', 06:17:29 INFO - 'config_files': ('talos/mac_config.py',), 06:17:29 INFO - 'default_actions': ('clobber', 06:17:29 INFO - 'read-buildbot-config', 06:17:29 INFO - 'download-and-extract', 06:17:29 INFO - 'populate-webroot', 06:17:29 INFO - 'create-virtualenv', 06:17:29 INFO - 'install', 06:17:29 INFO - 'run-tests'), 06:17:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:17:29 INFO - 'download_minidump_stackwalk': True, 06:17:29 INFO - 'download_symbols': 'ondemand', 06:17:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:17:29 INFO - 'tooltool.py': '/tools/tooltool.py', 06:17:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:17:29 INFO - '/tools/misc-python/virtualenv.py')}, 06:17:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:17:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:17:29 INFO - 'installer_path': 'installer.exe', 06:17:29 INFO - 'log_level': 'info', 06:17:29 INFO - 'log_name': 'talos', 06:17:29 INFO - 'log_to_console': True, 06:17:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:17:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:17:29 INFO - 'opt_config_files': (), 06:17:29 INFO - 'pip_index': False, 06:17:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:17:29 INFO - 'cmd': ('bash', 06:17:29 INFO - '-c', 06:17:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:17:29 INFO - 'enabled': True, 06:17:29 INFO - 'halt_on_failure': False, 06:17:29 INFO - 'name': 'check_screen_resolution'},), 06:17:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:17:29 INFO - 'cmd': ('bash', 06:17:29 INFO - '-c', 06:17:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:17:29 INFO - 'enabled': True, 06:17:29 INFO - 'halt_on_failure': False, 06:17:29 INFO - 'name': 'check_screen_resolution'},), 06:17:29 INFO - 'run_cmd_checks_enabled': True, 06:17:29 INFO - 'sps_profile': False, 06:17:29 INFO - 'sps_profile_interval': 0, 06:17:29 INFO - 'suite': 'dromaeojs', 06:17:29 INFO - 'system_bits': '32', 06:17:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:17:29 INFO - 'title': 't-yosemite-r7-0078', 06:17:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:17:29 INFO - 'use_talos_json': True, 06:17:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:17:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:17:29 INFO - 'work_dir': 'build'} 06:17:29 INFO - ##### 06:17:29 INFO - ##### Running clobber step. 06:17:29 INFO - ##### 06:17:29 INFO - Running pre-action listener: _resource_record_pre_action 06:17:29 INFO - Running main action method: clobber 06:17:29 INFO - rmtree: /builds/slave/test/build 06:17:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:17:31 INFO - Running post-action listener: _resource_record_post_action 06:17:31 INFO - ##### 06:17:31 INFO - ##### Finished clobber step (success) 06:17:31 INFO - ##### 06:17:31 INFO - ##### 06:17:31 INFO - ##### Running read-buildbot-config step. 06:17:31 INFO - ##### 06:17:31 INFO - Running pre-action listener: _resource_record_pre_action 06:17:31 INFO - Running main action method: read_buildbot_config 06:17:31 INFO - Using buildbot properties: 06:17:31 INFO - { 06:17:31 INFO - "project": "", 06:17:31 INFO - "product": "firefox", 06:17:31 INFO - "script_repo_revision": "production", 06:17:31 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 06:17:31 INFO - "repository": "", 06:17:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 06:17:31 INFO - "buildid": "20160725020629", 06:17:31 INFO - "pgo_build": "False", 06:17:31 INFO - "basedir": "/builds/slave/test", 06:17:31 INFO - "buildnumber": 7, 06:17:31 INFO - "slavename": "t-yosemite-r7-0078", 06:17:31 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 06:17:31 INFO - "platform": "yosemite_r7", 06:17:31 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 06:17:31 INFO - "branch": "mozilla-aurora", 06:17:31 INFO - "repo_path": "releases/mozilla-aurora", 06:17:31 INFO - "revision": "517663b29d7ffc2ce91f90dc7beaac4fa35e52e5", 06:17:31 INFO - "stage_platform": "macosx64", 06:17:31 INFO - "builduid": "e9cffd69a2054ead90893fbe00f05894", 06:17:31 INFO - "slavebuilddir": "test" 06:17:31 INFO - } 06:17:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/YHeanJsCT2-2b3TIqKJc8A/artifacts/public/build/firefox-49.0a2.en-US.mac.dmg. 06:17:31 INFO - Running post-action listener: _resource_record_post_action 06:17:31 INFO - ##### 06:17:31 INFO - ##### Finished read-buildbot-config step (success) 06:17:31 INFO - ##### 06:17:31 INFO - ##### 06:17:31 INFO - ##### Running download-and-extract step. 06:17:31 INFO - ##### 06:17:31 INFO - Running pre-action listener: _resource_record_pre_action 06:17:31 INFO - Running main action method: download_and_extract 06:17:31 INFO - mkdir: /builds/slave/test/build/tests 06:17:31 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')]} 06:17:31 INFO - https://queue.taskcluster.net/v1/task/YHeanJsCT2-2b3TIqKJc8A/artifacts/public/build/firefox-49.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 06:17:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHeanJsCT2-2b3TIqKJc8A/artifacts/public/build/firefox-49.0a2.en-US.mac.test_packages.json 06:17:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHeanJsCT2-2b3TIqKJc8A/artifacts/public/build/firefox-49.0a2.en-US.mac.test_packages.json 06:17:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHeanJsCT2-2b3TIqKJc8A/artifacts/public/build/firefox-49.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a2.en-US.mac.test_packages.json 06:17:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHeanJsCT2-2b3TIqKJc8A/artifacts/public/build/firefox-49.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.mac.test_packages.json'}, attempt #1 06:17:37 INFO - Downloaded 1199 bytes. 06:17:37 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.mac.test_packages.json 06:17:37 INFO - Using the following test package requirements: 06:17:37 INFO - {u'common': [u'firefox-49.0a2.en-US.mac.common.tests.zip'], 06:17:37 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.mac.common.tests.zip', 06:17:37 INFO - u'firefox-49.0a2.en-US.mac.cppunittest.tests.zip'], 06:17:37 INFO - u'gtest': [u'firefox-49.0a2.en-US.mac.common.tests.zip', 06:17:37 INFO - u'firefox-49.0a2.en-US.mac.gtest.tests.zip'], 06:17:37 INFO - u'jittest': [u'firefox-49.0a2.en-US.mac.common.tests.zip', 06:17:37 INFO - u'jsshell-mac.zip'], 06:17:37 INFO - u'mochitest': [u'firefox-49.0a2.en-US.mac.common.tests.zip', 06:17:37 INFO - u'firefox-49.0a2.en-US.mac.mochitest.tests.zip'], 06:17:37 INFO - u'mozbase': [u'firefox-49.0a2.en-US.mac.common.tests.zip'], 06:17:37 INFO - u'reftest': [u'firefox-49.0a2.en-US.mac.common.tests.zip', 06:17:37 INFO - u'firefox-49.0a2.en-US.mac.reftest.tests.zip'], 06:17:37 INFO - u'talos': [u'firefox-49.0a2.en-US.mac.common.tests.zip', 06:17:37 INFO - u'firefox-49.0a2.en-US.mac.talos.tests.zip'], 06:17:37 INFO - u'web-platform': [u'firefox-49.0a2.en-US.mac.common.tests.zip', 06:17:37 INFO - u'firefox-49.0a2.en-US.mac.web-platform.tests.zip'], 06:17:37 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.mac.common.tests.zip', 06:17:37 INFO - u'firefox-49.0a2.en-US.mac.xpcshell.tests.zip']} 06:17:37 INFO - Downloading packages: [u'firefox-49.0a2.en-US.mac.common.tests.zip'] for test suite category: common 06:17:37 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')]} 06:17:37 INFO - https://queue.taskcluster.net/v1/task/YHeanJsCT2-2b3TIqKJc8A/artifacts/public/build/firefox-49.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:17:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHeanJsCT2-2b3TIqKJc8A/artifacts/public/build/firefox-49.0a2.en-US.mac.common.tests.zip 06:17:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHeanJsCT2-2b3TIqKJc8A/artifacts/public/build/firefox-49.0a2.en-US.mac.common.tests.zip 06:17:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHeanJsCT2-2b3TIqKJc8A/artifacts/public/build/firefox-49.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.mac.common.tests.zip 06:17:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHeanJsCT2-2b3TIqKJc8A/artifacts/public/build/firefox-49.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.mac.common.tests.zip'}, attempt #1 06:18:07 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHeanJsCT2-2b3TIqKJc8A/artifacts/public/build/firefox-49.0a2.en-US.mac.common.tests.zip: timed out 06:18:07 INFO - retry: attempt #1 caught exception: timed out 06:18:07 INFO - retry: Failed, sleeping 30 seconds before retrying 06:18:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHeanJsCT2-2b3TIqKJc8A/artifacts/public/build/firefox-49.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.mac.common.tests.zip'}, attempt #2 06:19:07 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHeanJsCT2-2b3TIqKJc8A/artifacts/public/build/firefox-49.0a2.en-US.mac.common.tests.zip: timed out 06:19:07 INFO - retry: attempt #2 caught exception: timed out 06:19:07 INFO - retry: Failed, sleeping 60 seconds before retrying 06:20:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHeanJsCT2-2b3TIqKJc8A/artifacts/public/build/firefox-49.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.mac.common.tests.zip'}, attempt #3 06:20:09 INFO - Downloaded 18735913 bytes. 06:20:09 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:20:09 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 06:20:12 INFO - Return code: 0 06:20:12 INFO - Downloading packages: [u'firefox-49.0a2.en-US.mac.common.tests.zip', u'firefox-49.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 06:20:12 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')]} 06:20:12 INFO - https://queue.taskcluster.net/v1/task/YHeanJsCT2-2b3TIqKJc8A/artifacts/public/build/firefox-49.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:20:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHeanJsCT2-2b3TIqKJc8A/artifacts/public/build/firefox-49.0a2.en-US.mac.common.tests.zip 06:20:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHeanJsCT2-2b3TIqKJc8A/artifacts/public/build/firefox-49.0a2.en-US.mac.common.tests.zip 06:20:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHeanJsCT2-2b3TIqKJc8A/artifacts/public/build/firefox-49.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.mac.common.tests.zip 06:20:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHeanJsCT2-2b3TIqKJc8A/artifacts/public/build/firefox-49.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.mac.common.tests.zip'}, attempt #1 06:20:14 INFO - Downloaded 18735913 bytes. 06:20:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:20:14 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 06:20:18 INFO - Return code: 0 06:20:18 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')]} 06:20:18 INFO - https://queue.taskcluster.net/v1/task/YHeanJsCT2-2b3TIqKJc8A/artifacts/public/build/firefox-49.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 06:20:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHeanJsCT2-2b3TIqKJc8A/artifacts/public/build/firefox-49.0a2.en-US.mac.talos.tests.zip 06:20:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHeanJsCT2-2b3TIqKJc8A/artifacts/public/build/firefox-49.0a2.en-US.mac.talos.tests.zip 06:20:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHeanJsCT2-2b3TIqKJc8A/artifacts/public/build/firefox-49.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.mac.talos.tests.zip 06:20:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHeanJsCT2-2b3TIqKJc8A/artifacts/public/build/firefox-49.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.mac.talos.tests.zip'}, attempt #1 06:20:19 INFO - Downloaded 10833369 bytes. 06:20:19 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:20:19 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 06:20:19 INFO - Return code: 0 06:20:19 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')]} 06:20:19 INFO - https://queue.taskcluster.net/v1/task/YHeanJsCT2-2b3TIqKJc8A/artifacts/public/build/firefox-49.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 06:20:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHeanJsCT2-2b3TIqKJc8A/artifacts/public/build/firefox-49.0a2.en-US.mac.dmg 06:20:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHeanJsCT2-2b3TIqKJc8A/artifacts/public/build/firefox-49.0a2.en-US.mac.dmg 06:20:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHeanJsCT2-2b3TIqKJc8A/artifacts/public/build/firefox-49.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a2.en-US.mac.dmg 06:20:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHeanJsCT2-2b3TIqKJc8A/artifacts/public/build/firefox-49.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.mac.dmg'}, attempt #1 06:20:22 INFO - Downloaded 115649477 bytes. 06:20:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YHeanJsCT2-2b3TIqKJc8A/artifacts/public/build/firefox-49.0a2.en-US.mac.dmg 06:20:22 INFO - mkdir: /builds/slave/test/properties 06:20:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:20:22 INFO - Writing to file /builds/slave/test/properties/build_url 06:20:22 INFO - Contents: 06:20:22 INFO - build_url:https://queue.taskcluster.net/v1/task/YHeanJsCT2-2b3TIqKJc8A/artifacts/public/build/firefox-49.0a2.en-US.mac.dmg 06:20:23 INFO - Running post-action listener: _resource_record_post_action 06:20:23 INFO - Running post-action listener: set_extra_try_arguments 06:20:23 INFO - ##### 06:20:23 INFO - ##### Finished download-and-extract step (success) 06:20:23 INFO - ##### 06:20:23 INFO - ##### 06:20:23 INFO - ##### Running populate-webroot step. 06:20:23 INFO - ##### 06:20:23 INFO - Running pre-action listener: _resource_record_pre_action 06:20:23 INFO - Running main action method: populate_webroot 06:20:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:20:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:20:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:20:23 INFO - '--tppagecycles', 06:20:23 INFO - '7'], 06:20:23 INFO - 'tests': ['tsvgm']}}, 06:20:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:20:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:20:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:20:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:20:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:20:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:20:23 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:20:23 INFO - 'talos_options': ['--disable-e10s'], 06:20:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:20:23 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:20:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:20:23 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:20:23 INFO - 'talos_options': ['--disable-e10s'], 06:20:23 INFO - 'tests': ['damp', 'tps']}, 06:20:23 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:20:23 INFO - 'tests': ['damp', 'tps']}, 06:20:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:20:23 INFO - 'tests': ['dromaeo_dom']}, 06:20:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:20:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:20:23 INFO - 'tests': ['a11yr', 06:20:23 INFO - 'ts_paint', 06:20:23 INFO - 'tpaint', 06:20:23 INFO - 'sessionrestore', 06:20:23 INFO - 'sessionrestore_no_auto_restore', 06:20:23 INFO - 'tabpaint']}, 06:20:23 INFO - 'other-e10s': {'tests': ['a11yr', 06:20:23 INFO - 'ts_paint', 06:20:23 INFO - 'tpaint', 06:20:23 INFO - 'sessionrestore', 06:20:23 INFO - 'sessionrestore_no_auto_restore', 06:20:23 INFO - 'tabpaint']}, 06:20:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:20:23 INFO - 'ts_paint', 06:20:23 INFO - 'tpaint', 06:20:23 INFO - 'sessionrestore', 06:20:23 INFO - 'sessionrestore_no_auto_restore', 06:20:23 INFO - 'tabpaint']}, 06:20:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:20:23 INFO - 'ts_paint', 06:20:23 INFO - 'tpaint', 06:20:23 INFO - 'sessionrestore', 06:20:23 INFO - 'sessionrestore_no_auto_restore', 06:20:23 INFO - 'tabpaint']}, 06:20:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:20:23 INFO - 'tests': ['a11yr', 06:20:23 INFO - 'ts_paint', 06:20:23 INFO - 'tpaint', 06:20:23 INFO - 'sessionrestore', 06:20:23 INFO - 'sessionrestore_no_auto_restore', 06:20:23 INFO - 'tabpaint']}, 06:20:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:20:23 INFO - 'tests': ['a11yr', 06:20:23 INFO - 'ts_paint', 06:20:23 INFO - 'tpaint', 06:20:23 INFO - 'sessionrestore', 06:20:23 INFO - 'sessionrestore_no_auto_restore', 06:20:23 INFO - 'tabpaint']}, 06:20:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:20:23 INFO - 'tests': ['tsvgx', 06:20:23 INFO - 'tsvgr_opacity', 06:20:23 INFO - 'tart', 06:20:23 INFO - 'tscrollx', 06:20:23 INFO - 'cart']}, 06:20:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:20:23 INFO - 'tsvgr_opacity', 06:20:23 INFO - 'tart', 06:20:23 INFO - 'tscrollx', 06:20:23 INFO - 'cart']}, 06:20:23 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:20:23 INFO - 'talos_options': ['--disable-e10s'], 06:20:23 INFO - 'tests': ['tp5o']}, 06:20:23 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:20:23 INFO - 'tests': ['tp5o']}, 06:20:23 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:20:23 INFO - 'talos_options': ['--disable-e10s', 06:20:23 INFO - '--xperf_path', 06:20:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:20:23 INFO - 'tests': ['tp5n']}, 06:20:23 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:20:23 INFO - 'talos_options': ['--xperf_path', 06:20:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:20:23 INFO - 'tests': ['tp5n']}}, 06:20:23 INFO - 'talos.zip': {'path': '', 06:20:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:20:23 INFO - Running post-action listener: _resource_record_post_action 06:20:23 INFO - ##### 06:20:23 INFO - ##### Finished populate-webroot step (success) 06:20:23 INFO - ##### 06:20:23 INFO - ##### 06:20:23 INFO - ##### Running create-virtualenv step. 06:20:23 INFO - ##### 06:20:23 INFO - Running pre-action listener: _resource_record_pre_action 06:20:23 INFO - Running main action method: create_virtualenv 06:20:23 INFO - Creating virtualenv /builds/slave/test/build/venv 06:20:23 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 06:20:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:20:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:20:24 INFO - Using real prefix '/tools/python27' 06:20:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:20:24 INFO - Installing distribute.............................................................................................................................................................................................done. 06:20:27 INFO - Installing pip.................done. 06:20:27 INFO - Return code: 0 06:20:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:20:27 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')]} 06:20:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:20:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:27 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')]} 06:20:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:20:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:27 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 0x1051f6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051fd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb700c8a3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10509bd30>, '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 0x104f00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105092b20>, '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.zlmAHSJLNl/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.2YZznXWMHK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 06:20:27 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 06:20:27 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 06:20:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zlmAHSJLNl/Render', 06:20:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:20:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:20:27 INFO - 'HOME': '/Users/cltbld', 06:20:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:20:27 INFO - 'LOGNAME': 'cltbld', 06:20:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:20:27 INFO - 'MOZ_NO_REMOTE': '1', 06:20:27 INFO - 'NO_EM_RESTART': '1', 06:20:27 INFO - 'PAGER': '/bin/cat', 06:20:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:20:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:20:27 INFO - 'PWD': '/builds/slave/test', 06:20:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:20:27 INFO - 'SHELL': '/bin/bash', 06:20:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2YZznXWMHK/Listeners', 06:20:27 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 06:20:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:20:27 INFO - 'USER': 'cltbld', 06:20:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:20:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:20:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:20:27 INFO - 'XPC_FLAGS': '0x0', 06:20:27 INFO - 'XPC_SERVICE_NAME': '0', 06:20:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:20:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:20:27 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 06:20:27 INFO - Cleaning up... 06:20:27 INFO - Return code: 0 06:20:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:20:27 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')]} 06:20:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:20:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:27 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')]} 06:20:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:20:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:27 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 0x1051f6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051fd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb700c8a3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10509bd30>, '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 0x104f00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105092b20>, '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.zlmAHSJLNl/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.2YZznXWMHK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 06:20:27 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 06:20:27 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 06:20:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zlmAHSJLNl/Render', 06:20:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:20:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:20:27 INFO - 'HOME': '/Users/cltbld', 06:20:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:20:27 INFO - 'LOGNAME': 'cltbld', 06:20:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:20:27 INFO - 'MOZ_NO_REMOTE': '1', 06:20:27 INFO - 'NO_EM_RESTART': '1', 06:20:27 INFO - 'PAGER': '/bin/cat', 06:20:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:20:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:20:27 INFO - 'PWD': '/builds/slave/test', 06:20:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:20:27 INFO - 'SHELL': '/bin/bash', 06:20:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2YZznXWMHK/Listeners', 06:20:27 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 06:20:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:20:27 INFO - 'USER': 'cltbld', 06:20:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:20:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:20:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:20:27 INFO - 'XPC_FLAGS': '0x0', 06:20:27 INFO - 'XPC_SERVICE_NAME': '0', 06:20:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:20:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:20:28 INFO - Downloading/unpacking psutil>=3.1.1 06:20:28 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 06:20:28 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 06:20:28 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 06:20:28 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 06:20:28 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 06:20:28 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 06:20:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:20:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:20:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:20:31 INFO - Installing collected packages: psutil 06:20:31 INFO - Running setup.py install for psutil 06:20:31 INFO - building 'psutil._psutil_osx' extension 06:20:31 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 06:20:32 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 06:20:32 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 06:20:32 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 06:20:32 INFO - building 'psutil._psutil_posix' extension 06:20:32 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 06:20:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:20:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:20:32 INFO - ^ 06:20:32 INFO - 1 warning generated. 06:20:32 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 06:20:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:20:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:20:32 INFO - Successfully installed psutil 06:20:32 INFO - Cleaning up... 06:20:32 INFO - Return code: 0 06:20:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:20:32 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')]} 06:20:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:20:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:32 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')]} 06:20:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:20:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:32 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 0x1051f6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051fd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb700c8a3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10509bd30>, '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 0x104f00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105092b20>, '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.zlmAHSJLNl/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.2YZznXWMHK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 06:20:32 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 06:20:32 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 06:20:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zlmAHSJLNl/Render', 06:20:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:20:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:20:32 INFO - 'HOME': '/Users/cltbld', 06:20:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:20:32 INFO - 'LOGNAME': 'cltbld', 06:20:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:20:32 INFO - 'MOZ_NO_REMOTE': '1', 06:20:32 INFO - 'NO_EM_RESTART': '1', 06:20:32 INFO - 'PAGER': '/bin/cat', 06:20:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:20:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:20:32 INFO - 'PWD': '/builds/slave/test', 06:20:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:20:32 INFO - 'SHELL': '/bin/bash', 06:20:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2YZznXWMHK/Listeners', 06:20:32 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 06:20:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:20:32 INFO - 'USER': 'cltbld', 06:20:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:20:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:20:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:20:32 INFO - 'XPC_FLAGS': '0x0', 06:20:32 INFO - 'XPC_SERVICE_NAME': '0', 06:20:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:20:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:20:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:20:33 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 06:20:33 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 06:20:33 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 06:20:33 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 06:20:33 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 06:20:33 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 06:20:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:20:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:20:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:20:35 INFO - Installing collected packages: mozsystemmonitor 06:20:35 INFO - Running setup.py install for mozsystemmonitor 06:20:35 INFO - Successfully installed mozsystemmonitor 06:20:35 INFO - Cleaning up... 06:20:35 INFO - Return code: 0 06:20:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:20:35 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')]} 06:20:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:20:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:35 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')]} 06:20:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:20:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:35 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 0x1051f6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051fd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb700c8a3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10509bd30>, '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 0x104f00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105092b20>, '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.zlmAHSJLNl/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.2YZznXWMHK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 06:20:35 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 06:20:35 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 06:20:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zlmAHSJLNl/Render', 06:20:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:20:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:20:35 INFO - 'HOME': '/Users/cltbld', 06:20:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:20:35 INFO - 'LOGNAME': 'cltbld', 06:20:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:20:35 INFO - 'MOZ_NO_REMOTE': '1', 06:20:35 INFO - 'NO_EM_RESTART': '1', 06:20:35 INFO - 'PAGER': '/bin/cat', 06:20:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:20:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:20:35 INFO - 'PWD': '/builds/slave/test', 06:20:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:20:35 INFO - 'SHELL': '/bin/bash', 06:20:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2YZznXWMHK/Listeners', 06:20:35 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 06:20:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:20:35 INFO - 'USER': 'cltbld', 06:20:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:20:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:20:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:20:35 INFO - 'XPC_FLAGS': '0x0', 06:20:35 INFO - 'XPC_SERVICE_NAME': '0', 06:20:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:20:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:20:36 INFO - Downloading/unpacking jsonschema==2.5.1 06:20:36 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 06:20:36 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 06:20:36 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 06:20:36 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 06:20:36 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 06:20:36 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 06:20:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:20:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:20:38 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 06:20:38 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 06:20:38 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 06:20:38 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 06:20:38 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 06:20:38 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 06:20:39 INFO - Downloading functools32-3.2.3-2.tar.gz 06:20:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:20:39 INFO - warning: no files found matching '*.txt' 06:20:39 INFO - no previously-included directories found matching 'build' 06:20:39 INFO - no previously-included directories found matching 'dist' 06:20:39 INFO - no previously-included directories found matching '.git*' 06:20:39 INFO - Installing collected packages: jsonschema, functools32 06:20:39 INFO - Running setup.py install for functools32 06:20:39 INFO - warning: no files found matching '*.txt' 06:20:39 INFO - no previously-included directories found matching 'build' 06:20:39 INFO - no previously-included directories found matching 'dist' 06:20:39 INFO - no previously-included directories found matching '.git*' 06:20:39 INFO - Successfully installed jsonschema functools32 06:20:39 INFO - Cleaning up... 06:20:39 INFO - Return code: 0 06:20:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:20: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')]} 06:20:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:20:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20: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')]} 06:20:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:20:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:39 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 0x1051f6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051fd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb700c8a3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10509bd30>, '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 0x104f00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105092b20>, '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.zlmAHSJLNl/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.2YZznXWMHK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 06:20:39 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 06:20:39 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 06:20:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zlmAHSJLNl/Render', 06:20:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:20:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:20:39 INFO - 'HOME': '/Users/cltbld', 06:20:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:20:39 INFO - 'LOGNAME': 'cltbld', 06:20:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:20:39 INFO - 'MOZ_NO_REMOTE': '1', 06:20:39 INFO - 'NO_EM_RESTART': '1', 06:20:39 INFO - 'PAGER': '/bin/cat', 06:20:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:20:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:20:39 INFO - 'PWD': '/builds/slave/test', 06:20:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:20:39 INFO - 'SHELL': '/bin/bash', 06:20:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2YZznXWMHK/Listeners', 06:20:39 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 06:20:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:20:39 INFO - 'USER': 'cltbld', 06:20:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:20:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:20:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:20:39 INFO - 'XPC_FLAGS': '0x0', 06:20:39 INFO - 'XPC_SERVICE_NAME': '0', 06:20:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:20:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:20:39 INFO - Downloading/unpacking blobuploader==1.2.4 06:20:39 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 06:20:39 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 06:20:39 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 06:20:39 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 06:20:39 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 06:20:39 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 06:20:42 INFO - Downloading blobuploader-1.2.4.tar.gz 06:20:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:20:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:20:42 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 06:20:42 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 06:20:42 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 06:20:42 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 06:20:42 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 06:20:42 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 06:20:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:20:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:20:42 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 06:20:42 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 06:20:42 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 06:20:42 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 06:20:42 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 06:20:42 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 06:20:43 INFO - Downloading docopt-0.6.1.tar.gz 06:20:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:20:43 INFO - Installing collected packages: blobuploader, requests, docopt 06:20:43 INFO - Running setup.py install for blobuploader 06:20:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:20:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:20:43 INFO - Running setup.py install for requests 06:20:44 INFO - Running setup.py install for docopt 06:20:44 INFO - Successfully installed blobuploader requests docopt 06:20:44 INFO - Cleaning up... 06:20:44 INFO - Return code: 0 06:20:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:20:44 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')]} 06:20:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:20:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:44 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')]} 06:20:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:20:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:44 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 0x1051f6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051fd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb700c8a3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10509bd30>, '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 0x104f00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105092b20>, '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.zlmAHSJLNl/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.2YZznXWMHK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 06:20:44 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 06:20:44 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 06:20:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zlmAHSJLNl/Render', 06:20:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:20:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:20:44 INFO - 'HOME': '/Users/cltbld', 06:20:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:20:44 INFO - 'LOGNAME': 'cltbld', 06:20:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:20:44 INFO - 'MOZ_NO_REMOTE': '1', 06:20:44 INFO - 'NO_EM_RESTART': '1', 06:20:44 INFO - 'PAGER': '/bin/cat', 06:20:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:20:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:20:44 INFO - 'PWD': '/builds/slave/test', 06:20:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:20:44 INFO - 'SHELL': '/bin/bash', 06:20:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2YZznXWMHK/Listeners', 06:20:44 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 06:20:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:20:44 INFO - 'USER': 'cltbld', 06:20:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:20:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:20:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:20:44 INFO - 'XPC_FLAGS': '0x0', 06:20:44 INFO - 'XPC_SERVICE_NAME': '0', 06:20:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:20:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:20:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:20:44 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-xs9pQX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:20:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:20:44 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-PYOsqO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:20:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:20:44 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-a5rKj0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:20:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:20:44 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-6HqpB0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:20:45 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-vV7DwA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:20:45 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-iOVXKI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:20:45 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-8m5JWx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:20:45 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-9bkSMM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:20:45 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-c9r8Sr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:20:45 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-KNEh0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:20:45 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-5FPHPC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:20:45 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-bsfKhu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:20:45 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-ewKxKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:20:45 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-EI6xRz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:20:46 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-Gpkq3d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:20:46 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-g_8aBp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:20:46 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-bc2ydw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:20:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:20:46 INFO - Running setup.py install for manifestparser 06:20:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:20:46 INFO - Running setup.py install for mozcrash 06:20:46 INFO - Running setup.py install for mozdebug 06:20:46 INFO - Running setup.py install for mozdevice 06:20:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:20:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:20:46 INFO - Running setup.py install for mozfile 06:20:47 INFO - Running setup.py install for mozhttpd 06:20:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:20:47 INFO - Running setup.py install for mozinfo 06:20:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:20:47 INFO - Running setup.py install for mozInstall 06:20:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:20:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:20:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:20:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:20:47 INFO - Running setup.py install for mozleak 06:20:47 INFO - Running setup.py install for mozlog 06:20:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:20:47 INFO - Running setup.py install for moznetwork 06:20:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:20:48 INFO - Running setup.py install for mozprocess 06:20:48 INFO - Running setup.py install for mozprofile 06:20:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:20:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:20:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:20:48 INFO - Running setup.py install for mozrunner 06:20:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:20:48 INFO - Running setup.py install for mozscreenshot 06:20:48 INFO - Running setup.py install for moztest 06:20:48 INFO - Running setup.py install for mozversion 06:20:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:20:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:20:48 INFO - Cleaning up... 06:20:48 INFO - Return code: 0 06:20:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:20:48 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')]} 06:20:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:20:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:48 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')]} 06:20:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:20:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:48 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 0x1051f6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051fd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb700c8a3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10509bd30>, '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 0x104f00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105092b20>, '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.zlmAHSJLNl/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.2YZznXWMHK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 06:20:48 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 06:20:48 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 06:20:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zlmAHSJLNl/Render', 06:20:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:20:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:20:48 INFO - 'HOME': '/Users/cltbld', 06:20:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:20:48 INFO - 'LOGNAME': 'cltbld', 06:20:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:20:48 INFO - 'MOZ_NO_REMOTE': '1', 06:20:48 INFO - 'NO_EM_RESTART': '1', 06:20:48 INFO - 'PAGER': '/bin/cat', 06:20:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:20:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:20:48 INFO - 'PWD': '/builds/slave/test', 06:20:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:20:48 INFO - 'SHELL': '/bin/bash', 06:20:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2YZznXWMHK/Listeners', 06:20:48 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 06:20:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:20:48 INFO - 'USER': 'cltbld', 06:20:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:20:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:20:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:20:48 INFO - 'XPC_FLAGS': '0x0', 06:20:48 INFO - 'XPC_SERVICE_NAME': '0', 06:20:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:20:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:20:49 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-oSNdUN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:20:49 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)) 06:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:20:49 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-cfcRje-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:20:49 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)) 06:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:20:49 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-nR5C3u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:20:49 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)) 06:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:20:49 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-a_aApN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:20:49 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)) 06:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:20:49 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-klwBvz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:20:49 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)) 06:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:20:49 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-eSvuMa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:20:49 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)) 06:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:20:49 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-6DaIAo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:20:49 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)) 06:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:20:49 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-bPkbXE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:20:50 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)) 06:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:20:50 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-ziWH3U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:20:50 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)) 06:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:20:50 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-GlpZar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:20:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 06:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:20:50 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-HO3nSL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:20:50 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)) 06:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:20:50 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-9DldbZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:20:50 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)) 06:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:20:50 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-MtM9Qz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:20:50 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)) 06:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:20:50 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-N7h1zR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:20:50 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)) 06:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:20:50 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-Zhj7Ll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:20:50 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)) 06:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:20:50 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-U6XH0p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:20:50 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)) 06:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:20:50 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-MnusYI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:20:51 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)) 06:20:51 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)) 06:20:51 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)) 06:20:51 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)) 06:20:51 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)) 06:20:51 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)) 06:20:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:20:51 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 06:20:51 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 06:20:51 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 06:20:51 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 06:20:51 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 06:20:51 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 06:20:53 INFO - Downloading blessings-1.6.tar.gz 06:20:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:20:53 INFO - Installing collected packages: blessings 06:20:53 INFO - Running setup.py install for blessings 06:20:53 INFO - Successfully installed blessings 06:20:53 INFO - Cleaning up... 06:20:53 INFO - Return code: 0 06:20:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:20:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:20:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:20:54 INFO - Reading from file tmpfile_stdout 06:20:54 INFO - Current package versions: 06:20:54 INFO - blessings == 1.6 06:20:54 INFO - blobuploader == 1.2.4 06:20:54 INFO - docopt == 0.6.1 06:20:54 INFO - functools32 == 3.2.3-2 06:20:54 INFO - jsonschema == 2.5.1 06:20:54 INFO - manifestparser == 1.1 06:20:54 INFO - mozInstall == 1.12 06:20:54 INFO - mozcrash == 0.17 06:20:54 INFO - mozdebug == 0.1 06:20:54 INFO - mozdevice == 0.48 06:20:54 INFO - mozfile == 1.2 06:20:54 INFO - mozhttpd == 0.7 06:20:54 INFO - mozinfo == 0.9 06:20:54 INFO - mozleak == 0.1 06:20:54 INFO - mozlog == 3.2 06:20:54 INFO - moznetwork == 0.27 06:20:54 INFO - mozprocess == 0.23 06:20:54 INFO - mozprofile == 0.28 06:20:54 INFO - mozrunner == 6.12 06:20:54 INFO - mozscreenshot == 0.1 06:20:54 INFO - mozsystemmonitor == 0.3 06:20:54 INFO - moztest == 0.7 06:20:54 INFO - mozversion == 1.4 06:20:54 INFO - psutil == 3.1.1 06:20:54 INFO - requests == 1.2.3 06:20:54 INFO - wsgiref == 0.1.2 06:20:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:20: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')]} 06:20:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:20:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20: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')]} 06:20:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:20:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:54 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 0x1051f6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051fd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb700c8a3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10509bd30>, '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 0x104f00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105092b20>, '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.zlmAHSJLNl/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.2YZznXWMHK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 06:20:54 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 06:20:54 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 06:20:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zlmAHSJLNl/Render', 06:20:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:20:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:20:54 INFO - 'HOME': '/Users/cltbld', 06:20:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:20:54 INFO - 'LOGNAME': 'cltbld', 06:20:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:20:54 INFO - 'MOZ_NO_REMOTE': '1', 06:20:54 INFO - 'NO_EM_RESTART': '1', 06:20:54 INFO - 'PAGER': '/bin/cat', 06:20:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:20:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:20:54 INFO - 'PWD': '/builds/slave/test', 06:20:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:20:54 INFO - 'SHELL': '/bin/bash', 06:20:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2YZznXWMHK/Listeners', 06:20:54 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 06:20:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:20:54 INFO - 'USER': 'cltbld', 06:20:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:20:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:20:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:20:54 INFO - 'XPC_FLAGS': '0x0', 06:20:54 INFO - 'XPC_SERVICE_NAME': '0', 06:20:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:20:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:20:54 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)) 06:20:54 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)) 06:20:54 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)) 06:20:54 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)) 06:20:54 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)) 06:20:54 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)) 06:20:54 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)) 06:20:54 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)) 06:20:54 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)) 06:20:54 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)) 06:20:54 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)) 06:20:54 INFO - Cleaning up... 06:20:54 INFO - Return code: 0 06:20:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:20: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')]} 06:20:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:20:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20: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')]} 06:20:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:20:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1051f6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051fd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb700c8a3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10509bd30>, '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 0x104f00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105092b20>, '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.zlmAHSJLNl/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.2YZznXWMHK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 06:20: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', 'jsonschema'] in /builds/slave/test/build 06:20: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 jsonschema 06:20:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zlmAHSJLNl/Render', 06:20:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:20:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:20:54 INFO - 'HOME': '/Users/cltbld', 06:20:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:20:54 INFO - 'LOGNAME': 'cltbld', 06:20:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:20:54 INFO - 'MOZ_NO_REMOTE': '1', 06:20:54 INFO - 'NO_EM_RESTART': '1', 06:20:54 INFO - 'PAGER': '/bin/cat', 06:20:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:20:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:20:54 INFO - 'PWD': '/builds/slave/test', 06:20:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:20:54 INFO - 'SHELL': '/bin/bash', 06:20:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2YZznXWMHK/Listeners', 06:20:54 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 06:20:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:20:54 INFO - 'USER': 'cltbld', 06:20:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:20:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:20:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:20:54 INFO - 'XPC_FLAGS': '0x0', 06:20:54 INFO - 'XPC_SERVICE_NAME': '0', 06:20:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:20:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:20:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:20:54 INFO - Cleaning up... 06:20:54 INFO - Return code: 0 06:20:54 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 06:20: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')]} 06:20:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:20:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20: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')]} 06:20:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:20:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1051f6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051fd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb700c8a3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10509bd30>, '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 0x104f00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105092b20>, '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.zlmAHSJLNl/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.2YZznXWMHK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 06:20: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', 'flake8'] in /builds/slave/test/build 06:20: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 flake8 06:20:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zlmAHSJLNl/Render', 06:20:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:20:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:20:54 INFO - 'HOME': '/Users/cltbld', 06:20:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:20:54 INFO - 'LOGNAME': 'cltbld', 06:20:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:20:54 INFO - 'MOZ_NO_REMOTE': '1', 06:20:54 INFO - 'NO_EM_RESTART': '1', 06:20:54 INFO - 'PAGER': '/bin/cat', 06:20:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:20:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:20:54 INFO - 'PWD': '/builds/slave/test', 06:20:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:20:54 INFO - 'SHELL': '/bin/bash', 06:20:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2YZznXWMHK/Listeners', 06:20:54 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 06:20:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:20:54 INFO - 'USER': 'cltbld', 06:20:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:20:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:20:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:20:54 INFO - 'XPC_FLAGS': '0x0', 06:20:54 INFO - 'XPC_SERVICE_NAME': '0', 06:20:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:20:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:20:54 INFO - Downloading/unpacking flake8 06:20: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 06:20: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 06:20: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 06:20: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 06:20: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 06:20: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 06:20:57 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 06:20:57 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 06:20: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 06:20: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 06:20: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 06:20: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 06:20: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 06:20: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 06:20:57 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 06:20:57 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 06:20: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 06:20: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 06:20: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 06:20: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 06:20: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 06:20: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 06:20:58 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 06:20:58 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 06:20:58 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 06:20:58 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 06:20:58 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 06:20:58 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 06:20:58 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 06:20:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 06:20:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:20:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:20:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:20:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:20:58 INFO - no previously-included directories found matching 'docs/_build' 06:20:58 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 06:20:58 INFO - Running setup.py install for pep8 06:20:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:20:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:20:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:20:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:20:58 INFO - no previously-included directories found matching 'docs/_build' 06:20:58 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 06:20:58 INFO - Successfully installed flake8 mccabe pyflakes pep8 06:20:58 INFO - Cleaning up... 06:20:58 INFO - Return code: 0 06:20:58 INFO - Running post-action listener: _resource_record_post_action 06:20:58 INFO - Running post-action listener: _start_resource_monitoring 06:20:58 INFO - Starting resource monitoring. 06:20:59 INFO - ##### 06:20:59 INFO - ##### Finished create-virtualenv step (success) 06:20:59 INFO - ##### 06:20:59 INFO - ##### 06:20:59 INFO - ##### Running install step. 06:20:59 INFO - ##### 06:20:59 INFO - Running pre-action listener: _resource_record_pre_action 06:20:59 INFO - Running main action method: install 06:20:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:20:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:20:59 INFO - Reading from file tmpfile_stdout 06:20:59 INFO - Detecting whether we're running mozinstall >=1.0... 06:20:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:20:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:20:59 INFO - Reading from file tmpfile_stdout 06:20:59 INFO - Output received: 06:20:59 INFO - Usage: mozinstall [options] installer 06:20:59 INFO - Options: 06:20:59 INFO - -h, --help show this help message and exit 06:20:59 INFO - -d DEST, --destination=DEST 06:20:59 INFO - Directory to install application into. [default: 06:20:59 INFO - "/builds/slave/test"] 06:20:59 INFO - --app=APP Application being installed. [default: firefox] 06:20:59 INFO - mkdir: /builds/slave/test/build/application 06:20:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:20:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 06:21:31 INFO - Reading from file tmpfile_stdout 06:21:31 INFO - Output received: 06:21:31 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 06:21:31 INFO - Running post-action listener: _resource_record_post_action 06:21:31 INFO - ##### 06:21:31 INFO - ##### Finished install step (success) 06:21:31 INFO - ##### 06:21:31 INFO - ##### 06:21:31 INFO - ##### Running run-tests step. 06:21:31 INFO - ##### 06:21:31 INFO - Running pre-action listener: _resource_record_pre_action 06:21:31 INFO - Running main action method: run_tests 06:21:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:21:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:21:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:21:31 INFO - 2016-07-25 06:21:31.760 screenresolution[1650:7899] starting screenresolution argv=screenresolution get 06:21:31 INFO - 2016-07-25 06:21:31.762 screenresolution[1650:7899] Display 0: 1600x1200x32@60 06:21:31 INFO - 2016-07-25 06:21:31.769 screenresolution[1651:7902] starting screenresolution argv=screenresolution list 06:21:31 INFO - Available Modes on Display 0 06:21:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:21:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:21:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:21:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:21:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:21:31 INFO - Intel Iris: 06:21:31 INFO - Chipset Model: Intel Iris 06:21:31 INFO - Type: GPU 06:21:31 INFO - Bus: Built-In 06:21:31 INFO - VRAM (Dynamic, Max): 1536 MB 06:21:31 INFO - Vendor: Intel (0x8086) 06:21:31 INFO - Device ID: 0x0a2e 06:21:31 INFO - Revision ID: 0x0009 06:21:31 INFO - Displays: 06:21:31 INFO - Display: 06:21:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:21:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:21:31 INFO - Display Serial Number: 1600x1200 60 06:21:31 INFO - Main Display: Yes 06:21:31 INFO - Mirror: Off 06:21:31 INFO - Online: Yes 06:21:31 INFO - Rotation: Supported 06:21:32 INFO - Return code: 0 06:21:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:21:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:21:32 INFO - Python 2.7.3 06:21:32 INFO - Return code: 0 06:21:32 INFO - grabbing minidump binary from tooltool 06:21:32 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')]} 06:21:32 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 0x10509bd30>, '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 0x104f00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105092b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:21:32 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 06:21:32 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 06:21:32 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 06:21:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:21:32 INFO - Return code: 0 06:21:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:21:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:21:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:21:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:21:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:21:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 06:21:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 06:21:33 INFO - Return code: 0 06:21:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YHeanJsCT2-2b3TIqKJc8A/artifacts/public/build/firefox-49.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0078', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 06:21:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YHeanJsCT2-2b3TIqKJc8A/artifacts/public/build/firefox-49.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0078 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 06:21:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zlmAHSJLNl/Render', 06:21:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:21:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:21:33 INFO - 'HOME': '/Users/cltbld', 06:21:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:21:33 INFO - 'LOGNAME': 'cltbld', 06:21:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:21:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:21:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:21:33 INFO - 'MOZ_NO_REMOTE': '1', 06:21:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:21:33 INFO - 'NO_EM_RESTART': '1', 06:21:33 INFO - 'PAGER': '/bin/cat', 06:21:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:21:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:21:33 INFO - 'PWD': '/builds/slave/test', 06:21:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:21:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:21:33 INFO - 'SHELL': '/bin/bash', 06:21:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2YZznXWMHK/Listeners', 06:21:33 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 06:21:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:21:33 INFO - 'USER': 'cltbld', 06:21:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:21:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:21:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:21:33 INFO - 'XPC_FLAGS': '0x0', 06:21:33 INFO - 'XPC_SERVICE_NAME': '0', 06:21:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:21:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YHeanJsCT2-2b3TIqKJc8A/artifacts/public/build/firefox-49.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0078', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 06:21:33 INFO - mozversion application_buildid: 20160725020629 06:21:33 INFO - mozversion application_changeset: 517663b29d7ffc2ce91f90dc7beaac4fa35e52e5 06:21:33 INFO - mozversion application_display_name: FirefoxDeveloperEdition 06:21:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:21:33 INFO - mozversion application_name: Firefox 06:21:33 INFO - mozversion application_remotingname: firefox-dev 06:21:33 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 06:21:33 INFO - mozversion application_vendor: Mozilla 06:21:33 INFO - mozversion application_version: 49.0a2 06:21:33 INFO - mozversion platform_buildid: 20160725020629 06:21:33 INFO - mozversion platform_changeset: 517663b29d7ffc2ce91f90dc7beaac4fa35e52e5 06:21:33 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 06:21:33 INFO - mozversion platform_version: 49.0a2 06:21:33 INFO - using testdate: 1469452893 06:21:33 INFO - actual date: 1469452893 06:21:33 INFO - starting webserver on 'localhost:49272' 06:21:33 INFO - SUITE-START | Running 2 tests 06:21:33 INFO - TEST-START | dromaeo_css 06:21:33 INFO - operating with platform_type : mac_ 06:21:33 INFO - Initialising browser for dromaeo_css test... 06:21:33 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/tmp9WvfwU/profile http://localhost:49272/getInfo.html) 06:21:35 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 06:21:35 INFO - PROCESS | 1669 | colorDepth:24 06:21:35 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 06:21:35 INFO - PROCESS | 1669 | __metrics 06:21:36 INFO - PROCESS | 1669 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:21:36 ERROR - PROCESS | 1669 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:21:36 INFO - PROCESS | 1669 | ************************* 06:21:36 INFO - PROCESS | 1669 | A coding exception was thrown in a Promise resolution callback. 06:21:36 INFO - PROCESS | 1669 | See https://developer.mozilla.org/Mozilla/JavaScript_code_modules/Promise.jsm/Promise 06:21:36 INFO - PROCESS | 1669 | 06:21:36 ERROR - PROCESS | 1669 | Full message: TypeError: win is null 06:21:36 INFO - PROCESS | 1669 | Full stack: onSuccess@resource://app/components/nsBrowserGlue.js:850:17 06:21:36 INFO - PROCESS | 1669 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 06:21:36 INFO - PROCESS | 1669 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 06:21:36 INFO - PROCESS | 1669 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 06:21:36 INFO - PROCESS | 1669 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 06:21:36 INFO - PROCESS | 1669 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 06:21:36 INFO - PROCESS | 1669 | get _worker/worker.onmessage@resource://gre/modules/PromiseWorker.jsm:235:9 06:21:36 INFO - PROCESS | 1669 | EventHandlerNonNull*get _worker@resource://gre/modules/PromiseWorker.jsm:217:5 06:21:36 INFO - PROCESS | 1669 | postMessage@resource://gre/modules/PromiseWorker.jsm:292:9 06:21:36 INFO - PROCESS | 1669 | TaskImpl_run@resource://gre/modules/Task.jsm:319:40 06:21:36 INFO - PROCESS | 1669 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 06:21:36 INFO - PROCESS | 1669 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 06:21:36 INFO - PROCESS | 1669 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 06:21:36 INFO - PROCESS | 1669 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 06:21:36 INFO - PROCESS | 1669 | Promise.prototype.then@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:454:5 06:21:36 INFO - PROCESS | 1669 | this.DeferredSave.prototype._deferredSave@resource://gre/modules/DeferredSave.jsm:220:5 06:21:36 INFO - PROCESS | 1669 | this.DeferredSave.prototype._startTimer/<@resource://gre/modules/DeferredSave.jsm:175:40 06:21:36 INFO - PROCESS | 1669 | syncLoadManifestFromFile@resource://gre/modules/addons/XPIProvider.jsm:1500:5 06:21:36 INFO - PROCESS | 1669 | addMetadata@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:1647:21 06:21:36 INFO - PROCESS | 1669 | processFileChanges@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2011:23 06:21:36 INFO - PROCESS | 1669 | this.XPIProvider.checkForChanges@resource://gre/modules/addons/XPIProvider.jsm:3711:34 06:21:36 INFO - PROCESS | 1669 | this.XPIProvider.startup@resource://gre/modules/addons/XPIProvider.jsm:2700:25 06:21:36 INFO - PROCESS | 1669 | callProvider@resource://gre/modules/AddonManager.jsm:227:12 06:21:36 INFO - PROCESS | 1669 | _startProvider@resource://gre/modules/AddonManager.jsm:778:5 06:21:36 INFO - PROCESS | 1669 | AddonManagerInternal.startup@resource://gre/modules/AddonManager.jsm:962:9 06:21:36 INFO - PROCESS | 1669 | this.AddonManagerPrivate.startup@resource://gre/modules/AddonManager.jsm:2934:5 06:21:36 INFO - PROCESS | 1669 | amManager.prototype.observe@resource://gre/components/addonManager.js:71:9 06:21:36 INFO - PROCESS | 1669 | 06:21:36 INFO - PROCESS | 1669 | ************************* 06:21:36 INFO - TEST-INFO | 1669: exit 0 06:21:36 INFO - Browser initialized. 06:21:36 INFO - Running cycle 1/1 for dromaeo_css test... 06:21:36 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/tmp9WvfwU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:21:48 INFO - PROCESS | 1672 | RSS: Main: 233070592 06:21:48 INFO - PROCESS | 1672 | 06:22:40 INFO - PROCESS | 1672 | 1469452960245 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] 06:24:50 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 06:24:51 INFO - PROCESS | 1672 | RSS: Main: 368291840 06:24:51 INFO - PROCESS | 1672 | 06:27:55 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 06:27:55 INFO - PROCESS | 1672 | RSS: Main: 375451648 06:27:55 INFO - PROCESS | 1672 | 06:30:57 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 06:30:58 INFO - PROCESS | 1672 | RSS: Main: 373374976 06:30:58 INFO - PROCESS | 1672 | 06:34:00 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 06:34:01 INFO - PROCESS | 1672 | RSS: Main: 375001088 06:34:01 INFO - PROCESS | 1672 | 06:37:04 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 06:37:05 INFO - PROCESS | 1672 | RSS: Main: 345526272 06:37:05 INFO - PROCESS | 1672 | 06:40:08 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 06:40:08 INFO - PROCESS | 1672 | RSS: Main: 388866048 06:40:08 INFO - PROCESS | 1672 | 06:40:08 INFO - PROCESS | 1672 | __start_tp_report 06:40:08 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 06:40:08 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 06:40:08 INFO - PROCESS | 1672 | |i|pagename|runs| 06:40:08 INFO - PROCESS | 1672 | |0;dojo.html;3419.162305235218;3442.053435305292;3458.377492051431;3467.8266086695658;3501.649835016497;5663.178839068332;5723.856903577413;5726.570507211955;5726.799562015331;5731.283589551306;5676.176954341618;5752.539796816255;5768.538516918647;5772;5772.3361813391475;5099.949000509984;5132.409772876111;5151.124308867502;5216;5242.8165014224505;5212.5308722214995;5259.289995850569;5260.289860868791;5261.342332208474;5264.263003179558;4644.6980946238555;5143.562797162245;5148.536631703146;5161.561267292284;5239.842804715865;5210.452902445227;5217.243499692561;5220.373555173384;5230.450802665741;5249.42256351801;5011.599072074226;5064.392272927273;5077.720725360104;5129.692218466904;5165.7158856262895;4548.317752337143;4814.940713043125;5514.338279406456;5582.581306401996;5590.077637189859;4780.4741478437345;6275.466585340251;6276.184096067482;6278.560500764948;6292.1820163378925;5274.868128296785;6187.535934804908;6265.498760099181;6317.715702793385;6359.586626869239;5748.109043098327;5774.133879918046;5779.33537643168;5790.884182316414;5803.506701930341;6216.875662486725;6222.471089957314;6231.906521402183;6310.7791227306825;6322.304546499881;6004.6097003695495;6068.605540639845;6076.5442591805795;6117.309816958451;6159.784407545714;6186.1648677427975;6202.875942481215;6204.658743769136;6211.9689401552705;6226.875462490725;4944.8763780905765;6207.751689932352;6214.098955651405;6223.0354295084335;6230.968845155745;5119.769610367543;6121.84695382619;6134.314131579348;6143;6199.628022318675;4885.7312847793;4911.066897289504;4922.58158056562;4932.556069953722;4940.530649588283;4437.534058923831;4439.911201775946;4445;4449.933251001237;4479.910401792011;5665.150227465913;5667.951428985651;5708.286464191976;5722.4277572242445;5725.08398656213;5312.5484333832055;6125.234345706786;6130;6249.750009999549;6250.874982500415;5671.347795003628;5671.376148623648;5678.517326027334;5680.289963754531;5697.23087383198;5386;5398.40746979642;5402.540784033401;5409.486098820606;5417.674939503642;6220;6272.2159730033745;6278.246610406598;6293.213348331458;6319.905201422074;6228.813135605939;6280.717367718555;6305.779297724557;6306.936930630818;6315.368463153832;6280.560360774702;6297.370262973849;6299.338569450142;6303.590266632654;6340.080688300079;6067.998780201217;6113.476306051762;6146.569740118149;6197.783077592263;6236.064590311489;6301.873962520815;6305.842353941188;6306.590071645328;6312.5265605078885;6341;5920.822375328747;6126.754929802758;6142.078688196806;6167.475764560063;6168.0439531872635;5928.851778705567;6001.36985616504;6035.064564992434;6065.211522502046;6081.5134789217645;4752.714837109785;4839.975800120977;5895.351511333835;5913.58604897653;5986.102084687332;5815.680137592473;5947.256592925884;6071.787487437919;6105.175801266772;6121.204243448323;5830.2129212555765;5946.702664866826;5958.7616495341435;5993.520518358434;6010.098485227251 06:40:08 INFO - PROCESS | 1672 | |1;ext.html;490.8797344650561;493.117319997205;499.8503442083208;506.9506122326784;510.00803437314414;30508.38983220337;40816.591834081686;41736.37395439067;41855.37216941741;41933;42997.92505187372;43224.567754322445;43406;43529.69410917669;43539.3469097963;8251.95874020629;8549.016863060766;9743.610255589769;9773.07155820196;9773.706788796348;8592.656293748274;8758.430702004349;8761.649534018632;8768.254698350616;11488.71278218047;10214.233932455096;11455.026322762575;11489.310641361504;11702;11729.882701173006;10120.08919197272;11673.824892626617;11721.941390293037;11746;11785.882141178565;8540.914590854136;11391.886081139166;11525.250858694159;11709.063274937984;11712.765744685059;39752.40371394432;39919.8004009981;40048.59951400478;40085.398719019235;40099.7995010026;3425.2806910548816;3560.9821950890337;3653.2693461307845;3779.1874746929543;4195.580441955811;29733.702662973526;29800.40399192026;29947.550786738215;31640.683593164005;31799.20501987446;3198.5681932938987;3213.501907204387;3244.3511297740547;3263.7062664360324;3270.329582435595;480278;482144;483717;485527.5723621395;488015;60129.699351503834;60376;60439.69780151159;60572;60609;61426.692866535384;61604;61671.6916415415;61677.38322616716;61861.38138618646;17060.232289547;17281.913590432217;17312.653746924992;17317.7402338965;17326.39357622477;13429.328533573478;13459.932700336436;13477.19136851792;13483.258420786755;13508.189508629317;14156;14401.135931844123;14919.701605967819;15619;15929.522114336589;5251.684898906078;5828.825135245949;6210.937890621126;6285.748570057147;6816.284290149562;713.5845164119389;1060.984085238722;1078.3206579854798;1082.182951871332;1124.775044991005;25495;26201.34496637561;30581.69418305833;30635.08094757161;31080.533791993137;1081.918856085781;1093.6445655162104;1094.075506197262;1101.0805977009127;1162.6395817296666;15640.670543003744;15743.976641518264;15835.920820396055;16126.677466450605;16164.110973896548;643763;668879;673765;674689;675308.9876404889;48779;51600.22599661008;52359.47640523546;59017;59408.10887836686;1370.95122231493;1404.4382247101241;1449.1594874972702;1482.3996281506006;1485.0644094220572;25878.353041173366;28189.718102818708;30082.39700374532;31667.36665266728;32504.83747581247;51131.48868511267;51588.48411515837;51620.48379516307;51675.483245167066;51811.48188518066;538968;638739;640317.798411005;849114;889158;38297.61702382941;38427.84716458511;39040;39321;39776.403353949136;47247.76376118098;47598.6200689971;47634.76182619065;47696.523034770595;48655.0268994611;38390.616093838704;38885.61114388933;39047.6095239044;39332;39404.80297598494;34845.651543485255;37683.43474847934;38232.27954742099;38493.230135396574;39023.2195356097;38225.426618600184;38438.6156138435;38568.61431385762;39160.608393915696;39204;33970.490442642884;34214;34733.826330868185;34908.47637285392;34909.301813964084;25931.055170862637;26016.485969071327;26095.217143484966;26359.472810544066;26546 06:40:08 INFO - PROCESS | 1672 | |2;jquery.html;332.4730302470584;391.1024199462234;392.4878034165413;404.30056003114595;407.0779684015705;17907.999999999967;19214.51963700908;20619.896900515512;21660.24189153382;21668.891655541738;21473.67789483159;21530.677039844413;21844.89077554614;21890.23384181554;21952.914473125067;4983.576396006335;5313.601479889005;6024.608400453978;6154.292256390505;6167.814965551018;6017.608855424383;6031.366706495821;6066.6663333516635;6093.69531523422;6138.877222455548;5851.76592936282;5982.162497250389;6017.669028203447;6039.849003774897;6042.728077236535;5913.8817223655715;5978.760849566034;6004;6031.0048841941025;6033.48715359195;5177.792888284464;5247.449017853147;5268.262443257947;5289.338832645919;5290.232916227125;25859.870700646563;26082.217533474028;26440.8677956609;26469.205923822316;26544.8672756635;2269.512054908186;2294.1616210623206;2328.6507023946374;2473.75262473753;2782.4574208029444;14836.629084272872;15586.06483610987;15668.529944101696;15727.764083538756;15985.92007039969;1814.5100822777908;1840.7791065072142;1888.9149988250424;1958.7943265957153;1992.9302474413325;115099;116411.25383119345;117153.41423292914;118718;119160;10738.838917416246;11054.834177487344;11271.154663400277;11278.15413843965;11397.886021139848;14011.229382384066;14134.50529231472;14155.433782648784;14156.64608384778;14171.85828141726;8811.118888111137;8993.23557497607;8995.47076996912;9134.497602631785;9140.817183656289;9061;9069.18377346042;9106.908930910738;9260.351775375659;9269.258459323239;6708.53040287185;6787.963610916804;6792.660366981631;6793.354631310018;6874.2094659113845;1544.8686861616663;1701.8468337849406;1858.7676540432446;1869.8784579002593;1895.848332133426;863.6502216602161;884.7080463447047;904.2901322461836;977.032737589781;998.3211416236884;21116.366509004754;21799.45501362447;21840.89079554624;21987.780122198572;21996.340109796733;963.8265112279717;964.2671569607008;969.7478655549608;993.2997236947934;995.6963126246419;11119.777604447865;11548.942255288837;11644.53421863133;11877.703057423634;11902.40487975615;577335;577848;600030.999844998;600089;654250;129295;139247;142312;149049;154813.2259338696;802.7993001749679;915.6520522201521;990.2919412620012;1036.8652075230173;1039.7660526381655;13926.589291210486;16452;19008.904955475133;19355.8064419354;19762.308319208758;57748.71125644345;58595.12107318309;59087.40912590991;59287;59524.42853428808;75324.24675753173;76659.61670191614;77374;78641;79450;8266.338692904674;8359.874601881098;8488.193621605938;8595.82808343842;8668.826623467368;11328.37693926809;13159.144655597356;13245.602631921058;13321.933390332986;13391.665208369868;11374.374409407563;11393.886061139283;11553;11649.301041937511;11674.94162529182;11297.378644174649;11359.091272697999;11414.828777568177;11534.13493987971;11646.068314534647;8331.95834020826;11675.007624352025;11787.292762434281;11836.408179591159;11953.983911367632;7093.184283807428;7586.317231449196;8416.4950102994;8442.493450392996;8624.482531048157;10956.013958743433;11496.252743571975;11671.474783634927;11692;11738.413079345828 06:40:08 INFO - PROCESS | 1672 | |3;mootools.html;1365;1516.1736853414889;1550.9224538773058;1757.2882982392136;1925.4127491115205;7774.183710710378;8562.700305489308;8832.646694132234;8837.955810220956;8918.955405222981;8560.529170895597;8764.737057888273;8779.078196789342;8787.516686582236;8788.077251888555;2362.704661917256;2400.7839294463497;2402.459446624513;2402.6396040593877;2407.145463360508;2356.3402247370764;2390.6533552634855;2398.928032159038;2398.928032159038;2400.459896523277;2359.304005318427;2367.230650038736;2382.4877651304964;2395.8682272475007;2396.7962723168553;2337.5558643857785;2368.609179485383;2369.5260947810507;2372;2378.9048438062455;2268.9773102269096;2347.0377145370317;2352.517733864571;2352.9647055294186;2354.7645235476316;7382.557046577169;7997.360211183091;8621.741347759576;8769.649214031431;8795.032546419889;4133.565975572552;5051.621128415383;5074.264231686422;5240.973795131038;5411.621186516945;3552.9111772205642;3609.5488063992;3676.264747050574;3678.301122786662;3719.163188282642;1065.3075500924383;1077.1651969723448;1107.1530279336314;1113.4043286841581;1118.1278602689915;11594.594189203423;11727.296362218294;11730.237534560312;11732.061435085172;11737.002354799766;4707.576318131385;4708.693934894221;4823.782929768169;4858.46556878743;4865.343178670905;10101;10104.949475252577;10112.342697724624;10113.59545618167;10144.847827282596;5421.322334708161;5429.782808687608;5431.402545719968;5435.592330575223;5445.156000819879;4863.659543831968;4884.731339776346;4967.552920237196;4980.7509624518625;4984.451710311862;3850.7111966602615;3871.787051712183;3933.292007438631;3956.802159891994;3982.980085099556;1256.296473974577;1260.4076084240392;1262.4886920797092;1274.955376561816;1279.4434421026883;846.9661213551512;873.0004145253649;896.9551522423983;939.6617217801586;953.4708236928572;5969.31352894414;6080.209572755602;6135.355787642234;6140.6929653517145;6141.048137538689;446.5735222862159;449.5032988547673;464.9395578574831;465.28810919293534;466.8249406472573;4062.73592216505;4073.877783666495;4076.8776936691947;4129.132882094794;4172.144710334374;10434.947825260977;10753.56985720578;10893.891061089445;10899.945500272448;10923.016928476476;10747;10747.140228781835;10848.294860834021;10850.969157929983;10873.293235939638;8066.516009039477;8094.31198348147;8099.797505062421;8211.727182286755;8224.177582241848;6035;6149.385061493815;6218.906716399348;6243.748040430597;6295.401936815995;10026.849597256192;10285.9485702571;10301.072903438726;10312.587496500057;10329.483525823829;9686.07982241686;10009.899901001188;10204.693859184237;10227.539760710935;10267.537960791633;6308.684565771601;7655.6937722490475;7770.961145194238;7775.961120194363;7803.570803605629;8324.83350333002;8370.330373570221;8377.916220837713;8450.112738162405;8457.196566326189;7527;7529.096508419025;7625.23747625233;7661.578613176329;7724.382049436144;7464.216257292906;7489.251074892467;7508.324250817453;7551.320381165721;7591.392688585011;7075.221725610076;7087.362137407452;7097.117137704763;7131.25121862197;7162;5108.131617625087;5108.3103780989095;5133.230015497706;5142.357205349332;5160.122779127632;4901.926471103007;5187.221916712372;5220.869478263074;5233.398159211587;5314 06:40:08 INFO - PROCESS | 1672 | |4;prototype.html;302.96667366589685;304.80797097828366;313.3513626792539;316.6010826358787;319.8336864830288;5025.723585202817;5081.33942587464;5186.196139598359;5284;5297.9205311920305;4161.271777438944;4503.549645035495;4511.330807601192;4888.51114888511;5132.999065182291;3482.2165012872156;3533.681968622823;3615.764975276612;3947;4023.859164929228;3487.89536313911;3635.909102272438;3736.7481893565687;3957.4657421248194;3997.400389941503;3143.748500119985;3457.357755066336;3690.9077273068256;3790.4124860646643;3868.858686687434;3830.8276127574186;3836.8848934532007;3843.577206507282;3844.0966372902344;3851.691864650821;3352.7485438592203;3360.059183428644;3367.0235631666787;3369.376665316924;3371.96628033719;4208.915821683549;4408.669349798778;4428.734275943454;4671.672982891199;4926;3883.689304855605;4287.892802679927;4327.7619730914785;4366.432363792718;4400.9559904401185;4604.930926036079;4611.723296602215;4664.720116793003;4881.730750004989;5312.6281160318795;3562.4834399012257;3589.335972845032;3689.6679298862964;3723.236736469017;3750.8372404554675;5560.471755183251;5611.775528978836;5616.494515493625;5634.605577609569;5642.633228840113;3759;3761.285355782393;3781.5084039074745;3792.8482860685262;3801.4107813288983;3812.5806161322234;3849.5957924418094;3870.40008798631;3885.3394922862617;3885.4560361549406;3178.98410507946;3666.871659491905;4393.846215382447;4401.559844015573;4451.683495795653;4461.397711308997;5252.474752524717;5337.03794394115;5344.091504444254;5375.930266847292;1193.8507686539183;1258.9496420143091;1264.7794877942829;1360.5034162530605;1366;787.2678409079497;838.8993320801421;840.9740117057303;849.613425891218;850.6554845287668;3674.52231209941;3886.844526218976;4233.724807887477;4359.520452750195;4377.4965878923695;4188.434561334242;4424.491183513873;4678.701515431612;4690.366800481959;4850.860047888743;3795.7153213509096;3877.5877228039144;3909.765414075108;3974.4237085623;4030.9395359069636;2270.5922328156316;2387.331547166796;2503.5243303772227;2533.5312967101145;2551.6555265039356;4249.830006799693;4733.035790147119;4919.311296418504;4957.108669694273;4995.700257984533;4299.7536859670745;4449.021215332622;4574.101747774703;4804.351412559329;4869;3423.117285492969;4029.9597004029583;4260.913467065879;4563.543645635476;4941.777620007037;4004.77973711437;4186.623203911662;4563.3839431677625;4719.78170259991;4731.69243999139;3955.92088158241;4142.026623743478;4548.431446069241;4759.3336932830125;4788.976055119702;3932.7247092703237;4294.9355759663;4799.5841226837465;4953.944113192984;4980.35255416808;2862.967293221836;2970.61382020336;3356.781809182395;3368.4105281576117;3411.880584179542;3858.9966608681384;3879.3211188042546;4715.78778954941;4724.881877953078;5411.540019098421;4883.731394773322;4965.677230979974;5031.949680503295;5176.764522173641;5563.6354549315565;4498.685092043525;5480.479354461319;5482.725863706878;5506.559475241891;5516.124517664013;4979.1369962272565;5072.239164125411;5330.200469929541;5344.492273234192;5360;4769.874058939062;5396;5408.269883565668;5414.675119492843;5477.342718873601;4420.010995052175;4627.7686115693405;5284.762185701576;5336.973315133399;5337.733113344395 06:40:08 INFO - PROCESS | 1672 | |5;yui.html;714.6426786606696;751.9060117485315;766.0271455251831;794.0947320055135;842.1830824100622;15190.772138417919;15647;15695;15762.36950521979;15820.971636843611;14184.290785460737;14261.857381426182;14273.286335683226;14282.928585357058;14308.928455357734;701.2881924846286;868.6395146014396;907.1246247371286;911.6399022386154;912.2337236721153;892.3530440430701;904.5929331800716;907.7503686486236;908.3959167153835;909.277124685873;735.5108852613006;948.2840455456121;949.2927768812244;950.6435086842434;950.9001554836747;760.7413479417024;760.8364201696662;762.8169239382564;763.107164617399;891.3225948279354;686.4268335939496;686.9038334633156;687.5153017122916;688.2910602079866;688.834679676879;15056.096634201984;15111.622209444742;15123.470678526308;15127.092374457568;15144.924275378662;14064.367103480368;14383.856161438356;14477.855221447757;14584.489542866055;14665;12958.935205324007;13047.347632618426;13127.606171814861;13181.472741090345;13233.206007639572;4632.513586073481;4637.072585482883;4638.42202507444;4643.349931009661;4666.836660716893;108379;120513.39743301315;121791;122265;122705;10546.209034322457;10763.19276054299;11443.599474018369;11725.061995040376;12116.636500904988;21192.894035530033;21531.031103600377;21537.784622153893;21580.352589422342;21608.56782864334;6865.9313406866295;7528.962355188299;7810.218978102258;7813.765587032398;8020.35837133028;4292.5492823253735;4379;4407.27279998803;4408.845690400821;4501.41481607389;3521.771084879526;3623.202895363016;3623.8912832615065;3625.5649322081517;3630.709543236535;631.9146915166485;632.3992207402986;639.8336432527576;641.9486441084701;652.8139480248107;600.4505877122472;602.6655206360512;604.918336024631;605.436943642408;658.8682263547214;9071.59177836999;9240.768980775534;9281.025492323344;9339.439633622005;9477.526123693788;486.5815398757066;602.6956387024525;630.0895206426761;640.8942524483501;650.6031320894251;4448.266036104006;4449.866504004885;4456.866294011184;4464.464264288306;4583.931241031387;144885;148893;149764;150153.24923375313;151876;81952;123344;124508.37745811213;126551;128278.35860820636;103658;104050;106537.46731266449;108081;109345.99999999841;8587.613557389808;8630.611622477129;8639;8666;8669.739907802523;60898.08652870299;61547.6922615384;61736;62987;63513.6824315894;73380;85576.14423855682;86690.56654716686;91478;91701.54149229478;11010.614628487965;11390.544378224778;11508.82736758966;11975.940120299343;12016.519339226683;12490.375481226085;12523.74952500963;12607.810882836582;12988.090833641554;13053.412596432994;11989.580364687194;12051.33717645538;12307;12378.504859805507;12458.003359731381;11776.88223117792;11934.462949167135;12016.596680664077;12342;12461;11077.833832492357;11587.652370428901;11719.472623731783;11994.160408771302;12026.879731202576;9536.284778641491;9823.675066895667;9860.852087218555;9920.751981200528;10148.289619726844;9616.278779091455;9782.9021709782;9784.461854598065;9845.753856153367;9863.457509837028 06:40:08 INFO - PROCESS | 1672 | __end_tp_report 06:40:08 INFO - PROCESS | 1672 | __start_cc_report 06:40:08 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,1340 06:40:08 INFO - PROCESS | 1672 | __end_cc_report 06:40:08 INFO - PROCESS | 1672 | __startTimestamp1469454008627__endTimestamp 06:40:08 INFO - PROCESS | 1672 | 06:40:08 INFO - PROCESS | 1672 | ------- Summary: start ------- 06:40:08 INFO - PROCESS | 1672 | Number of tests: 6 06:40:08 INFO - PROCESS | 1672 | 06:40:08 INFO - PROCESS | 1672 | [#0] dojo.html Cycles:165 Average:5675.68 Median:5815.68 stddev:652.25 (11.2%) stddev-sans-first:629.77 06:40:08 INFO - PROCESS | 1672 | Values: 3419.2 3442.1 3458.4 3467.8 3501.6 5663.2 5723.9 5726.6 5726.8 5731.3 5676.2 5752.5 5768.5 5772.0 5772.3 5099.9 5132.4 5151.1 5216.0 5242.8 5212.5 5259.3 5260.3 5261.3 5264.3 4644.7 5143.6 5148.5 5161.6 5239.8 5210.5 5217.2 5220.4 5230.5 5249.4 5011.6 5064.4 5077.7 5129.7 5165.7 4548.3 4814.9 5514.3 5582.6 5590.1 4780.5 6275.5 6276.2 6278.6 6292.2 5274.9 6187.5 6265.5 6317.7 6359.6 5748.1 5774.1 5779.3 5790.9 5803.5 6216.9 6222.5 6231.9 6310.8 6322.3 6004.6 6068.6 6076.5 6117.3 6159.8 6186.2 6202.9 6204.7 6212.0 6226.9 4944.9 6207.8 6214.1 6223.0 6231.0 5119.8 6121.8 6134.3 6143.0 6199.6 4885.7 4911.1 4922.6 4932.6 4940.5 4437.5 4439.9 4445.0 4449.9 4479.9 5665.2 5668.0 5708.3 5722.4 5725.1 5312.5 6125.2 6130.0 6249.8 6250.9 5671.3 5671.4 5678.5 5680.3 5697.2 5386.0 5398.4 5402.5 5409.5 5417.7 6220.0 6272.2 6278.2 6293.2 6319.9 6228.8 6280.7 6305.8 6306.9 6315.4 6280.6 6297.4 6299.3 6303.6 6340.1 6068.0 6113.5 6146.6 6197.8 6236.1 6301.9 6305.8 6306.6 6312.5 6341.0 5920.8 6126.8 6142.1 6167.5 6168.0 5928.9 6001.4 6035.1 6065.2 6081.5 4752.7 4840.0 5895.4 5913.6 5986.1 5815.7 5947.3 6071.8 6105.2 6121.2 5830.2 5946.7 5958.8 5993.5 6010.1 06:40:08 INFO - PROCESS | 1672 | 06:40:08 INFO - PROCESS | 1672 | [#1] ext.html Cycles:180 Average:74885.59 Median:38433.23 stddev:169966.77 (442.2%) stddev-sans-first:170351.79 06:40:08 INFO - PROCESS | 1672 | Values: 490.9 493.1 499.9 507.0 510.0 30508.4 40816.6 41736.4 41855.4 41933.0 42997.9 43224.6 43406.0 43529.7 43539.3 8252.0 8549.0 9743.6 9773.1 9773.7 8592.7 8758.4 8761.6 8768.3 11488.7 10214.2 11455.0 11489.3 11702.0 11729.9 10120.1 11673.8 11721.9 11746.0 11785.9 8540.9 11391.9 11525.3 11709.1 11712.8 39752.4 39919.8 40048.6 40085.4 40099.8 3425.3 3561.0 3653.3 3779.2 4195.6 29733.7 29800.4 29947.6 31640.7 31799.2 3198.6 3213.5 3244.4 3263.7 3270.3 480278.0 482144.0 483717.0 485527.6 488015.0 60129.7 60376.0 60439.7 60572.0 60609.0 61426.7 61604.0 61671.7 61677.4 61861.4 17060.2 17281.9 17312.7 17317.7 17326.4 13429.3 13459.9 13477.2 13483.3 13508.2 14156.0 14401.1 14919.7 15619.0 15929.5 5251.7 5828.8 6210.9 6285.7 6816.3 713.6 1061.0 1078.3 1082.2 1124.8 25495.0 26201.3 30581.7 30635.1 31080.5 1081.9 1093.6 1094.1 1101.1 1162.6 15640.7 15744.0 15835.9 16126.7 16164.1 643763.0 668879.0 673765.0 674689.0 675309.0 48779.0 51600.2 52359.5 59017.0 59408.1 1371.0 1404.4 1449.2 1482.4 1485.1 25878.4 28189.7 30082.4 31667.4 32504.8 51131.5 51588.5 51620.5 51675.5 51811.5 538968.0 638739.0 640317.8 849114.0 889158.0 38297.6 38427.8 39040.0 39321.0 39776.4 47247.8 47598.6 47634.8 47696.5 48655.0 38390.6 38885.6 39047.6 39332.0 39404.8 34845.7 37683.4 38232.3 38493.2 39023.2 38225.4 38438.6 38568.6 39160.6 39204.0 33970.5 34214.0 34733.8 34908.5 34909.3 25931.1 26016.5 26095.2 26359.5 26546.0 06:40:08 INFO - PROCESS | 1672 | 06:40:08 INFO - PROCESS | 1672 | [#2] jquery.html Cycles:180 Average:36027.25 Median:25971.04 stddev:100887.98 (388.5%) stddev-sans-first:101135.40 06:40:08 INFO - PROCESS | 1672 | Values: 332.5 391.1 392.5 404.3 407.1 17908.0 19214.5 20619.9 21660.2 21668.9 21473.7 21530.7 21844.9 21890.2 21952.9 4983.6 5313.6 6024.6 6154.3 6167.8 6017.6 6031.4 6066.7 6093.7 6138.9 5851.8 5982.2 6017.7 6039.8 6042.7 5913.9 5978.8 6004.0 6031.0 6033.5 5177.8 5247.4 5268.3 5289.3 5290.2 25859.9 26082.2 26440.9 26469.2 26544.9 2269.5 2294.2 2328.7 2473.8 2782.5 14836.6 15586.1 15668.5 15727.8 15985.9 1814.5 1840.8 1888.9 1958.8 1992.9 115099.0 116411.3 117153.4 118718.0 119160.0 10738.8 11054.8 11271.2 11278.2 11397.9 14011.2 14134.5 14155.4 14156.6 14171.9 8811.1 8993.2 8995.5 9134.5 9140.8 9061.0 9069.2 9106.9 9260.4 9269.3 6708.5 6788.0 6792.7 6793.4 6874.2 1544.9 1701.8 1858.8 1869.9 1895.8 863.7 884.7 904.3 977.0 998.3 21116.4 21799.5 21840.9 21987.8 21996.3 963.8 964.3 969.7 993.3 995.7 11119.8 11548.9 11644.5 11877.7 11902.4 577335.0 577848.0 600031.0 600089.0 654250.0 129295.0 139247.0 142312.0 149049.0 154813.2 802.8 915.7 990.3 1036.9 1039.8 13926.6 16452.0 19008.9 19355.8 19762.3 57748.7 58595.1 59087.4 59287.0 59524.4 75324.2 76659.6 77374.0 78641.0 79450.0 8266.3 8359.9 8488.2 8595.8 8668.8 11328.4 13159.1 13245.6 13321.9 13391.7 11374.4 11393.9 11553.0 11649.3 11674.9 11297.4 11359.1 11414.8 11534.1 11646.1 8332.0 11675.0 11787.3 11836.4 11954.0 7093.2 7586.3 8416.5 8442.5 8624.5 10956.0 11496.3 11671.5 11692.0 11738.4 06:40:08 INFO - PROCESS | 1672 | 06:40:08 INFO - PROCESS | 1672 | [#3] mootools.html Cycles:180 Average:5756.42 Median:4708.14 stddev:3233.95 (68.7%) stddev-sans-first:3226.18 06:40:08 INFO - PROCESS | 1672 | Values: 1365.0 1516.2 1550.9 1757.3 1925.4 7774.2 8562.7 8832.6 8838.0 8919.0 8560.5 8764.7 8779.1 8787.5 8788.1 2362.7 2400.8 2402.5 2402.6 2407.1 2356.3 2390.7 2398.9 2398.9 2400.5 2359.3 2367.2 2382.5 2395.9 2396.8 2337.6 2368.6 2369.5 2372.0 2378.9 2269.0 2347.0 2352.5 2353.0 2354.8 7382.6 7997.4 8621.7 8769.6 8795.0 4133.6 5051.6 5074.3 5241.0 5411.6 3552.9 3609.5 3676.3 3678.3 3719.2 1065.3 1077.2 1107.2 1113.4 1118.1 11594.6 11727.3 11730.2 11732.1 11737.0 4707.6 4708.7 4823.8 4858.5 4865.3 10101.0 10104.9 10112.3 10113.6 10144.8 5421.3 5429.8 5431.4 5435.6 5445.2 4863.7 4884.7 4967.6 4980.8 4984.5 3850.7 3871.8 3933.3 3956.8 3983.0 1256.3 1260.4 1262.5 1275.0 1279.4 847.0 873.0 897.0 939.7 953.5 5969.3 6080.2 6135.4 6140.7 6141.0 446.6 449.5 464.9 465.3 466.8 4062.7 4073.9 4076.9 4129.1 4172.1 10434.9 10753.6 10893.9 10899.9 10923.0 10747.0 10747.1 10848.3 10851.0 10873.3 8066.5 8094.3 8099.8 8211.7 8224.2 6035.0 6149.4 6218.9 6243.7 6295.4 10026.8 10285.9 10301.1 10312.6 10329.5 9686.1 10009.9 10204.7 10227.5 10267.5 6308.7 7655.7 7771.0 7776.0 7803.6 8324.8 8370.3 8377.9 8450.1 8457.2 7527.0 7529.1 7625.2 7661.6 7724.4 7464.2 7489.3 7508.3 7551.3 7591.4 7075.2 7087.4 7097.1 7131.3 7162.0 5108.1 5108.3 5133.2 5142.4 5160.1 4901.9 5187.2 5220.9 5233.4 5314.0 06:40:08 INFO - PROCESS | 1672 | 06:40:08 INFO - PROCESS | 1672 | [#4] prototype.html Cycles:180 Average:4055.17 Median:4371.96 stddev:1224.12 (28.0%) stddev-sans-first:1194.72 06:40:08 INFO - PROCESS | 1672 | Values: 303.0 304.8 313.4 316.6 319.8 5025.7 5081.3 5186.2 5284.0 5297.9 4161.3 4503.5 4511.3 4888.5 5133.0 3482.2 3533.7 3615.8 3947.0 4023.9 3487.9 3635.9 3736.7 3957.5 3997.4 3143.7 3457.4 3690.9 3790.4 3868.9 3830.8 3836.9 3843.6 3844.1 3851.7 3352.7 3360.1 3367.0 3369.4 3372.0 4208.9 4408.7 4428.7 4671.7 4926.0 3883.7 4287.9 4327.8 4366.4 4401.0 4604.9 4611.7 4664.7 4881.7 5312.6 3562.5 3589.3 3689.7 3723.2 3750.8 5560.5 5611.8 5616.5 5634.6 5642.6 3759.0 3761.3 3781.5 3792.8 3801.4 3812.6 3849.6 3870.4 3885.3 3885.5 3179.0 3666.9 4393.8 4401.6 4451.7 4461.4 5252.5 5337.0 5344.1 5375.9 1193.9 1258.9 1264.8 1360.5 1366.0 787.3 838.9 841.0 849.6 850.7 3674.5 3886.8 4233.7 4359.5 4377.5 4188.4 4424.5 4678.7 4690.4 4850.9 3795.7 3877.6 3909.8 3974.4 4030.9 2270.6 2387.3 2503.5 2533.5 2551.7 4249.8 4733.0 4919.3 4957.1 4995.7 4299.8 4449.0 4574.1 4804.4 4869.0 3423.1 4030.0 4260.9 4563.5 4941.8 4004.8 4186.6 4563.4 4719.8 4731.7 3955.9 4142.0 4548.4 4759.3 4789.0 3932.7 4294.9 4799.6 4953.9 4980.4 2863.0 2970.6 3356.8 3368.4 3411.9 3859.0 3879.3 4715.8 4724.9 5411.5 4883.7 4965.7 5031.9 5176.8 5563.6 4498.7 5480.5 5482.7 5506.6 5516.1 4979.1 5072.2 5330.2 5344.5 5360.0 4769.9 5396.0 5408.3 5414.7 5477.3 4420.0 4627.8 5284.8 5337.0 5337.7 06:40:08 INFO - PROCESS | 1672 | 06:40:08 INFO - PROCESS | 1672 | [#5] yui.html Cycles:180 Average:24282.41 Median:4453.37 stddev:39073.23 (877.4%) stddev-sans-first:39142.77 06:40:08 INFO - PROCESS | 1672 | Values: 714.6 751.9 766.0 794.1 842.2 15190.8 15647.0 15695.0 15762.4 15821.0 14184.3 14261.9 14273.3 14282.9 14308.9 701.3 868.6 907.1 911.6 912.2 892.4 904.6 907.8 908.4 909.3 735.5 948.3 949.3 950.6 950.9 760.7 760.8 762.8 763.1 891.3 686.4 686.9 687.5 688.3 688.8 15056.1 15111.6 15123.5 15127.1 15144.9 14064.4 14383.9 14477.9 14584.5 14665.0 12958.9 13047.3 13127.6 13181.5 13233.2 4632.5 4637.1 4638.4 4643.3 4666.8 108379.0 120513.4 121791.0 122265.0 122705.0 10546.2 10763.2 11443.6 11725.1 12116.6 21192.9 21531.0 21537.8 21580.4 21608.6 6865.9 7529.0 7810.2 7813.8 8020.4 4292.5 4379.0 4407.3 4408.8 4501.4 3521.8 3623.2 3623.9 3625.6 3630.7 631.9 632.4 639.8 641.9 652.8 600.5 602.7 604.9 605.4 658.9 9071.6 9240.8 9281.0 9339.4 9477.5 486.6 602.7 630.1 640.9 650.6 4448.3 4449.9 4456.9 4464.5 4583.9 144885.0 148893.0 149764.0 150153.2 151876.0 81952.0 123344.0 124508.4 126551.0 128278.4 103658.0 104050.0 106537.5 108081.0 109346.0 8587.6 8630.6 8639.0 8666.0 8669.7 60898.1 61547.7 61736.0 62987.0 63513.7 73380.0 85576.1 86690.6 91478.0 91701.5 11010.6 11390.5 11508.8 11975.9 12016.5 12490.4 12523.7 12607.8 12988.1 13053.4 11989.6 12051.3 12307.0 12378.5 12458.0 11776.9 11934.5 12016.6 12342.0 12461.0 11077.8 11587.7 11719.5 11994.2 12026.9 9536.3 9823.7 9860.9 9920.8 10148.3 9616.3 9782.9 9784.5 9845.8 9863.5 06:40:08 INFO - PROCESS | 1672 | -------- Summary: end -------- 06:40:08 INFO - PROCESS | 1672 | 06:40:09 INFO - TEST-INFO | 1672: exit 0 06:40:09 INFO - TEST-OK | dromaeo_css | took 1115772ms 06:40:09 INFO - TEST-START | kraken 06:40:09 INFO - operating with platform_type : mac_ 06:40:09 INFO - Initialising browser for kraken test... 06:40:09 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/tmp_bY5HE/profile http://localhost:49272/getInfo.html) 06:40:11 INFO - PROCESS | 1679 | __metrics Screen width/height:1600/1200 06:40:11 INFO - PROCESS | 1679 | colorDepth:24 06:40:11 INFO - PROCESS | 1679 | Browser inner width/height: 1024/689 06:40:11 INFO - PROCESS | 1679 | __metrics 06:40:11 INFO - PROCESS | 1679 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:40:11 ERROR - PROCESS | 1679 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:40:12 INFO - PROCESS | 1679 | ************************* 06:40:12 INFO - PROCESS | 1679 | A coding exception was thrown in a Promise resolution callback. 06:40:12 INFO - PROCESS | 1679 | See https://developer.mozilla.org/Mozilla/JavaScript_code_modules/Promise.jsm/Promise 06:40:12 INFO - PROCESS | 1679 | 06:40:12 ERROR - PROCESS | 1679 | Full message: TypeError: win is null 06:40:12 INFO - PROCESS | 1679 | Full stack: onSuccess@resource://app/components/nsBrowserGlue.js:850:17 06:40:12 INFO - PROCESS | 1679 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 06:40:12 INFO - PROCESS | 1679 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 06:40:12 INFO - PROCESS | 1679 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 06:40:12 INFO - PROCESS | 1679 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 06:40:12 INFO - PROCESS | 1679 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 06:40:12 INFO - PROCESS | 1679 | get _worker/worker.onmessage@resource://gre/modules/PromiseWorker.jsm:235:9 06:40:12 INFO - PROCESS | 1679 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 06:40:12 INFO - PROCESS | 1679 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 06:40:12 INFO - PROCESS | 1679 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 06:40:12 INFO - PROCESS | 1679 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 06:40:12 INFO - PROCESS | 1679 | Barrier/this.client.addBlocker/promise53.7% 06:41:09 INFO - TinderboxPrint: I/O read bytes / time
31,534,592 / 320 06:41:09 INFO - TinderboxPrint: I/O write bytes / time
660,836,352 / 2,187 06:41:09 INFO - TinderboxPrint: CPU idle
2,240.9 (46.3%) 06:41:09 INFO - TinderboxPrint: CPU system
965.3 (20.0%) 06:41:09 INFO - TinderboxPrint: CPU user
1,631.5 (33.7%) 06:41:09 INFO - TinderboxPrint: Swap in / out
387,510,272 / 0 06:41:09 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 352373248; Write bytes: 352253952; Read time: 27530; Write time: 636 06:41:09 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 30612992; Write bytes: 298629120; Read time: 307; Write time: 1517 06:41:09 INFO - Running post-run listener: _upload_blobber_files 06:41:09 INFO - Blob upload gear active. 06:41:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:41:09 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 06:41:09 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:41:09 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:41:10 INFO - (blobuploader) - INFO - Open directory for files ... 06:41:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:41:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:41:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:41:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:41:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:41:11 INFO - (blobuploader) - INFO - Done attempting. 06:41:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:41:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:41:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:41:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:41:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:41:12 INFO - (blobuploader) - INFO - Done attempting. 06:41:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:41:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:41:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:41:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:41:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:41:15 INFO - (blobuploader) - INFO - Done attempting. 06:41:15 INFO - (blobuploader) - INFO - Iteration through files over. 06:41:15 INFO - Return code: 0 06:41:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:41:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:41:15 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d7d2136f287073f588055a9395b5015b908c0432decadaa1de419c9f9b17551117d216cc7d5113fd0c773456f7921f649cc8468f8d7f316f2524736cba946bbb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d7d2df1f039b88e873ae37aca99dad65059947c998598461bc7c653214065c9183e515323fab170e72d73ff13b8624af4868da4d6d598e2a58de945481b59a6a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8d987cfa3bde4615a8da6c9f0cd26c3e32464bfbc6645f8ec5a73d3ab5d229622a3aab0c7b95f92ce74e845301bc0f24d864564bb3988f870c0baaa67a63949f"} 06:41:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:41:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:41:15 INFO - Contents: 06:41:15 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d7d2136f287073f588055a9395b5015b908c0432decadaa1de419c9f9b17551117d216cc7d5113fd0c773456f7921f649cc8468f8d7f316f2524736cba946bbb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d7d2df1f039b88e873ae37aca99dad65059947c998598461bc7c653214065c9183e515323fab170e72d73ff13b8624af4868da4d6d598e2a58de945481b59a6a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8d987cfa3bde4615a8da6c9f0cd26c3e32464bfbc6645f8ec5a73d3ab5d229622a3aab0c7b95f92ce74e845301bc0f24d864564bb3988f870c0baaa67a63949f"} 06:41:15 INFO - Running post-run listener: copy_logs_to_upload_dir 06:41:15 INFO - Copying logs to upload dir... 06:41:15 INFO - mkdir: /builds/slave/test/build/upload/logs 06:41:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1426.608676 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 46 secs) (at 2016-07-25 06:41:16.056956) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-25 06:41:16.060970) ========= 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.zlmAHSJLNl/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.2YZznXWMHK/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d7d2136f287073f588055a9395b5015b908c0432decadaa1de419c9f9b17551117d216cc7d5113fd0c773456f7921f649cc8468f8d7f316f2524736cba946bbb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d7d2df1f039b88e873ae37aca99dad65059947c998598461bc7c653214065c9183e515323fab170e72d73ff13b8624af4868da4d6d598e2a58de945481b59a6a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8d987cfa3bde4615a8da6c9f0cd26c3e32464bfbc6645f8ec5a73d3ab5d229622a3aab0c7b95f92ce74e845301bc0f24d864564bb3988f870c0baaa67a63949f"} build_url:https://queue.taskcluster.net/v1/task/YHeanJsCT2-2b3TIqKJc8A/artifacts/public/build/firefox-49.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011234 build_url: 'https://queue.taskcluster.net/v1/task/YHeanJsCT2-2b3TIqKJc8A/artifacts/public/build/firefox-49.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d7d2136f287073f588055a9395b5015b908c0432decadaa1de419c9f9b17551117d216cc7d5113fd0c773456f7921f649cc8468f8d7f316f2524736cba946bbb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d7d2df1f039b88e873ae37aca99dad65059947c998598461bc7c653214065c9183e515323fab170e72d73ff13b8624af4868da4d6d598e2a58de945481b59a6a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8d987cfa3bde4615a8da6c9f0cd26c3e32464bfbc6645f8ec5a73d3ab5d229622a3aab0c7b95f92ce74e845301bc0f24d864564bb3988f870c0baaa67a63949f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-25 06:41:16.094780) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 06:41:16.095217) ========= 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.zlmAHSJLNl/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.2YZznXWMHK/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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.005138 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 06:41:16.144768) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-25 06:41:16.145154) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-25 06:41:16.145601) ========= ========= Total master_lag: 0.18 =========