builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0253 starttime: 1473222215.41 results: success (0) buildid: 20160906190512 builduid: 5829d07410ef488194662e31981ec599 revision: 2374ab2d2b77a203efeef432a98f2b04c36f4749 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 21:23:35.412191) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 21:23:35.434606) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 21:23:35.434972) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 21:23:35.458562) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 21:23:35.458918) ========= 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.Q1ZM3yaTEZ/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.YGeId9yQgj/Listeners TMPDIR=/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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-09-06 21:23:35-- 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% 8.17M=0.001s 2016-09-06 21:23:35 (8.17 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.129120 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 21:23:35.610067) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 21:23:35.610404) ========= 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.Q1ZM3yaTEZ/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.YGeId9yQgj/Listeners TMPDIR=/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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.020374 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 21:23:35.659268) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-06 21:23:35.659661) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2374ab2d2b77a203efeef432a98f2b04c36f4749 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2374ab2d2b77a203efeef432a98f2b04c36f4749 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Q1ZM3yaTEZ/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.YGeId9yQgj/Listeners TMPDIR=/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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-09-06 21:23:35,745 truncating revision to first 12 chars 2016-09-06 21:23:35,746 Setting DEBUG logging. 2016-09-06 21:23:35,746 attempt 1/10 2016-09-06 21:23:35,746 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/2374ab2d2b77?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-06 21:23:37,096 unpacking tar archive at: mozilla-aurora-2374ab2d2b77/testing/mozharness/ program finished with exit code 0 elapsedTime=1.629972 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-06 21:23:37.315307) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 21:23:37.315805) ========= script_repo_revision: 2374ab2d2b77a203efeef432a98f2b04c36f4749 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 21:23:37.316424) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 21:23:37.317032) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 21:23:37.337437) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 33 secs) (at 2016-09-06 21:23:37.338073) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Q1ZM3yaTEZ/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.YGeId9yQgj/Listeners TMPDIR=/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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 21:23:37 INFO - MultiFileLogger online at 20160906 21:23:37 in /builds/slave/test 21:23:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 21:23:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:23:37 INFO - {'append_to_log': False, 21:23:37 INFO - 'base_work_dir': '/builds/slave/test', 21:23:37 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 21:23:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:23:37 INFO - 'branch': 'Mozilla-Aurora', 21:23:37 INFO - 'buildbot_json_path': 'buildprops.json', 21:23:37 INFO - 'config_files': ('talos/mac_config.py',), 21:23:37 INFO - 'default_actions': ('clobber', 21:23:37 INFO - 'read-buildbot-config', 21:23:37 INFO - 'download-and-extract', 21:23:37 INFO - 'populate-webroot', 21:23:37 INFO - 'create-virtualenv', 21:23:37 INFO - 'install', 21:23:37 INFO - 'run-tests'), 21:23:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:23:37 INFO - 'download_minidump_stackwalk': True, 21:23:37 INFO - 'download_symbols': 'ondemand', 21:23:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:23:37 INFO - 'tooltool.py': '/tools/tooltool.py', 21:23:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:23:37 INFO - '/tools/misc-python/virtualenv.py')}, 21:23:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:23:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:23:37 INFO - 'installer_path': 'installer.exe', 21:23:37 INFO - 'log_level': 'info', 21:23:37 INFO - 'log_name': 'talos', 21:23:37 INFO - 'log_to_console': True, 21:23:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:23:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:23:37 INFO - 'opt_config_files': (), 21:23:37 INFO - 'pip_index': False, 21:23:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:23:37 INFO - 'cmd': ('bash', 21:23:37 INFO - '-c', 21:23:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:23:37 INFO - 'enabled': True, 21:23:37 INFO - 'halt_on_failure': False, 21:23:37 INFO - 'name': 'check_screen_resolution'},), 21:23:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:23:37 INFO - 'cmd': ('bash', 21:23:37 INFO - '-c', 21:23:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:23:37 INFO - 'enabled': True, 21:23:37 INFO - 'halt_on_failure': False, 21:23:37 INFO - 'name': 'check_screen_resolution'},), 21:23:37 INFO - 'run_cmd_checks_enabled': True, 21:23:37 INFO - 'sps_profile': False, 21:23:37 INFO - 'sps_profile_interval': 0, 21:23:37 INFO - 'suite': 'dromaeojs', 21:23:37 INFO - 'system_bits': '32', 21:23:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:23:37 INFO - 'title': 't-yosemite-r7-0253', 21:23:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:23:37 INFO - 'use_talos_json': True, 21:23:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:23:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:23:37 INFO - 'work_dir': 'build'} 21:23:37 INFO - [mozharness: 2016-09-07 04:23:37.532580Z] Running clobber step. 21:23:37 INFO - Running pre-action listener: _resource_record_pre_action 21:23:37 INFO - Running main action method: clobber 21:23:37 INFO - rmtree: /builds/slave/test/build 21:23:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:23:38 INFO - Running post-action listener: _resource_record_post_action 21:23:38 INFO - [mozharness: 2016-09-07 04:23:38.524491Z] Finished clobber step (success) 21:23:38 INFO - [mozharness: 2016-09-07 04:23:38.524644Z] Running read-buildbot-config step. 21:23:38 INFO - Running pre-action listener: _resource_record_pre_action 21:23:38 INFO - Running main action method: read_buildbot_config 21:23:38 INFO - Using buildbot properties: 21:23:38 INFO - { 21:23:38 INFO - "project": "", 21:23:38 INFO - "product": "firefox", 21:23:38 INFO - "script_repo_revision": "production", 21:23:38 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 21:23:38 INFO - "repository": "", 21:23:38 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 21:23:38 INFO - "buildid": "20160906190512", 21:23:38 INFO - "pgo_build": "False", 21:23:38 INFO - "basedir": "/builds/slave/test", 21:23:38 INFO - "buildnumber": 44, 21:23:38 INFO - "slavename": "t-yosemite-r7-0253", 21:23:38 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 21:23:38 INFO - "platform": "yosemite_r7", 21:23:38 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 21:23:38 INFO - "branch": "mozilla-aurora", 21:23:38 INFO - "repo_path": "releases/mozilla-aurora", 21:23:38 INFO - "revision": "2374ab2d2b77a203efeef432a98f2b04c36f4749", 21:23:38 INFO - "stage_platform": "macosx64", 21:23:38 INFO - "builduid": "5829d07410ef488194662e31981ec599", 21:23:38 INFO - "slavebuilddir": "test" 21:23:38 INFO - } 21:23:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.dmg. 21:23:38 INFO - Running post-action listener: _resource_record_post_action 21:23:38 INFO - [mozharness: 2016-09-07 04:23:38.527590Z] Finished read-buildbot-config step (success) 21:23:38 INFO - [mozharness: 2016-09-07 04:23:38.527698Z] Running download-and-extract step. 21:23:38 INFO - Running pre-action listener: _resource_record_pre_action 21:23:38 INFO - Running main action method: download_and_extract 21:23:38 INFO - mkdir: /builds/slave/test/build/tests 21:23:38 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')]} 21:23:38 INFO - https://queue.taskcluster.net/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 21:23:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.test_packages.json 21:23:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.test_packages.json 21:23:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.mac.test_packages.json 21:23:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.mac.test_packages.json'}, attempt #1 21:23:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.test_packages.json 21:23:38 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:23:38 INFO - retry: Failed, sleeping 30 seconds before retrying 21:24:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.mac.test_packages.json'}, attempt #2 21:24:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.test_packages.json 21:24:08 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:24:08 INFO - retry: Failed, sleeping 60 seconds before retrying 21:25:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.mac.test_packages.json'}, attempt #3 21:25:09 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.test_packages.json 21:25:09 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:25:09 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.mac.test_packages.json! 21:25:09 INFO - Caught exception: HTTP Error 404: Not Found 21:25:09 INFO - Caught exception: HTTP Error 404: Not Found 21:25:09 INFO - Caught exception: HTTP Error 404: Not Found 21:25:09 INFO - trying https://queue.taskcluster.net/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.test_packages.json 21:25:09 INFO - Downloading https://queue.taskcluster.net/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.mac.test_packages.json 21:25:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.mac.test_packages.json'}, attempt #1 21:25:10 INFO - Downloaded 1199 bytes. 21:25:10 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.mac.test_packages.json 21:25:10 INFO - Using the following test package requirements: 21:25:10 INFO - {u'common': [u'firefox-50.0a2.en-US.mac.common.tests.zip'], 21:25:10 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 21:25:10 INFO - u'firefox-50.0a2.en-US.mac.cppunittest.tests.zip'], 21:25:10 INFO - u'gtest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 21:25:10 INFO - u'firefox-50.0a2.en-US.mac.gtest.tests.zip'], 21:25:10 INFO - u'jittest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 21:25:10 INFO - u'jsshell-mac.zip'], 21:25:10 INFO - u'mochitest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 21:25:10 INFO - u'firefox-50.0a2.en-US.mac.mochitest.tests.zip'], 21:25:10 INFO - u'mozbase': [u'firefox-50.0a2.en-US.mac.common.tests.zip'], 21:25:10 INFO - u'reftest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 21:25:10 INFO - u'firefox-50.0a2.en-US.mac.reftest.tests.zip'], 21:25:10 INFO - u'talos': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 21:25:10 INFO - u'firefox-50.0a2.en-US.mac.talos.tests.zip'], 21:25:10 INFO - u'web-platform': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 21:25:10 INFO - u'firefox-50.0a2.en-US.mac.web-platform.tests.zip'], 21:25:10 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 21:25:10 INFO - u'firefox-50.0a2.en-US.mac.xpcshell.tests.zip']} 21:25:10 INFO - Downloading packages: [u'firefox-50.0a2.en-US.mac.common.tests.zip'] for test suite category: common 21:25: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')]} 21:25:10 INFO - https://queue.taskcluster.net/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 21:25:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip 21:25:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip 21:25:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip 21:25:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip'}, attempt #1 21:25:10 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip 21:25:10 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:25:10 INFO - retry: Failed, sleeping 30 seconds before retrying 21:25:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip'}, attempt #2 21:25:40 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip 21:25:40 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:25:40 INFO - retry: Failed, sleeping 60 seconds before retrying 21:26:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip'}, attempt #3 21:26:40 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip 21:26:40 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:26:40 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip! 21:26:40 INFO - Caught exception: HTTP Error 404: Not Found 21:26:40 INFO - Caught exception: HTTP Error 404: Not Found 21:26:40 INFO - Caught exception: HTTP Error 404: Not Found 21:26:40 INFO - trying https://queue.taskcluster.net/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip 21:26:40 INFO - Downloading https://queue.taskcluster.net/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip 21:26:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip'}, attempt #1 21:26:41 INFO - Downloaded 16307819 bytes. 21:26:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:26:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 21:26:44 INFO - Return code: 0 21:26:44 INFO - Downloading packages: [u'firefox-50.0a2.en-US.mac.common.tests.zip', u'firefox-50.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 21:26: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')]} 21:26:44 INFO - https://queue.taskcluster.net/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 21:26:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip 21:26:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip 21:26:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip 21:26:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip'}, attempt #1 21:26:44 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip 21:26:44 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:26:44 INFO - retry: Failed, sleeping 30 seconds before retrying 21:27:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip'}, attempt #2 21:27:14 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip 21:27:14 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:27:14 INFO - retry: Failed, sleeping 60 seconds before retrying 21:28:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip'}, attempt #3 21:28:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip 21:28:15 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:28:15 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip! 21:28:15 INFO - Caught exception: HTTP Error 404: Not Found 21:28:15 INFO - Caught exception: HTTP Error 404: Not Found 21:28:15 INFO - Caught exception: HTTP Error 404: Not Found 21:28:15 INFO - trying https://queue.taskcluster.net/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip 21:28:15 INFO - Downloading https://queue.taskcluster.net/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip 21:28:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip'}, attempt #1 21:28:16 INFO - Downloaded 16307819 bytes. 21:28:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:28:16 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 21:28:19 INFO - Return code: 0 21:28:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:28:19 INFO - https://queue.taskcluster.net/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 21:28:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.talos.tests.zip 21:28:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.talos.tests.zip 21:28:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.mac.talos.tests.zip 21:28:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.talos.tests.zip'}, attempt #1 21:28:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.talos.tests.zip 21:28:19 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:28:19 INFO - retry: Failed, sleeping 30 seconds before retrying 21:28:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.talos.tests.zip'}, attempt #2 21:28:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.talos.tests.zip 21:28:49 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:28:49 INFO - retry: Failed, sleeping 60 seconds before retrying 21:29:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.talos.tests.zip'}, attempt #3 21:29:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.talos.tests.zip 21:29:50 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:29:50 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.mac.talos.tests.zip! 21:29:50 INFO - Caught exception: HTTP Error 404: Not Found 21:29:50 INFO - Caught exception: HTTP Error 404: Not Found 21:29:50 INFO - Caught exception: HTTP Error 404: Not Found 21:29:50 INFO - trying https://queue.taskcluster.net/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.talos.tests.zip 21:29:50 INFO - Downloading https://queue.taskcluster.net/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.mac.talos.tests.zip 21:29:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.talos.tests.zip'}, attempt #1 21:29:51 INFO - Downloaded 14413740 bytes. 21:29:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:29:51 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 21:29:51 INFO - Return code: 0 21:29:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:29:51 INFO - https://queue.taskcluster.net/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 21:29:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.dmg 21:29:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.dmg 21:29:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a2.en-US.mac.dmg 21:29:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.mac.dmg'}, attempt #1 21:29:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.dmg 21:29:51 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:29:51 INFO - retry: Failed, sleeping 30 seconds before retrying 21:30:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.mac.dmg'}, attempt #2 21:30:21 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.dmg 21:30:21 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:30:21 INFO - retry: Failed, sleeping 60 seconds before retrying 21:31:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.mac.dmg'}, attempt #3 21:31:21 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.dmg 21:31:21 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:31:21 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a2.en-US.mac.dmg! 21:31:21 INFO - Caught exception: HTTP Error 404: Not Found 21:31:21 INFO - Caught exception: HTTP Error 404: Not Found 21:31:21 INFO - Caught exception: HTTP Error 404: Not Found 21:31:21 INFO - trying https://queue.taskcluster.net/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.dmg 21:31:21 INFO - Downloading https://queue.taskcluster.net/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a2.en-US.mac.dmg 21:31:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.mac.dmg'}, attempt #1 21:31:24 INFO - Downloaded 115334248 bytes. 21:31:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.dmg 21:31:24 INFO - mkdir: /builds/slave/test/properties 21:31:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:31:24 INFO - Writing to file /builds/slave/test/properties/build_url 21:31:24 INFO - Contents: 21:31:24 INFO - build_url:https://queue.taskcluster.net/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.dmg 21:31:25 INFO - Running post-action listener: _resource_record_post_action 21:31:25 INFO - Running post-action listener: set_extra_try_arguments 21:31:25 INFO - [mozharness: 2016-09-07 04:31:25.638389Z] Finished download-and-extract step (success) 21:31:25 INFO - [mozharness: 2016-09-07 04:31:25.638551Z] Running populate-webroot step. 21:31:25 INFO - Running pre-action listener: _resource_record_pre_action 21:31:25 INFO - Running main action method: populate_webroot 21:31:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:31:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:31:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:31:25 INFO - '--tppagecycles', 21:31:25 INFO - '7'], 21:31:25 INFO - 'tests': ['tsvgm']}}, 21:31:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:31:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:31:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:31:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:31:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:31:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:31:25 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:31:25 INFO - 'talos_options': ['--disable-e10s'], 21:31:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:31:25 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:31:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:31:25 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:31:25 INFO - 'talos_options': ['--disable-e10s'], 21:31:25 INFO - 'tests': ['damp', 'tps']}, 21:31:25 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:31:25 INFO - 'tests': ['damp', 'tps']}, 21:31:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:31:25 INFO - 'tests': ['dromaeo_dom']}, 21:31:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:31:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:31:25 INFO - 'tests': ['basic_compositor_video']}, 21:31:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:31:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:31:25 INFO - 'tests': ['a11yr', 21:31:25 INFO - 'ts_paint', 21:31:25 INFO - 'tpaint', 21:31:25 INFO - 'sessionrestore', 21:31:25 INFO - 'sessionrestore_no_auto_restore', 21:31:25 INFO - 'tabpaint']}, 21:31:25 INFO - 'other-e10s': {'tests': ['a11yr', 21:31:25 INFO - 'ts_paint', 21:31:25 INFO - 'tpaint', 21:31:25 INFO - 'sessionrestore', 21:31:25 INFO - 'sessionrestore_no_auto_restore', 21:31:25 INFO - 'tabpaint']}, 21:31:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:31:25 INFO - 'ts_paint', 21:31:25 INFO - 'tpaint', 21:31:25 INFO - 'sessionrestore', 21:31:25 INFO - 'sessionrestore_no_auto_restore', 21:31:25 INFO - 'tabpaint']}, 21:31:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:31:25 INFO - 'ts_paint', 21:31:25 INFO - 'tpaint', 21:31:25 INFO - 'sessionrestore', 21:31:25 INFO - 'sessionrestore_no_auto_restore', 21:31:25 INFO - 'tabpaint']}, 21:31:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:31:25 INFO - 'tests': ['a11yr', 21:31:25 INFO - 'ts_paint', 21:31:25 INFO - 'tpaint', 21:31:25 INFO - 'sessionrestore', 21:31:25 INFO - 'sessionrestore_no_auto_restore', 21:31:25 INFO - 'tabpaint']}, 21:31:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:31:25 INFO - 'tests': ['a11yr', 21:31:25 INFO - 'ts_paint', 21:31:25 INFO - 'tpaint', 21:31:25 INFO - 'sessionrestore', 21:31:25 INFO - 'sessionrestore_no_auto_restore', 21:31:25 INFO - 'tabpaint']}, 21:31:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:31:25 INFO - 'tests': ['tsvgx', 21:31:25 INFO - 'tsvgr_opacity', 21:31:25 INFO - 'tart', 21:31:25 INFO - 'tscrollx', 21:31:25 INFO - 'cart']}, 21:31:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:31:25 INFO - 'tsvgr_opacity', 21:31:25 INFO - 'tart', 21:31:25 INFO - 'tscrollx', 21:31:25 INFO - 'cart']}, 21:31:25 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:31:25 INFO - 'talos_options': ['--disable-e10s'], 21:31:25 INFO - 'tests': ['tp5o']}, 21:31:25 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:31:25 INFO - 'tests': ['tp5o']}, 21:31:25 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:31:25 INFO - 'talos_options': ['--disable-e10s', 21:31:25 INFO - '--xperf_path', 21:31:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:31:25 INFO - 'tests': ['tp5n']}, 21:31:25 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:31:25 INFO - 'talos_options': ['--xperf_path', 21:31:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:31:25 INFO - 'tests': ['tp5n']}}, 21:31:25 INFO - 'talos.zip': {'path': '', 21:31:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:31:25 INFO - Running post-action listener: _resource_record_post_action 21:31:25 INFO - [mozharness: 2016-09-07 04:31:25.657656Z] Finished populate-webroot step (success) 21:31:25 INFO - [mozharness: 2016-09-07 04:31:25.657788Z] Running create-virtualenv step. 21:31:25 INFO - Running pre-action listener: _resource_record_pre_action 21:31:25 INFO - Running main action method: create_virtualenv 21:31:25 INFO - Creating virtualenv /builds/slave/test/build/venv 21:31:25 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 21:31:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:31:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:31:25 INFO - Using real prefix '/tools/python27' 21:31:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:31:26 INFO - Installing distribute.............................................................................................................................................................................................done. 21:31:29 INFO - Installing pip.................done. 21:31:29 INFO - Return code: 0 21:31:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:31:29 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')]} 21:31:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:29 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')]} 21:31:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:29 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 0x1081f6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10828b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9b3dd5c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10815ad30>, '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 0x107ebfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108152b20>, '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.Q1ZM3yaTEZ/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.YGeId9yQgj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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 21:31:29 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 21:31:29 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 21:31:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q1ZM3yaTEZ/Render', 21:31:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:31:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:31:29 INFO - 'HOME': '/Users/cltbld', 21:31:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:31:29 INFO - 'LOGNAME': 'cltbld', 21:31:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:29 INFO - 'MOZ_NO_REMOTE': '1', 21:31:29 INFO - 'NO_EM_RESTART': '1', 21:31:29 INFO - 'PAGER': '/bin/cat', 21:31:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:31:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:29 INFO - 'PWD': '/builds/slave/test', 21:31:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:31:29 INFO - 'SHELL': '/bin/bash', 21:31:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGeId9yQgj/Listeners', 21:31:29 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 21:31:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:31:29 INFO - 'USER': 'cltbld', 21:31:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:31:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:31:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:29 INFO - 'XPC_FLAGS': '0x0', 21:31:29 INFO - 'XPC_SERVICE_NAME': '0', 21:31:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:31:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:29 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 21:31:29 INFO - Cleaning up... 21:31:29 INFO - Return code: 0 21:31:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:31:29 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')]} 21:31:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:29 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')]} 21:31:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:29 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 0x1081f6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10828b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9b3dd5c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10815ad30>, '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 0x107ebfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108152b20>, '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.Q1ZM3yaTEZ/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.YGeId9yQgj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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 21:31:29 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 21:31:29 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 21:31:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q1ZM3yaTEZ/Render', 21:31:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:31:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:31:29 INFO - 'HOME': '/Users/cltbld', 21:31:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:31:29 INFO - 'LOGNAME': 'cltbld', 21:31:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:29 INFO - 'MOZ_NO_REMOTE': '1', 21:31:29 INFO - 'NO_EM_RESTART': '1', 21:31:29 INFO - 'PAGER': '/bin/cat', 21:31:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:31:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:29 INFO - 'PWD': '/builds/slave/test', 21:31:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:31:29 INFO - 'SHELL': '/bin/bash', 21:31:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGeId9yQgj/Listeners', 21:31:29 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 21:31:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:31:29 INFO - 'USER': 'cltbld', 21:31:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:31:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:31:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:29 INFO - 'XPC_FLAGS': '0x0', 21:31:29 INFO - 'XPC_SERVICE_NAME': '0', 21:31:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:31:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:29 INFO - Downloading/unpacking psutil>=3.1.1 21:31:29 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 21:31:29 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 21:31:29 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 21:31:29 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 21:31:29 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 21:31:29 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 21:31:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:31:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:31:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:31:32 INFO - Installing collected packages: psutil 21:31:32 INFO - Running setup.py install for psutil 21:31:32 INFO - building 'psutil._psutil_osx' extension 21:31:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 21:31:33 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 21:31:33 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 21:31:33 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 21:31:33 INFO - building 'psutil._psutil_posix' extension 21:31:33 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 21:31:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:31:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:31:33 INFO - ^ 21:31:33 INFO - 1 warning generated. 21:31:33 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 21:31:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:31:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:31:33 INFO - Successfully installed psutil 21:31:33 INFO - Cleaning up... 21:31:33 INFO - Return code: 0 21:31:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:31:33 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')]} 21:31:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:33 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')]} 21:31:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:33 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 0x1081f6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10828b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9b3dd5c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10815ad30>, '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 0x107ebfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108152b20>, '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.Q1ZM3yaTEZ/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.YGeId9yQgj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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 21:31:33 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 21:31:33 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 21:31:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q1ZM3yaTEZ/Render', 21:31:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:31:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:31:33 INFO - 'HOME': '/Users/cltbld', 21:31:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:31:33 INFO - 'LOGNAME': 'cltbld', 21:31:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:33 INFO - 'MOZ_NO_REMOTE': '1', 21:31:33 INFO - 'NO_EM_RESTART': '1', 21:31:33 INFO - 'PAGER': '/bin/cat', 21:31:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:31:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:33 INFO - 'PWD': '/builds/slave/test', 21:31:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:31:33 INFO - 'SHELL': '/bin/bash', 21:31:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGeId9yQgj/Listeners', 21:31:33 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 21:31:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:31:33 INFO - 'USER': 'cltbld', 21:31:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:31:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:31:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:33 INFO - 'XPC_FLAGS': '0x0', 21:31:33 INFO - 'XPC_SERVICE_NAME': '0', 21:31:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:31:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:31:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:31:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:31:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:31:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:31:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:31:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:31:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:31:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:31:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:31:36 INFO - Installing collected packages: mozsystemmonitor 21:31:36 INFO - Running setup.py install for mozsystemmonitor 21:31:36 INFO - Successfully installed mozsystemmonitor 21:31:36 INFO - Cleaning up... 21:31:36 INFO - Return code: 0 21:31:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:31:36 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')]} 21:31:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:36 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')]} 21:31:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:36 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 0x1081f6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10828b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9b3dd5c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10815ad30>, '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 0x107ebfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108152b20>, '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.Q1ZM3yaTEZ/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.YGeId9yQgj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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 21:31:36 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 21:31:36 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 21:31:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q1ZM3yaTEZ/Render', 21:31:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:31:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:31:36 INFO - 'HOME': '/Users/cltbld', 21:31:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:31:36 INFO - 'LOGNAME': 'cltbld', 21:31:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:36 INFO - 'MOZ_NO_REMOTE': '1', 21:31:36 INFO - 'NO_EM_RESTART': '1', 21:31:36 INFO - 'PAGER': '/bin/cat', 21:31:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:31:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:36 INFO - 'PWD': '/builds/slave/test', 21:31:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:31:36 INFO - 'SHELL': '/bin/bash', 21:31:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGeId9yQgj/Listeners', 21:31:36 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 21:31:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:31:36 INFO - 'USER': 'cltbld', 21:31:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:31:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:31:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:36 INFO - 'XPC_FLAGS': '0x0', 21:31:36 INFO - 'XPC_SERVICE_NAME': '0', 21:31:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:31:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:37 INFO - Downloading/unpacking jsonschema==2.5.1 21:31:37 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 21:31:37 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 21:31:37 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 21:31:37 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 21:31:37 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 21:31:37 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 21:31:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:31:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:31:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:31:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:31:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:31:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:31:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:31:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:31:39 INFO - Downloading functools32-3.2.3-2.tar.gz 21:31:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:31:39 INFO - warning: no files found matching '*.txt' 21:31:39 INFO - no previously-included directories found matching 'build' 21:31:39 INFO - no previously-included directories found matching 'dist' 21:31:39 INFO - no previously-included directories found matching '.git*' 21:31:39 INFO - Installing collected packages: jsonschema, functools32 21:31:39 INFO - Running setup.py install for functools32 21:31:40 INFO - warning: no files found matching '*.txt' 21:31:40 INFO - no previously-included directories found matching 'build' 21:31:40 INFO - no previously-included directories found matching 'dist' 21:31:40 INFO - no previously-included directories found matching '.git*' 21:31:40 INFO - Successfully installed jsonschema functools32 21:31:40 INFO - Cleaning up... 21:31:40 INFO - Return code: 0 21:31:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:31:40 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')]} 21:31:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:40 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')]} 21:31:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1081f6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10828b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9b3dd5c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10815ad30>, '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 0x107ebfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108152b20>, '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.Q1ZM3yaTEZ/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.YGeId9yQgj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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 21:31:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 21:31:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 21:31:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q1ZM3yaTEZ/Render', 21:31:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:31:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:31:40 INFO - 'HOME': '/Users/cltbld', 21:31:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:31:40 INFO - 'LOGNAME': 'cltbld', 21:31:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:40 INFO - 'MOZ_NO_REMOTE': '1', 21:31:40 INFO - 'NO_EM_RESTART': '1', 21:31:40 INFO - 'PAGER': '/bin/cat', 21:31:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:31:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:40 INFO - 'PWD': '/builds/slave/test', 21:31:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:31:40 INFO - 'SHELL': '/bin/bash', 21:31:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGeId9yQgj/Listeners', 21:31:40 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 21:31:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:31:40 INFO - 'USER': 'cltbld', 21:31:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:31:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:31:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:40 INFO - 'XPC_FLAGS': '0x0', 21:31:40 INFO - 'XPC_SERVICE_NAME': '0', 21:31:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:31:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:31:40 INFO - Cleaning up... 21:31:40 INFO - Return code: 0 21:31:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:31:40 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')]} 21:31:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:40 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')]} 21:31:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:40 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 0x1081f6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10828b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9b3dd5c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10815ad30>, '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 0x107ebfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108152b20>, '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.Q1ZM3yaTEZ/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.YGeId9yQgj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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 21:31:40 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 21:31:40 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 21:31:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q1ZM3yaTEZ/Render', 21:31:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:31:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:31:40 INFO - 'HOME': '/Users/cltbld', 21:31:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:31:40 INFO - 'LOGNAME': 'cltbld', 21:31:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:40 INFO - 'MOZ_NO_REMOTE': '1', 21:31:40 INFO - 'NO_EM_RESTART': '1', 21:31:40 INFO - 'PAGER': '/bin/cat', 21:31:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:31:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:40 INFO - 'PWD': '/builds/slave/test', 21:31:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:31:40 INFO - 'SHELL': '/bin/bash', 21:31:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGeId9yQgj/Listeners', 21:31:40 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 21:31:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:31:40 INFO - 'USER': 'cltbld', 21:31:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:31:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:31:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:40 INFO - 'XPC_FLAGS': '0x0', 21:31:40 INFO - 'XPC_SERVICE_NAME': '0', 21:31:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:31:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:40 INFO - Downloading/unpacking blobuploader==1.2.4 21:31:40 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 21:31:40 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 21:31:40 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 21:31:40 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 21:31:40 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 21:31:40 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 21:31:43 INFO - Downloading blobuploader-1.2.4.tar.gz 21:31:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:31:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:31:43 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 21:31:43 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 21:31:43 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 21:31:43 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 21:31:43 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 21:31:43 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 21:31:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:31:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:31:43 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 21:31:43 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 21:31:43 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 21:31:43 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 21:31:43 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 21:31:43 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 21:31:44 INFO - Downloading docopt-0.6.1.tar.gz 21:31:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:31:44 INFO - Installing collected packages: blobuploader, requests, docopt 21:31:44 INFO - Running setup.py install for blobuploader 21:31:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:31:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:31:44 INFO - Running setup.py install for requests 21:31:45 INFO - Running setup.py install for docopt 21:31:45 INFO - Successfully installed blobuploader requests docopt 21:31:45 INFO - Cleaning up... 21:31:45 INFO - Return code: 0 21:31:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:31:45 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')]} 21:31:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:45 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')]} 21:31:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:45 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 0x1081f6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10828b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9b3dd5c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10815ad30>, '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 0x107ebfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108152b20>, '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.Q1ZM3yaTEZ/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.YGeId9yQgj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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 21:31:45 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 21:31:45 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 21:31:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q1ZM3yaTEZ/Render', 21:31:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:31:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:31:45 INFO - 'HOME': '/Users/cltbld', 21:31:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:31:45 INFO - 'LOGNAME': 'cltbld', 21:31:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:45 INFO - 'MOZ_NO_REMOTE': '1', 21:31:45 INFO - 'NO_EM_RESTART': '1', 21:31:45 INFO - 'PAGER': '/bin/cat', 21:31:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:31:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:45 INFO - 'PWD': '/builds/slave/test', 21:31:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:31:45 INFO - 'SHELL': '/bin/bash', 21:31:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGeId9yQgj/Listeners', 21:31:45 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 21:31:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:31:45 INFO - 'USER': 'cltbld', 21:31:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:31:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:31:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:45 INFO - 'XPC_FLAGS': '0x0', 21:31:45 INFO - 'XPC_SERVICE_NAME': '0', 21:31:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:31:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:31:45 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-_4n2XR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:31:45 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-ZBVnCf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:31:45 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-VmeMNH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:31:45 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-NQMxD6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:31:46 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-IB5259-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:31:46 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-PjrKd9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:31:46 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-tTL_hb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:31:46 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-enkWve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:31:46 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-KSBN8a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:31:46 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-lUq97A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:31:46 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-nziw7p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:31:46 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-QjwshK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:31:46 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-qEh3ZZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:31:46 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-Idbwlw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:31:47 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-Z5dP1B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:31:47 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-hcVtzp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:31:47 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-q1_Y53-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:31:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:31:47 INFO - Running setup.py install for manifestparser 21:31:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:31:47 INFO - Running setup.py install for mozcrash 21:31:47 INFO - Running setup.py install for mozdebug 21:31:47 INFO - Running setup.py install for mozdevice 21:31:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:31:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:31:47 INFO - Running setup.py install for mozfile 21:31:48 INFO - Running setup.py install for mozhttpd 21:31:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:31:48 INFO - Running setup.py install for mozinfo 21:31:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:31:48 INFO - Running setup.py install for mozInstall 21:31:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:31:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:31:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:31:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:31:48 INFO - Running setup.py install for mozleak 21:31:48 INFO - Running setup.py install for mozlog 21:31:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:31:48 INFO - Running setup.py install for moznetwork 21:31:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:31:48 INFO - Running setup.py install for mozprocess 21:31:49 INFO - Running setup.py install for mozprofile 21:31:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:31:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:31:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:31:49 INFO - Running setup.py install for mozrunner 21:31:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:31:49 INFO - Running setup.py install for mozscreenshot 21:31:49 INFO - Running setup.py install for moztest 21:31:49 INFO - Running setup.py install for mozversion 21:31:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:31:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:31:49 INFO - Cleaning up... 21:31:49 INFO - Return code: 0 21:31:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:31: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')]} 21:31:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31: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')]} 21:31:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:49 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 0x1081f6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10828b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9b3dd5c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10815ad30>, '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 0x107ebfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108152b20>, '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.Q1ZM3yaTEZ/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.YGeId9yQgj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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 21:31:49 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 21:31:49 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 21:31:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q1ZM3yaTEZ/Render', 21:31:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:31:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:31:49 INFO - 'HOME': '/Users/cltbld', 21:31:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:31:49 INFO - 'LOGNAME': 'cltbld', 21:31:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:49 INFO - 'MOZ_NO_REMOTE': '1', 21:31:49 INFO - 'NO_EM_RESTART': '1', 21:31:49 INFO - 'PAGER': '/bin/cat', 21:31:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:31:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:49 INFO - 'PWD': '/builds/slave/test', 21:31:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:31:49 INFO - 'SHELL': '/bin/bash', 21:31:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGeId9yQgj/Listeners', 21:31:49 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 21:31:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:31:49 INFO - 'USER': 'cltbld', 21:31:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:31:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:31:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:49 INFO - 'XPC_FLAGS': '0x0', 21:31:49 INFO - 'XPC_SERVICE_NAME': '0', 21:31:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:31:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:31:50 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-z71Yez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:31:50 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)) 21:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:31:50 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-qlaJDA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:31:50 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)) 21:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:31:50 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-4553wx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:31:50 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)) 21:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:31:50 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-wLXkhL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:31:50 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)) 21:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:31:50 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-Dc87qj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:31:50 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)) 21:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:31:50 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-AYmbwJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:31:50 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)) 21:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:31:50 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-mShH2g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:31:50 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)) 21:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:31:50 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-X13lMa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:31:51 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)) 21:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:31:51 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-57AquP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:31:51 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)) 21:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:31:51 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-WTP0Ib-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:31:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:31:51 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-7Ovz4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:31:51 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)) 21:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:31:51 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-SIAbVG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:31:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 21:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:31:51 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-XNr0qV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:31:51 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)) 21:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:31:51 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-IZwblx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:31:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 21:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:31:51 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-s6TVQQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:31:51 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)) 21:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:31:51 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-D02MU9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:31:51 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)) 21:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:31:51 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-o_GP9s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:31:52 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)) 21:31:52 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)) 21:31:52 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)) 21:31:52 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)) 21:31:52 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)) 21:31:52 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)) 21:31:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:31:52 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 21:31:52 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 21:31:52 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 21:31:52 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 21:31:52 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 21:31:52 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 21:31:54 INFO - Downloading blessings-1.6.tar.gz 21:31:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:31:54 INFO - Installing collected packages: blessings 21:31:54 INFO - Running setup.py install for blessings 21:31:54 INFO - Successfully installed blessings 21:31:54 INFO - Cleaning up... 21:31:54 INFO - Return code: 0 21:31:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:31:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:31:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:31:55 INFO - Reading from file tmpfile_stdout 21:31:55 INFO - Current package versions: 21:31:55 INFO - blessings == 1.6 21:31:55 INFO - blobuploader == 1.2.4 21:31:55 INFO - docopt == 0.6.1 21:31:55 INFO - functools32 == 3.2.3-2 21:31:55 INFO - jsonschema == 2.5.1 21:31:55 INFO - manifestparser == 1.1 21:31:55 INFO - mozInstall == 1.12 21:31:55 INFO - mozcrash == 0.17 21:31:55 INFO - mozdebug == 0.1 21:31:55 INFO - mozdevice == 0.48 21:31:55 INFO - mozfile == 1.2 21:31:55 INFO - mozhttpd == 0.7 21:31:55 INFO - mozinfo == 0.9 21:31:55 INFO - mozleak == 0.1 21:31:55 INFO - mozlog == 3.3 21:31:55 INFO - moznetwork == 0.27 21:31:55 INFO - mozprocess == 0.23 21:31:55 INFO - mozprofile == 0.28 21:31:55 INFO - mozrunner == 6.12 21:31:55 INFO - mozscreenshot == 0.1 21:31:55 INFO - mozsystemmonitor == 0.3 21:31:55 INFO - moztest == 0.7 21:31:55 INFO - mozversion == 1.4 21:31:55 INFO - psutil == 3.1.1 21:31:55 INFO - requests == 1.2.3 21:31:55 INFO - wsgiref == 0.1.2 21:31:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:31:55 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')]} 21:31:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:55 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')]} 21:31:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:55 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 0x1081f6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10828b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9b3dd5c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10815ad30>, '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 0x107ebfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108152b20>, '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.Q1ZM3yaTEZ/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.YGeId9yQgj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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 21:31:55 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 21:31:55 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 21:31:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q1ZM3yaTEZ/Render', 21:31:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:31:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:31:55 INFO - 'HOME': '/Users/cltbld', 21:31:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:31:55 INFO - 'LOGNAME': 'cltbld', 21:31:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:55 INFO - 'MOZ_NO_REMOTE': '1', 21:31:55 INFO - 'NO_EM_RESTART': '1', 21:31:55 INFO - 'PAGER': '/bin/cat', 21:31:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:31:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:55 INFO - 'PWD': '/builds/slave/test', 21:31:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:31:55 INFO - 'SHELL': '/bin/bash', 21:31:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGeId9yQgj/Listeners', 21:31:55 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 21:31:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:31:55 INFO - 'USER': 'cltbld', 21:31:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:31:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:31:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:55 INFO - 'XPC_FLAGS': '0x0', 21:31:55 INFO - 'XPC_SERVICE_NAME': '0', 21:31:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:31:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:55 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)) 21:31:55 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)) 21:31:55 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)) 21:31:55 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)) 21:31:55 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)) 21:31:55 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)) 21:31:55 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)) 21:31:55 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)) 21:31:55 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)) 21:31:55 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)) 21:31:55 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)) 21:31:55 INFO - Cleaning up... 21:31:55 INFO - Return code: 0 21:31:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:31:55 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')]} 21:31:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:55 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')]} 21:31:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:55 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 0x1081f6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10828b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9b3dd5c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10815ad30>, '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 0x107ebfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108152b20>, '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.Q1ZM3yaTEZ/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.YGeId9yQgj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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 21:31:55 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 21:31:55 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 21:31:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q1ZM3yaTEZ/Render', 21:31:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:31:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:31:55 INFO - 'HOME': '/Users/cltbld', 21:31:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:31:55 INFO - 'LOGNAME': 'cltbld', 21:31:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:55 INFO - 'MOZ_NO_REMOTE': '1', 21:31:55 INFO - 'NO_EM_RESTART': '1', 21:31:55 INFO - 'PAGER': '/bin/cat', 21:31:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:31:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:55 INFO - 'PWD': '/builds/slave/test', 21:31:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:31:55 INFO - 'SHELL': '/bin/bash', 21:31:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGeId9yQgj/Listeners', 21:31:55 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 21:31:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:31:55 INFO - 'USER': 'cltbld', 21:31:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:31:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:31:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:55 INFO - 'XPC_FLAGS': '0x0', 21:31:55 INFO - 'XPC_SERVICE_NAME': '0', 21:31:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:31:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:31:55 INFO - Cleaning up... 21:31:55 INFO - Return code: 0 21:31:55 INFO - Running post-action listener: _resource_record_post_action 21:31:55 INFO - Running post-action listener: _start_resource_monitoring 21:31:55 INFO - Starting resource monitoring. 21:31:55 INFO - [mozharness: 2016-09-07 04:31:55.692182Z] Finished create-virtualenv step (success) 21:31:55 INFO - [mozharness: 2016-09-07 04:31:55.693119Z] Running install step. 21:31:55 INFO - Running pre-action listener: _resource_record_pre_action 21:31:55 INFO - Running main action method: install 21:31:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:31:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:31:55 INFO - Reading from file tmpfile_stdout 21:31:55 INFO - Detecting whether we're running mozinstall >=1.0... 21:31:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:31:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:31:56 INFO - Reading from file tmpfile_stdout 21:31:56 INFO - Output received: 21:31:56 INFO - Usage: mozinstall [options] installer 21:31:56 INFO - Options: 21:31:56 INFO - -h, --help show this help message and exit 21:31:56 INFO - -d DEST, --destination=DEST 21:31:56 INFO - Directory to install application into. [default: 21:31:56 INFO - "/builds/slave/test"] 21:31:56 INFO - --app=APP Application being installed. [default: firefox] 21:31:56 INFO - mkdir: /builds/slave/test/build/application 21:31:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 21:31:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 21:32:28 INFO - Reading from file tmpfile_stdout 21:32:28 INFO - Output received: 21:32:28 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 21:32:28 INFO - Running post-action listener: _resource_record_post_action 21:32:28 INFO - [mozharness: 2016-09-07 04:32:28.651458Z] Finished install step (success) 21:32:28 INFO - [mozharness: 2016-09-07 04:32:28.651609Z] Running run-tests step. 21:32:28 INFO - Running pre-action listener: _resource_record_pre_action 21:32:28 INFO - Running main action method: run_tests 21:32:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:32:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:32:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:32:28 INFO - 2016-09-06 21:32:28.666 screenresolution[1980:14406] starting screenresolution argv=screenresolution get 21:32:28 INFO - 2016-09-06 21:32:28.669 screenresolution[1980:14406] Display 0: 1600x1200x32@60 21:32:28 INFO - 2016-09-06 21:32:28.675 screenresolution[1981:14408] starting screenresolution argv=screenresolution list 21:32:28 INFO - Available Modes on Display 0 21:32:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:32:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:32:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:32:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:32:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:32:28 INFO - Intel Iris: 21:32:28 INFO - Chipset Model: Intel Iris 21:32:28 INFO - Type: GPU 21:32:28 INFO - Bus: Built-In 21:32:28 INFO - VRAM (Dynamic, Max): 1536 MB 21:32:28 INFO - Vendor: Intel (0x8086) 21:32:28 INFO - Device ID: 0x0a2e 21:32:28 INFO - Revision ID: 0x0009 21:32:28 INFO - Displays: 21:32:28 INFO - Display: 21:32:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:32:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:32:28 INFO - Display Serial Number: 1600x1200 60 21:32:28 INFO - Main Display: Yes 21:32:28 INFO - Mirror: Off 21:32:28 INFO - Online: Yes 21:32:28 INFO - Rotation: Supported 21:32:28 INFO - Return code: 0 21:32:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:32:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:32:28 INFO - Python 2.7.3 21:32:28 INFO - Return code: 0 21:32:28 INFO - grabbing minidump binary from tooltool 21:32:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:32:28 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 0x10815ad30>, '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 0x107ebfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108152b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:32:28 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 21:32:28 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 21:32:28 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 21:32:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:32:29 INFO - Return code: 0 21:32:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:32:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:32:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:32:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:32:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:32:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0253', '--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 21:32:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0253 --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 21:32:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q1ZM3yaTEZ/Render', 21:32:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:32:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:32:29 INFO - 'HOME': '/Users/cltbld', 21:32:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:32:29 INFO - 'LOGNAME': 'cltbld', 21:32:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:32:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:32:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:32:29 INFO - 'MOZ_NO_REMOTE': '1', 21:32:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:32:29 INFO - 'NO_EM_RESTART': '1', 21:32:29 INFO - 'PAGER': '/bin/cat', 21:32:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:32:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:32:29 INFO - 'PWD': '/builds/slave/test', 21:32:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:32:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:32:29 INFO - 'SHELL': '/bin/bash', 21:32:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGeId9yQgj/Listeners', 21:32:29 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 21:32:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:32:29 INFO - 'USER': 'cltbld', 21:32:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:32:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:32:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:32:29 INFO - 'XPC_FLAGS': '0x0', 21:32:29 INFO - 'XPC_SERVICE_NAME': '0', 21:32:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:32:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0253', '--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 21:32:29 INFO - mozversion application_buildid: 20160906190512 21:32:29 INFO - mozversion application_changeset: 2374ab2d2b77a203efeef432a98f2b04c36f4749 21:32:29 INFO - mozversion application_display_name: FirefoxDeveloperEdition 21:32:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:32:29 INFO - mozversion application_name: Firefox 21:32:29 INFO - mozversion application_remotingname: firefox-dev 21:32:29 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 21:32:29 INFO - mozversion application_vendor: Mozilla 21:32:29 INFO - mozversion application_version: 50.0a2 21:32:29 INFO - mozversion platform_buildid: 20160906190512 21:32:29 INFO - mozversion platform_changeset: 2374ab2d2b77a203efeef432a98f2b04c36f4749 21:32:29 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 21:32:29 INFO - mozversion platform_version: 50.0a2 21:32:29 INFO - using testdate: 1473222749 21:32:29 INFO - actual date: 1473222749 21:32:29 INFO - starting webserver on 'localhost:51378' 21:32:29 INFO - SUITE-START | Running 2 tests 21:32:29 INFO - TEST-START | dromaeo_css 21:32:29 INFO - operating with platform_type : mac_ 21:32:29 INFO - Initialising browser for dromaeo_css test... 21:32:29 INFO - TEST-INFO | started process 1991 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/tmpdVDeD1/profile http://localhost:51378/getInfo.html) 21:32:31 INFO - PROCESS | 1991 | __metrics Screen width/height:1600/1200 21:32:31 INFO - PROCESS | 1991 | colorDepth:24 21:32:31 INFO - PROCESS | 1991 | Browser inner width/height: 1024/689 21:32:31 INFO - PROCESS | 1991 | __metrics 21:32:31 INFO - PROCESS | 1991 | 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" 21:32:31 INFO - PROCESS | 1991 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:32:32 INFO - PROCESS | 1991 | ************************* 21:32:32 INFO - PROCESS | 1991 | A coding exception was thrown in a Promise resolution callback. 21:32:32 INFO - PROCESS | 1991 | See https://developer.mozilla.org/Mozilla/JavaScript_code_modules/Promise.jsm/Promise 21:32:32 INFO - PROCESS | 1991 | 21:32:32 ERROR - PROCESS | 1991 | Full message: TypeError: win is null 21:32:32 INFO - PROCESS | 1991 | Full stack: onSuccess@resource://app/components/nsBrowserGlue.js:844:17 21:32:32 INFO - PROCESS | 1991 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 21:32:32 INFO - PROCESS | 1991 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 21:32:32 INFO - PROCESS | 1991 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 21:32:32 INFO - PROCESS | 1991 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 21:32:32 INFO - PROCESS | 1991 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 21:32:32 INFO - PROCESS | 1991 | get _worker/worker.onmessage@resource://gre/modules/PromiseWorker.jsm:235:9 21:32:32 INFO - PROCESS | 1991 | EventHandlerNonNull*get _worker@resource://gre/modules/PromiseWorker.jsm:217:5 21:32:32 INFO - PROCESS | 1991 | postMessage@resource://gre/modules/PromiseWorker.jsm:292:9 21:32:32 INFO - PROCESS | 1991 | TaskImpl_run@resource://gre/modules/Task.jsm:319:40 21:32:32 INFO - PROCESS | 1991 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 21:32:32 INFO - PROCESS | 1991 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 21:32:32 INFO - PROCESS | 1991 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 21:32:32 INFO - PROCESS | 1991 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 21:32:32 INFO - PROCESS | 1991 | Promise.prototype.then@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:454:5 21:32:32 INFO - PROCESS | 1991 | this.DeferredSave.prototype._deferredSave@resource://gre/modules/DeferredSave.jsm:220:5 21:32:32 INFO - PROCESS | 1991 | this.DeferredSave.prototype._startTimer/<@resource://gre/modules/DeferredSave.jsm:175:40 21:32:32 INFO - PROCESS | 1991 | syncLoadManifestFromFile@resource://gre/modules/addons/XPIProvider.jsm:1511:5 21:32:32 INFO - PROCESS | 1991 | addMetadata@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:1650:21 21:32:32 INFO - PROCESS | 1991 | processFileChanges@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2014:23 21:32:32 INFO - PROCESS | 1991 | this.XPIProvider.checkForChanges@resource://gre/modules/addons/XPIProvider.jsm:3723:34 21:32:32 INFO - PROCESS | 1991 | this.XPIProvider.startup@resource://gre/modules/addons/XPIProvider.jsm:2713:25 21:32:32 INFO - PROCESS | 1991 | callProvider@resource://gre/modules/AddonManager.jsm:236:12 21:32:32 INFO - PROCESS | 1991 | _startProvider@resource://gre/modules/AddonManager.jsm:788:5 21:32:32 INFO - PROCESS | 1991 | AddonManagerInternal.startup@resource://gre/modules/AddonManager.jsm:972:9 21:32:32 INFO - PROCESS | 1991 | this.AddonManagerPrivate.startup@resource://gre/modules/AddonManager.jsm:3016:5 21:32:32 INFO - PROCESS | 1991 | amManager.prototype.observe@resource://gre/components/addonManager.js:71:9 21:32:32 INFO - PROCESS | 1991 | 21:32:32 INFO - PROCESS | 1991 | ************************* 21:32:32 INFO - TEST-INFO | 1991: exit 0 21:32:32 INFO - Browser initialized. 21:32:32 INFO - Running cycle 1/1 for dromaeo_css test... 21:32:32 INFO - TEST-INFO | started process 1993 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/tmpdVDeD1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:32:44 INFO - PROCESS | 1993 | RSS: Main: 233943040 21:32:44 INFO - PROCESS | 1993 | 21:33:35 INFO - PROCESS | 1993 | 1473222815471 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] 21:35:47 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:51378/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51378/tests/dromaeo/cssquery-ext.html) 21:35:48 INFO - PROCESS | 1993 | RSS: Main: 343252992 21:35:48 INFO - PROCESS | 1993 | 21:38:51 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:51378/tests/dromaeo/cssquery-ext.html (next: http://localhost:51378/tests/dromaeo/cssquery-jquery.html) 21:38:52 INFO - PROCESS | 1993 | RSS: Main: 374448128 21:38:52 INFO - PROCESS | 1993 | 21:41:54 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:51378/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51378/tests/dromaeo/cssquery-mootools.html) 21:41:55 INFO - PROCESS | 1993 | RSS: Main: 338526208 21:41:55 INFO - PROCESS | 1993 | 21:44:57 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:51378/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51378/tests/dromaeo/cssquery-prototype.html) 21:44:58 INFO - PROCESS | 1993 | RSS: Main: 335454208 21:44:58 INFO - PROCESS | 1993 | 21:48:01 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:51378/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51378/tests/dromaeo/cssquery-yui.html) 21:48:02 INFO - PROCESS | 1993 | RSS: Main: 331624448 21:48:02 INFO - PROCESS | 1993 | 21:51:05 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:51378/tests/dromaeo/cssquery-yui.html (next: http://localhost:51378/tests/dromaeo/cssquery-dojo.html) 21:51:05 INFO - PROCESS | 1993 | RSS: Main: 399110144 21:51:05 INFO - PROCESS | 1993 | 21:51:05 INFO - PROCESS | 1993 | __start_tp_report 21:51:05 INFO - PROCESS | 1993 | _x_x_mozilla_page_load 21:51:05 INFO - PROCESS | 1993 | _x_x_mozilla_page_load_details 21:51:05 INFO - PROCESS | 1993 | |i|pagename|runs| 21:51:05 INFO - PROCESS | 1993 | |0;dojo.html;3354.2452948086698;3355.714764245039;3358.7145092667115;3389.7796643218203;3427.6572342765726;5761.107028410598;5823.796167134151;5829.417058294179;5849.03490923997;5854.677992710404;5782.971085144579;5837.270341207349;5845.824625261238;5846.0061789495785;5865.941340586593;5188.610854185932;5208.687478751278;5224.425313215562;5248.5276325130735;5340.67955922644;5290.788368465257;5347.732613369335;5351;5356.839294821161;5363.704996225206;5218.373795144588;5272.209168624699;5272.6836389816735;5273.630845840793;5323.068463018975;5345.599080068991;5357.142857142878;5360.839174824761;5368.221607866837;5408.405075441738;5105.897882042375;5169.896602067975;5207.661502002357;5274.55166310864;5279.841604751864;5712.828615141552;5740.167675687044;5752.626079304831;5765.625234359795;5774.797882074107;5792.304923409176;6195.16365290689;6199.101130336121;6204.255489341308;6210.254769427653;6116.663583502906;6117.785877494266;6209.130721698965;6211.658358790265;6217.378262173792;5896.734646940898;5978.222831032025;5979.760809567569;5986.072158815347;5989.251343582052;6260.874782504325;6265.122882796412;6279.717412716439;6284.183056202664;6295.622262664255;6114.084479019511;6120.1737765401995;6214.25428948529;6216.285127210429;6233.158523599256;5221.477852214748;6206.286277078104;6215.409536094064;6217.191765070512;6226.377362263737;6134.263888333428;6176.073588961602;6195.535334849904;6209.65846878417;6252.155958945484;6116.579253789288;6146.631202127886;6178.876422471615;6222.937770622236;6228.968855155696;4965.106280869478;4980.05378977993;4998.475160108209;5021.698698078054;5022.372203474566;4482.775861206992;4496.64026877849;4500.662450316239;4540.6821522493765;4571.131485017836;5837.766489340379;5841.620294680833;5852.8536786579825;5854.648721076664;5901;6114.78598249068;6188.133661287423;6190.102435146879;6208.068789681584;6208.286047104641;5750.367459579443;5751.7411716472025;5792.073268277056;5795.536357091507;5848.122781582797;5497.175423686399;5510.980468613319;5520.089185284463;5541.307336582927;5542.196381524737;6400.615963042233;6403.48772098231;6409.262934762375;6428.903566446507;6435.839104022437;6139.522803442411;6215.223097112861;6225.470834979077;6226.221722284715;6236.594621349598;6229.96885015572;6268.341824108403;6300.306966233802;6382.776602818879;6392.776252831129;6191.504679625709;6223.688815559113;6230.190075290365;6231.626102433868;6238.844028899132;6139.645466941873;6182.381761823782;6201.410865967726;6209.782657606962;6220.191375121205;6108.8581424937165;6129.817282374624;6165.84585385369;6208.875822483435;6226.937730622636;6177.907331390123;6181.196444462191;6195.473384762346;6196.907046394217;6213.751449941951;5912.70436478183;6108.541859360477;6116.235470566179;6119.265688117454;6123.142760013513;6110.680268050674;6130.551011451207;6194.194754681863;6238.500919926486;6240.251169859646;5952.077427998667;6129.632222066691;6136.018237082046;6139.570230083851;6139.693015349126 21:51:05 INFO - PROCESS | 1993 | |1;ext.html;502.16389711130967;507.34299082687915;526.2264471227297;541.9051665958457;551.622138834898;30820.537691934613;31435.371292574164;32912.01263962079;32983.17542061453;33012;42542;44083.77958110213;44219.77890110553;46858;46995.295070573884;9711.757206069851;9854.704358869245;9870.21038316933;9874.802503949917;9876.506174691236;8638.654453821871;9472.242220622335;9565.139137477625;9572.664956726516;9582.041795820433;8700.477971321709;11714.707132321717;11824.054075673925;11828.645140645795;12107.273563586168;11741.530338786439;11853.525858965631;11855.110866684947;11857.288562686266;11900.404979750978;11779.764404711943;11863.169578129535;11869.287842729464;11873.584424545099;11895.107866910017;41772.79113604443;42083.36874946848;42099;42124.157516849795;42191.789041054595;3372.3423932333203;3440.638732933031;3539.4513850353237;3543.826014019535;3661.487391765155;32324.688755419455;32577.18557036093;32964;33284.833575831966;33560.32879342424;2477.9752202477925;3214.517822326646;3238.5142228665654;3258.75559333051;3265.96734032659;450240;453583.7320813408;453872;456981;457287;33153.171170720925;33632.83183584066;64758.028629570596;65384.67307663431;65412;35672.46491302633;36252;36272.09319767027;36295.818520907225;36335.636643633756;15442;15608.219589020504;15619.531414057596;15628.687426251412;15663.451779187903;13039.608811735663;13061.020423468279;13137.737245255232;13167.078304518593;13173.736525269442;6738.932610673928;7388.963055184689;7542.245775422523;7724.536527808349;8035.116137224901;2957.8668959896854;3180.8886688965777;3234.062121984598;3264.379767844102;3286.7206287465356;923.8429466990627;939.1735272959752;1041.9010194031555;1085.2565992295304;1113.9640134674669;29757;30326.696733032386;30616.234594135323;30735.077947661604;31354.42059575482;984.8227319082633;1074.9623763168252;1075.875709883174;1179.1745777955293;1184.466989854569;14395.712085758227;15215.63059324666;15482.767758483631;15908.886377953664;15961.840381596268;645422;652028;652262;653443;653532;52516.687082823235;52843.735781321615;59467;59622.70188649116;59885.10172347418;1407.345584303304;1432.4198699526708;1448.2396741710686;1448.6450819549277;1458.161557104669;29840.26698091727;31408.842955785076;31453.528197076605;31876;32178.83910580432;52541.211881822564;53565;53724.194137087194;53772;53853.73073134609;707701;723035;723208;929314;930864;45611.08777824491;45981.310280345155;46193.76903115597;46892.82767930692;46918.12327506862;49252.768680783265;49667.00665986732;49690.50309496859;49894.002119958124;50269.49730502648;39759.80120099381;40235.39646905357;41251.793741031106;41272.1745565081;42312.576874230865;39934.40098398585;40066.79966600148;40387;41108;42366;39146.02134946649;40059.998500037735;40097.79706608807;40102.59897401105;40270.59729402786;34301.098813600765;35551;35854.10364740902;36172.4574131383;36256.818715907306;20332.186712531926;25757;25943.481130377666;26213.082542110933;26391.34021649474 21:51:05 INFO - PROCESS | 1993 | |2;jquery.html;349.902027432319;372.9049092481417;376.0392197934278;385.7878167008146;405.4911086586334;15641.921790391047;15860.127692976881;20341.084651190697;20744.585108297826;20916.849573273485;19641.9196944168;20236.190552377924;20281.98590070494;20674.379768606927;21701.240456584022;5254.81608143715;5890.764369425217;6076.908846367308;6151.477124444427;6151.569390142692;5788.218590490272;6084.726187321581;6106.114613381057;6111.083337499366;6117.847053823668;5852.824415267549;5988.580799344048;6039.516838652919;6056.848578785522;6097.237845269341;6002.789902353396;6025.728842202111;6046.365131661179;6061.727222275008;6069.998450255703;5113.360829896263;5209.843704688866;5265.946810637888;5280.841574752763;5283.101872681615;24827.62758558623;26586.202413927615;26600.46799064027;26772.46455070907;27028.189154325402;2097.5699981503753;2231.5760005598886;2393.174354847592;2457.213691618683;2850.1734496995846;9081.642850004957;10537;10628.149748020218;10642.04221620058;10686.091682207021;1452.8474510176386;1794.596215851436;1812.918418671163;1836.8071352508061;1892.8012558681417;106808.99368689362;111657;114779.85220147776;115603;121719;9337.533123343806;11153.498092585853;11319.320840749582;11664.61512603383;11797.705057373634;14747.188904610135;14765.40938362475;14770.556883293519;14779.556613301618;14787.630309242355;8707.47755134694;8836.11638836124;8865.955670221736;8867.379283450226;8991.910080899239;8811.559422029;8819.250363719155;8842.115788421106;8978.820423591622;8980.146886045815;4000.139969906484;4362.629176519968;4559.179347717442;4587.334836448763;4595.2188128018315;1896.5448292409721;1932.1595106128598;1940.7671079470554;1967.7835438101797;1969.468243574227;713.5575942915426;725.793148952546;795.4749865089013;1003.0471052500154;1052.7157667429751;17070.658586828195;21462.678059829115;21700.34898953034;21703.240386586393;21828.45428864259;952.1492858880428;956.2445667922264;965.0735294117585;972.5963725054023;978.1392374710209;11209.887901121048;11324.433778311051;11375.43122843871;11375.886241137649;11439.37083460401;569251;583087;589522;600137;601410;126672.36663816622;128010.71989279988;129263;134295;136662;988.2588058955783;990.2127808392202;1021.5352014833232;1023.2683631203726;1024.1397226329918;18542.25830966746;18736.437906862815;18998.43004709861;19033.238670453582;19385.03074846224;55502;57089;57295;57497;57687.71156144192;81313.18686813056;82460.58769706113;83109;83455.7481637788;83681.58159209165;6465.612063276219;7885.2114788520685;7968.7609371718945;8088.71689490865;8402.537860417735;12961.805572916226;13181.868181318448;13224.735505290033;13226.272555009182;13827.032107752762;5514.6691198528215;9307.464268395639;11294.491747871201;11816.70458238551;11875.346855922662;9374.765630859283;11008.724781880517;11358.399848061548;11443.542258309908;11733.471993760131;5856.4143585641095;6458.806235812933;10912.181586380895;11618.547681539807;12008.159428840247;8280.337572994025;8287.834243315221;8315.542645154575;8330.833383332421;8374.958125209336;11026.283291586022;11254.88745112571;11332.490037948477;11348.54605815758;11544.249623774427 21:51:05 INFO - PROCESS | 1993 | |3;mootools.html;1557.221389305347;1598.2248609424428;1821.7267409888516;1923.932662356818;1964.104639023342;8628.482291062533;9141.725748227547;9145.908540914588;9157.038510956345;9166.31252656051;7960.2437768412055;8211.137830527798;8659.177378149066;8903.73288801337;8925.73222803317;2377.2511658827416;2404.326788499223;2407.6990376202975;2408.1089996701244;2415.9396015099674;1932.9658632631545;2385.9642105368434;2387.6896003519605;2415.2029830156007;2415.408224984881;2110.535682149932;2183.3449965010514;2328.2200462844967;2399.5560821248037;2403.0267741564694;2378.2984019714227;2379.762023797624;2406.8326861472174;2407.205622144697;2411.903523859061;2388.0925248405497;2388.5461762265113;2396.2571602803187;2397.616381378971;2398.4603464220586;9046.004939456654;9113.635454581809;9124.0419755926;9135.177833994905;9143;5145.511176438233;5298.973505132488;5304.310439642822;5341.786328546854;5351.224072509464;3499.737519686034;3591.910202244939;3707.610700876423;3710.3135919854913;3711.3876210425246;1053.0943388685723;1073.0664322039797;1084.6908631040194;1085.880553139154;1091.7598128411737;11845.763084738343;11975.64073077809;11980.640580782503;11993.340366279852;12000;4595.448546174481;4665.766711664403;4831.51684831514;4836.298736683231;4837.984023355065;10076.294659373774;10079;10079.445630490394;10087.445190514592;10101.646442374482;5334.413214546396;5338.225957236256;5347.732613369316;5347.759350829222;5365.248865158881;4816.8795780105775;4880.097182021337;4888.633352498506;4890.608751299887;4955.98402327522;3828.9042773930873;3885.4948856648457;3887.7667339959694;3905.453236546886;3967.3652215645357;1051.7064011266186;1100.6918062942389;1156.346664134761;1170.777552265067;1197.2995797458411;634.8920683483819;829.8879651247125;904.9140331668481;949.6628696812533;965.3290962780941;5696.629719068247;6009.278886533644;6053.848653783691;6187.257529096537;6208.478922663976;980.3137803537552;982.1062832822098;991.494337887668;1013.4983183324301;1018.4347687033619;3924.50943632046;3935.5080614923136;4079.449274347925;4125.381192821101;4149.190907772955;10948.616798412017;11212.66362009141;11336.43317834105;11390.031847292907;11406.600768973047;10820.350778953289;11027;11286.66140015801;11288.830667539829;11296.6046188383;8466.195711407407;8524.488530688179;8583.828323433496;8621.439606425562;8639.265662418766;5926.851828704144;6022.789202377896;6026.969865150646;6058.515318774399;6183;9857.211423086279;10143.797124057624;10256.948715256376;10361.948190259;10430.739231519272;10365;10505.42220177867;10642.095421889226;10647.988441098083;10777.838332424863;7213.098362704662;7440.925590744023;7448.478606497493;7451.217622149813;7452.143003554444;8218.589070546568;8644.524551149747;8731.257843083155;8741.519216443157;8767.342449316198;6586.604803711793;7833.09919359281;7878.684852605831;7885.960570197112;7935.285824275843;7644.159142494433;7700.653470593948;7748.690052397841;7792.844143116992;7885.172056934169;7230.313120253568;7232.819179520554;7238.131424229126;7293.489455738048;7304.707811687473;4786.521347865325;4838.782254798472;4898.044881248123;5008.1235783736975;5136.178211486144;5049.84850454487;5129.230615407719;5134.484102789532;5156.484351564814;5263.36839579253 21:51:05 INFO - PROCESS | 1993 | |4;prototype.html;250.38155755284447;294.8643623932992;306.96316442026944;308.06348699952144;311.03733943445036;5243.2659427680155;5408.729563521823;5438.782448702057;5462.672239665618;5483.725813709319;5064.84805455836;5320.4679532046775;5365.248865158871;5374.758135883885;5393.759435329876;3263.624683161443;4116.34138537834;4161.563035881234;4167.791610419482;4168.645665118469;3561;3972.3247048001763;4129.719786866066;4149.958500414988;4201.957980420202;3864.555576108741;4040.9595904041025;4116.176764647068;4137.186713035135;4152.854650087247;4103.712740108193;4108.876733697994;4145.8963525912095;4157.376393540962;4172.958270417318;3122.0945925681526;3614.8192590370377;3638.417853143511;3642.3261696586214;3643.3077715234285;4934.753262336904;5231.979763946032;5236.162214045772;5268.7607766699;5370.355557333145;4776.140294746944;4792.568668819788;4793.352897358846;4803.255495398219;4811.158047341736;4731.503192164842;4762.6904251223905;5699.943000570024;5718.628289161191;5737.799177028784;3828.1195325075387;3961.4652021976954;3963.2271707017144;3969.8809035728973;4023.396490526415;5607.355154157242;5918;5948.7097467474905;5965.135055416984;5993.632515156489;3565.750397472152;3793.886183414502;4068.416555487287;4069.4099355593867;4070.61329173728;3947.388154836005;4002.5597184309704;4106.753594784323;4107.6508496777515;4141.213169497786;4732.692374995614;4736.218523943579;4748.240281554935;4752.453467851172;4809.44691360491;4824.49342819006;5068.771905264272;5713.971430142823;5733.483986441241;5744;1266.0884163402336;1292.6315999939973;1319.4260496683974;1350.709597436556;1426.5506365495044;832.5920299053541;841.8947631546057;856.648774002656;865.2472349056361;872.8865247517782;3770.4155855842387;4213.683973701924;4213.768242746678;4475.597196252353;4561.885952851205;4801.927971080367;4831.545445006837;4871.5128487151005;4879.95120048795;4947.975260123749;3781.9432708509394;4367.563243675607;4389.42262056423;4407.45200113905;4446.888827779331;2449.5835707929696;2468.9506209875703;2529.4435224250637;2553.272317389535;2564.3332733489055;4288.764117973541;4389.103191893335;4765.928511072337;4881.487443818348;5011.268858472142;4448.844290449819;4942.393722040351;4977.925331120036;5006.349174607278;5045.697258164449;4447.2439685253585;4571.725696458223;4661.603763680057;4841.530819282262;4962.21360310288;4458.509563948028;4592.540745925381;4927.649198545024;4927.8173238421805;4951.306817045688;4136.544980052252;4641.883952901205;4709.387779588632;4774.355462012584;4783.875789189468;4546.136234115507;4645.446357932191;4670.813167473263;4734.19518681818;5065.366009675022;3170.6841660710547;3275.0174947516125;3362.5957440429233;3500.8949731508096;3513.350030244413;4079.0618157823274;4879.707217566957;4899.962023545282;4911.63162762787;5418.566514678738;4606.332081848046;4963.6525443218625;5240.292560504436;5327.893442131213;5573.498385145194;4750.904981900274;5439;5712.22956865928;5743.4256574342235;5770.942290577041;4875.219964805614;5126.769295381791;5137.563307118937;5217.81737639196;5632.211490391431;5775.33583637887;5823.970880145572;5824.475797178275;5830.912536311874;5871.94128058714;4987.002599480191;5126.923346097212;5645.887082258249;5659.660420374791;5690.3456102548735 21:51:05 INFO - PROCESS | 1993 | |5;yui.html;724.1961422820668;775.2828633514005;779.4855395439012;805.770355448697;848.9448185867918;15052.774208386883;16504.42234521792;16517;16522.256498457555;16551.006939583633;14925.328360223788;15104.622384440394;15115.244237788122;15154.166520841349;15167;712.1667648850831;733.1275781819644;882.7793051737066;888.0719647967873;908.2461556907783;892.0811564088999;904.1500989070253;904.3217586809892;904.8778414914001;905.8958219804741;964.4840010594334;964.9662261820838;967.4823969176492;969.1616751509929;970.3401686852937;754.762249891288;931.9534023298876;958.9664361747306;959.6161535385831;960.6493629825098;600.7957294519884;709.4714437743862;710.6944014073946;710.8862581986909;711.1466240511355;15340.156291403968;15407.537773866801;15409.922950385288;15418;15421.768673469907;11798.056155507538;14209.715805683829;14437.278136093153;14515.419383224764;14611.853881461155;12658.177218480861;13079.934600327033;13268.73462530744;13309.534166304133;13739.106958047694;3922.8626998054933;4488.124815660947;4706.576408123286;4904.0191961607825;4907.092187945206;116120.41939790247;119313.21030184554;119581;123022;127059.7294027057;10949.343039417661;11273.830892536618;11399.772004559862;11610.6516804496;11669.008134308679;23377;23692.881535592212;23700.407489812547;23713.932873020756;23748.406289842547;7988.560629165451;8045.195480451908;8082.353411727047;8138.593070346459;8154.796130096676;4347.5217726050105;4388.385626012361;4467.173572888961;4488.438945131858;4612.3542704021465;3515.33208690348;3665.9816700916326;3671.4859919611276;3671.9265614688093;3673.1551743098703;646.6407015554151;693.1924308181013;694.0699462719879;696.4602433114377;698.7135274537416;549.8652830056582;557.2198921509919;598.1625723986367;616.4575173846986;622.5610944284268;7236.529625574321;8319.29454461846;8419.747407577783;9020.503872287087;9265.166135047879;481.0475258987225;486.5620941152991;535.1464414259328;598.8562744941182;629.2574761781142;3758.605346438639;3953.347697629859;4381.075826312391;4449.377087207794;4552.817887284472;138244;145102;151189;151577;154852.2257388706;116928.83071169349;125793.37103314608;126682;130836.69163308245;132261.3386933059;99645;118328.81671183178;119506;120516.39741801235;122702.77297226913;8198.262156405952;8317.334613231049;8547.401681882457;8863.689770857989;8882.333824963283;58776.70611646914;59491;62151.06773398312;62546;63878.68060659667;74740;101051.37909140543;101529.49235253777;102620;103736;11618.6514404568;11622.76754464889;11664.533418663159;11721.706957326134;12108.455119519467;12320.487499875984;12442.129050966345;12804.231746095264;12988.935055324662;13197.406116724578;11909.702257443632;12074;12243;12460.563880264148;12521.624351269476;12047.75904481923;12070.155089144027;12071.57749478764;12500;12535.498580056696;11732.354720490455;11855.76288474243;12124.878751212727;12258.448369823202;12416.255024698547;9703.417794932328;9826.55780489894;9948.104670579683;10027.703302094857;10285.485725713535;8550.315974722133;9859.654912078044;10013.799724005625;10036.84576273738;10052.195824334181 21:51:05 INFO - PROCESS | 1993 | __end_tp_report 21:51:05 INFO - PROCESS | 1993 | __start_cc_report 21:51:05 INFO - PROCESS | 1993 | _x_x_mozilla_cycle_collect,1513 21:51:05 INFO - PROCESS | 1993 | __end_cc_report 21:51:05 INFO - PROCESS | 1993 | __startTimestamp1473223865433__endTimestamp 21:51:05 INFO - PROCESS | 1993 | 21:51:05 INFO - PROCESS | 1993 | ------- Summary: start ------- 21:51:05 INFO - PROCESS | 1993 | Number of tests: 6 21:51:05 INFO - PROCESS | 1993 | 21:51:05 INFO - PROCESS | 1993 | [#0] dojo.html Cycles:165 Average:5795.82 Median:6114.08 stddev:622.07 (10.2%) stddev-sans-first:593.76 21:51:05 INFO - PROCESS | 1993 | Values: 3354.2 3355.7 3358.7 3389.8 3427.7 5761.1 5823.8 5829.4 5849.0 5854.7 5783.0 5837.3 5845.8 5846.0 5865.9 5188.6 5208.7 5224.4 5248.5 5340.7 5290.8 5347.7 5351.0 5356.8 5363.7 5218.4 5272.2 5272.7 5273.6 5323.1 5345.6 5357.1 5360.8 5368.2 5408.4 5105.9 5169.9 5207.7 5274.6 5279.8 5712.8 5740.2 5752.6 5765.6 5774.8 5792.3 6195.2 6199.1 6204.3 6210.3 6116.7 6117.8 6209.1 6211.7 6217.4 5896.7 5978.2 5979.8 5986.1 5989.3 6260.9 6265.1 6279.7 6284.2 6295.6 6114.1 6120.2 6214.3 6216.3 6233.2 5221.5 6206.3 6215.4 6217.2 6226.4 6134.3 6176.1 6195.5 6209.7 6252.2 6116.6 6146.6 6178.9 6222.9 6229.0 4965.1 4980.1 4998.5 5021.7 5022.4 4482.8 4496.6 4500.7 4540.7 4571.1 5837.8 5841.6 5852.9 5854.6 5901.0 6114.8 6188.1 6190.1 6208.1 6208.3 5750.4 5751.7 5792.1 5795.5 5848.1 5497.2 5511.0 5520.1 5541.3 5542.2 6400.6 6403.5 6409.3 6428.9 6435.8 6139.5 6215.2 6225.5 6226.2 6236.6 6230.0 6268.3 6300.3 6382.8 6392.8 6191.5 6223.7 6230.2 6231.6 6238.8 6139.6 6182.4 6201.4 6209.8 6220.2 6108.9 6129.8 6165.8 6208.9 6226.9 6177.9 6181.2 6195.5 6196.9 6213.8 5912.7 6108.5 6116.2 6119.3 6123.1 6110.7 6130.6 6194.2 6238.5 6240.3 5952.1 6129.6 6136.0 6139.6 6139.7 21:51:05 INFO - PROCESS | 1993 | 21:51:05 INFO - PROCESS | 1993 | [#1] ext.html Cycles:180 Average:75542.34 Median:36315.73 stddev:176217.60 (485.2%) stddev-sans-first:176621.87 21:51:05 INFO - PROCESS | 1993 | Values: 502.2 507.3 526.2 541.9 551.6 30820.5 31435.4 32912.0 32983.2 33012.0 42542.0 44083.8 44219.8 46858.0 46995.3 9711.8 9854.7 9870.2 9874.8 9876.5 8638.7 9472.2 9565.1 9572.7 9582.0 8700.5 11714.7 11824.1 11828.6 12107.3 11741.5 11853.5 11855.1 11857.3 11900.4 11779.8 11863.2 11869.3 11873.6 11895.1 41772.8 42083.4 42099.0 42124.2 42191.8 3372.3 3440.6 3539.5 3543.8 3661.5 32324.7 32577.2 32964.0 33284.8 33560.3 2478.0 3214.5 3238.5 3258.8 3266.0 450240.0 453583.7 453872.0 456981.0 457287.0 33153.2 33632.8 64758.0 65384.7 65412.0 35672.5 36252.0 36272.1 36295.8 36335.6 15442.0 15608.2 15619.5 15628.7 15663.5 13039.6 13061.0 13137.7 13167.1 13173.7 6738.9 7389.0 7542.2 7724.5 8035.1 2957.9 3180.9 3234.1 3264.4 3286.7 923.8 939.2 1041.9 1085.3 1114.0 29757.0 30326.7 30616.2 30735.1 31354.4 984.8 1075.0 1075.9 1179.2 1184.5 14395.7 15215.6 15482.8 15908.9 15961.8 645422.0 652028.0 652262.0 653443.0 653532.0 52516.7 52843.7 59467.0 59622.7 59885.1 1407.3 1432.4 1448.2 1448.6 1458.2 29840.3 31408.8 31453.5 31876.0 32178.8 52541.2 53565.0 53724.2 53772.0 53853.7 707701.0 723035.0 723208.0 929314.0 930864.0 45611.1 45981.3 46193.8 46892.8 46918.1 49252.8 49667.0 49690.5 49894.0 50269.5 39759.8 40235.4 41251.8 41272.2 42312.6 39934.4 40066.8 40387.0 41108.0 42366.0 39146.0 40060.0 40097.8 40102.6 40270.6 34301.1 35551.0 35854.1 36172.5 36256.8 20332.2 25757.0 25943.5 26213.1 26391.3 21:51:05 INFO - PROCESS | 1993 | 21:51:05 INFO - PROCESS | 1993 | [#2] jquery.html Cycles:180 Average:34945.17 Median:26900.33 stddev:98453.20 (366.0%) stddev-sans-first:98695.12 21:51:05 INFO - PROCESS | 1993 | Values: 349.9 372.9 376.0 385.8 405.5 15641.9 15860.1 20341.1 20744.6 20916.8 19641.9 20236.2 20282.0 20674.4 21701.2 5254.8 5890.8 6076.9 6151.5 6151.6 5788.2 6084.7 6106.1 6111.1 6117.8 5852.8 5988.6 6039.5 6056.8 6097.2 6002.8 6025.7 6046.4 6061.7 6070.0 5113.4 5209.8 5265.9 5280.8 5283.1 24827.6 26586.2 26600.5 26772.5 27028.2 2097.6 2231.6 2393.2 2457.2 2850.2 9081.6 10537.0 10628.1 10642.0 10686.1 1452.8 1794.6 1812.9 1836.8 1892.8 106809.0 111657.0 114779.9 115603.0 121719.0 9337.5 11153.5 11319.3 11664.6 11797.7 14747.2 14765.4 14770.6 14779.6 14787.6 8707.5 8836.1 8866.0 8867.4 8991.9 8811.6 8819.3 8842.1 8978.8 8980.1 4000.1 4362.6 4559.2 4587.3 4595.2 1896.5 1932.2 1940.8 1967.8 1969.5 713.6 725.8 795.5 1003.0 1052.7 17070.7 21462.7 21700.3 21703.2 21828.5 952.1 956.2 965.1 972.6 978.1 11209.9 11324.4 11375.4 11375.9 11439.4 569251.0 583087.0 589522.0 600137.0 601410.0 126672.4 128010.7 129263.0 134295.0 136662.0 988.3 990.2 1021.5 1023.3 1024.1 18542.3 18736.4 18998.4 19033.2 19385.0 55502.0 57089.0 57295.0 57497.0 57687.7 81313.2 82460.6 83109.0 83455.7 83681.6 6465.6 7885.2 7968.8 8088.7 8402.5 12961.8 13181.9 13224.7 13226.3 13827.0 5514.7 9307.5 11294.5 11816.7 11875.3 9374.8 11008.7 11358.4 11443.5 11733.5 5856.4 6458.8 10912.2 11618.5 12008.2 8280.3 8287.8 8315.5 8330.8 8375.0 11026.3 11254.9 11332.5 11348.5 11544.2 21:51:05 INFO - PROCESS | 1993 | 21:51:05 INFO - PROCESS | 1993 | [#3] mootools.html Cycles:180 Average:5855.59 Median:4833.91 stddev:3327.15 (68.8%) stddev-sans-first:3320.80 21:51:05 INFO - PROCESS | 1993 | Values: 1557.2 1598.2 1821.7 1923.9 1964.1 8628.5 9141.7 9145.9 9157.0 9166.3 7960.2 8211.1 8659.2 8903.7 8925.7 2377.3 2404.3 2407.7 2408.1 2415.9 1933.0 2386.0 2387.7 2415.2 2415.4 2110.5 2183.3 2328.2 2399.6 2403.0 2378.3 2379.8 2406.8 2407.2 2411.9 2388.1 2388.5 2396.3 2397.6 2398.5 9046.0 9113.6 9124.0 9135.2 9143.0 5145.5 5299.0 5304.3 5341.8 5351.2 3499.7 3591.9 3707.6 3710.3 3711.4 1053.1 1073.1 1084.7 1085.9 1091.8 11845.8 11975.6 11980.6 11993.3 12000.0 4595.4 4665.8 4831.5 4836.3 4838.0 10076.3 10079.0 10079.4 10087.4 10101.6 5334.4 5338.2 5347.7 5347.8 5365.2 4816.9 4880.1 4888.6 4890.6 4956.0 3828.9 3885.5 3887.8 3905.5 3967.4 1051.7 1100.7 1156.3 1170.8 1197.3 634.9 829.9 904.9 949.7 965.3 5696.6 6009.3 6053.8 6187.3 6208.5 980.3 982.1 991.5 1013.5 1018.4 3924.5 3935.5 4079.4 4125.4 4149.2 10948.6 11212.7 11336.4 11390.0 11406.6 10820.4 11027.0 11286.7 11288.8 11296.6 8466.2 8524.5 8583.8 8621.4 8639.3 5926.9 6022.8 6027.0 6058.5 6183.0 9857.2 10143.8 10256.9 10361.9 10430.7 10365.0 10505.4 10642.1 10648.0 10777.8 7213.1 7440.9 7448.5 7451.2 7452.1 8218.6 8644.5 8731.3 8741.5 8767.3 6586.6 7833.1 7878.7 7886.0 7935.3 7644.2 7700.7 7748.7 7792.8 7885.2 7230.3 7232.8 7238.1 7293.5 7304.7 4786.5 4838.8 4898.0 5008.1 5136.2 5049.8 5129.2 5134.5 5156.5 5263.4 21:51:05 INFO - PROCESS | 1993 | 21:51:05 INFO - PROCESS | 1993 | [#4] prototype.html Cycles:180 Average:4326.63 Median:4749.57 stddev:1298.38 (27.3%) stddev-sans-first:1265.46 21:51:05 INFO - PROCESS | 1993 | Values: 250.4 294.9 307.0 308.1 311.0 5243.3 5408.7 5438.8 5462.7 5483.7 5064.8 5320.5 5365.2 5374.8 5393.8 3263.6 4116.3 4161.6 4167.8 4168.6 3561.0 3972.3 4129.7 4150.0 4202.0 3864.6 4041.0 4116.2 4137.2 4152.9 4103.7 4108.9 4145.9 4157.4 4173.0 3122.1 3614.8 3638.4 3642.3 3643.3 4934.8 5232.0 5236.2 5268.8 5370.4 4776.1 4792.6 4793.4 4803.3 4811.2 4731.5 4762.7 5699.9 5718.6 5737.8 3828.1 3961.5 3963.2 3969.9 4023.4 5607.4 5918.0 5948.7 5965.1 5993.6 3565.8 3793.9 4068.4 4069.4 4070.6 3947.4 4002.6 4106.8 4107.7 4141.2 4732.7 4736.2 4748.2 4752.5 4809.4 4824.5 5068.8 5714.0 5733.5 5744.0 1266.1 1292.6 1319.4 1350.7 1426.6 832.6 841.9 856.6 865.2 872.9 3770.4 4213.7 4213.8 4475.6 4561.9 4801.9 4831.5 4871.5 4880.0 4948.0 3781.9 4367.6 4389.4 4407.5 4446.9 2449.6 2469.0 2529.4 2553.3 2564.3 4288.8 4389.1 4765.9 4881.5 5011.3 4448.8 4942.4 4977.9 5006.3 5045.7 4447.2 4571.7 4661.6 4841.5 4962.2 4458.5 4592.5 4927.6 4927.8 4951.3 4136.5 4641.9 4709.4 4774.4 4783.9 4546.1 4645.4 4670.8 4734.2 5065.4 3170.7 3275.0 3362.6 3500.9 3513.4 4079.1 4879.7 4900.0 4911.6 5418.6 4606.3 4963.7 5240.3 5327.9 5573.5 4750.9 5439.0 5712.2 5743.4 5770.9 4875.2 5126.8 5137.6 5217.8 5632.2 5775.3 5824.0 5824.5 5830.9 5871.9 4987.0 5126.9 5645.9 5659.7 5690.3 21:51:05 INFO - PROCESS | 1993 | 21:51:05 INFO - PROCESS | 1993 | [#5] yui.html Cycles:180 Average:25234.98 Median:3671.71 stddev:40796.86 (1111.1%) stddev-sans-first:40869.80 21:51:05 INFO - PROCESS | 1993 | Values: 724.2 775.3 779.5 805.8 848.9 15052.8 16504.4 16517.0 16522.3 16551.0 14925.3 15104.6 15115.2 15154.2 15167.0 712.2 733.1 882.8 888.1 908.2 892.1 904.2 904.3 904.9 905.9 964.5 965.0 967.5 969.2 970.3 754.8 932.0 959.0 959.6 960.6 600.8 709.5 710.7 710.9 711.1 15340.2 15407.5 15409.9 15418.0 15421.8 11798.1 14209.7 14437.3 14515.4 14611.9 12658.2 13079.9 13268.7 13309.5 13739.1 3922.9 4488.1 4706.6 4904.0 4907.1 116120.4 119313.2 119581.0 123022.0 127059.7 10949.3 11273.8 11399.8 11610.7 11669.0 23377.0 23692.9 23700.4 23713.9 23748.4 7988.6 8045.2 8082.4 8138.6 8154.8 4347.5 4388.4 4467.2 4488.4 4612.4 3515.3 3666.0 3671.5 3671.9 3673.2 646.6 693.2 694.1 696.5 698.7 549.9 557.2 598.2 616.5 622.6 7236.5 8319.3 8419.7 9020.5 9265.2 481.0 486.6 535.1 598.9 629.3 3758.6 3953.3 4381.1 4449.4 4552.8 138244.0 145102.0 151189.0 151577.0 154852.2 116928.8 125793.4 126682.0 130836.7 132261.3 99645.0 118328.8 119506.0 120516.4 122702.8 8198.3 8317.3 8547.4 8863.7 8882.3 58776.7 59491.0 62151.1 62546.0 63878.7 74740.0 101051.4 101529.5 102620.0 103736.0 11618.7 11622.8 11664.5 11721.7 12108.5 12320.5 12442.1 12804.2 12988.9 13197.4 11909.7 12074.0 12243.0 12460.6 12521.6 12047.8 12070.2 12071.6 12500.0 12535.5 11732.4 11855.8 12124.9 12258.4 12416.3 9703.4 9826.6 9948.1 10027.7 10285.5 8550.3 9859.7 10013.8 10036.8 10052.2 21:51:05 INFO - PROCESS | 1993 | -------- Summary: end -------- 21:51:05 INFO - PROCESS | 1993 | 21:51:06 INFO - TEST-INFO | 1993: exit 0 21:51:06 INFO - TEST-OK | dromaeo_css | took 1116786ms 21:51:06 INFO - TEST-START | kraken 21:51:06 INFO - operating with platform_type : mac_ 21:51:06 INFO - Initialising browser for kraken test... 21:51:06 INFO - TEST-INFO | started process 1996 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/tmpumTpOp/profile http://localhost:51378/getInfo.html) 21:51:08 INFO - PROCESS | 1996 | __metrics Screen width/height:1600/1200 21:51:08 INFO - PROCESS | 1996 | colorDepth:24 21:51:08 INFO - PROCESS | 1996 | Browser inner width/height: 1024/689 21:51:08 INFO - PROCESS | 1996 | __metrics 21:51:08 INFO - PROCESS | 1996 | 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" 21:51:08 INFO - PROCESS | 1996 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:51:08 INFO - PROCESS | 1996 | ************************* 21:51:08 INFO - PROCESS | 1996 | A coding exception was thrown in a Promise resolution callback. 21:51:08 INFO - PROCESS | 1996 | See https://developer.mozilla.org/Mozilla/JavaScript_code_modules/Promise.jsm/Promise 21:51:08 INFO - PROCESS | 1996 | 21:51:08 ERROR - PROCESS | 1996 | Full message: TypeError: win is null 21:51:08 INFO - PROCESS | 1996 | Full stack: onSuccess@resource://app/components/nsBrowserGlue.js:844:17 21:51:08 INFO - PROCESS | 1996 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 21:51:08 INFO - PROCESS | 1996 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 21:51:08 INFO - PROCESS | 1996 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 21:51:08 INFO - PROCESS | 1996 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 21:51:08 INFO - PROCESS | 1996 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 21:51:08 INFO - PROCESS | 1996 | get _worker/worker.onmessage@resource://gre/modules/PromiseWorker.jsm:235:9 21:51:08 INFO - PROCESS | 1996 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 21:51:08 INFO - PROCESS | 1996 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 21:51:08 INFO - PROCESS | 1996 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 21:51:08 INFO - PROCESS | 1996 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 21:51:08 INFO - PROCESS | 1996 | Barrier/this.client.addBlocker/promise53.5% 21:52:05 INFO - TinderboxPrint: I/O read bytes / time
831,488 / 9 21:52:05 INFO - TinderboxPrint: I/O write bytes / time
663,858,176 / 1,882 21:52:05 INFO - TinderboxPrint: CPU idle
2,249.3 (46.5%) 21:52:05 INFO - TinderboxPrint: CPU system
968.3 (20.0%) 21:52:05 INFO - TinderboxPrint: CPU user
1,620.0 (33.5%) 21:52:05 INFO - TinderboxPrint: Swap in / out
365,252,608 / 0 21:52:05 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 352795136; Write bytes: 348796928; Read time: 27414; Write time: 741 21:52:05 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 831488; Write bytes: 295126016; Read time: 9; Write time: 1095 21:52:05 INFO - Running post-run listener: _upload_blobber_files 21:52:05 INFO - Blob upload gear active. 21:52:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:52:05 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 21:52:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:52:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:52:06 INFO - (blobuploader) - INFO - Open directory for files ... 21:52:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:52:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:52:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:52:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:52:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:52:07 INFO - (blobuploader) - INFO - Done attempting. 21:52:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:52:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:52:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:52:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:52:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:52:08 INFO - (blobuploader) - INFO - Done attempting. 21:52:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:52:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:52:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:52:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:52:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:52:10 INFO - (blobuploader) - INFO - Done attempting. 21:52:10 INFO - (blobuploader) - INFO - Iteration through files over. 21:52:10 INFO - Return code: 0 21:52:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:52:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:52:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9bdc3bee282ad82dfc3f3ef41b02ba3291083490e5607721654d4a93a6c6adb144da95fa950946374845a485b0938fd4de181ce4d82619b4913295a1a60bceb2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7996d0c23b244704f1069348ba6b8ee229fd7b48a51424306bb47339cd9e0be1996cb08de6e837ebb9503609af8091b98f228836bc44f4ae47ebda8687992abc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1df2ddc42e371d1f90789d9eddda927dd8a7d64920d8abf42e56688c6f6bc8928aadcce70a21f469a6885b16a017f54d046938525c1c11c87f3cdbe654db9849"} 21:52:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:52:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:52:10 INFO - Contents: 21:52:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9bdc3bee282ad82dfc3f3ef41b02ba3291083490e5607721654d4a93a6c6adb144da95fa950946374845a485b0938fd4de181ce4d82619b4913295a1a60bceb2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7996d0c23b244704f1069348ba6b8ee229fd7b48a51424306bb47339cd9e0be1996cb08de6e837ebb9503609af8091b98f228836bc44f4ae47ebda8687992abc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1df2ddc42e371d1f90789d9eddda927dd8a7d64920d8abf42e56688c6f6bc8928aadcce70a21f469a6885b16a017f54d046938525c1c11c87f3cdbe654db9849"} 21:52:10 INFO - Running post-run listener: copy_logs_to_upload_dir 21:52:10 INFO - Copying logs to upload dir... 21:52:10 INFO - mkdir: /builds/slave/test/build/upload/logs 21:52:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1713.249313 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 33 secs) (at 2016-09-06 21:52:10.645848) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 21:52:10.649785) ========= 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.Q1ZM3yaTEZ/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.YGeId9yQgj/Listeners TMPDIR=/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9bdc3bee282ad82dfc3f3ef41b02ba3291083490e5607721654d4a93a6c6adb144da95fa950946374845a485b0938fd4de181ce4d82619b4913295a1a60bceb2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7996d0c23b244704f1069348ba6b8ee229fd7b48a51424306bb47339cd9e0be1996cb08de6e837ebb9503609af8091b98f228836bc44f4ae47ebda8687992abc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1df2ddc42e371d1f90789d9eddda927dd8a7d64920d8abf42e56688c6f6bc8928aadcce70a21f469a6885b16a017f54d046938525c1c11c87f3cdbe654db9849"} build_url:https://queue.taskcluster.net/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010806 build_url: 'https://queue.taskcluster.net/v1/task/RSzxmuD5RvqHOIOVOqn75Q/artifacts/public/build/firefox-50.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9bdc3bee282ad82dfc3f3ef41b02ba3291083490e5607721654d4a93a6c6adb144da95fa950946374845a485b0938fd4de181ce4d82619b4913295a1a60bceb2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7996d0c23b244704f1069348ba6b8ee229fd7b48a51424306bb47339cd9e0be1996cb08de6e837ebb9503609af8091b98f228836bc44f4ae47ebda8687992abc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1df2ddc42e371d1f90789d9eddda927dd8a7d64920d8abf42e56688c6f6bc8928aadcce70a21f469a6885b16a017f54d046938525c1c11c87f3cdbe654db9849"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 21:52:10.682225) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 21:52:10.685673) ========= 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.Q1ZM3yaTEZ/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.YGeId9yQgj/Listeners TMPDIR=/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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.005254 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 21:52:10.737463) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 21:52:10.737774) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 21:52:10.738107) ========= ========= Total master_lag: 0.20 =========