builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0082 starttime: 1465295140.33 results: success (0) buildid: 20160607010031 builduid: 981e3ce444a64c52a5cf0668191eb73d revision: 28646bde9f7a1e136e748e1b111f168dd7ad1eab ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-07 03:25:40.332900) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-07 03:25:40.333545) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-07 03:25:40.333950) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-07 03:25:40.359344) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 03:25:40.359676) ========= 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.SIye3L56e0/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.droFgu7lfg/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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-06-07 03:25:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.9M=0.001s 2016-06-07 03:25:40 (14.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.138788 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 03:25:40.521814) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 03:25:40.522302) ========= 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.SIye3L56e0/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.droFgu7lfg/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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.027453 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 03:25:40.573903) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 03:25:40.574376) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 28646bde9f7a1e136e748e1b111f168dd7ad1eab --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 28646bde9f7a1e136e748e1b111f168dd7ad1eab --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SIye3L56e0/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.droFgu7lfg/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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-06-07 03:25:40,652 truncating revision to first 12 chars 2016-06-07 03:25:40,652 Setting DEBUG logging. 2016-06-07 03:25:40,652 attempt 1/10 2016-06-07 03:25:40,653 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/28646bde9f7a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-07 03:25:40,968 unpacking tar archive at: fx-team-28646bde9f7a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.591717 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 03:25:41.188748) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-07 03:25:41.189096) ========= script_repo_revision: 28646bde9f7a1e136e748e1b111f168dd7ad1eab ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-07 03:25:41.189562) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-07 03:25:41.189908) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-07 03:25:41.208016) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-06-07 03:25:41.208348) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SIye3L56e0/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.droFgu7lfg/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 03:25:41 INFO - MultiFileLogger online at 20160607 03:25:41 in /builds/slave/test 03:25:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 03:25:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:25:41 INFO - {'append_to_log': False, 03:25:41 INFO - 'base_work_dir': '/builds/slave/test', 03:25:41 INFO - 'blob_upload_branch': 'Fx-Team', 03:25:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:25:41 INFO - 'branch': 'Fx-Team', 03:25:41 INFO - 'buildbot_json_path': 'buildprops.json', 03:25:41 INFO - 'config_files': ('talos/mac_config.py',), 03:25:41 INFO - 'default_actions': ('clobber', 03:25:41 INFO - 'read-buildbot-config', 03:25:41 INFO - 'download-and-extract', 03:25:41 INFO - 'populate-webroot', 03:25:41 INFO - 'create-virtualenv', 03:25:41 INFO - 'install', 03:25:41 INFO - 'run-tests'), 03:25:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:25:41 INFO - 'download_minidump_stackwalk': True, 03:25:41 INFO - 'download_symbols': 'ondemand', 03:25:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:25:41 INFO - 'tooltool.py': '/tools/tooltool.py', 03:25:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:25:41 INFO - '/tools/misc-python/virtualenv.py')}, 03:25:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:25:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:25:41 INFO - 'installer_path': 'installer.exe', 03:25:41 INFO - 'log_level': 'info', 03:25:41 INFO - 'log_name': 'talos', 03:25:41 INFO - 'log_to_console': True, 03:25:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:25:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:25:41 INFO - 'opt_config_files': (), 03:25:41 INFO - 'pip_index': False, 03:25:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:25:41 INFO - 'cmd': ('bash', 03:25:41 INFO - '-c', 03:25:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:25:41 INFO - 'enabled': True, 03:25:41 INFO - 'halt_on_failure': False, 03:25:41 INFO - 'name': 'check_screen_resolution'},), 03:25:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:25:41 INFO - 'cmd': ('bash', 03:25:41 INFO - '-c', 03:25:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:25:41 INFO - 'enabled': True, 03:25:41 INFO - 'halt_on_failure': False, 03:25:41 INFO - 'name': 'check_screen_resolution'},), 03:25:41 INFO - 'run_cmd_checks_enabled': True, 03:25:41 INFO - 'sps_profile': False, 03:25:41 INFO - 'sps_profile_interval': 0, 03:25:41 INFO - 'suite': 'dromaeojs', 03:25:41 INFO - 'system_bits': '32', 03:25:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:25:41 INFO - 'title': 't-yosemite-r7-0082', 03:25:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:25:41 INFO - 'use_talos_json': True, 03:25:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:25:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:25:41 INFO - 'work_dir': 'build'} 03:25:41 INFO - ##### 03:25:41 INFO - ##### Running clobber step. 03:25:41 INFO - ##### 03:25:41 INFO - Running pre-action listener: _resource_record_pre_action 03:25:41 INFO - Running main action method: clobber 03:25:41 INFO - rmtree: /builds/slave/test/build 03:25:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:25:43 INFO - Running post-action listener: _resource_record_post_action 03:25:43 INFO - ##### 03:25:43 INFO - ##### Finished clobber step (success) 03:25:43 INFO - ##### 03:25:43 INFO - ##### 03:25:43 INFO - ##### Running read-buildbot-config step. 03:25:43 INFO - ##### 03:25:43 INFO - Running pre-action listener: _resource_record_pre_action 03:25:43 INFO - Running main action method: read_buildbot_config 03:25:43 INFO - Using buildbot properties: 03:25:43 INFO - { 03:25:43 INFO - "project": "", 03:25:43 INFO - "product": "firefox", 03:25:43 INFO - "script_repo_revision": "production", 03:25:43 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 03:25:43 INFO - "repository": "", 03:25:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 03:25:43 INFO - "buildid": "20160607010031", 03:25:43 INFO - "pgo_build": "False", 03:25:43 INFO - "basedir": "/builds/slave/test", 03:25:43 INFO - "buildnumber": 40, 03:25:43 INFO - "slavename": "t-yosemite-r7-0082", 03:25:43 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 03:25:43 INFO - "platform": "yosemite_r7", 03:25:43 INFO - "scheduler": "tests-fx-team-macosx64-talos", 03:25:43 INFO - "branch": "fx-team", 03:25:43 INFO - "repo_path": "integration/fx-team", 03:25:43 INFO - "revision": "28646bde9f7a1e136e748e1b111f168dd7ad1eab", 03:25:43 INFO - "stage_platform": "macosx64", 03:25:43 INFO - "builduid": "981e3ce444a64c52a5cf0668191eb73d", 03:25:43 INFO - "slavebuilddir": "test" 03:25:43 INFO - } 03:25:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/c_DjE3nCQ7iWGbtkmbNEHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 03:25:43 INFO - Running post-action listener: _resource_record_post_action 03:25:43 INFO - ##### 03:25:43 INFO - ##### Finished read-buildbot-config step (success) 03:25:43 INFO - ##### 03:25:43 INFO - ##### 03:25:43 INFO - ##### Running download-and-extract step. 03:25:43 INFO - ##### 03:25:43 INFO - Running pre-action listener: _resource_record_pre_action 03:25:43 INFO - Running main action method: download_and_extract 03:25:43 INFO - mkdir: /builds/slave/test/build/tests 03:25:43 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')]} 03:25:43 INFO - https://queue.taskcluster.net/v1/task/c_DjE3nCQ7iWGbtkmbNEHg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 03:25:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_DjE3nCQ7iWGbtkmbNEHg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 03:25:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_DjE3nCQ7iWGbtkmbNEHg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 03:25:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_DjE3nCQ7iWGbtkmbNEHg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 03:25:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_DjE3nCQ7iWGbtkmbNEHg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 03:25:44 INFO - Downloaded 1199 bytes. 03:25:44 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 03:25:44 INFO - Using the following test package requirements: 03:25:44 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 03:25:44 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:25:44 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 03:25:44 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:25:44 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 03:25:44 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:25:44 INFO - u'jsshell-mac.zip'], 03:25:44 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:25:44 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 03:25:44 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 03:25:44 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:25:44 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 03:25:44 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:25:44 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 03:25:44 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:25:44 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 03:25:44 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:25:44 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 03:25:44 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:25: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')]} 03:25:44 INFO - https://queue.taskcluster.net/v1/task/c_DjE3nCQ7iWGbtkmbNEHg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:25:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_DjE3nCQ7iWGbtkmbNEHg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:25:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_DjE3nCQ7iWGbtkmbNEHg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:25:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_DjE3nCQ7iWGbtkmbNEHg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:25:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_DjE3nCQ7iWGbtkmbNEHg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:25:46 INFO - Downloaded 18718469 bytes. 03:25:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:25:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:25:49 INFO - Return code: 0 03:25:49 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 03:25:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:49 INFO - https://queue.taskcluster.net/v1/task/c_DjE3nCQ7iWGbtkmbNEHg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:25:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_DjE3nCQ7iWGbtkmbNEHg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:25:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_DjE3nCQ7iWGbtkmbNEHg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:25:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_DjE3nCQ7iWGbtkmbNEHg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:25:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_DjE3nCQ7iWGbtkmbNEHg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:25:52 INFO - Downloaded 18718469 bytes. 03:25:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:25:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:25:56 INFO - Return code: 0 03:25:56 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')]} 03:25:56 INFO - https://queue.taskcluster.net/v1/task/c_DjE3nCQ7iWGbtkmbNEHg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:25:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_DjE3nCQ7iWGbtkmbNEHg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 03:25:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_DjE3nCQ7iWGbtkmbNEHg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 03:25:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_DjE3nCQ7iWGbtkmbNEHg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 03:25:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_DjE3nCQ7iWGbtkmbNEHg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 03:25:57 INFO - Downloaded 10833369 bytes. 03:25:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:25:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 03:25:57 INFO - Return code: 0 03:25:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:57 INFO - https://queue.taskcluster.net/v1/task/c_DjE3nCQ7iWGbtkmbNEHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 03:25:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_DjE3nCQ7iWGbtkmbNEHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 03:25:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_DjE3nCQ7iWGbtkmbNEHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 03:25:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_DjE3nCQ7iWGbtkmbNEHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 03:25:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_DjE3nCQ7iWGbtkmbNEHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 03:26:00 INFO - Downloaded 111430675 bytes. 03:26:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/c_DjE3nCQ7iWGbtkmbNEHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 03:26:00 INFO - mkdir: /builds/slave/test/properties 03:26:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:26:00 INFO - Writing to file /builds/slave/test/properties/build_url 03:26:00 INFO - Contents: 03:26:00 INFO - build_url:https://queue.taskcluster.net/v1/task/c_DjE3nCQ7iWGbtkmbNEHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 03:26:01 INFO - Running post-action listener: _resource_record_post_action 03:26:01 INFO - Running post-action listener: set_extra_try_arguments 03:26:01 INFO - ##### 03:26:01 INFO - ##### Finished download-and-extract step (success) 03:26:01 INFO - ##### 03:26:01 INFO - ##### 03:26:01 INFO - ##### Running populate-webroot step. 03:26:01 INFO - ##### 03:26:01 INFO - Running pre-action listener: _resource_record_pre_action 03:26:01 INFO - Running main action method: populate_webroot 03:26:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:26:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:26:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:26:01 INFO - '--tppagecycles', 03:26:01 INFO - '7'], 03:26:01 INFO - 'tests': ['tsvgm']}}, 03:26:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:26:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:26:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:26:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:26:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:26:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:26:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:26:01 INFO - 'talos_options': ['--disable-e10s'], 03:26:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:26:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:26:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:26:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:26:01 INFO - 'talos_options': ['--disable-e10s'], 03:26:01 INFO - 'tests': ['damp', 'tps']}, 03:26:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:26:01 INFO - 'tests': ['damp', 'tps']}, 03:26:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:26:01 INFO - 'tests': ['dromaeo_dom']}, 03:26:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:26:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:26:01 INFO - 'tests': ['a11yr', 03:26:01 INFO - 'ts_paint', 03:26:01 INFO - 'tpaint', 03:26:01 INFO - 'sessionrestore', 03:26:01 INFO - 'sessionrestore_no_auto_restore', 03:26:01 INFO - 'tabpaint']}, 03:26:01 INFO - 'other-e10s': {'tests': ['a11yr', 03:26:01 INFO - 'ts_paint', 03:26:01 INFO - 'tpaint', 03:26:01 INFO - 'sessionrestore', 03:26:01 INFO - 'sessionrestore_no_auto_restore', 03:26:01 INFO - 'tabpaint']}, 03:26:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:26:01 INFO - 'ts_paint', 03:26:01 INFO - 'tpaint', 03:26:01 INFO - 'sessionrestore', 03:26:01 INFO - 'sessionrestore_no_auto_restore', 03:26:01 INFO - 'tabpaint']}, 03:26:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:26:01 INFO - 'ts_paint', 03:26:01 INFO - 'tpaint', 03:26:01 INFO - 'sessionrestore', 03:26:01 INFO - 'sessionrestore_no_auto_restore', 03:26:01 INFO - 'tabpaint']}, 03:26:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:26:01 INFO - 'tests': ['a11yr', 03:26:01 INFO - 'ts_paint', 03:26:01 INFO - 'tpaint', 03:26:01 INFO - 'sessionrestore', 03:26:01 INFO - 'sessionrestore_no_auto_restore', 03:26:01 INFO - 'tabpaint']}, 03:26:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:26:01 INFO - 'tests': ['a11yr', 03:26:01 INFO - 'ts_paint', 03:26:01 INFO - 'tpaint', 03:26:01 INFO - 'sessionrestore', 03:26:01 INFO - 'sessionrestore_no_auto_restore', 03:26:01 INFO - 'tabpaint']}, 03:26:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:26:01 INFO - 'tests': ['tsvgx', 03:26:01 INFO - 'tsvgr_opacity', 03:26:01 INFO - 'tart', 03:26:01 INFO - 'tscrollx', 03:26:01 INFO - 'cart']}, 03:26:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:26:01 INFO - 'tsvgr_opacity', 03:26:01 INFO - 'tart', 03:26:01 INFO - 'tscrollx', 03:26:01 INFO - 'cart']}, 03:26:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:26:01 INFO - 'talos_options': ['--disable-e10s'], 03:26:01 INFO - 'tests': ['tp5o']}, 03:26:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:26:01 INFO - 'tests': ['tp5o']}, 03:26:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:26:01 INFO - 'talos_options': ['--disable-e10s', 03:26:01 INFO - '--xperf_path', 03:26:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:26:01 INFO - 'tests': ['tp5n']}, 03:26:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:26:01 INFO - 'talos_options': ['--xperf_path', 03:26:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:26:01 INFO - 'tests': ['tp5n']}}, 03:26:01 INFO - 'talos.zip': {'path': '', 03:26:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:26:01 INFO - Running post-action listener: _resource_record_post_action 03:26:01 INFO - ##### 03:26:01 INFO - ##### Finished populate-webroot step (success) 03:26:01 INFO - ##### 03:26:01 INFO - ##### 03:26:01 INFO - ##### Running create-virtualenv step. 03:26:01 INFO - ##### 03:26:01 INFO - Running pre-action listener: _resource_record_pre_action 03:26:01 INFO - Running main action method: create_virtualenv 03:26:01 INFO - Creating virtualenv /builds/slave/test/build/venv 03:26:01 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 03:26:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:26:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:26:01 INFO - Using real prefix '/tools/python27' 03:26:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:26:02 INFO - Installing distribute.............................................................................................................................................................................................done. 03:26:05 INFO - Installing pip.................done. 03:26:05 INFO - Return code: 0 03:26:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:26:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:26:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:26:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:05 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 0x105e71a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e78570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cea4a3010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d16d30>, '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 0x105b3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d0db20>, '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.SIye3L56e0/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.droFgu7lfg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 03:26:05 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 03:26:05 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 03:26:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SIye3L56e0/Render', 03:26:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:26:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:26:05 INFO - 'HOME': '/Users/cltbld', 03:26:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:26:05 INFO - 'LOGNAME': 'cltbld', 03:26:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:05 INFO - 'MOZ_NO_REMOTE': '1', 03:26:05 INFO - 'NO_EM_RESTART': '1', 03:26:05 INFO - 'PAGER': '/bin/cat', 03:26:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:26:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:05 INFO - 'PWD': '/builds/slave/test', 03:26:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:26:05 INFO - 'SHELL': '/bin/bash', 03:26:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.droFgu7lfg/Listeners', 03:26:05 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 03:26:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:26:05 INFO - 'USER': 'cltbld', 03:26:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:26:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:26:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:05 INFO - 'XPC_FLAGS': '0x0', 03:26:05 INFO - 'XPC_SERVICE_NAME': '0', 03:26:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:26:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:05 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 03:26:05 INFO - Cleaning up... 03:26:05 INFO - Return code: 0 03:26:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:26:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:26:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:26:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:05 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 0x105e71a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e78570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cea4a3010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d16d30>, '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 0x105b3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d0db20>, '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.SIye3L56e0/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.droFgu7lfg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 03:26:05 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 03:26:05 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 03:26:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SIye3L56e0/Render', 03:26:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:26:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:26:05 INFO - 'HOME': '/Users/cltbld', 03:26:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:26:05 INFO - 'LOGNAME': 'cltbld', 03:26:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:05 INFO - 'MOZ_NO_REMOTE': '1', 03:26:05 INFO - 'NO_EM_RESTART': '1', 03:26:05 INFO - 'PAGER': '/bin/cat', 03:26:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:26:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:05 INFO - 'PWD': '/builds/slave/test', 03:26:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:26:05 INFO - 'SHELL': '/bin/bash', 03:26:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.droFgu7lfg/Listeners', 03:26:05 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 03:26:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:26:05 INFO - 'USER': 'cltbld', 03:26:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:26:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:26:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:05 INFO - 'XPC_FLAGS': '0x0', 03:26:05 INFO - 'XPC_SERVICE_NAME': '0', 03:26:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:26:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:05 INFO - Downloading/unpacking psutil>=3.1.1 03:26:05 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 03:26:05 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 03:26:05 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 03:26:05 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 03:26:05 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 03:26:05 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 03:26:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:26:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:26:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:26:08 INFO - Installing collected packages: psutil 03:26:08 INFO - Running setup.py install for psutil 03:26:08 INFO - building 'psutil._psutil_osx' extension 03:26:08 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 03:26:09 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 03:26:09 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 03:26:09 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 03:26:09 INFO - building 'psutil._psutil_posix' extension 03:26:09 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 03:26:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:26:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:26:09 INFO - ^ 03:26:09 INFO - 1 warning generated. 03:26:09 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 03:26:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:26:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:26:09 INFO - Successfully installed psutil 03:26:09 INFO - Cleaning up... 03:26:10 INFO - Return code: 0 03:26:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:26:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:26:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:26:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:10 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 0x105e71a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e78570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cea4a3010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d16d30>, '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 0x105b3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d0db20>, '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.SIye3L56e0/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.droFgu7lfg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 03:26:10 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 03:26:10 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 03:26:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SIye3L56e0/Render', 03:26:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:26:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:26:10 INFO - 'HOME': '/Users/cltbld', 03:26:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:26:10 INFO - 'LOGNAME': 'cltbld', 03:26:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:10 INFO - 'MOZ_NO_REMOTE': '1', 03:26:10 INFO - 'NO_EM_RESTART': '1', 03:26:10 INFO - 'PAGER': '/bin/cat', 03:26:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:26:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:10 INFO - 'PWD': '/builds/slave/test', 03:26:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:26:10 INFO - 'SHELL': '/bin/bash', 03:26:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.droFgu7lfg/Listeners', 03:26:10 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 03:26:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:26:10 INFO - 'USER': 'cltbld', 03:26:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:26:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:26:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:10 INFO - 'XPC_FLAGS': '0x0', 03:26:10 INFO - 'XPC_SERVICE_NAME': '0', 03:26:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:26:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:26:10 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 03:26:10 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 03:26:10 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 03:26:10 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 03:26:10 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 03:26:10 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 03:26:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:26:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:26:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:26:12 INFO - Installing collected packages: mozsystemmonitor 03:26:12 INFO - Running setup.py install for mozsystemmonitor 03:26:12 INFO - Successfully installed mozsystemmonitor 03:26:12 INFO - Cleaning up... 03:26:13 INFO - Return code: 0 03:26:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:26:13 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')]} 03:26:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:13 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')]} 03:26:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:13 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 0x105e71a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e78570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cea4a3010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d16d30>, '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 0x105b3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d0db20>, '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.SIye3L56e0/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.droFgu7lfg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 03:26:13 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 03:26:13 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 03:26:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SIye3L56e0/Render', 03:26:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:26:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:26:13 INFO - 'HOME': '/Users/cltbld', 03:26:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:26:13 INFO - 'LOGNAME': 'cltbld', 03:26:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:13 INFO - 'MOZ_NO_REMOTE': '1', 03:26:13 INFO - 'NO_EM_RESTART': '1', 03:26:13 INFO - 'PAGER': '/bin/cat', 03:26:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:26:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:13 INFO - 'PWD': '/builds/slave/test', 03:26:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:26:13 INFO - 'SHELL': '/bin/bash', 03:26:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.droFgu7lfg/Listeners', 03:26:13 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 03:26:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:26:13 INFO - 'USER': 'cltbld', 03:26:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:26:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:26:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:13 INFO - 'XPC_FLAGS': '0x0', 03:26:13 INFO - 'XPC_SERVICE_NAME': '0', 03:26:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:26:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:13 INFO - Downloading/unpacking jsonschema==2.5.1 03:26:13 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 03:26:13 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 03:26:13 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 03:26:13 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 03:26:13 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 03:26:13 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 03:26:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:26:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:26:15 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 03:26:15 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 03:26:15 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 03:26:15 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 03:26:15 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 03:26:15 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 03:26:16 INFO - Downloading functools32-3.2.3-2.tar.gz 03:26:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:26:16 INFO - warning: no files found matching '*.txt' 03:26:16 INFO - no previously-included directories found matching 'build' 03:26:16 INFO - no previously-included directories found matching 'dist' 03:26:16 INFO - no previously-included directories found matching '.git*' 03:26:16 INFO - Installing collected packages: jsonschema, functools32 03:26:16 INFO - Running setup.py install for functools32 03:26:16 INFO - warning: no files found matching '*.txt' 03:26:16 INFO - no previously-included directories found matching 'build' 03:26:16 INFO - no previously-included directories found matching 'dist' 03:26:16 INFO - no previously-included directories found matching '.git*' 03:26:16 INFO - Successfully installed jsonschema functools32 03:26:16 INFO - Cleaning up... 03:26:16 INFO - Return code: 0 03:26:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:26:16 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')]} 03:26:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:16 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')]} 03:26:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:16 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 0x105e71a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e78570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cea4a3010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d16d30>, '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 0x105b3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d0db20>, '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.SIye3L56e0/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.droFgu7lfg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 03:26:16 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 03:26:16 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 03:26:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SIye3L56e0/Render', 03:26:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:26:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:26:16 INFO - 'HOME': '/Users/cltbld', 03:26:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:26:16 INFO - 'LOGNAME': 'cltbld', 03:26:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:16 INFO - 'MOZ_NO_REMOTE': '1', 03:26:16 INFO - 'NO_EM_RESTART': '1', 03:26:16 INFO - 'PAGER': '/bin/cat', 03:26:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:26:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:16 INFO - 'PWD': '/builds/slave/test', 03:26:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:26:16 INFO - 'SHELL': '/bin/bash', 03:26:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.droFgu7lfg/Listeners', 03:26:16 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 03:26:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:26:16 INFO - 'USER': 'cltbld', 03:26:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:26:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:26:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:16 INFO - 'XPC_FLAGS': '0x0', 03:26:16 INFO - 'XPC_SERVICE_NAME': '0', 03:26:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:26:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:16 INFO - Downloading/unpacking blobuploader==1.2.4 03:26:16 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 03:26:16 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 03:26:16 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 03:26:16 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 03:26:16 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 03:26:16 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 03:26:19 INFO - Downloading blobuploader-1.2.4.tar.gz 03:26:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:26:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:26:19 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 03:26:19 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 03:26:19 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 03:26:19 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 03:26:19 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 03:26:19 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 03:26:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:26:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:26:19 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 03:26:19 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 03:26:19 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 03:26:19 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 03:26:19 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 03:26:19 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 03:26:20 INFO - Downloading docopt-0.6.1.tar.gz 03:26:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:26:20 INFO - Installing collected packages: blobuploader, requests, docopt 03:26:20 INFO - Running setup.py install for blobuploader 03:26:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:26:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:26:20 INFO - Running setup.py install for requests 03:26:21 INFO - Running setup.py install for docopt 03:26:21 INFO - Successfully installed blobuploader requests docopt 03:26:21 INFO - Cleaning up... 03:26:21 INFO - Return code: 0 03:26:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:26:21 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')]} 03:26:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:21 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')]} 03:26:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:21 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 0x105e71a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e78570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cea4a3010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d16d30>, '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 0x105b3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d0db20>, '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.SIye3L56e0/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.droFgu7lfg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 03:26:21 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 03:26:21 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 03:26:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SIye3L56e0/Render', 03:26:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:26:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:26:21 INFO - 'HOME': '/Users/cltbld', 03:26:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:26:21 INFO - 'LOGNAME': 'cltbld', 03:26:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:21 INFO - 'MOZ_NO_REMOTE': '1', 03:26:21 INFO - 'NO_EM_RESTART': '1', 03:26:21 INFO - 'PAGER': '/bin/cat', 03:26:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:26:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:21 INFO - 'PWD': '/builds/slave/test', 03:26:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:26:21 INFO - 'SHELL': '/bin/bash', 03:26:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.droFgu7lfg/Listeners', 03:26:21 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 03:26:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:26:21 INFO - 'USER': 'cltbld', 03:26:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:26:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:26:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:21 INFO - 'XPC_FLAGS': '0x0', 03:26:21 INFO - 'XPC_SERVICE_NAME': '0', 03:26:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:26:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:26:21 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-nLY5fv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:26:21 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-usKclh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:26:21 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-kpjauO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:26:21 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-dhBWYw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:26:21 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-EMiVO2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:26:22 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-_aLZL2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:26:22 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-jlQua8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:26:22 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-kIwDrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:26:22 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-e_2PJo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:26:22 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-VdtHT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:26:22 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-jTU4xi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:26:22 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-pZo7lE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:26:22 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-6BY71T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:26:22 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-PxftzU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:26:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:26:23 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-6fUSol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:26:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:26:23 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-lq1M2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:26:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:26:23 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-8fkE72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:26:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:26:23 INFO - Running setup.py install for manifestparser 03:26:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:26:23 INFO - Running setup.py install for mozcrash 03:26:23 INFO - Running setup.py install for mozdebug 03:26:23 INFO - Running setup.py install for mozdevice 03:26:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:26:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:26:23 INFO - Running setup.py install for mozfile 03:26:24 INFO - Running setup.py install for mozhttpd 03:26:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:26:24 INFO - Running setup.py install for mozinfo 03:26:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:26:24 INFO - Running setup.py install for mozInstall 03:26:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:26:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:26:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:26:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:26:24 INFO - Running setup.py install for mozleak 03:26:24 INFO - Running setup.py install for mozlog 03:26:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:26:24 INFO - Running setup.py install for moznetwork 03:26:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:26:24 INFO - Running setup.py install for mozprocess 03:26:25 INFO - Running setup.py install for mozprofile 03:26:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:26:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:26:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:26:25 INFO - Running setup.py install for mozrunner 03:26:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:26:25 INFO - Running setup.py install for mozscreenshot 03:26:25 INFO - Running setup.py install for moztest 03:26:25 INFO - Running setup.py install for mozversion 03:26:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:26:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:26:25 INFO - Cleaning up... 03:26:25 INFO - Return code: 0 03:26:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:26:25 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')]} 03:26:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:25 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')]} 03:26:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:25 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 0x105e71a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e78570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cea4a3010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d16d30>, '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 0x105b3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d0db20>, '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.SIye3L56e0/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.droFgu7lfg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 03:26:25 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 03:26:25 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 03:26:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SIye3L56e0/Render', 03:26:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:26:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:26:25 INFO - 'HOME': '/Users/cltbld', 03:26:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:26:25 INFO - 'LOGNAME': 'cltbld', 03:26:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:25 INFO - 'MOZ_NO_REMOTE': '1', 03:26:25 INFO - 'NO_EM_RESTART': '1', 03:26:25 INFO - 'PAGER': '/bin/cat', 03:26:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:26:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:25 INFO - 'PWD': '/builds/slave/test', 03:26:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:26:25 INFO - 'SHELL': '/bin/bash', 03:26:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.droFgu7lfg/Listeners', 03:26:25 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 03:26:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:26:25 INFO - 'USER': 'cltbld', 03:26:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:26:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:26:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:25 INFO - 'XPC_FLAGS': '0x0', 03:26:25 INFO - 'XPC_SERVICE_NAME': '0', 03:26:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:26:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:26:26 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-Er4pzs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:26:26 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)) 03:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:26:26 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-6bJ63M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:26:26 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)) 03:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:26:26 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-KY9u8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:26:26 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)) 03:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:26:26 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-a6y8HM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:26:26 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)) 03:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:26:26 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-aeFcmL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:26:26 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)) 03:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:26:26 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-9Mf2LC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:26:26 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)) 03:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:26:26 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-e7nrM2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:26:26 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)) 03:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:26:26 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-KlYTLf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:26:27 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)) 03:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:26:27 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-m4poI6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:26:27 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)) 03:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:26:27 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-Bxb6B7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:26:27 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)) 03:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:26:27 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-zDxuVD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:26:27 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)) 03:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:26:27 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-nmGMvS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:26:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 03:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:26:27 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-5VqHVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:26:27 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)) 03:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:26:27 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-mdKKNC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:26:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 03:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:26:27 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-Owq4J7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:26:27 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)) 03:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:26:27 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-rj0TZV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:26:27 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)) 03:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:26:27 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-IxgMKW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:26:27 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)) 03:26:27 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)) 03:26:27 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)) 03:26:27 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)) 03:26:27 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)) 03:26:27 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)) 03:26:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:26: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 03:26: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 03:26: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 03:26: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 03:26: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 03:26: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 03:26:30 INFO - Downloading blessings-1.6.tar.gz 03:26:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:26:30 INFO - Installing collected packages: blessings 03:26:30 INFO - Running setup.py install for blessings 03:26:30 INFO - Successfully installed blessings 03:26:30 INFO - Cleaning up... 03:26:30 INFO - Return code: 0 03:26:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:26:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:26:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:26:30 INFO - Reading from file tmpfile_stdout 03:26:30 INFO - Current package versions: 03:26:30 INFO - blessings == 1.6 03:26:30 INFO - blobuploader == 1.2.4 03:26:30 INFO - docopt == 0.6.1 03:26:30 INFO - functools32 == 3.2.3-2 03:26:30 INFO - jsonschema == 2.5.1 03:26:30 INFO - manifestparser == 1.1 03:26:30 INFO - mozInstall == 1.12 03:26:30 INFO - mozcrash == 0.17 03:26:30 INFO - mozdebug == 0.1 03:26:30 INFO - mozdevice == 0.48 03:26:30 INFO - mozfile == 1.2 03:26:30 INFO - mozhttpd == 0.7 03:26:30 INFO - mozinfo == 0.9 03:26:30 INFO - mozleak == 0.1 03:26:30 INFO - mozlog == 3.2 03:26:30 INFO - moznetwork == 0.27 03:26:30 INFO - mozprocess == 0.22 03:26:30 INFO - mozprofile == 0.28 03:26:30 INFO - mozrunner == 6.11 03:26:30 INFO - mozscreenshot == 0.1 03:26:30 INFO - mozsystemmonitor == 0.3 03:26:30 INFO - moztest == 0.7 03:26:30 INFO - mozversion == 1.4 03:26:30 INFO - psutil == 3.1.1 03:26:30 INFO - requests == 1.2.3 03:26:30 INFO - wsgiref == 0.1.2 03:26:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:26:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:26:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:26:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:30 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 0x105e71a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e78570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cea4a3010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d16d30>, '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 0x105b3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d0db20>, '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.SIye3L56e0/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.droFgu7lfg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 03:26:30 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 03:26:30 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 03:26:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SIye3L56e0/Render', 03:26:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:26:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:26:30 INFO - 'HOME': '/Users/cltbld', 03:26:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:26:30 INFO - 'LOGNAME': 'cltbld', 03:26:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:30 INFO - 'MOZ_NO_REMOTE': '1', 03:26:30 INFO - 'NO_EM_RESTART': '1', 03:26:30 INFO - 'PAGER': '/bin/cat', 03:26:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:26:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:30 INFO - 'PWD': '/builds/slave/test', 03:26:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:26:30 INFO - 'SHELL': '/bin/bash', 03:26:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.droFgu7lfg/Listeners', 03:26:30 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 03:26:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:26:30 INFO - 'USER': 'cltbld', 03:26:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:26:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:26:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:30 INFO - 'XPC_FLAGS': '0x0', 03:26:30 INFO - 'XPC_SERVICE_NAME': '0', 03:26:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:26:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:31 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)) 03:26:31 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)) 03:26:31 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)) 03:26:31 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)) 03:26:31 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)) 03:26:31 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)) 03:26:31 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)) 03:26:31 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)) 03:26:31 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)) 03:26:31 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)) 03:26:31 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)) 03:26:31 INFO - Cleaning up... 03:26:31 INFO - Return code: 0 03:26:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:26: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')]} 03:26:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26: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')]} 03:26:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:31 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 0x105e71a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e78570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cea4a3010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d16d30>, '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 0x105b3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d0db20>, '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.SIye3L56e0/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.droFgu7lfg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 03:26:31 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 03:26:31 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 03:26:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SIye3L56e0/Render', 03:26:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:26:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:26:31 INFO - 'HOME': '/Users/cltbld', 03:26:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:26:31 INFO - 'LOGNAME': 'cltbld', 03:26:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:31 INFO - 'MOZ_NO_REMOTE': '1', 03:26:31 INFO - 'NO_EM_RESTART': '1', 03:26:31 INFO - 'PAGER': '/bin/cat', 03:26:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:26:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:31 INFO - 'PWD': '/builds/slave/test', 03:26:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:26:31 INFO - 'SHELL': '/bin/bash', 03:26:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.droFgu7lfg/Listeners', 03:26:31 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 03:26:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:26:31 INFO - 'USER': 'cltbld', 03:26:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:26:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:26:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:31 INFO - 'XPC_FLAGS': '0x0', 03:26:31 INFO - 'XPC_SERVICE_NAME': '0', 03:26:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:26:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:26:31 INFO - Cleaning up... 03:26:31 INFO - Return code: 0 03:26:31 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:26: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')]} 03:26:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26: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')]} 03:26:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:31 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 0x105e71a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e78570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cea4a3010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d16d30>, '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 0x105b3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d0db20>, '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.SIye3L56e0/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.droFgu7lfg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 03:26:31 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 03:26:31 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 03:26:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SIye3L56e0/Render', 03:26:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:26:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:26:31 INFO - 'HOME': '/Users/cltbld', 03:26:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:26:31 INFO - 'LOGNAME': 'cltbld', 03:26:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:31 INFO - 'MOZ_NO_REMOTE': '1', 03:26:31 INFO - 'NO_EM_RESTART': '1', 03:26:31 INFO - 'PAGER': '/bin/cat', 03:26:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:26:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:31 INFO - 'PWD': '/builds/slave/test', 03:26:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:26:31 INFO - 'SHELL': '/bin/bash', 03:26:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.droFgu7lfg/Listeners', 03:26:31 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 03:26:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:26:31 INFO - 'USER': 'cltbld', 03:26:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:26:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:26:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:31 INFO - 'XPC_FLAGS': '0x0', 03:26:31 INFO - 'XPC_SERVICE_NAME': '0', 03:26:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:26:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:31 INFO - Downloading/unpacking flake8 03:26:31 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 03:26:31 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 03:26:31 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 03:26:31 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 03:26:31 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 03:26:31 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 03:26:34 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:26:34 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:26:34 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 03:26:34 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 03:26:34 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 03:26:34 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 03:26:34 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 03:26:34 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 03:26:34 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:26:34 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:26:34 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 03:26:34 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 03:26:34 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 03:26:34 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 03:26:34 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 03:26:34 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 03:26:35 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:26:35 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 03:26:35 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 03:26:35 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 03:26:35 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 03:26:35 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 03:26:35 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 03:26:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:26:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:26:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:26:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:26:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:26:35 INFO - no previously-included directories found matching 'docs/_build' 03:26:35 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:26:35 INFO - Running setup.py install for pep8 03:26:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:26:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:26:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:26:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:26:35 INFO - no previously-included directories found matching 'docs/_build' 03:26:35 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:26:35 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:26:35 INFO - Cleaning up... 03:26:35 INFO - Return code: 0 03:26:35 INFO - Running post-action listener: _resource_record_post_action 03:26:35 INFO - Running post-action listener: _start_resource_monitoring 03:26:35 INFO - Starting resource monitoring. 03:26:35 INFO - ##### 03:26:35 INFO - ##### Finished create-virtualenv step (success) 03:26:35 INFO - ##### 03:26:35 INFO - ##### 03:26:35 INFO - ##### Running install step. 03:26:35 INFO - ##### 03:26:35 INFO - Running pre-action listener: _resource_record_pre_action 03:26:35 INFO - Running main action method: install 03:26:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:26:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:26:36 INFO - Reading from file tmpfile_stdout 03:26:36 INFO - Detecting whether we're running mozinstall >=1.0... 03:26:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:26:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:26:36 INFO - Reading from file tmpfile_stdout 03:26:36 INFO - Output received: 03:26:36 INFO - Usage: mozinstall [options] installer 03:26:36 INFO - Options: 03:26:36 INFO - -h, --help show this help message and exit 03:26:36 INFO - -d DEST, --destination=DEST 03:26:36 INFO - Directory to install application into. [default: 03:26:36 INFO - "/builds/slave/test"] 03:26:36 INFO - --app=APP Application being installed. [default: firefox] 03:26:36 INFO - mkdir: /builds/slave/test/build/application 03:26:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:26:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 03:27:10 INFO - Reading from file tmpfile_stdout 03:27:10 INFO - Output received: 03:27:10 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:27:10 INFO - Running post-action listener: _resource_record_post_action 03:27:10 INFO - ##### 03:27:10 INFO - ##### Finished install step (success) 03:27:10 INFO - ##### 03:27:10 INFO - ##### 03:27:10 INFO - ##### Running run-tests step. 03:27:10 INFO - ##### 03:27:10 INFO - Running pre-action listener: _resource_record_pre_action 03:27:10 INFO - Running main action method: run_tests 03:27:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:27:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:27:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:27:10 INFO - 2016-06-07 03:27:10.415 screenresolution[1649:7876] starting screenresolution argv=screenresolution get 03:27:10 INFO - 2016-06-07 03:27:10.418 screenresolution[1649:7876] Display 0: 1600x1200x32@60 03:27:10 INFO - 2016-06-07 03:27:10.423 screenresolution[1650:7878] starting screenresolution argv=screenresolution list 03:27:10 INFO - Available Modes on Display 0 03:27:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:27:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:27:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:27:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:27:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:27:10 INFO - Intel Iris: 03:27:10 INFO - Chipset Model: Intel Iris 03:27:10 INFO - Type: GPU 03:27:10 INFO - Bus: Built-In 03:27:10 INFO - VRAM (Dynamic, Max): 1536 MB 03:27:10 INFO - Vendor: Intel (0x8086) 03:27:10 INFO - Device ID: 0x0a2e 03:27:10 INFO - Revision ID: 0x0009 03:27:10 INFO - Displays: 03:27:10 INFO - Display: 03:27:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:27:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:27:10 INFO - Display Serial Number: 1600x1200 60 03:27:10 INFO - Main Display: Yes 03:27:10 INFO - Mirror: Off 03:27:10 INFO - Online: Yes 03:27:10 INFO - Rotation: Supported 03:27:10 INFO - Return code: 0 03:27:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:27:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:27:10 INFO - Python 2.7.3 03:27:10 INFO - Return code: 0 03:27:10 INFO - grabbing minidump binary from tooltool 03:27:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:27:10 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 0x105d16d30>, '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 0x105b3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d0db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:27:10 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 03:27:10 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 03:27:10 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 03:27:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:27:10 INFO - Return code: 0 03:27:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:27:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:27:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:27:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:27:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:27:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:27:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:27:11 INFO - Return code: 0 03:27:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/c_DjE3nCQ7iWGbtkmbNEHg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0082', '--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 03:27:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/c_DjE3nCQ7iWGbtkmbNEHg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0082 --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 03:27:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SIye3L56e0/Render', 03:27:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:27:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:27:11 INFO - 'HOME': '/Users/cltbld', 03:27:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:27:11 INFO - 'LOGNAME': 'cltbld', 03:27:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:27:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:27:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:27:11 INFO - 'MOZ_NO_REMOTE': '1', 03:27:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:27:11 INFO - 'NO_EM_RESTART': '1', 03:27:11 INFO - 'PAGER': '/bin/cat', 03:27:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:27:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:27:11 INFO - 'PWD': '/builds/slave/test', 03:27:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:27:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:27:11 INFO - 'SHELL': '/bin/bash', 03:27:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.droFgu7lfg/Listeners', 03:27:11 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 03:27:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:27:11 INFO - 'USER': 'cltbld', 03:27:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:27:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:27:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:27:11 INFO - 'XPC_FLAGS': '0x0', 03:27:11 INFO - 'XPC_SERVICE_NAME': '0', 03:27:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:27:11 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/c_DjE3nCQ7iWGbtkmbNEHg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0082', '--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 03:27:12 INFO - mozversion application_buildid: 20160607010031 03:27:12 INFO - mozversion application_changeset: 28646bde9f7a1e136e748e1b111f168dd7ad1eab 03:27:12 INFO - mozversion application_display_name: Nightly 03:27:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:27:12 INFO - mozversion application_name: Firefox 03:27:12 INFO - mozversion application_remotingname: firefox 03:27:12 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 03:27:12 INFO - mozversion application_vendor: Mozilla 03:27:12 INFO - mozversion application_version: 50.0a1 03:27:12 INFO - mozversion platform_buildid: 20160607010031 03:27:12 INFO - mozversion platform_changeset: 28646bde9f7a1e136e748e1b111f168dd7ad1eab 03:27:12 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 03:27:12 INFO - mozversion platform_version: 50.0a1 03:27:12 INFO - using testdate: 1465295232 03:27:12 INFO - actual date: 1465295232 03:27:12 INFO - starting webserver on 'localhost:49268' 03:27:12 INFO - SUITE-START | Running 2 tests 03:27:12 INFO - TEST-START | dromaeo_css 03:27:12 INFO - operating with platform_type : mac_ 03:27:12 INFO - Initialising browser for dromaeo_css test... 03:27:12 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmpiGvImE/profile http://localhost:49268/getInfo.html) 03:27:14 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 03:27:14 INFO - PROCESS | 1668 | colorDepth:24 03:27:14 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 03:27:14 INFO - PROCESS | 1668 | __metrics 03:27:14 INFO - PROCESS | 1668 | 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" 03:27:14 ERROR - PROCESS | 1668 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:27:14 INFO - PROCESS | 1668 | ************************* 03:27:14 INFO - PROCESS | 1668 | A coding exception was thrown in a Promise resolution callback. 03:27:14 INFO - PROCESS | 1668 | See https://developer.mozilla.org/Mozilla/JavaScript_code_modules/Promise.jsm/Promise 03:27:14 INFO - PROCESS | 1668 | 03:27:14 ERROR - PROCESS | 1668 | Full message: TypeError: win is null 03:27:14 INFO - PROCESS | 1668 | Full stack: onSuccess@resource://app/components/nsBrowserGlue.js:850:17 03:27:14 INFO - PROCESS | 1668 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 03:27:14 INFO - PROCESS | 1668 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 03:27:14 INFO - PROCESS | 1668 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 03:27:14 INFO - PROCESS | 1668 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 03:27:14 INFO - PROCESS | 1668 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 03:27:14 INFO - PROCESS | 1668 | get _worker/worker.onmessage@resource://gre/modules/PromiseWorker.jsm:235:9 03:27:14 INFO - PROCESS | 1668 | EventHandlerNonNull*get _worker@resource://gre/modules/PromiseWorker.jsm:217:5 03:27:14 INFO - PROCESS | 1668 | postMessage@resource://gre/modules/PromiseWorker.jsm:292:9 03:27:14 INFO - PROCESS | 1668 | TaskImpl_run@resource://gre/modules/Task.jsm:319:40 03:27:14 INFO - PROCESS | 1668 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 03:27:14 INFO - PROCESS | 1668 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 03:27:14 INFO - PROCESS | 1668 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 03:27:14 INFO - PROCESS | 1668 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 03:27:14 INFO - PROCESS | 1668 | Promise.prototype.then@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:454:5 03:27:14 INFO - PROCESS | 1668 | this.DeferredSave.prototype._deferredSave@resource://gre/modules/DeferredSave.jsm:220:5 03:27:14 INFO - PROCESS | 1668 | this.DeferredSave.prototype._startTimer/<@resource://gre/modules/DeferredSave.jsm:175:40 03:27:14 INFO - PROCESS | 1668 | syncLoadManifestFromFile@resource://gre/modules/addons/XPIProvider.jsm:1485:5 03:27:14 INFO - PROCESS | 1668 | addMetadata@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:1647:21 03:27:14 INFO - PROCESS | 1668 | processFileChanges@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2011:23 03:27:14 INFO - PROCESS | 1668 | this.XPIProvider.checkForChanges@resource://gre/modules/addons/XPIProvider.jsm:3696:34 03:27:14 INFO - PROCESS | 1668 | this.XPIProvider.startup@resource://gre/modules/addons/XPIProvider.jsm:2685:25 03:27:14 INFO - PROCESS | 1668 | callProvider@resource://gre/modules/AddonManager.jsm:227:12 03:27:14 INFO - PROCESS | 1668 | _startProvider@resource://gre/modules/AddonManager.jsm:778:5 03:27:14 INFO - PROCESS | 1668 | AddonManagerInternal.startup@resource://gre/modules/AddonManager.jsm:962:9 03:27:14 INFO - PROCESS | 1668 | this.AddonManagerPrivate.startup@resource://gre/modules/AddonManager.jsm:2922:5 03:27:14 INFO - PROCESS | 1668 | amManager.prototype.observe@resource://gre/components/addonManager.js:71:9 03:27:14 INFO - PROCESS | 1668 | 03:27:14 INFO - PROCESS | 1668 | ************************* 03:27:15 INFO - TEST-INFO | 1668: exit 0 03:27:15 INFO - Browser initialized. 03:27:15 INFO - Running cycle 1/1 for dromaeo_css test... 03:27:15 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmpiGvImE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:27:27 INFO - PROCESS | 1671 | RSS: Main: 261455872 03:27:27 INFO - PROCESS | 1671 | 03:28:18 INFO - PROCESS | 1671 | 1465295298795 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] 03:30:29 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49268/tests/dromaeo/cssquery-ext.html) 03:30:30 INFO - PROCESS | 1671 | RSS: Main: 386646016 03:30:30 INFO - PROCESS | 1671 | 03:33:33 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-ext.html (next: http://localhost:49268/tests/dromaeo/cssquery-jquery.html) 03:33:34 INFO - PROCESS | 1671 | RSS: Main: 392978432 03:33:34 INFO - PROCESS | 1671 | 03:36:36 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49268/tests/dromaeo/cssquery-mootools.html) 03:36:37 INFO - PROCESS | 1671 | RSS: Main: 391761920 03:36:37 INFO - PROCESS | 1671 | 03:39:39 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49268/tests/dromaeo/cssquery-prototype.html) 03:39:40 INFO - PROCESS | 1671 | RSS: Main: 393302016 03:39:40 INFO - PROCESS | 1671 | 03:42:43 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49268/tests/dromaeo/cssquery-yui.html) 03:42:44 INFO - PROCESS | 1671 | RSS: Main: 359092224 03:42:44 INFO - PROCESS | 1671 | 03:45:47 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-yui.html (next: http://localhost:49268/tests/dromaeo/cssquery-dojo.html) 03:45:47 INFO - PROCESS | 1671 | RSS: Main: 415285248 03:45:47 INFO - PROCESS | 1671 | 03:45:47 INFO - PROCESS | 1671 | __start_tp_report 03:45:47 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 03:45:47 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 03:45:47 INFO - PROCESS | 1671 | |i|pagename|runs| 03:45:47 INFO - PROCESS | 1671 | |0;dojo.html;3363.495475678648;3407.1141503209174;3425.9828700856483;3431.7940923544575;3477.460993546001;5488.862778430541;5576.832695019146;5609.831705048845;5645.8588535286635;5653.406392328808;5563.443655634434;5591.077472217079;5606.579506537006;5608.803691870786;5619.634723742954;4990.825321113762;5068.4424713281505;5083.593312534988;5102.719350435725;5128.53843154116;5145.716985565792;5156.071907056728;5163.586913046947;5165.173572228444;5168.508991645787;4308.74147551148;4846.466888642247;5097.464766199551;5116.2069879168785;5156.53591176794;5190.532852043297;5197.506236907526;5200.97399513;5203.245529398235;5207.505286997729;4998.375203099613;5043.218301163325;5072.644914855961;5127.128388174019;5129.615278854101;5468.234447177399;5469.753861076221;5528.226048353234;5548.889022219573;5552;5091.9236211456855;6171.9691401542705;6198.442140207403;6202.100695399187;6206.038064100072;4997.2004479283505;6150.8769824603705;6235.127082208494;6251.4061164189325;6252.53106017046;5745.310562732415;5777.19119323295;5781.1328300754385;5782.595218334718;5799.5650326225705;6145.877082458415;6215.12988181655;6223.408776166257;6314.273858506239;6314.747410103636;5935.40645935403;6014.6090504117965;6074.848128796815;6093.964420269931;6142.846428839225;6219.942609756262;6234.781782637677;6246.500279977591;6247.375262473716;6247.687615619201;6172.135900973867;6184.567080304336;6189.442950134509;6192.659403732838;6206.875862482725;5955.940440595626;5974.820755377345;6009.278886533644;6193.566450348522;6232.626042437469;4757.976210118927;4786.282057691375;4797.040591881638;4803.78382972767;4822.132016237111;3709.2025214578994;4371.0602220522105;4374.278244089754;4384.56154384559;4414.161309351213;5752.539796816244;5769.105788602773;5797.739101740432;5797.9420205797405;5837.211976383219;6116.938830611726;6119.015143218474;6137.867325793252;6237.095621135;6238.313785483593;5671.319441667027;5755.827325180251;5775.133729940542;5780.537556995514;5829.09649004405;5333.253344531769;5340.893182136336;5347.67913925158;5348.572114230852;5352.464753524617;6100.8839630929915;6152.830962117184;6208.161898143693;6282.089097080806;6282.591631543935;5164.354455693038;6206.131141640084;6248.0003199487865;6253.468455181361;6272.341404152498;6259.687015649109;6291.622502649856;6304.968475157595;6316.242050953915;6316.399942005502;5993.520518358608;6052.90920636182;6118.694065296806;6121.387861213843;6161.106639537153;6247.875042499216;6265.906011409924;6273.404026617464;6275.466585340298;6307.779227727007;6017.7362753820435;6028.216331876828;6072.271327440735;6081.726322315418;6113.449789518964;5798.5651076168615;5837.766489340379;6029.517638588814;6058.515318774399;6067.271927368744;5854.707264636836;5855.180274761452;5863.970680146572;5905.084711869667;5912.852178695567;6083.270007599116;6108.938910610837;6123.571350005635;6133.110698948717;6137.877242455036;5759.712014399347;5833.445822646841;5846.532277417881;5895.14520394549;5913.674747888907 03:45:47 INFO - PROCESS | 1671 | |1;ext.html;488.47001003910754;496.6994968044333;512.6001718659446;512.8897287083277;547.0508667461951;31048.739882312344;31454.685453145463;42568.78715606425;43808.78095609525;43847.12305753883;45415.77292113535;45779.77110114445;45789.31316030254;45925.85185370367;45955.54044459554;9459.716208513755;9705.369151005198;9710.757231069209;9721.611135554569;9740.172085372717;8476.364272679542;8716.128387161265;8718.171773681523;8846.115388461134;8869.467831930106;11616.825747613793;11911;11923.642290731292;12108.576199833007;12196.45115969779;9795.706128816148;11830.704232394173;11831.40842957853;11831.645050648494;11872.465739041763;8440.19818117284;11369.715757106056;11653.30080195191;11712.590059347967;11782.234154779997;41266.174676506605;41768;41978.79010604927;42062;42211.36682949758;3433.261848702541;3459.757816952814;3487.250241198129;3555.520004799371;4089.44792453018;31391.68608313933;31913.52129718056;32022.839885800422;32315.6768432315;32447.02658920236;2980.955285670717;3053.908382748521;3069.554914537402;3077.261457250266;3098.798578092439;511439;512482;513290;515287;517292;58814.41185588175;59355.70322148448;59602;59642.701786491656;59828;63208.683956579924;63226;63292.25308101911;63381.366186337546;63678;16095.758563621555;16548.917255413886;16594.170291485618;16604.08677522748;16612.58468538296;12369.93815030919;12442;12444.377781110908;12465.813012804816;12467.127301089018;14016.159030458206;14312.928435357964;15669.059856408425;15970.76043859343;16132.596685082966;6374.553781235269;7345.706171753177;7442.255774422514;7485.663145158481;7497.625118744041;970.45169479244;1076.9407682577378;1094.500534268042;1099.724319789041;1103.431732657682;28253.71746282511;30321.090367289016;30438.54342184869;30681.8465907669;30917.69082309148;941.5809964565702;1031.2471895516164;1110.8778034416207;1165.3066425476827;1183.4910988275124;15373.92382533212;15654.373825047098;16049.125315224064;16133.515994520183;16134.919325403298;669719;674981;675650;677297;677331;60570.394296057355;60842.391576083675;60865;61008;61040.38959610436;1403.585942147069;1409.37987285594;1443.9422423102958;1464.9194294313916;1469.7427950108688;27061.45877082408;30160.84919575388;30191.547126793554;30349;30552.236194095327;51000;51710.22434663558;52087.218691718896;52181.47818521766;52349.95300094053;599353;600093;604591;605231;605558;33951.66048339485;33980.66019339775;35224.82387588046;41677.623849495365;41827;45917.31124033075;48062.27906581334;48071.75964120157;48505.75747121242;48868.5113148864;40296;40411.39382909318;40847.591524084375;41085.794571026956;41552.168956620095;39016.6098339013;39544.2091158181;40013;40503.1899362017;40558.79720601496;37817.432738509495;38729;39570;39726.801365992986;39727.80136099301;34255.31489370248;35347.46978795269;35755.28489430145;35955.921322359325;36063;23285.16890731595;24668.396554223917;24844.006239750932;25301.734913253895;25319.62020569656 03:45:47 INFO - PROCESS | 1671 | |2;jquery.html;379.60141851056386;381.79955523350236;385.11807959772125;398.76871414579546;406.18357102224536;18362;21675.69945803253;22024.44938876529;22041.00815463304;22046.669299960515;20447.897760511176;20583.176672933103;21286.89356553215;21825.454363640918;21836.672449913225;4824.807007719687;5675.943240567603;5877.85305367365;5881.382454842223;5892.175095486636;4069;4293.313069908815;5731.713414329267;5814.738336774834;6199.0391489319;6475.870482590369;6551.181102362205;6562;6563.474922006252;6564.934350656504;6248.281447633535;6410.711517981678;6444.130042444257;6506.772262970797;6523.869522609569;3684.4473329000866;5317.229001794718;5566.693831839248;5583.720813959286;5587.664740115565;25523.234302970755;25801.741982580308;26076.217713468628;26692.33269168267;27063.1881043569;2245.494763678176;2280.6807047013267;2384.1655420602824;2402.5555272274687;2412.589859723851;15414.922925385301;15547.766783498257;15699.764503532457;15711.292991815282;16427;1874.9718754218698;1883.4067268810281;1894.7063205203215;1895.7535520382262;1907.513584036062;114977.42511287474;117982.41008794901;118659;118779;119887.80112198854;10630.840537391945;10908;11197.552097916156;11207.628373442862;11434.656960291204;13835.79246311306;13913.443462261599;13914.443422262995;13951.651208719863;14021.158730476203;8435.95782021086;8726.86909696355;8746.86879696805;8859.24696400813;8864.64541418336;8803;8813.823723525493;8815.426997245158;8870.600822962982;8953.507557084293;5753.626014309057;6732.932670673231;6894.965525172342;6900.6204658744255;6917.550359226735;1450.9263145272423;1678.1860797513195;1790.3912669692368;1808.502661767998;1837.3121836515095;905.42053086025;911.4531281231208;1001.4592120254981;1089.4226060188114;1138.1577632552016;21867.781322186576;22546.54906901853;22606.886965565067;22667.546649066928;22790.202342917917;911.5578944212052;953.6900507335005;1001.9699409017741;1041.9947900260602;1042.9269951103502;10372.222083343779;11367.374794386387;11845.466953987092;11918.529300340984;12124.333161676017;564958;573750;582549;596683;602417;115572.42213788877;117556;121011;123591.38204308921;124114.9999999982;812.7358608452276;978.6476868327396;1018.9032041956002;1020.8825985011819;1024.9590016399409;18807.905960470656;19370.903145484182;19389.2244310226;19408.223671053;19443.902780486005;47935.76032119817;51803.96392072062;52433.7378313106;53061.67345816385;53317.46682533125;59583.70208148932;74974.2502574989;76622;80920;81579.3684126326;7721.845563088595;7976.282134607681;7985.361171106414;8294.129116442928;8299.543525106177;9039.819203616022;12039.157258991787;12723.745525089262;12940.935295323463;13043.543475978295;10789.514471848943;11099.944500277446;11105.666829995112;11130.721731956766;11162.330260184415;6708.691805097959;7185.3773372719625;10889.292196007234;10923.289986151192;11104.389258590854;10778.407187604756;10873.836892446461;11153.832692509457;11271.211015228855;11418.086553075567;7070.787876363717;7271.927280727125;7349.6692648832;7483.289087536676;7712.1516633169185;11116.610918618133;11476.311421314747;11629.011534019703;11636.650900473;11650.6504804856 03:45:47 INFO - PROCESS | 1671 | |3;mootools.html;1415.6673181802282;1574.0791636892418;1610.798650168729;1881.2757088520916;2014.0634604908714;7782.221777822215;8232.176782321765;8240.54676992766;8244.34045276378;8246.340292776578;8342.74971750847;8629.482231066124;8643.438176518523;8745.781355466117;8751.993520745116;2324.5583339165587;2391.3304274802995;2391.796697280733;2393.042782886842;2395.173665085543;2329.9534009319887;2365.66880636711;2372.2764556810166;2377.084822343393;2383.9046438142454;2341.9648705269433;2363.326451961191;2381.940451488709;2386.009805930537;2387.057112440585;2332.6734257204007;2351.3651314145172;2363.314638754759;2364.798992085666;2368.325027367192;2323.3959170615763;2350;2358.3868194269444;2361.9055237790467;2366.7159940807214;8660.82678346436;8740.737777866674;8753.080926502753;8762.34282428814;8782.736517904474;5097.643164978453;5167.689938603696;5302.840914772563;5324.866878328034;5337.226102215157;3485.093875592339;3585.8386372613036;3649.3978493548534;3654.3787556115526;3678.9264214715554;1042.5621239079528;1080.5137688040413;1082.7455547946226;1089.3572792052648;1101.2126329674322;11477.65567032982;11565.016973557258;11606.4196790161;11624.6512604622;11635.418229088596;4453.532379100147;4471.754053527087;4583.175028494903;4596.6092882105395;4599.563041511051;9042.18620324174;9052.094790521027;9072.364934454657;9072.90927090734;9074.909250907407;5194.974025129925;5209.270702101709;5217.7912883485;5220.791168353299;5221.530062294411;4358.171947330061;4524.6380289576755;4736.881577960578;4760.523947605212;4892.608591312686;3469.7855750487633;3496.1434448560267;3718.4794128821986;3783.375743002374;3830.2531006453473;987.2052997337069;1048.758785479344;1154.6074334726234;1157.6006277834133;1170.7424366639325;649.4901502320656;765.6018870187471;922.1424075609614;942.3827393057559;958.8945216026232;5787.450192231731;5904.940950590525;6000.5799594028;6009.158717779427;6143.201383820075;485.4926601701231;490.7546226886557;498.9401271847329;499.0542921164373;639.6897504710212;3602.369585322558;3669.7247706422127;3984.063745019941;4001.079751657077;4034.9798251009142;10308.175345972304;10588.364698118137;10765.192610554239;10766.569337226423;10778.29941053829;10614.310069845435;10676.679699609023;10704.518296676668;10716.732081697864;10732.195085368628;7779.649915753805;8069.6368663410285;8113.472624279403;8118.431709780377;8134.63394147253;5766.106253530709;5865.970670146793;5877;5884.7940322088525;5891;9929.106380425508;10014.39913605186;10017;10036.849447258151;10047.547860346156;9797.363171393836;9891.752706182404;10087.546060427445;10207.54066067014;10228.335158214692;6844.384005439534;6934.271901450275;6945.166580010431;6960.582365058112;6975.988481670231;7753.1859154787935;8075.515469071875;8231.135730748185;8246.299064579578;8257.587120644064;7365.631718413951;7429.925700742923;7444.888326675213;7565.773026809205;7566.129895061918;6205.751769929152;7211.495195336276;7288.781336559911;7445.181030086795;7449.366803821736;5955.3746856581165;6925.826854328682;6989.615571143636;6998.860022799618;7014.368706816411;4814.6870453420415;4970.204767237225;5012.749362531932;5042.4705405931845;5054.924176137288;4096.446979657827;4980.701157930535;4994.725290109079;5059.595232381327;5193.039287731782 03:45:47 INFO - PROCESS | 1671 | |4;prototype.html;297.6235062645753;307.3576225688311;315.161669957912;318.4697478697968;320.31932144193587;5126.231065340196;5147.716875571842;5162.716050617215;5173.870653233666;5189.6886186828815;4231.640310573597;4812.6390520711;4873.5857452116525;5020.995800839828;5089.3638295213095;3280.835958202092;3913.256481268564;3919.314120028998;3932.547757007952;3941.408788681692;3191;3694.4827724118645;3856.3444214483466;3896.2856343208896;3920.705947053968;3526.0078055898334;3585.6593623605845;3695.9445608315896;3881.3789793633023;3893.4620824774197;3857.652811246973;3868.651821336065;3868.6905047596124;3904.902377440558;3915.4533959086143;3127.9530807037686;3183.6179658440906;3246.5617141685807;3391.5590973173576;3412.737287203726;4704.906316689075;5023.497650234984;5024.296598476216;5039.294498770175;5065.1895696688725;3675.4119340905327;4007.5391329997133;4422.336649502568;4456.94980309055;4502.3246513023305;4414.602685758265;4540.841070562547;4636.559526844944;5359.866003349908;5415.620906536544;3185.3629274145264;3409.1136304560746;3687.912065940527;3757.271655038658;3793.430985352192;4950.826721064782;5352.090144675414;5612.438756124355;5648.915266271009;5683.374828768832;3223.3714425687235;3469.427544455187;3767;3786.848526058982;3839.462475253467;3315.834208289576;3386.7967921924765;3822.522184726909;3852.4799152114106;3856.633619806114;4092.7135100543273;4448.5551444855255;4456.398386217884;4459.888502787457;4468.4414448193975;4219.113986062961;4871.561559459666;5110.923336149961;5252.05463016653;5513.869656720362;1116.9553217871194;1178.192937837567;1186.9881301186876;1276.387334079647;1284.543986884661;740.5001623903844;747.6075060593232;878.3851304087165;879.5294517433142;879.9604017819214;4717.806220107598;4726.740029298421;5079.6698214615935;5083.872903177376;5140.588752899759;5141.8687888664435;5524.8342549723575;5582.720863956786;5583.218349431083;5629.296337957755;4397.934030989538;4840.685355451885;5073.644844860824;5085.796568137233;5225.216217567396;2636.709961904189;2830.4905117079124;2847.572864070406;2951.4687356276077;2957.5120105182405;4786.037724532925;5909.409059094056;5913.319968203626;5925.674087925119;5972.701364931736;5586.832395028156;5770.6249093808765;5775.584981679515;5784.0957353786025;5824.912626310609;5007.198848184273;5251.054810134213;5777.884442311214;5878.3239927408895;6026.427489388501;4688.6717929745955;4726.62187025044;4896.824762057152;5609.551235901036;5662.377138514842;5585.553155747449;5596.216529685766;5599.0683214291;5599.0761524349655;5622.2128901954575;5448.863778405413;5808.9543882101625;5902.409759024235;5906.586538942232;5920.437558431942;2991.790574659753;3366.848491817825;3757.680597149273;3773.735838491279;3780.3951367781024;5182.637215394886;5298.629095963245;5543.61194716366;5547.973624879411;5597.5521958242425;4624.30635404692;5416.458354164552;5437.211604317431;5459;5510.614257002132;5087.2623469595965;5379.784808607612;5388.191771234347;5402.486763757437;5426;4303.74177549348;4685.882852928704;4788.16207163738;4949.628777841748;5161.509656582619;5045.31888195089;5477.863053423696;5479.972600136974;5487.6981766003255;5529.64523691682;4392.846250381349;4706.917408996019;5120.183898855689;5237.240600112886;5277.709725965109 03:45:47 INFO - PROCESS | 1671 | |5;yui.html;668.8561959178782;731.4002517935294;797.2545669798736;798.6845196147522;834.7370578267845;15004.77492837607;15623.828212884036;15625.531234062997;15633;15636.218189090529;14071.015028947952;14078.507252246174;14133.222672752994;14164.929175354135;14165.362558684858;729.1032030602362;752.7553545097865;901.7430032440756;915.7390143809016;929.8651695504147;925.6482536636103;930.985226103547;937.6155776131787;943.5895385507292;944.7401964459759;992.9623543397131;996.0736515041045;996.4469719305782;997.5959736306808;997.9521502422427;759.589821496394;947.6067432015706;985.5959056786683;989.1740396768706;995;739.3973911262304;741.2735519191169;741.5106030020215;741.5217184915699;741.7144399406213;14712.190829504372;14721.705565888622;14750.041247318997;14775.70448591033;14793.260336983216;13477.460901563925;13688.726225475435;13709.45162193511;13732.725345493134;14005.719885602333;12487.75024499505;12585.559505417266;12606.180598261084;12611.432485538082;12692.61922142328;4424.40270563473;4495.842645507391;4510.932336014962;4528.456585209763;4687.1328804171335;104636;105826.99999999923;106726;106855.46572267167;107465;10136.087752102347;10693.572257109785;10767;10865.83701244482;11059.834102488468;17488.475345739647;21855;21860.344189674335;21880.124795008338;21923.67114493284;7932.08780990182;8025.964601857876;8044.1955804419085;8145.063317718538;8187;4569.451665800125;4582.495925448198;4591.150637132141;4606.95393046072;4685.156671799041;3409.16475463513;3509.192885636267;3511.701505372072;3515.20907795749;3815.809209539512;700.8282970672218;705.7106586299592;716.1084449859853;719.1011235955057;720.81619187108;569.3992837556335;638.8658381739887;646.7930262316107;690.702997710979;697.3444961736053;9828.754281143028;9981.750456238653;9984.600615975425;10000.499975001221;10015.799684006279;657.5922927784804;681.0465348512024;697.9197392299849;698.8742026435291;701.5229643842237;3564.5009698641693;4290.50659174199;4389.934150987738;4572.565606267399;4584.266517357207;115911;117925.41037294759;149288.50711492746;149715;155909;85851.14148858556;89019;109335;111594;115499.42250288694;84392;96902.51548742212;101339;101558.98441015644;102557;8322.334213262804;9587.18508926749;9607.279454041114;9653.855192171983;9685.951570242103;41255.58744412517;56175.71912140413;57711.71144144252;57810.84378312495;58866.7056664714;75367.24632753602;76390.2360976383;78597.21402786128;82409.58795205985;88530.55734721285;11048.944099378881;13076.738465230832;13325.800112998506;13506.39221235027;13646.241627387744;14979.176145311676;15162.393504259706;15429.22853857325;15524.844751552791;15760.842391575938;13832.170069795844;13941.860581394056;14201.43194272259;14296.428142874585;14297.785533216802;13675.658108547366;14095.577132686036;14096.718065638835;14097.929510352382;14135.787963180355;13395.397207125898;13599.52401665937;13637.318134093453;13742;14175.574732758034;9728.9027109728;11217.943910280397;11351.489182986621;11487.48306326234;11632;9815.075396944214;10231.82334031596;11063.72340691489;11546.76906461883;11575.652730418102 03:45:47 INFO - PROCESS | 1671 | __end_tp_report 03:45:47 INFO - PROCESS | 1671 | __start_cc_report 03:45:47 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,1247 03:45:47 INFO - PROCESS | 1671 | __end_cc_report 03:45:47 INFO - PROCESS | 1671 | __startTimestamp1465296347816__endTimestamp 03:45:47 INFO - PROCESS | 1671 | 03:45:47 INFO - PROCESS | 1671 | ------- Summary: start ------- 03:45:47 INFO - PROCESS | 1671 | Number of tests: 6 03:45:47 INFO - PROCESS | 1671 | 03:45:47 INFO - PROCESS | 1671 | [#0] dojo.html Cycles:165 Average:5664.86 Median:5846.53 stddev:657.72 (11.2%) stddev-sans-first:634.48 03:45:47 INFO - PROCESS | 1671 | Values: 3363.5 3407.1 3426.0 3431.8 3477.5 5488.9 5576.8 5609.8 5645.9 5653.4 5563.4 5591.1 5606.6 5608.8 5619.6 4990.8 5068.4 5083.6 5102.7 5128.5 5145.7 5156.1 5163.6 5165.2 5168.5 4308.7 4846.5 5097.5 5116.2 5156.5 5190.5 5197.5 5201.0 5203.2 5207.5 4998.4 5043.2 5072.6 5127.1 5129.6 5468.2 5469.8 5528.2 5548.9 5552.0 5091.9 6172.0 6198.4 6202.1 6206.0 4997.2 6150.9 6235.1 6251.4 6252.5 5745.3 5777.2 5781.1 5782.6 5799.6 6145.9 6215.1 6223.4 6314.3 6314.7 5935.4 6014.6 6074.8 6094.0 6142.8 6219.9 6234.8 6246.5 6247.4 6247.7 6172.1 6184.6 6189.4 6192.7 6206.9 5955.9 5974.8 6009.3 6193.6 6232.6 4758.0 4786.3 4797.0 4803.8 4822.1 3709.2 4371.1 4374.3 4384.6 4414.2 5752.5 5769.1 5797.7 5797.9 5837.2 6116.9 6119.0 6137.9 6237.1 6238.3 5671.3 5755.8 5775.1 5780.5 5829.1 5333.3 5340.9 5347.7 5348.6 5352.5 6100.9 6152.8 6208.2 6282.1 6282.6 5164.4 6206.1 6248.0 6253.5 6272.3 6259.7 6291.6 6305.0 6316.2 6316.4 5993.5 6052.9 6118.7 6121.4 6161.1 6247.9 6265.9 6273.4 6275.5 6307.8 6017.7 6028.2 6072.3 6081.7 6113.4 5798.6 5837.8 6029.5 6058.5 6067.3 5854.7 5855.2 5864.0 5905.1 5912.9 6083.3 6108.9 6123.6 6133.1 6137.9 5759.7 5833.4 5846.5 5895.1 5913.7 03:45:47 INFO - PROCESS | 1671 | 03:45:47 INFO - PROCESS | 1671 | [#1] ext.html Cycles:180 Average:73374.29 Median:39727.30 stddev:160619.35 (404.3%) stddev-sans-first:160976.70 03:45:47 INFO - PROCESS | 1671 | Values: 488.5 496.7 512.6 512.9 547.1 31048.7 31454.7 42568.8 43808.8 43847.1 45415.8 45779.8 45789.3 45925.9 45955.5 9459.7 9705.4 9710.8 9721.6 9740.2 8476.4 8716.1 8718.2 8846.1 8869.5 11616.8 11911.0 11923.6 12108.6 12196.5 9795.7 11830.7 11831.4 11831.6 11872.5 8440.2 11369.7 11653.3 11712.6 11782.2 41266.2 41768.0 41978.8 42062.0 42211.4 3433.3 3459.8 3487.3 3555.5 4089.4 31391.7 31913.5 32022.8 32315.7 32447.0 2981.0 3053.9 3069.6 3077.3 3098.8 511439.0 512482.0 513290.0 515287.0 517292.0 58814.4 59355.7 59602.0 59642.7 59828.0 63208.7 63226.0 63292.3 63381.4 63678.0 16095.8 16548.9 16594.2 16604.1 16612.6 12369.9 12442.0 12444.4 12465.8 12467.1 14016.2 14312.9 15669.1 15970.8 16132.6 6374.6 7345.7 7442.3 7485.7 7497.6 970.5 1076.9 1094.5 1099.7 1103.4 28253.7 30321.1 30438.5 30681.8 30917.7 941.6 1031.2 1110.9 1165.3 1183.5 15373.9 15654.4 16049.1 16133.5 16134.9 669719.0 674981.0 675650.0 677297.0 677331.0 60570.4 60842.4 60865.0 61008.0 61040.4 1403.6 1409.4 1443.9 1464.9 1469.7 27061.5 30160.8 30191.5 30349.0 30552.2 51000.0 51710.2 52087.2 52181.5 52350.0 599353.0 600093.0 604591.0 605231.0 605558.0 33951.7 33980.7 35224.8 41677.6 41827.0 45917.3 48062.3 48071.8 48505.8 48868.5 40296.0 40411.4 40847.6 41085.8 41552.2 39016.6 39544.2 40013.0 40503.2 40558.8 37817.4 38729.0 39570.0 39726.8 39727.8 34255.3 35347.5 35755.3 35955.9 36063.0 23285.2 24668.4 24844.0 25301.7 25319.6 03:45:47 INFO - PROCESS | 1671 | 03:45:47 INFO - PROCESS | 1671 | [#2] jquery.html Cycles:180 Average:34571.66 Median:22728.87 stddev:97361.80 (428.4%) stddev-sans-first:97601.08 03:45:47 INFO - PROCESS | 1671 | Values: 379.6 381.8 385.1 398.8 406.2 18362.0 21675.7 22024.4 22041.0 22046.7 20447.9 20583.2 21286.9 21825.5 21836.7 4824.8 5675.9 5877.9 5881.4 5892.2 4069.0 4293.3 5731.7 5814.7 6199.0 6475.9 6551.2 6562.0 6563.5 6564.9 6248.3 6410.7 6444.1 6506.8 6523.9 3684.4 5317.2 5566.7 5583.7 5587.7 25523.2 25801.7 26076.2 26692.3 27063.2 2245.5 2280.7 2384.2 2402.6 2412.6 15414.9 15547.8 15699.8 15711.3 16427.0 1875.0 1883.4 1894.7 1895.8 1907.5 114977.4 117982.4 118659.0 118779.0 119887.8 10630.8 10908.0 11197.6 11207.6 11434.7 13835.8 13913.4 13914.4 13951.7 14021.2 8436.0 8726.9 8746.9 8859.2 8864.6 8803.0 8813.8 8815.4 8870.6 8953.5 5753.6 6732.9 6895.0 6900.6 6917.6 1450.9 1678.2 1790.4 1808.5 1837.3 905.4 911.5 1001.5 1089.4 1138.2 21867.8 22546.5 22606.9 22667.5 22790.2 911.6 953.7 1002.0 1042.0 1042.9 10372.2 11367.4 11845.5 11918.5 12124.3 564958.0 573750.0 582549.0 596683.0 602417.0 115572.4 117556.0 121011.0 123591.4 124115.0 812.7 978.6 1018.9 1020.9 1025.0 18807.9 19370.9 19389.2 19408.2 19443.9 47935.8 51804.0 52433.7 53061.7 53317.5 59583.7 74974.3 76622.0 80920.0 81579.4 7721.8 7976.3 7985.4 8294.1 8299.5 9039.8 12039.2 12723.7 12940.9 13043.5 10789.5 11099.9 11105.7 11130.7 11162.3 6708.7 7185.4 10889.3 10923.3 11104.4 10778.4 10873.8 11153.8 11271.2 11418.1 7070.8 7271.9 7349.7 7483.3 7712.2 11116.6 11476.3 11629.0 11636.7 11650.7 03:45:47 INFO - PROCESS | 1671 | 03:45:47 INFO - PROCESS | 1671 | [#3] mootools.html Cycles:180 Average:5598.02 Median:2734.57 stddev:3156.48 (115.4%) stddev-sans-first:3149.69 03:45:47 INFO - PROCESS | 1671 | Values: 1415.7 1574.1 1610.8 1881.3 2014.1 7782.2 8232.2 8240.5 8244.3 8246.3 8342.7 8629.5 8643.4 8745.8 8752.0 2324.6 2391.3 2391.8 2393.0 2395.2 2330.0 2365.7 2372.3 2377.1 2383.9 2342.0 2363.3 2381.9 2386.0 2387.1 2332.7 2351.4 2363.3 2364.8 2368.3 2323.4 2350.0 2358.4 2361.9 2366.7 8660.8 8740.7 8753.1 8762.3 8782.7 5097.6 5167.7 5302.8 5324.9 5337.2 3485.1 3585.8 3649.4 3654.4 3678.9 1042.6 1080.5 1082.7 1089.4 1101.2 11477.7 11565.0 11606.4 11624.7 11635.4 4453.5 4471.8 4583.2 4596.6 4599.6 9042.2 9052.1 9072.4 9072.9 9074.9 5195.0 5209.3 5217.8 5220.8 5221.5 4358.2 4524.6 4736.9 4760.5 4892.6 3469.8 3496.1 3718.5 3783.4 3830.3 987.2 1048.8 1154.6 1157.6 1170.7 649.5 765.6 922.1 942.4 958.9 5787.5 5904.9 6000.6 6009.2 6143.2 485.5 490.8 498.9 499.1 639.7 3602.4 3669.7 3984.1 4001.1 4035.0 10308.2 10588.4 10765.2 10766.6 10778.3 10614.3 10676.7 10704.5 10716.7 10732.2 7779.6 8069.6 8113.5 8118.4 8134.6 5766.1 5866.0 5877.0 5884.8 5891.0 9929.1 10014.4 10017.0 10036.8 10047.5 9797.4 9891.8 10087.5 10207.5 10228.3 6844.4 6934.3 6945.2 6960.6 6976.0 7753.2 8075.5 8231.1 8246.3 8257.6 7365.6 7429.9 7444.9 7565.8 7566.1 6205.8 7211.5 7288.8 7445.2 7449.4 5955.4 6925.8 6989.6 6998.9 7014.4 4814.7 4970.2 5012.7 5042.5 5054.9 4096.4 4980.7 4994.7 5059.6 5193.0 03:45:47 INFO - PROCESS | 1671 | 03:45:47 INFO - PROCESS | 1671 | [#4] prototype.html Cycles:180 Average:4358.25 Median:4872.57 stddev:1371.59 (28.1%) stddev-sans-first:1341.14 03:45:47 INFO - PROCESS | 1671 | Values: 297.6 307.4 315.2 318.5 320.3 5126.2 5147.7 5162.7 5173.9 5189.7 4231.6 4812.6 4873.6 5021.0 5089.4 3280.8 3913.3 3919.3 3932.5 3941.4 3191.0 3694.5 3856.3 3896.3 3920.7 3526.0 3585.7 3695.9 3881.4 3893.5 3857.7 3868.7 3868.7 3904.9 3915.5 3128.0 3183.6 3246.6 3391.6 3412.7 4704.9 5023.5 5024.3 5039.3 5065.2 3675.4 4007.5 4422.3 4456.9 4502.3 4414.6 4540.8 4636.6 5359.9 5415.6 3185.4 3409.1 3687.9 3757.3 3793.4 4950.8 5352.1 5612.4 5648.9 5683.4 3223.4 3469.4 3767.0 3786.8 3839.5 3315.8 3386.8 3822.5 3852.5 3856.6 4092.7 4448.6 4456.4 4459.9 4468.4 4219.1 4871.6 5110.9 5252.1 5513.9 1117.0 1178.2 1187.0 1276.4 1284.5 740.5 747.6 878.4 879.5 880.0 4717.8 4726.7 5079.7 5083.9 5140.6 5141.9 5524.8 5582.7 5583.2 5629.3 4397.9 4840.7 5073.6 5085.8 5225.2 2636.7 2830.5 2847.6 2951.5 2957.5 4786.0 5909.4 5913.3 5925.7 5972.7 5586.8 5770.6 5775.6 5784.1 5824.9 5007.2 5251.1 5777.9 5878.3 6026.4 4688.7 4726.6 4896.8 5609.6 5662.4 5585.6 5596.2 5599.1 5599.1 5622.2 5448.9 5809.0 5902.4 5906.6 5920.4 2991.8 3366.8 3757.7 3773.7 3780.4 5182.6 5298.6 5543.6 5548.0 5597.6 4624.3 5416.5 5437.2 5459.0 5510.6 5087.3 5379.8 5388.2 5402.5 5426.0 4303.7 4685.9 4788.2 4949.6 5161.5 5045.3 5477.9 5480.0 5487.7 5529.6 4392.8 4706.9 5120.2 5237.2 5277.7 03:45:47 INFO - PROCESS | 1671 | 03:45:47 INFO - PROCESS | 1671 | [#5] yui.html Cycles:180 Average:22899.82 Median:3690.16 stddev:35221.50 (954.5%) stddev-sans-first:35280.75 03:45:47 INFO - PROCESS | 1671 | Values: 668.9 731.4 797.3 798.7 834.7 15004.8 15623.8 15625.5 15633.0 15636.2 14071.0 14078.5 14133.2 14164.9 14165.4 729.1 752.8 901.7 915.7 929.9 925.6 931.0 937.6 943.6 944.7 993.0 996.1 996.4 997.6 998.0 759.6 947.6 985.6 989.2 995.0 739.4 741.3 741.5 741.5 741.7 14712.2 14721.7 14750.0 14775.7 14793.3 13477.5 13688.7 13709.5 13732.7 14005.7 12487.8 12585.6 12606.2 12611.4 12692.6 4424.4 4495.8 4510.9 4528.5 4687.1 104636.0 105827.0 106726.0 106855.5 107465.0 10136.1 10693.6 10767.0 10865.8 11059.8 17488.5 21855.0 21860.3 21880.1 21923.7 7932.1 8026.0 8044.2 8145.1 8187.0 4569.5 4582.5 4591.2 4607.0 4685.2 3409.2 3509.2 3511.7 3515.2 3815.8 700.8 705.7 716.1 719.1 720.8 569.4 638.9 646.8 690.7 697.3 9828.8 9981.8 9984.6 10000.5 10015.8 657.6 681.0 697.9 698.9 701.5 3564.5 4290.5 4389.9 4572.6 4584.3 115911.0 117925.4 149288.5 149715.0 155909.0 85851.1 89019.0 109335.0 111594.0 115499.4 84392.0 96902.5 101339.0 101559.0 102557.0 8322.3 9587.2 9607.3 9653.9 9686.0 41255.6 56175.7 57711.7 57810.8 58866.7 75367.2 76390.2 78597.2 82409.6 88530.6 11048.9 13076.7 13325.8 13506.4 13646.2 14979.2 15162.4 15429.2 15524.8 15760.8 13832.2 13941.9 14201.4 14296.4 14297.8 13675.7 14095.6 14096.7 14097.9 14135.8 13395.4 13599.5 13637.3 13742.0 14175.6 9728.9 11217.9 11351.5 11487.5 11632.0 9815.1 10231.8 11063.7 11546.8 11575.7 03:45:47 INFO - PROCESS | 1671 | -------- Summary: end -------- 03:45:47 INFO - PROCESS | 1671 | 03:45:48 INFO - TEST-INFO | 1671: exit 0 03:45:48 INFO - TEST-OK | dromaeo_css | took 1116340ms 03:45:48 INFO - TEST-START | kraken 03:45:48 INFO - operating with platform_type : mac_ 03:45:48 INFO - Initialising browser for kraken test... 03:45:48 INFO - TEST-INFO | started process 1699 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmp2lea9U/profile http://localhost:49268/getInfo.html) 03:45:50 INFO - PROCESS | 1699 | __metrics Screen width/height:1600/1200 03:45:50 INFO - PROCESS | 1699 | colorDepth:24 03:45:50 INFO - PROCESS | 1699 | Browser inner width/height: 1024/689 03:45:50 INFO - PROCESS | 1699 | __metrics 03:45:51 INFO - PROCESS | 1699 | 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" 03:45:51 ERROR - PROCESS | 1699 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:45:51 INFO - PROCESS | 1699 | ************************* 03:45:51 INFO - PROCESS | 1699 | A coding exception was thrown in a Promise resolution callback. 03:45:51 INFO - PROCESS | 1699 | See https://developer.mozilla.org/Mozilla/JavaScript_code_modules/Promise.jsm/Promise 03:45:51 INFO - PROCESS | 1699 | 03:45:51 ERROR - PROCESS | 1699 | Full message: TypeError: win is null 03:45:51 INFO - PROCESS | 1699 | Full stack: onSuccess@resource://app/components/nsBrowserGlue.js:850:17 03:45:51 INFO - PROCESS | 1699 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 03:45:51 INFO - PROCESS | 1699 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 03:45:51 INFO - PROCESS | 1699 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 03:45:51 INFO - PROCESS | 1699 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 03:45:51 INFO - PROCESS | 1699 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 03:45:51 INFO - PROCESS | 1699 | get _worker/worker.onmessage@resource://gre/modules/PromiseWorker.jsm:235:9 03:45:51 INFO - PROCESS | 1699 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 03:45:51 INFO - PROCESS | 1699 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 03:45:51 INFO - PROCESS | 1699 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 03:45:51 INFO - PROCESS | 1699 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 03:45:51 INFO - PROCESS | 1699 | Barrier/this.client.addBlocker/promise53.8% 03:46:48 INFO - TinderboxPrint: I/O read bytes / time
32,632,320 / 327 03:46:48 INFO - TinderboxPrint: I/O write bytes / time
701,018,112 / 2,098 03:46:48 INFO - TinderboxPrint: CPU idle
2,238.3 (46.2%) 03:46:48 INFO - TinderboxPrint: CPU system
972.3 (20.0%) 03:46:48 INFO - TinderboxPrint: CPU user
1,639.1 (33.8%) 03:46:48 INFO - TinderboxPrint: Swap in / out
429,649,920 / 0 03:46:48 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 395196928; Write bytes: 389834240; Read time: 29796; Write time: 869 03:46:48 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 30842368; Write bytes: 295688704; Read time: 308; Write time: 1194 03:46:49 INFO - Running post-run listener: _upload_blobber_files 03:46:49 INFO - Blob upload gear active. 03:46:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:46:49 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 03:46:49 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:46:49 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:46:49 INFO - (blobuploader) - INFO - Open directory for files ... 03:46:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:46:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:46:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:46:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:46:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:46:50 INFO - (blobuploader) - INFO - Done attempting. 03:46:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:46:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:46:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:46:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:46:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:46:51 INFO - (blobuploader) - INFO - Done attempting. 03:46:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:46:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:46:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:46:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:46:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:46:54 INFO - (blobuploader) - INFO - Done attempting. 03:46:54 INFO - (blobuploader) - INFO - Iteration through files over. 03:46:54 INFO - Return code: 0 03:46:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:46:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:46:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9f796c36a13cef80ead894ecf0d18f8bdbedd84e271c5fc8ff4bc80858a9d3bd35e58c3d4644244e7b4372b3be2e4e0c507a4c4691ffad0f31f59151dc4ead36", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/136e139e9f697e43759efe8f1c2877c94779f69796ed0d0487531a184297b20eaa64ec2e0db78dbcb42f79e13bee3517bba0617852dfdfc530d269cee81a6610", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c471feefd5b8727ee2dd82c35b7e53a64fa6340e459797d89319c0ab83576a6cbe09f216221d1014e67dec0674034b40ff51f1624c5a93c7fe75c5d17eb793c5"} 03:46:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:46:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:46:54 INFO - Contents: 03:46:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9f796c36a13cef80ead894ecf0d18f8bdbedd84e271c5fc8ff4bc80858a9d3bd35e58c3d4644244e7b4372b3be2e4e0c507a4c4691ffad0f31f59151dc4ead36", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/136e139e9f697e43759efe8f1c2877c94779f69796ed0d0487531a184297b20eaa64ec2e0db78dbcb42f79e13bee3517bba0617852dfdfc530d269cee81a6610", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c471feefd5b8727ee2dd82c35b7e53a64fa6340e459797d89319c0ab83576a6cbe09f216221d1014e67dec0674034b40ff51f1624c5a93c7fe75c5d17eb793c5"} 03:46:54 INFO - Running post-run listener: copy_logs_to_upload_dir 03:46:54 INFO - Copying logs to upload dir... 03:46:54 INFO - mkdir: /builds/slave/test/build/upload/logs 03:46:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.090552 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-06-07 03:46:54.335154) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-07 03:46:54.338757) ========= 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.SIye3L56e0/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.droFgu7lfg/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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/Fx-Team/sha512/9f796c36a13cef80ead894ecf0d18f8bdbedd84e271c5fc8ff4bc80858a9d3bd35e58c3d4644244e7b4372b3be2e4e0c507a4c4691ffad0f31f59151dc4ead36", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/136e139e9f697e43759efe8f1c2877c94779f69796ed0d0487531a184297b20eaa64ec2e0db78dbcb42f79e13bee3517bba0617852dfdfc530d269cee81a6610", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c471feefd5b8727ee2dd82c35b7e53a64fa6340e459797d89319c0ab83576a6cbe09f216221d1014e67dec0674034b40ff51f1624c5a93c7fe75c5d17eb793c5"} build_url:https://queue.taskcluster.net/v1/task/c_DjE3nCQ7iWGbtkmbNEHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011250 build_url: 'https://queue.taskcluster.net/v1/task/c_DjE3nCQ7iWGbtkmbNEHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9f796c36a13cef80ead894ecf0d18f8bdbedd84e271c5fc8ff4bc80858a9d3bd35e58c3d4644244e7b4372b3be2e4e0c507a4c4691ffad0f31f59151dc4ead36", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/136e139e9f697e43759efe8f1c2877c94779f69796ed0d0487531a184297b20eaa64ec2e0db78dbcb42f79e13bee3517bba0617852dfdfc530d269cee81a6610", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c471feefd5b8727ee2dd82c35b7e53a64fa6340e459797d89319c0ab83576a6cbe09f216221d1014e67dec0674034b40ff51f1624c5a93c7fe75c5d17eb793c5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-07 03:46:54.368109) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 03:46:54.368461) ========= 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.SIye3L56e0/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.droFgu7lfg/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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.005160 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 03:46:54.417059) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-07 03:46:54.417393) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-07 03:46:54.417726) ========= ========= Total master_lag: 0.17 =========