builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0055 starttime: 1472499781.24 results: success (0) buildid: 20160829102231 builduid: 22fe23d2699145c5873ec15d49d44bb4 revision: 350ef810694c3704431f65c91bd83b9f2e933622 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 12:43:01.235810) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 12:43:01.236240) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-29 12:43:01.236519) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-29 12:43:01.259764) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 12:43:01.260041) ========= 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.y2gnHV7Bhx/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.lkWpvolxMM/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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-08-29 12:43:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.65M=0.002s 2016-08-29 12:43:01 (7.65 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.091273 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 12:43:01.373469) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 12:43:01.373790) ========= 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.y2gnHV7Bhx/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.lkWpvolxMM/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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.021034 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 12:43:01.422287) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-08-29 12:43:01.422612) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 350ef810694c3704431f65c91bd83b9f2e933622 --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 350ef810694c3704431f65c91bd83b9f2e933622 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.y2gnHV7Bhx/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.lkWpvolxMM/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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-08-29 12:43:01,501 truncating revision to first 12 chars 2016-08-29 12:43:01,502 Setting DEBUG logging. 2016-08-29 12:43:01,502 attempt 1/10 2016-08-29 12:43:01,502 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/350ef810694c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-29 12:43:02,222 unpacking tar archive at: mozilla-aurora-350ef810694c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.972193 ========= master_lag: 3.36 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-08-29 12:43:05.756035) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 12:43:05.756355) ========= script_repo_revision: 350ef810694c3704431f65c91bd83b9f2e933622 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 12:43:05.756767) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 12:43:05.757034) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 12:43:05.777510) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 37 secs) (at 2016-08-29 12:43:05.777808) ========= /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.y2gnHV7Bhx/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.lkWpvolxMM/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 12:43:05 INFO - MultiFileLogger online at 20160829 12:43:05 in /builds/slave/test 12:43:05 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 12:43:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:43:05 INFO - {'append_to_log': False, 12:43:05 INFO - 'base_work_dir': '/builds/slave/test', 12:43:05 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 12:43:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:43:05 INFO - 'branch': 'Mozilla-Aurora', 12:43:05 INFO - 'buildbot_json_path': 'buildprops.json', 12:43:05 INFO - 'config_files': ('talos/mac_config.py',), 12:43:05 INFO - 'default_actions': ('clobber', 12:43:05 INFO - 'read-buildbot-config', 12:43:05 INFO - 'download-and-extract', 12:43:05 INFO - 'populate-webroot', 12:43:05 INFO - 'create-virtualenv', 12:43:05 INFO - 'install', 12:43:05 INFO - 'run-tests'), 12:43:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:43:05 INFO - 'download_minidump_stackwalk': True, 12:43:05 INFO - 'download_symbols': 'ondemand', 12:43:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:43:05 INFO - 'tooltool.py': '/tools/tooltool.py', 12:43:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:43:05 INFO - '/tools/misc-python/virtualenv.py')}, 12:43:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:43:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:43:05 INFO - 'installer_path': 'installer.exe', 12:43:05 INFO - 'log_level': 'info', 12:43:05 INFO - 'log_name': 'talos', 12:43:05 INFO - 'log_to_console': True, 12:43:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:43:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:43:05 INFO - 'opt_config_files': (), 12:43:05 INFO - 'pip_index': False, 12:43:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:43:05 INFO - 'cmd': ('bash', 12:43:05 INFO - '-c', 12:43:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:43:05 INFO - 'enabled': True, 12:43:05 INFO - 'halt_on_failure': False, 12:43:05 INFO - 'name': 'check_screen_resolution'},), 12:43:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:43:05 INFO - 'cmd': ('bash', 12:43:05 INFO - '-c', 12:43:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:43:05 INFO - 'enabled': True, 12:43:05 INFO - 'halt_on_failure': False, 12:43:05 INFO - 'name': 'check_screen_resolution'},), 12:43:05 INFO - 'run_cmd_checks_enabled': True, 12:43:05 INFO - 'sps_profile': False, 12:43:05 INFO - 'sps_profile_interval': 0, 12:43:05 INFO - 'suite': 'dromaeojs', 12:43:05 INFO - 'system_bits': '32', 12:43:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:43:05 INFO - 'title': 't-yosemite-r7-0055', 12:43:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:43:05 INFO - 'use_talos_json': True, 12:43:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:43:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:43:05 INFO - 'work_dir': 'build'} 12:43:05 INFO - [mozharness: 2016-08-29 19:43:05.970747Z] Running clobber step. 12:43:05 INFO - Running pre-action listener: _resource_record_pre_action 12:43:05 INFO - Running main action method: clobber 12:43:05 INFO - rmtree: /builds/slave/test/build 12:43:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:43:07 INFO - Running post-action listener: _resource_record_post_action 12:43:07 INFO - [mozharness: 2016-08-29 19:43:07.533792Z] Finished clobber step (success) 12:43:07 INFO - [mozharness: 2016-08-29 19:43:07.533920Z] Running read-buildbot-config step. 12:43:07 INFO - Running pre-action listener: _resource_record_pre_action 12:43:07 INFO - Running main action method: read_buildbot_config 12:43:07 INFO - Using buildbot properties: 12:43:07 INFO - { 12:43:07 INFO - "project": "", 12:43:07 INFO - "product": "firefox", 12:43:07 INFO - "script_repo_revision": "production", 12:43:07 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 12:43:07 INFO - "repository": "", 12:43:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 12:43:07 INFO - "buildid": "20160829102231", 12:43:07 INFO - "pgo_build": "False", 12:43:07 INFO - "basedir": "/builds/slave/test", 12:43:07 INFO - "buildnumber": 26, 12:43:07 INFO - "slavename": "t-yosemite-r7-0055", 12:43:07 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 12:43:07 INFO - "platform": "yosemite_r7", 12:43:07 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 12:43:07 INFO - "branch": "mozilla-aurora", 12:43:07 INFO - "repo_path": "releases/mozilla-aurora", 12:43:07 INFO - "revision": "350ef810694c3704431f65c91bd83b9f2e933622", 12:43:07 INFO - "stage_platform": "macosx64", 12:43:07 INFO - "builduid": "22fe23d2699145c5873ec15d49d44bb4", 12:43:07 INFO - "slavebuilddir": "test" 12:43:07 INFO - } 12:43:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.dmg. 12:43:07 INFO - Running post-action listener: _resource_record_post_action 12:43:07 INFO - [mozharness: 2016-08-29 19:43:07.537432Z] Finished read-buildbot-config step (success) 12:43:07 INFO - [mozharness: 2016-08-29 19:43:07.537557Z] Running download-and-extract step. 12:43:07 INFO - Running pre-action listener: _resource_record_pre_action 12:43:07 INFO - Running main action method: download_and_extract 12:43:07 INFO - mkdir: /builds/slave/test/build/tests 12:43:07 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')]} 12:43:07 INFO - https://queue.taskcluster.net/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 12:43:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.test_packages.json 12:43:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.test_packages.json 12:43:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/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 12:43:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/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 12:43:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.test_packages.json 12:43:08 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 12:43:08 INFO - retry: Failed, sleeping 30 seconds before retrying 12:43:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/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 12:43:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.test_packages.json 12:43:38 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 12:43:38 INFO - retry: Failed, sleeping 60 seconds before retrying 12:44:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/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 12:44:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.test_packages.json 12:44:38 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 12:44:38 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/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! 12:44:38 INFO - Caught exception: HTTP Error 404: Not Found 12:44:38 INFO - Caught exception: HTTP Error 404: Not Found 12:44:38 INFO - Caught exception: HTTP Error 404: Not Found 12:44:38 INFO - trying https://queue.taskcluster.net/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.test_packages.json 12:44:38 INFO - Downloading https://queue.taskcluster.net/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/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 12:44:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/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 12:44:39 INFO - Downloaded 1199 bytes. 12:44:39 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.mac.test_packages.json 12:44:39 INFO - Using the following test package requirements: 12:44:39 INFO - {u'common': [u'firefox-50.0a2.en-US.mac.common.tests.zip'], 12:44:39 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 12:44:39 INFO - u'firefox-50.0a2.en-US.mac.cppunittest.tests.zip'], 12:44:39 INFO - u'gtest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 12:44:39 INFO - u'firefox-50.0a2.en-US.mac.gtest.tests.zip'], 12:44:39 INFO - u'jittest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 12:44:39 INFO - u'jsshell-mac.zip'], 12:44:39 INFO - u'mochitest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 12:44:39 INFO - u'firefox-50.0a2.en-US.mac.mochitest.tests.zip'], 12:44:39 INFO - u'mozbase': [u'firefox-50.0a2.en-US.mac.common.tests.zip'], 12:44:39 INFO - u'reftest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 12:44:39 INFO - u'firefox-50.0a2.en-US.mac.reftest.tests.zip'], 12:44:39 INFO - u'talos': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 12:44:39 INFO - u'firefox-50.0a2.en-US.mac.talos.tests.zip'], 12:44:39 INFO - u'web-platform': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 12:44:39 INFO - u'firefox-50.0a2.en-US.mac.web-platform.tests.zip'], 12:44:39 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 12:44:39 INFO - u'firefox-50.0a2.en-US.mac.xpcshell.tests.zip']} 12:44:39 INFO - Downloading packages: [u'firefox-50.0a2.en-US.mac.common.tests.zip'] for test suite category: common 12:44:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:44:39 INFO - https://queue.taskcluster.net/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:44:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip 12:44:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip 12:44:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/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 12:44:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/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 12:44:39 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip 12:44:39 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 12:44:39 INFO - retry: Failed, sleeping 30 seconds before retrying 12:45:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/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 12:45:09 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip 12:45:09 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 12:45:09 INFO - retry: Failed, sleeping 60 seconds before retrying 12:46:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/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 12:46:09 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip 12:46:09 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 12:46:09 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/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! 12:46:09 INFO - Caught exception: HTTP Error 404: Not Found 12:46:09 INFO - Caught exception: HTTP Error 404: Not Found 12:46:09 INFO - Caught exception: HTTP Error 404: Not Found 12:46:09 INFO - trying https://queue.taskcluster.net/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip 12:46:09 INFO - Downloading https://queue.taskcluster.net/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/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 12:46:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/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 12:46:10 INFO - Downloaded 16308117 bytes. 12:46:10 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'] 12:46:10 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 12:46:13 INFO - Return code: 0 12:46:13 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 12:46:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:46:13 INFO - https://queue.taskcluster.net/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:46:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip 12:46:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip 12:46:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/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 12:46:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/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 12:46:13 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip 12:46:13 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 12:46:13 INFO - retry: Failed, sleeping 30 seconds before retrying 12:46:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/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 12:46:43 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip 12:46:43 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 12:46:43 INFO - retry: Failed, sleeping 60 seconds before retrying 12:47:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/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 12:47:44 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip 12:47:44 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 12:47:44 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/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! 12:47:44 INFO - Caught exception: HTTP Error 404: Not Found 12:47:44 INFO - Caught exception: HTTP Error 404: Not Found 12:47:44 INFO - Caught exception: HTTP Error 404: Not Found 12:47:44 INFO - trying https://queue.taskcluster.net/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.common.tests.zip 12:47:44 INFO - Downloading https://queue.taskcluster.net/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/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 12:47:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/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 12:47:46 INFO - Downloaded 16308117 bytes. 12:47:46 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'] 12:47:46 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 12:47:49 INFO - Return code: 0 12:47: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')]} 12:47:49 INFO - https://queue.taskcluster.net/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:47:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.talos.tests.zip 12:47:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.talos.tests.zip 12:47:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/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 12:47:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/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 12:47:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.talos.tests.zip 12:47:49 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 12:47:49 INFO - retry: Failed, sleeping 30 seconds before retrying 12:48:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/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 12:48:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.talos.tests.zip 12:48:19 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 12:48:19 INFO - retry: Failed, sleeping 60 seconds before retrying 12:49:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/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 12:49:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.talos.tests.zip 12:49:20 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 12:49:20 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/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! 12:49:20 INFO - Caught exception: HTTP Error 404: Not Found 12:49:20 INFO - Caught exception: HTTP Error 404: Not Found 12:49:20 INFO - Caught exception: HTTP Error 404: Not Found 12:49:20 INFO - trying https://queue.taskcluster.net/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.talos.tests.zip 12:49:20 INFO - Downloading https://queue.taskcluster.net/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/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 12:49:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/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 12:49:21 INFO - Downloaded 14413723 bytes. 12:49:21 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'] 12:49:21 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 12:49:21 INFO - Return code: 0 12:49:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:49:21 INFO - https://queue.taskcluster.net/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 12:49:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.dmg 12:49:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.dmg 12:49:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a2.en-US.mac.dmg 12:49:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/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 12:49:21 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.dmg 12:49:21 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 12:49:21 INFO - retry: Failed, sleeping 30 seconds before retrying 12:49:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/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 12:49:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.dmg 12:49:51 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 12:49:51 INFO - retry: Failed, sleeping 60 seconds before retrying 12:50:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/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 12:50:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.dmg 12:50:51 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 12:50:51 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a2.en-US.mac.dmg! 12:50:51 INFO - Caught exception: HTTP Error 404: Not Found 12:50:51 INFO - Caught exception: HTTP Error 404: Not Found 12:50:51 INFO - Caught exception: HTTP Error 404: Not Found 12:50:51 INFO - trying https://queue.taskcluster.net/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.dmg 12:50:51 INFO - Downloading https://queue.taskcluster.net/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a2.en-US.mac.dmg 12:50:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/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 12:50:55 INFO - Downloaded 115327500 bytes. 12:50:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.dmg 12:50:55 INFO - mkdir: /builds/slave/test/properties 12:50:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:50:55 INFO - Writing to file /builds/slave/test/properties/build_url 12:50:55 INFO - Contents: 12:50:55 INFO - build_url:https://queue.taskcluster.net/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.dmg 12:50:55 INFO - Running post-action listener: _resource_record_post_action 12:50:55 INFO - Running post-action listener: set_extra_try_arguments 12:50:55 INFO - [mozharness: 2016-08-29 19:50:55.824336Z] Finished download-and-extract step (success) 12:50:55 INFO - [mozharness: 2016-08-29 19:50:55.824489Z] Running populate-webroot step. 12:50:55 INFO - Running pre-action listener: _resource_record_pre_action 12:50:55 INFO - Running main action method: populate_webroot 12:50:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:50:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:50:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:50:55 INFO - '--tppagecycles', 12:50:55 INFO - '7'], 12:50:55 INFO - 'tests': ['tsvgm']}}, 12:50:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:50:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:50:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:50:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:50:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:50:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:50:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:50:55 INFO - 'talos_options': ['--disable-e10s'], 12:50:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:50:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:50:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:50:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:50:55 INFO - 'talos_options': ['--disable-e10s'], 12:50:55 INFO - 'tests': ['damp', 'tps']}, 12:50:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:50:55 INFO - 'tests': ['damp', 'tps']}, 12:50:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:50:55 INFO - 'tests': ['dromaeo_dom']}, 12:50:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:50:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:50:55 INFO - 'tests': ['basic_compositor_video']}, 12:50:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:50:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:50:55 INFO - 'tests': ['a11yr', 12:50:55 INFO - 'ts_paint', 12:50:55 INFO - 'tpaint', 12:50:55 INFO - 'sessionrestore', 12:50:55 INFO - 'sessionrestore_no_auto_restore', 12:50:55 INFO - 'tabpaint']}, 12:50:55 INFO - 'other-e10s': {'tests': ['a11yr', 12:50:55 INFO - 'ts_paint', 12:50:55 INFO - 'tpaint', 12:50:55 INFO - 'sessionrestore', 12:50:55 INFO - 'sessionrestore_no_auto_restore', 12:50:55 INFO - 'tabpaint']}, 12:50:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:50:55 INFO - 'ts_paint', 12:50:55 INFO - 'tpaint', 12:50:55 INFO - 'sessionrestore', 12:50:55 INFO - 'sessionrestore_no_auto_restore', 12:50:55 INFO - 'tabpaint']}, 12:50:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:50:55 INFO - 'ts_paint', 12:50:55 INFO - 'tpaint', 12:50:55 INFO - 'sessionrestore', 12:50:55 INFO - 'sessionrestore_no_auto_restore', 12:50:55 INFO - 'tabpaint']}, 12:50:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:50:55 INFO - 'tests': ['a11yr', 12:50:55 INFO - 'ts_paint', 12:50:55 INFO - 'tpaint', 12:50:55 INFO - 'sessionrestore', 12:50:55 INFO - 'sessionrestore_no_auto_restore', 12:50:55 INFO - 'tabpaint']}, 12:50:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:50:55 INFO - 'tests': ['a11yr', 12:50:55 INFO - 'ts_paint', 12:50:55 INFO - 'tpaint', 12:50:55 INFO - 'sessionrestore', 12:50:55 INFO - 'sessionrestore_no_auto_restore', 12:50:55 INFO - 'tabpaint']}, 12:50:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:50:55 INFO - 'tests': ['tsvgx', 12:50:55 INFO - 'tsvgr_opacity', 12:50:55 INFO - 'tart', 12:50:55 INFO - 'tscrollx', 12:50:55 INFO - 'cart']}, 12:50:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:50:55 INFO - 'tsvgr_opacity', 12:50:55 INFO - 'tart', 12:50:55 INFO - 'tscrollx', 12:50:55 INFO - 'cart']}, 12:50:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:50:55 INFO - 'talos_options': ['--disable-e10s'], 12:50:55 INFO - 'tests': ['tp5o']}, 12:50:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:50:55 INFO - 'tests': ['tp5o']}, 12:50:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:50:55 INFO - 'talos_options': ['--disable-e10s', 12:50:55 INFO - '--xperf_path', 12:50:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:50:55 INFO - 'tests': ['tp5n']}, 12:50:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:50:55 INFO - 'talos_options': ['--xperf_path', 12:50:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:50:55 INFO - 'tests': ['tp5n']}}, 12:50:55 INFO - 'talos.zip': {'path': '', 12:50:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:50:55 INFO - Running post-action listener: _resource_record_post_action 12:50:55 INFO - [mozharness: 2016-08-29 19:50:55.843344Z] Finished populate-webroot step (success) 12:50:55 INFO - [mozharness: 2016-08-29 19:50:55.843428Z] Running create-virtualenv step. 12:50:55 INFO - Running pre-action listener: _resource_record_pre_action 12:50:55 INFO - Running main action method: create_virtualenv 12:50:55 INFO - Creating virtualenv /builds/slave/test/build/venv 12:50:55 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 12:50:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:50:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:50:55 INFO - Using real prefix '/tools/python27' 12:50:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:50:56 INFO - Installing distribute.............................................................................................................................................................................................done. 12:50:59 INFO - Installing pip.................done. 12:50:59 INFO - Return code: 0 12:50:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:50:59 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')]} 12:50:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:50:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:50:59 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')]} 12:50:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:50:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:50:59 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 0x10553ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd343db2e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054a0d30>, '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 0x105205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105498b20>, '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.y2gnHV7Bhx/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.lkWpvolxMM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 12:50:59 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 12:50:59 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 12:50:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y2gnHV7Bhx/Render', 12:50:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:50:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:50:59 INFO - 'HOME': '/Users/cltbld', 12:50:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:50:59 INFO - 'LOGNAME': 'cltbld', 12:50:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:50:59 INFO - 'MOZ_NO_REMOTE': '1', 12:50:59 INFO - 'NO_EM_RESTART': '1', 12:50:59 INFO - 'PAGER': '/bin/cat', 12:50:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:50:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:50:59 INFO - 'PWD': '/builds/slave/test', 12:50:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:50:59 INFO - 'SHELL': '/bin/bash', 12:50:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lkWpvolxMM/Listeners', 12:50:59 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 12:50:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:50:59 INFO - 'USER': 'cltbld', 12:50:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:50:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:50:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:50:59 INFO - 'XPC_FLAGS': '0x0', 12:50:59 INFO - 'XPC_SERVICE_NAME': '0', 12:50:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:50:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:50:59 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 12:50:59 INFO - Cleaning up... 12:50:59 INFO - Return code: 0 12:50:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:50:59 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')]} 12:50:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:50:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:50:59 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')]} 12:50:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:50:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:50:59 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 0x10553ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd343db2e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054a0d30>, '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 0x105205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105498b20>, '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.y2gnHV7Bhx/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.lkWpvolxMM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 12:50:59 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 12:50:59 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 12:50:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y2gnHV7Bhx/Render', 12:50:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:50:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:50:59 INFO - 'HOME': '/Users/cltbld', 12:50:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:50:59 INFO - 'LOGNAME': 'cltbld', 12:50:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:50:59 INFO - 'MOZ_NO_REMOTE': '1', 12:50:59 INFO - 'NO_EM_RESTART': '1', 12:50:59 INFO - 'PAGER': '/bin/cat', 12:50:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:50:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:50:59 INFO - 'PWD': '/builds/slave/test', 12:50:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:50:59 INFO - 'SHELL': '/bin/bash', 12:50:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lkWpvolxMM/Listeners', 12:50:59 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 12:50:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:50:59 INFO - 'USER': 'cltbld', 12:50:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:50:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:50:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:50:59 INFO - 'XPC_FLAGS': '0x0', 12:50:59 INFO - 'XPC_SERVICE_NAME': '0', 12:50:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:51:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:51:00 INFO - Downloading/unpacking psutil>=3.1.1 12:51:00 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 12:51:00 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 12:51:00 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 12:51:00 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 12:51:00 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 12:51:00 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 12:51:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:51:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:51:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:51:03 INFO - Installing collected packages: psutil 12:51:03 INFO - Running setup.py install for psutil 12:51:03 INFO - building 'psutil._psutil_osx' extension 12:51:03 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 12:51:03 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 12:51:03 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 12:51:04 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 12:51:04 INFO - building 'psutil._psutil_posix' extension 12:51:04 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 12:51:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:51:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:51:04 INFO - ^ 12:51:04 INFO - 1 warning generated. 12:51:04 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 12:51:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:51:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:51:04 INFO - Successfully installed psutil 12:51:04 INFO - Cleaning up... 12:51:04 INFO - Return code: 0 12:51:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:51:04 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')]} 12:51:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:51:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:51:04 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')]} 12:51:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:51:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:51:04 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 0x10553ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd343db2e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054a0d30>, '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 0x105205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105498b20>, '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.y2gnHV7Bhx/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.lkWpvolxMM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 12:51:04 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 12:51:04 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 12:51:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y2gnHV7Bhx/Render', 12:51:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:51:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:51:04 INFO - 'HOME': '/Users/cltbld', 12:51:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:51:04 INFO - 'LOGNAME': 'cltbld', 12:51:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:51:04 INFO - 'MOZ_NO_REMOTE': '1', 12:51:04 INFO - 'NO_EM_RESTART': '1', 12:51:04 INFO - 'PAGER': '/bin/cat', 12:51:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:51:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:51:04 INFO - 'PWD': '/builds/slave/test', 12:51:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:51:04 INFO - 'SHELL': '/bin/bash', 12:51:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lkWpvolxMM/Listeners', 12:51:04 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 12:51:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:51:04 INFO - 'USER': 'cltbld', 12:51:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:51:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:51:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:51:04 INFO - 'XPC_FLAGS': '0x0', 12:51:04 INFO - 'XPC_SERVICE_NAME': '0', 12:51:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:51:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:51:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:51:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:51:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:51:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:51:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:51:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:51:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:51:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:51:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:51:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:51:07 INFO - Installing collected packages: mozsystemmonitor 12:51:07 INFO - Running setup.py install for mozsystemmonitor 12:51:07 INFO - Successfully installed mozsystemmonitor 12:51:07 INFO - Cleaning up... 12:51:07 INFO - Return code: 0 12:51:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:51:07 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')]} 12:51:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:51:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:51:07 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')]} 12:51:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:51:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:51:07 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 0x10553ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd343db2e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054a0d30>, '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 0x105205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105498b20>, '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.y2gnHV7Bhx/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.lkWpvolxMM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 12:51:07 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 12:51:07 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 12:51:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y2gnHV7Bhx/Render', 12:51:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:51:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:51:07 INFO - 'HOME': '/Users/cltbld', 12:51:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:51:07 INFO - 'LOGNAME': 'cltbld', 12:51:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:51:07 INFO - 'MOZ_NO_REMOTE': '1', 12:51:07 INFO - 'NO_EM_RESTART': '1', 12:51:07 INFO - 'PAGER': '/bin/cat', 12:51:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:51:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:51:07 INFO - 'PWD': '/builds/slave/test', 12:51:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:51:07 INFO - 'SHELL': '/bin/bash', 12:51:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lkWpvolxMM/Listeners', 12:51:07 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 12:51:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:51:07 INFO - 'USER': 'cltbld', 12:51:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:51:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:51:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:51:07 INFO - 'XPC_FLAGS': '0x0', 12:51:07 INFO - 'XPC_SERVICE_NAME': '0', 12:51:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:51:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:51:07 INFO - Downloading/unpacking jsonschema==2.5.1 12:51:07 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 12:51:07 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 12:51:07 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 12:51:07 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 12:51:07 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 12:51:07 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 12:51:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:51:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:51:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:51:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:51:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:51:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:51:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:51:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:51:10 INFO - Downloading functools32-3.2.3-2.tar.gz 12:51:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:51:10 INFO - warning: no files found matching '*.txt' 12:51:10 INFO - no previously-included directories found matching 'build' 12:51:10 INFO - no previously-included directories found matching 'dist' 12:51:10 INFO - no previously-included directories found matching '.git*' 12:51:10 INFO - Installing collected packages: jsonschema, functools32 12:51:10 INFO - Running setup.py install for functools32 12:51:10 INFO - warning: no files found matching '*.txt' 12:51:10 INFO - no previously-included directories found matching 'build' 12:51:10 INFO - no previously-included directories found matching 'dist' 12:51:10 INFO - no previously-included directories found matching '.git*' 12:51:10 INFO - Successfully installed jsonschema functools32 12:51:10 INFO - Cleaning up... 12:51:10 INFO - Return code: 0 12:51:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:51: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')]} 12:51:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:51:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:51: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')]} 12:51:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:51:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:51:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10553ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd343db2e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054a0d30>, '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 0x105205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105498b20>, '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.y2gnHV7Bhx/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.lkWpvolxMM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 12:51:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:51:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:51:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y2gnHV7Bhx/Render', 12:51:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:51:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:51:10 INFO - 'HOME': '/Users/cltbld', 12:51:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:51:10 INFO - 'LOGNAME': 'cltbld', 12:51:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:51:10 INFO - 'MOZ_NO_REMOTE': '1', 12:51:10 INFO - 'NO_EM_RESTART': '1', 12:51:10 INFO - 'PAGER': '/bin/cat', 12:51:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:51:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:51:10 INFO - 'PWD': '/builds/slave/test', 12:51:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:51:10 INFO - 'SHELL': '/bin/bash', 12:51:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lkWpvolxMM/Listeners', 12:51:10 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 12:51:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:51:10 INFO - 'USER': 'cltbld', 12:51:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:51:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:51:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:51:10 INFO - 'XPC_FLAGS': '0x0', 12:51:10 INFO - 'XPC_SERVICE_NAME': '0', 12:51:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:51:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:51:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:51:11 INFO - Cleaning up... 12:51:11 INFO - Return code: 0 12:51:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:51:11 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')]} 12:51:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:51:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:51:11 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')]} 12:51:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:51:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:51:11 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 0x10553ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd343db2e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054a0d30>, '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 0x105205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105498b20>, '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.y2gnHV7Bhx/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.lkWpvolxMM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 12:51:11 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 12:51:11 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 12:51:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y2gnHV7Bhx/Render', 12:51:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:51:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:51:11 INFO - 'HOME': '/Users/cltbld', 12:51:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:51:11 INFO - 'LOGNAME': 'cltbld', 12:51:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:51:11 INFO - 'MOZ_NO_REMOTE': '1', 12:51:11 INFO - 'NO_EM_RESTART': '1', 12:51:11 INFO - 'PAGER': '/bin/cat', 12:51:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:51:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:51:11 INFO - 'PWD': '/builds/slave/test', 12:51:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:51:11 INFO - 'SHELL': '/bin/bash', 12:51:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lkWpvolxMM/Listeners', 12:51:11 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 12:51:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:51:11 INFO - 'USER': 'cltbld', 12:51:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:51:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:51:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:51:11 INFO - 'XPC_FLAGS': '0x0', 12:51:11 INFO - 'XPC_SERVICE_NAME': '0', 12:51:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:51:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:51:11 INFO - Downloading/unpacking blobuploader==1.2.4 12:51:11 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 12:51:11 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 12:51:11 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 12:51:11 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 12:51:11 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 12:51:11 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 12:51:13 INFO - Downloading blobuploader-1.2.4.tar.gz 12:51:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:51:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:51:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:51:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:51:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:51:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:51:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:51:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:51:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:51:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:51:14 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 12:51:14 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 12:51:14 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 12:51:14 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 12:51:14 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 12:51:14 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 12:51:15 INFO - Downloading docopt-0.6.1.tar.gz 12:51:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:51:15 INFO - Installing collected packages: blobuploader, requests, docopt 12:51:15 INFO - Running setup.py install for blobuploader 12:51:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:51:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:51:15 INFO - Running setup.py install for requests 12:51:15 INFO - Running setup.py install for docopt 12:51:15 INFO - Successfully installed blobuploader requests docopt 12:51:15 INFO - Cleaning up... 12:51:15 INFO - Return code: 0 12:51:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:51:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:51:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:51:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:51:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:51:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:51:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:51:15 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 0x10553ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd343db2e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054a0d30>, '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 0x105205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105498b20>, '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.y2gnHV7Bhx/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.lkWpvolxMM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 12:51:15 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 12:51:15 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 12:51:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y2gnHV7Bhx/Render', 12:51:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:51:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:51:15 INFO - 'HOME': '/Users/cltbld', 12:51:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:51:15 INFO - 'LOGNAME': 'cltbld', 12:51:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:51:15 INFO - 'MOZ_NO_REMOTE': '1', 12:51:15 INFO - 'NO_EM_RESTART': '1', 12:51:15 INFO - 'PAGER': '/bin/cat', 12:51:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:51:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:51:15 INFO - 'PWD': '/builds/slave/test', 12:51:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:51:15 INFO - 'SHELL': '/bin/bash', 12:51:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lkWpvolxMM/Listeners', 12:51:15 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 12:51:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:51:15 INFO - 'USER': 'cltbld', 12:51:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:51:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:51:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:51:15 INFO - 'XPC_FLAGS': '0x0', 12:51:15 INFO - 'XPC_SERVICE_NAME': '0', 12:51:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:51:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:51:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:51:16 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-HT7uJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:51:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:51:16 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-Dje2qx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:51:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:51:16 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-Er3DM0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:51:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:51:16 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-vR49ym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:51:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:51:16 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-mU01ht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:51:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:51:16 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-bFnnsn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:51:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:51:16 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-8UThuu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:51:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:51:16 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-ZO7MWu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:51:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:51:17 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-qfNlnC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:51:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:51:17 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-fENN_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:51:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:51:17 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-egTJyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:51:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:51:17 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-nAw_JA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:51:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:51:17 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-P4UEtN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:51:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:51:17 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-zbFbSb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:51:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:51:17 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-2u8AF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:51:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:51:17 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-aHoqzN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:51:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:51:17 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-OjgEKK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:51:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:51:18 INFO - Running setup.py install for manifestparser 12:51:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:51:18 INFO - Running setup.py install for mozcrash 12:51:18 INFO - Running setup.py install for mozdebug 12:51:18 INFO - Running setup.py install for mozdevice 12:51:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:51:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:51:18 INFO - Running setup.py install for mozfile 12:51:18 INFO - Running setup.py install for mozhttpd 12:51:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:51:18 INFO - Running setup.py install for mozinfo 12:51:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:51:19 INFO - Running setup.py install for mozInstall 12:51:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:51:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:51:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:51:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:51:19 INFO - Running setup.py install for mozleak 12:51:19 INFO - Running setup.py install for mozlog 12:51:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:51:19 INFO - Running setup.py install for moznetwork 12:51:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:51:19 INFO - Running setup.py install for mozprocess 12:51:19 INFO - Running setup.py install for mozprofile 12:51:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:51:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:51:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:51:19 INFO - Running setup.py install for mozrunner 12:51:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:51:20 INFO - Running setup.py install for mozscreenshot 12:51:20 INFO - Running setup.py install for moztest 12:51:20 INFO - Running setup.py install for mozversion 12:51:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:51:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:51:20 INFO - Cleaning up... 12:51:20 INFO - Return code: 0 12:51:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:51:20 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')]} 12:51:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:51:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:51:20 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')]} 12:51:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:51:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:51:20 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 0x10553ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd343db2e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054a0d30>, '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 0x105205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105498b20>, '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.y2gnHV7Bhx/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.lkWpvolxMM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 12:51:20 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 12:51:20 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 12:51:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y2gnHV7Bhx/Render', 12:51:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:51:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:51:20 INFO - 'HOME': '/Users/cltbld', 12:51:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:51:20 INFO - 'LOGNAME': 'cltbld', 12:51:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:51:20 INFO - 'MOZ_NO_REMOTE': '1', 12:51:20 INFO - 'NO_EM_RESTART': '1', 12:51:20 INFO - 'PAGER': '/bin/cat', 12:51:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:51:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:51:20 INFO - 'PWD': '/builds/slave/test', 12:51:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:51:20 INFO - 'SHELL': '/bin/bash', 12:51:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lkWpvolxMM/Listeners', 12:51:20 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 12:51:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:51:20 INFO - 'USER': 'cltbld', 12:51:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:51:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:51:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:51:20 INFO - 'XPC_FLAGS': '0x0', 12:51:20 INFO - 'XPC_SERVICE_NAME': '0', 12:51:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:51:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:51:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:51:20 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-wQnYkd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:51:20 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)) 12:51:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:51:20 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-PDh6FU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:51:21 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)) 12:51:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:51:21 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-beXuCB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:51:21 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)) 12:51:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:51:21 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-u5XB_6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:51:21 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)) 12:51:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:51:21 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-HlAGpZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:51:21 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)) 12:51:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:51:21 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-hOSLdH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:51:21 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)) 12:51:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:51:21 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-0ieEfp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:51:21 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)) 12:51:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:51:21 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-xxsbGl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:51:21 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)) 12:51:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:51:21 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-NLuxp6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:51:21 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)) 12:51:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:51:21 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-zz2E5p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:51:21 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)) 12:51:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:51:21 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-tj_PSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:51:22 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)) 12:51:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:51:22 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-UzZXLE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:51:22 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)) 12:51:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:51:22 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-1Kts_d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:51:22 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)) 12:51:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:51:22 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-O6TKEL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:51:22 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)) 12:51:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:51:22 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-j5Y5Yr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:51:22 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)) 12:51:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:51:22 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-v422XK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:51:22 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)) 12:51:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:51:22 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-2Dq6AZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:51:22 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)) 12:51:22 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)) 12:51:22 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)) 12:51:22 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)) 12:51:22 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)) 12:51:22 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)) 12:51:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:51:22 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 12:51:22 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 12:51:22 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 12:51:22 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 12:51:22 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 12:51:22 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 12:51:25 INFO - Downloading blessings-1.6.tar.gz 12:51:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:51:25 INFO - Installing collected packages: blessings 12:51:25 INFO - Running setup.py install for blessings 12:51:25 INFO - Successfully installed blessings 12:51:25 INFO - Cleaning up... 12:51:25 INFO - Return code: 0 12:51:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:51:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:51:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:51:25 INFO - Reading from file tmpfile_stdout 12:51:25 INFO - Current package versions: 12:51:25 INFO - blessings == 1.6 12:51:25 INFO - blobuploader == 1.2.4 12:51:25 INFO - docopt == 0.6.1 12:51:25 INFO - functools32 == 3.2.3-2 12:51:25 INFO - jsonschema == 2.5.1 12:51:25 INFO - manifestparser == 1.1 12:51:25 INFO - mozInstall == 1.12 12:51:25 INFO - mozcrash == 0.17 12:51:25 INFO - mozdebug == 0.1 12:51:25 INFO - mozdevice == 0.48 12:51:25 INFO - mozfile == 1.2 12:51:25 INFO - mozhttpd == 0.7 12:51:25 INFO - mozinfo == 0.9 12:51:25 INFO - mozleak == 0.1 12:51:25 INFO - mozlog == 3.3 12:51:25 INFO - moznetwork == 0.27 12:51:25 INFO - mozprocess == 0.23 12:51:25 INFO - mozprofile == 0.28 12:51:25 INFO - mozrunner == 6.12 12:51:25 INFO - mozscreenshot == 0.1 12:51:25 INFO - mozsystemmonitor == 0.3 12:51:25 INFO - moztest == 0.7 12:51:25 INFO - mozversion == 1.4 12:51:25 INFO - psutil == 3.1.1 12:51:25 INFO - requests == 1.2.3 12:51:25 INFO - wsgiref == 0.1.2 12:51:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:51:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:51:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:51:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:51:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:51:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:51:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:51:25 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 0x10553ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd343db2e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054a0d30>, '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 0x105205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105498b20>, '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.y2gnHV7Bhx/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.lkWpvolxMM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 12:51:25 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 12:51:25 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 12:51:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y2gnHV7Bhx/Render', 12:51:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:51:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:51:25 INFO - 'HOME': '/Users/cltbld', 12:51:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:51:25 INFO - 'LOGNAME': 'cltbld', 12:51:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:51:25 INFO - 'MOZ_NO_REMOTE': '1', 12:51:25 INFO - 'NO_EM_RESTART': '1', 12:51:25 INFO - 'PAGER': '/bin/cat', 12:51:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:51:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:51:25 INFO - 'PWD': '/builds/slave/test', 12:51:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:51:25 INFO - 'SHELL': '/bin/bash', 12:51:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lkWpvolxMM/Listeners', 12:51:25 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 12:51:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:51:25 INFO - 'USER': 'cltbld', 12:51:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:51:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:51:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:51:25 INFO - 'XPC_FLAGS': '0x0', 12:51:25 INFO - 'XPC_SERVICE_NAME': '0', 12:51:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:51:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:51:26 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)) 12:51:26 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)) 12:51:26 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)) 12:51:26 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)) 12:51:26 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)) 12:51:26 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)) 12:51:26 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)) 12:51:26 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)) 12:51:26 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)) 12:51:26 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)) 12:51:26 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)) 12:51:26 INFO - Cleaning up... 12:51:26 INFO - Return code: 0 12:51:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:51:26 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')]} 12:51:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:51:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:51:26 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')]} 12:51:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:51:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:51:26 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 0x10553ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd343db2e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054a0d30>, '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 0x105205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105498b20>, '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.y2gnHV7Bhx/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.lkWpvolxMM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 12:51:26 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 12:51:26 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 12:51:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y2gnHV7Bhx/Render', 12:51:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:51:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:51:26 INFO - 'HOME': '/Users/cltbld', 12:51:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:51:26 INFO - 'LOGNAME': 'cltbld', 12:51:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:51:26 INFO - 'MOZ_NO_REMOTE': '1', 12:51:26 INFO - 'NO_EM_RESTART': '1', 12:51:26 INFO - 'PAGER': '/bin/cat', 12:51:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:51:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:51:26 INFO - 'PWD': '/builds/slave/test', 12:51:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:51:26 INFO - 'SHELL': '/bin/bash', 12:51:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lkWpvolxMM/Listeners', 12:51:26 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 12:51:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:51:26 INFO - 'USER': 'cltbld', 12:51:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:51:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:51:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:51:26 INFO - 'XPC_FLAGS': '0x0', 12:51:26 INFO - 'XPC_SERVICE_NAME': '0', 12:51:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:51:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:51:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:51:26 INFO - Cleaning up... 12:51:26 INFO - Return code: 0 12:51:26 INFO - Running post-action listener: _resource_record_post_action 12:51:26 INFO - Running post-action listener: _start_resource_monitoring 12:51:26 INFO - Starting resource monitoring. 12:51:26 INFO - [mozharness: 2016-08-29 19:51:26.324372Z] Finished create-virtualenv step (success) 12:51:26 INFO - [mozharness: 2016-08-29 19:51:26.325382Z] Running install step. 12:51:26 INFO - Running pre-action listener: _resource_record_pre_action 12:51:26 INFO - Running main action method: install 12:51:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:51:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:51:26 INFO - Reading from file tmpfile_stdout 12:51:26 INFO - Detecting whether we're running mozinstall >=1.0... 12:51:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:51:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:51:26 INFO - Reading from file tmpfile_stdout 12:51:26 INFO - Output received: 12:51:26 INFO - Usage: mozinstall [options] installer 12:51:26 INFO - Options: 12:51:26 INFO - -h, --help show this help message and exit 12:51:26 INFO - -d DEST, --destination=DEST 12:51:26 INFO - Directory to install application into. [default: 12:51:26 INFO - "/builds/slave/test"] 12:51:26 INFO - --app=APP Application being installed. [default: firefox] 12:51:26 INFO - mkdir: /builds/slave/test/build/application 12:51:26 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'] 12:51:26 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 12:51:59 INFO - Reading from file tmpfile_stdout 12:51:59 INFO - Output received: 12:51:59 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 12:51:59 INFO - Running post-action listener: _resource_record_post_action 12:51:59 INFO - [mozharness: 2016-08-29 19:51:59.473447Z] Finished install step (success) 12:51:59 INFO - [mozharness: 2016-08-29 19:51:59.473554Z] Running run-tests step. 12:51:59 INFO - Running pre-action listener: _resource_record_pre_action 12:51:59 INFO - Running main action method: run_tests 12:51:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:51:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:51:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:51:59 INFO - 2016-08-29 12:51:59.486 screenresolution[1921:12162] starting screenresolution argv=screenresolution get 12:51:59 INFO - 2016-08-29 12:51:59.489 screenresolution[1921:12162] Display 0: 1600x1200x32@60 12:51:59 INFO - 2016-08-29 12:51:59.494 screenresolution[1922:12164] starting screenresolution argv=screenresolution list 12:51:59 INFO - Available Modes on Display 0 12:51:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:51:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:51:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:51:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:51:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:51:59 INFO - Intel Iris: 12:51:59 INFO - Chipset Model: Intel Iris 12:51:59 INFO - Type: GPU 12:51:59 INFO - Bus: Built-In 12:51:59 INFO - VRAM (Dynamic, Max): 1536 MB 12:51:59 INFO - Vendor: Intel (0x8086) 12:51:59 INFO - Device ID: 0x0a2e 12:51:59 INFO - Revision ID: 0x0009 12:51:59 INFO - Displays: 12:51:59 INFO - Display: 12:51:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:51:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:51:59 INFO - Display Serial Number: 1600x1200 60 12:51:59 INFO - Main Display: Yes 12:51:59 INFO - Mirror: Off 12:51:59 INFO - Online: Yes 12:51:59 INFO - Rotation: Supported 12:51:59 INFO - Return code: 0 12:51:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:51:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:51:59 INFO - Python 2.7.3 12:51:59 INFO - Return code: 0 12:51:59 INFO - grabbing minidump binary from tooltool 12:51:59 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')]} 12:51:59 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 0x1054a0d30>, '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 0x105205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105498b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:51:59 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 12:51:59 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 12:51:59 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 12:51:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:51:59 INFO - Return code: 0 12:51:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:51:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:51:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:51:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:51:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:51:59 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/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0055', '--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 12:51:59 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/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0055 --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 12:51:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y2gnHV7Bhx/Render', 12:51:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:51:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:51:59 INFO - 'HOME': '/Users/cltbld', 12:51:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:51:59 INFO - 'LOGNAME': 'cltbld', 12:51:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:51:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:51:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:51:59 INFO - 'MOZ_NO_REMOTE': '1', 12:51:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:51:59 INFO - 'NO_EM_RESTART': '1', 12:51:59 INFO - 'PAGER': '/bin/cat', 12:51:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:51:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:51:59 INFO - 'PWD': '/builds/slave/test', 12:51:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:51:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:51:59 INFO - 'SHELL': '/bin/bash', 12:51:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lkWpvolxMM/Listeners', 12:51:59 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 12:51:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:51:59 INFO - 'USER': 'cltbld', 12:51:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:51:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:51:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:51:59 INFO - 'XPC_FLAGS': '0x0', 12:51:59 INFO - 'XPC_SERVICE_NAME': '0', 12:51:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:51:59 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/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0055', '--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 12:52:00 INFO - mozversion application_buildid: 20160829102231 12:52:00 INFO - mozversion application_changeset: 350ef810694c3704431f65c91bd83b9f2e933622 12:52:00 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:52:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:52:00 INFO - mozversion application_name: Firefox 12:52:00 INFO - mozversion application_remotingname: firefox-dev 12:52:00 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:52:00 INFO - mozversion application_vendor: Mozilla 12:52:00 INFO - mozversion application_version: 50.0a2 12:52:00 INFO - mozversion platform_buildid: 20160829102231 12:52:00 INFO - mozversion platform_changeset: 350ef810694c3704431f65c91bd83b9f2e933622 12:52:00 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:52:00 INFO - mozversion platform_version: 50.0a2 12:52:00 INFO - using testdate: 1472500320 12:52:00 INFO - actual date: 1472500320 12:52:00 INFO - starting webserver on 'localhost:50882' 12:52:00 INFO - SUITE-START | Running 2 tests 12:52:00 INFO - TEST-START | dromaeo_css 12:52:00 INFO - operating with platform_type : mac_ 12:52:00 INFO - Initialising browser for dromaeo_css test... 12:52:00 INFO - TEST-INFO | started process 1932 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/tmplYZYsQ/profile http://localhost:50882/getInfo.html) 12:52:02 INFO - PROCESS | 1932 | __metrics Screen width/height:1600/1200 12:52:02 INFO - PROCESS | 1932 | colorDepth:24 12:52:02 INFO - PROCESS | 1932 | Browser inner width/height: 1024/689 12:52:02 INFO - PROCESS | 1932 | __metrics 12:52:02 INFO - PROCESS | 1932 | 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" 12:52:02 INFO - PROCESS | 1932 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:52:02 INFO - PROCESS | 1932 | ************************* 12:52:02 INFO - PROCESS | 1932 | A coding exception was thrown in a Promise resolution callback. 12:52:02 INFO - PROCESS | 1932 | See https://developer.mozilla.org/Mozilla/JavaScript_code_modules/Promise.jsm/Promise 12:52:02 INFO - PROCESS | 1932 | 12:52:02 ERROR - PROCESS | 1932 | Full message: TypeError: win is null 12:52:02 INFO - PROCESS | 1932 | Full stack: onSuccess@resource://app/components/nsBrowserGlue.js:844:17 12:52:02 INFO - PROCESS | 1932 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 12:52:02 INFO - PROCESS | 1932 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 12:52:02 INFO - PROCESS | 1932 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 12:52:02 INFO - PROCESS | 1932 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 12:52:02 INFO - PROCESS | 1932 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 12:52:02 INFO - PROCESS | 1932 | get _worker/worker.onmessage@resource://gre/modules/PromiseWorker.jsm:235:9 12:52:02 INFO - PROCESS | 1932 | EventHandlerNonNull*get _worker@resource://gre/modules/PromiseWorker.jsm:217:5 12:52:02 INFO - PROCESS | 1932 | postMessage@resource://gre/modules/PromiseWorker.jsm:292:9 12:52:02 INFO - PROCESS | 1932 | TaskImpl_run@resource://gre/modules/Task.jsm:319:40 12:52:02 INFO - PROCESS | 1932 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 12:52:02 INFO - PROCESS | 1932 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 12:52:02 INFO - PROCESS | 1932 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 12:52:02 INFO - PROCESS | 1932 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 12:52:02 INFO - PROCESS | 1932 | Promise.prototype.then@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:454:5 12:52:02 INFO - PROCESS | 1932 | this.DeferredSave.prototype._deferredSave@resource://gre/modules/DeferredSave.jsm:220:5 12:52:02 INFO - PROCESS | 1932 | this.DeferredSave.prototype._startTimer/<@resource://gre/modules/DeferredSave.jsm:175:40 12:52:02 INFO - PROCESS | 1932 | syncLoadManifestFromFile@resource://gre/modules/addons/XPIProvider.jsm:1511:5 12:52:02 INFO - PROCESS | 1932 | addMetadata@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:1650:21 12:52:02 INFO - PROCESS | 1932 | processFileChanges@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2014:23 12:52:02 INFO - PROCESS | 1932 | this.XPIProvider.checkForChanges@resource://gre/modules/addons/XPIProvider.jsm:3723:34 12:52:02 INFO - PROCESS | 1932 | this.XPIProvider.startup@resource://gre/modules/addons/XPIProvider.jsm:2713:25 12:52:02 INFO - PROCESS | 1932 | callProvider@resource://gre/modules/AddonManager.jsm:236:12 12:52:02 INFO - PROCESS | 1932 | _startProvider@resource://gre/modules/AddonManager.jsm:788:5 12:52:02 INFO - PROCESS | 1932 | AddonManagerInternal.startup@resource://gre/modules/AddonManager.jsm:972:9 12:52:02 INFO - PROCESS | 1932 | this.AddonManagerPrivate.startup@resource://gre/modules/AddonManager.jsm:3016:5 12:52:02 INFO - PROCESS | 1932 | amManager.prototype.observe@resource://gre/components/addonManager.js:71:9 12:52:02 INFO - PROCESS | 1932 | 12:52:02 INFO - PROCESS | 1932 | ************************* 12:52:03 INFO - TEST-INFO | 1932: exit 0 12:52:03 INFO - Browser initialized. 12:52:03 INFO - Running cycle 1/1 for dromaeo_css test... 12:52:03 INFO - TEST-INFO | started process 1934 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/tmplYZYsQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:52:14 INFO - PROCESS | 1934 | RSS: Main: 235167744 12:52:14 INFO - PROCESS | 1934 | 12:53:05 INFO - PROCESS | 1934 | 1472500385994 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] 12:55:17 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:50882/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50882/tests/dromaeo/cssquery-ext.html) 12:55:19 INFO - PROCESS | 1934 | RSS: Main: 360017920 12:55:19 INFO - PROCESS | 1934 | 12:58:22 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:50882/tests/dromaeo/cssquery-ext.html (next: http://localhost:50882/tests/dromaeo/cssquery-jquery.html) 12:58:22 INFO - PROCESS | 1934 | RSS: Main: 372535296 12:58:22 INFO - PROCESS | 1934 | 13:01:25 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:50882/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50882/tests/dromaeo/cssquery-mootools.html) 13:01:25 INFO - PROCESS | 1934 | RSS: Main: 337932288 13:01:25 INFO - PROCESS | 1934 | 13:04:28 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:50882/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50882/tests/dromaeo/cssquery-prototype.html) 13:04:28 INFO - PROCESS | 1934 | RSS: Main: 353796096 13:04:28 INFO - PROCESS | 1934 | 13:07:32 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:50882/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50882/tests/dromaeo/cssquery-yui.html) 13:07:32 INFO - PROCESS | 1934 | RSS: Main: 336478208 13:07:32 INFO - PROCESS | 1934 | 13:10:35 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:50882/tests/dromaeo/cssquery-yui.html (next: http://localhost:50882/tests/dromaeo/cssquery-dojo.html) 13:10:36 INFO - PROCESS | 1934 | RSS: Main: 516132864 13:10:36 INFO - PROCESS | 1934 | 13:10:36 INFO - PROCESS | 1934 | __start_tp_report 13:10:36 INFO - PROCESS | 1934 | _x_x_mozilla_page_load 13:10:36 INFO - PROCESS | 1934 | _x_x_mozilla_page_load_details 13:10:36 INFO - PROCESS | 1934 | |i|pagename|runs| 13:10:36 INFO - PROCESS | 1934 | |0;dojo.html;3326.3347330533893;3339.916502087449;3351.3464874349497;3360.7479439042077;3413.2320227948717;5771.076627739557;5859.6777177255235;5871.295444546649;5882.735276912538;5915.408459154088;5839.0949402842525;5883.911741323873;5888.646681199131;5896.882062358761;5904.970475147618;5186.585073194155;5238.47615238475;5255.448177941309;5276.657017293864;5386.649867758602;5317.175837745156;5342.252084708144;5352.090144675414;5362.892742145154;5363.088274993241;5226.817061402851;5257.185136303879;5260.421353651095;5272.420033796298;5355.973220133894;5336.493033161843;5349.866253343697;5351.973240133813;5358;5389.0569150398915;5082.237664350377;5207.062728708831;5209.270702101709;5269;5281.234221037967;5677.233573467612;5680.744366503517;5731.083026715747;5755.424457554211;5773.538116950675;6276.843078923018;6347.793919155346;6363.87272254557;6368.649724265164;6381;6164.321924588292;6254;6266.154069200691;6324.114623952651;6328.335524769925;5873.85315367115;5907.586468947133;5924.289085309704;5928.170056192136;5942.286925568959;6321.589096708699;6333.081703153017;6334.904976425358;6339.144215530844;6344.302126766052;6005.279366475964;6061.392692582306;6070.484008859296;6220.968895155495;6232.127502149703;6273.309935907047;6321;6327.240731112204;6328.240611126695;6350.872982540323;6226.128342032119;6275.278342990523;6297.685115744286;6298.8425289367215;6310.0534919761485;6116.640127388518;6189.412005859436;6333.46165575922;6338.429541341209;6340.207474065742;4992.550670439678;4993.300937868701;5019.171836646985;5026.924596131061;5027.899442011212;3894.2406230784727;4487.775611219491;4509.842155524541;4564.269716845289;4594.954050459452;5852.092925596539;5865.853353666107;5880.794172203952;5904.498117659961;5924.348321684612;6198.907016394758;6266.968665156645;6285.057241413824;6333.588316759396;6359.586626869239;5787.131930210502;5839.0949402842625;5848.005839007379;5854.765809367577;5876.44173803488;5529.778808847682;5534.169874518774;5547.667139971534;5550.47270509301;5553.555715542747;6240.968795155995;6309.305976342691;6369.7452101915405;6376.266729326094;6383.202099737533;6270.905936410866;6308.653024083719;6338.841528961812;6342.460890824332;6367.363263673596;6338.809835704937;6344.048392741126;6344.936550634435;6348.396902294275;6352.460040896575;6112.969435152796;6274.372562743689;6290.559660823698;6307.274663413767;6315.336889626523;6263.404976527223;6291.370862913672;6294.685265736786;6296.811095667137;6355.3326900674765;6192.195014648247;6194.101855230876;6206.286277078013;6207.068939659087;6208.130861679459;5961.195238642901;6211.9068213977735;6214;6216.409441103088;6221.502279817694;5936.851578710394;5973.402659733992;6012.488938440107;6082.087686847009;6086.087086936995;6162;6191.597546159485;6191.628502289877;6194.752209911733;6212.689365531615;5836.883262334814;6003;6009.699515024144;6022.697255296676;6065.363136870565 13:10:36 INFO - PROCESS | 1934 | |1;ext.html;509.85469141294726;524.3236225269403;543.8477226376614;548.0710196217414;553.2752094755868;24904.626430603534;30990.225244368903;32445.83777081111;41334.17331653365;41960.370594441105;43161;43496.565034349645;43623.56376436243;43626.34560481595;43645.563544364544;9595.136437720586;9772.169365603932;9788.314817962746;9810.411375317504;9813.705588832347;8655.610497527627;8817.118288171196;9639.373440726366;11521.942390288079;11756.588519401823;11799.528018879277;11850.3482308473;11853.703657408547;11863.703407414798;11872.347020913847;11773.882261177365;11835.408229588485;11847.82228266581;11859.407029648482;11884.584039558575;8553.529555874424;11468.65594032172;11736.295822250693;11781.352025638587;12013.459394327276;41065;41431.7928410359;41468.58531414707;41537.169256614696;41554.792226038975;3350.664933506654;3443.896683099511;3496.4930085137767;3560.768550044239;3580.516630254934;25815.225543233733;31528.842355788074;32533.837330813196;32831.50752738711;33124.17189570256;3095.1178914009397;3211.4379983502795;3226.1128189747774;3257.723093537052;3287.539744435781;546108;546409;547896;548358.2582087064;549045;66017.33982660112;66646.33353666401;66665.66667166633;66717.33282667112;66719;67877;67976;68222.31777682259;68230.976535352;68456.6577167111;15005.69988600222;17643;17697.761156718905;17720;17752.491038261633;10663.253572249869;12186.964108050737;12352.876471235353;12645.367731613382;12664.746705065847;16669.927940037895;18630.720539191923;18957.43127706171;19534;19556.413307600797;6958;7842;8014.959925200336;8015.83968320642;8052.95311609487;884.1644645809777;945.5177859291673;947.3842002698163;996.7159359582485;1193.665773583398;29169.7083029167;29885.40229195404;30546.930857419884;30628;30731.846340768152;1007.0885848307294;1117.7652692934412;1119.311623351633;1135.6763322453226;1184.3722826901756;15586.844131558766;15921.601959951095;16055.116968566841;16109.516714498584;16399.83600164007;745053;751744;753766;754280;754410;61305.38694613086;61548.38451615426;61990;62014.68992655098;62191;1122.9444322336976;1308.574713218208;1421.7440860645183;1531.4486784757255;1635.6238065244822;27439.862800685867;30650;32933;32980.83509582533;33168.20569615502;51974.22038669347;52651.73674131605;52693.736531317096;52877.94244115019;52928.47071529235;662122;677380;677902;678751;679931;45618.77190614026;45824.312635311166;45929;45949;48007.27989080236;50140;50450.990980180926;50547.98904021973;50682.74658626683;51078.744606276734;40818;40859.38710919398;41315.586844131176;41357;42985.925351866455;41924;42002.15995680008;42398.72803815891;42760.78619606882;43055.569444306406;39856.00359991023;40037.599624003386;40247.597524024386;40571.98570035773;40695.18609627731;34634.307313853074;36386.8180659095;36474.8176259117;37012.25975480422;37043.62956370402;26239.606405903545;26345.814438350702;26420.471590568464;26764.3778875257;27332 13:10:36 INFO - PROCESS | 1934 | |2;jquery.html;353.526274791779;373.19948710017013;379.85945200275904;385.5123269064632;407.9449274347962;15844;16077.51767446977;17100.486985390427;21106.733595984253;21388.25141120061;20200.494987625316;20383.490412739688;20820.479488012807;21075.68386474204;21095.894520527414;5011.724355160465;5065.974670126644;5316.175992721134;5443.455654434554;5675.233843431125;5923.318818335903;6086.208792856923;6091.878162436771;6093.78671746489;6102.420270074336;5757.309122905257;5872.471477567018;5942.762289508414;6050.667213303266;6051.818445446644;5954.70226488676;5977.491913187384;5983.222181116427;5993.220881285427;5994.070919007539;5041.193409054534;5058.204337460223;5182.844514664528;5188.740562971836;5199.922001169985;25497.362565935815;25601;25659.615105773428;25761.227163185133;26125.869370653214;2312.6531020347084;2322.744498105224;2443.5235129149823;2513.0952856971476;2834.518131917579;15291.235438228156;16993.66012679752;17403.216855241546;17445.127743612895;17594.91202543992;1947.8507680468592;1995.451250905998;2079.688046792978;2093.5289559849066;2109.0930899713117;62986.68506657438;65252.99999999953;66016;116009;128576.35711821474;14883.627909302355;16992.660146796996;21540.13839446436;21785.673214901788;21903.342899713036;34316.82841585776;34560;35162.12094697653;35175.4723679145;35211.82394088013;10821.37679348104;11089;11252.549898003988;11301.152413569016;11370.488328025258;9245.075492450836;9254.074592540826;9308.953455232817;9416.387934784218;9466.857997130048;8186.754397368089;8737.257333126756;9383.061693830563;9716.708498745049;9802.9019709802;3467.029231815096;3589.65898239672;3766.1714422826935;3767.7362584618813;3904.364553926145;849.2865992566274;863.7883718489011;929.5166513412987;948.2461443152711;990.2127808392345;17916.372926947493;19850.00749962496;20406.08172632235;20924.058417371256;21028.053737581846;952.6951375559893;971.640493017588;986.9259805514615;996.8454889492141;1036.8341065429495;10529.986052998727;10981.341119532854;11002.174836887265;11002.559897604006;11036;616845.9157704272;632920;637511;641927.7903610546;646070;124602;132184;133772;136351;136408;960.9759756006156;972.1639390124346;1001.8747656542932;1002.6791426743519;1002.7643503776607;18390.804597701106;18675.43973680792;18826.811731883055;18881.527961800515;18887.433376998713;47228.34700785456;55782.163267551834;56429.71785141048;57287.14069288881;57520.411581478096;78832.60583697046;80146;81170.59414702888;81595.18404815876;84607.57696211479;6308.589941653778;7358.632068396666;7377.668004939898;7844.607769611611;8247;12122.030237581148;12334.75330493403;12370.81443778326;13703.246321452018;13742.244176570384;11177.664670059587;11362.318260904372;11620.244684095507;11777.585594952843;12189.69525761863;9765.609375624896;10795.676129716121;11259.549618015188;11596.5361385547;11706.590269340531;11525.423728813357;11568.24806387582;11913.583024594443;11961.760764784829;12035.879641203826;6570.244421891543;7338.816529586803;7360.852782944418;7469.925300746923;7589.051368578927;10012.499375031364;11491.540338386705;11511.251768635348;11841.407929603312;11871.347075910908 13:10:36 INFO - PROCESS | 1934 | |3;mootools.html;1393.944242230311;1394.4004078246353;1529.418820848078;1624.650700099479;1967.16395531899;7292.781216563499;7915.683372665094;8870.334724895634;8871.201591856732;9027.548622568878;8829;8892.288616910648;8898.599563019663;8910.50992195429;8913.3315001375;2358.457554762406;2404.2186289456;2406.6991626046743;2409.771071748181;2410.4937963027787;2103.9894800526054;2377.8454400464;2401.891914863835;2411.9517609647796;2424.0667343072955;2368.561816064025;2390.426297688551;2396.880155992202;2399.6880405547254;2406.0255596483453;1578.905265684051;2378.2270762002418;2380.7143142822883;2392.294273189413;2393.8204634652297;2329.5690297295055;2376.453415714378;2376.7979721723673;2384.701912260967;2384.7734465225776;8782.912170878337;8948.955255223747;9020.819583608356;9031.548422578844;9056.230220431271;4466.687331886769;5411.540019098381;5412.134058550652;5416.241726158341;5426.294581704393;3526.1360966563357;3558.2883423315184;3569.6430356964097;3622.3298689742483;3631.1648320886343;1044.3733759744168;1052.7999680060764;1066.3548553125402;1069.1393428290223;1071.8928107189297;11863.584774532936;11908.34504102274;12002.399880006053;12005.699857503545;12009.97915177211;4461.397711308931;4511.887202819956;4687.179743544934;4690.061987602493;4690.108879312919;9374.343795934219;9403.200727938201;9409.858852117224;9428.90571094294;9448.385854919547;5316.175992721134;5320.547753441001;5323.041852466516;5324.281222035053;5333.066713325153;4355.259605867011;4533.54664533544;4788.832390866303;4945.060438516671;4979.775910084055;3429.902431222035;3641.1625326175126;3646.34365814156;3766.453864189677;3883.262180185812;1136.9602063927723;1178.9528418863322;1181.291225264834;1190.4642910690225;1192.8866757658182;835.5132936132884;852.2840813716508;925.3383830561181;926.6941909169958;976.8827740671165;5697.686627235542;5811.738471768781;6015.127806468037;6062.605930614496;6126.908096378558;419.7963987466077;447.2129052866978;450.6484941745444;980.9901900981041;1102.2064113838023;3826.9617303827163;3896.2207558488417;3942.1918506706056;4038.818253178614;4081.8571350003203;10399.272050956512;10818;10839.83740243897;10848.620298289521;10909.890901090888;10767.084797792275;10799.78400431987;10800.027997480265;10813;10829.891701082888;6902.413294869993;8374.995000599967;8436.957815210884;8459.915400846035;8545.444546104483;5974.820755377345;6023;6045.848853778691;6074.301455332693;6080.665563394055;10104.242181836245;10372.740681483023;10376.273660843668;10398.844017339898;10406.479676016017;10028;10569.947150264199;10582;10607.363558186533;10609.522571484147;6147.877042459037;7181.138263408216;7371.152317483344;7739.7678069658;7885.290323870649;8449.155084491502;8771.517566533646;8778.122187781426;8784.429012114193;8801;7602.885956710543;7712.305892469704;7712.8457430852195;7833;7893.092294386219;6500.967495162494;7485.476016678998;7613.314801667655;7615.162332143571;7615.162332143571;7113.8577228454105;7264.382527485223;7270.309320614533;7271.309225623558;7275.527090739085;4924.532169443897;4961.255811628284;5055.6713813602;5065.164247899055;5145.125328694056;4088.8568900088353;4548.249538826057;5053.620978426559;5081.72050537224;5227.1636538155235 13:10:36 INFO - PROCESS | 1934 | |4;prototype.html;289.4471559321696;296.60107155875346;308.2478751845496;310.92382366320226;316.14798119069104;4058.9188216235693;4870.9770948100895;5335.519803217714;5337.813176538822;5355.6794787231875;4437.445319335083;4495.820167193308;4779.426468823737;4965.453800081988;5379.273798037264;4095.9590404096025;4108.322126849066;4120.7321524100835;4137.420761093449;4149.211649786538;3644.776686221416;3753.8738378486482;3957.3272543667645;4106.8562600309;4140.565240649734;3346.4311067118647;3871.399933010388;4027.738197017199;4072.1855628874196;4087.6321131098193;3410.38613049651;4030.6976976726864;4031.8387264509383;4038.8586399475876;4096.754194748325;3535.1869070114017;3545.298256836733;3548.225887056472;3553.733469989735;3581.603174761845;4790.1797317019655;4999.075171093359;5237.47625237477;5261;5280.339957505312;3977.482927219443;4483.282674772054;4650.9069818603775;4683.8360657376825;4742.359781429497;5299.947000530023;5546.112621980504;5551.916721249185;5580.944190558083;5593.6854839112775;3759.3609086455094;3913.630229419709;3917.941230881539;3928.7249892507534;3998.7400818946676;5266.4470230626;5575.637583557056;5706.144078388192;5756.251687280626;5811.331696854873;3539.9380185944115;3576.766015724571;3805.733598648068;3964.32606456903;3968.1865217630316;4014.6587540059422;4084.4894388201474;4095.5904409559403;4113;4117.773522456234;3971.582983786661;4502.729836209773;4670.462896766915;4772.331873537708;4796.760161991887;4940.90118197634;5425.593080518898;5649.813539156823;5666.716664166775;5693.4875861172695;1348.7032852772375;1382.3433868912186;1382.9861701383059;1400.4328247059957;1403.2843249942398;790.7627711686471;798.3094623151048;806.7902345390123;846.6697987784769;869;3661.5972243053243;4313.91555038143;4320.546342634041;4328.675349348811;4392.165488557164;4713.34013238147;4732.320026390651;4752.358431611758;4757.904841903142;4803.327534145223;4157.875263742093;4269.231538323132;4277.0539404570845;4313.598080623809;4413.911721765547;2217.6895234666836;2307.6423154411095;2362.940926476852;2371.383440305533;2598.8440693558446;4276.48682158143;4634.559716826896;4934.730024188913;4992.804605052769;5025.899482010339;3983.972181142353;4839.564439200489;4908.821882748115;4910.435299940481;5009.499050095034;4599.195140850338;4657.991191363171;4668;4844.636652251166;4849.781759820746;4668.789904454376;4682.742449165328;4690.343351930801;4704.788284527273;4723.574878260973;4480.350349199413;4746.762661866824;4783.545563171493;4823.879403014953;4877.1221180186485;4915.296586877274;5497.39528651856;6031.788887388921;6040.486558642388;6058.636481811105;3890.533136023695;3929.7838618875094;3966.2662407454714;3972.960270397259;3979.5275747973437;4818.135578006661;5963.940360596339;5964.642121472725;5980.401959804159;5987.371326010706;5596.250512156783;5606.411326810626;5638.830835074954;5654.915176272277;5676;5598.944010559842;5625.805935685004;5648.293963254593;5656.773729050792;5804.796832110856;5161.380634323905;5448;5552.7778888843995;5646.265985421869;5668.574856885833;4990.451050384382;5627.634203776905;5663.66018038919;5665.858353541194;5748.54011679073;4657.627389808875;5072.036313100647;5657.971710141423;5667.696429821081;5719.771209151588 13:10:36 INFO - PROCESS | 1934 | |5;yui.html;706.4454403293415;735.9705611775529;775.6897241103561;798.9800254993625;860.586918279226;14128.858711412882;15238.314275857585;16593.17034148294;16632.750508742363;16674.499765007055;14282.2858857057;14388.640283992905;14411.711765764705;14419;14421.062630928982;705.1502938958542;719.481972979454;720.240146645289;729.7810656802965;882.0650110882453;724.0840336973732;878.3675753457533;907.9909200908005;909.9590518426687;911.0525053943924;943.2548286853378;961.927855410847;962.1821451765978;962.9759256018585;963.335298643937;955.2787645327802;955.8231727130503;956.4834989105909;957.1385752822446;957.4064080270207;547.4361407750024;691.7544271783495;709.5529816215802;711.6690738806479;713.3936154268882;15553.377864885391;15678.68642627141;15704.685906281924;15710.371585136696;15722.056676599326;10807;13155.60533184006;14414.063085899488;14570.490032848906;14682.412703491847;11247.606333778278;11688;11793.469293881795;12728.490860365668;13348.666283342896;4653.976730116362;4674.766261686902;4721.763911804396;4870.001649661811;4880.755962201912;111569;111845.32232016526;112791;114238.99999999917;117456.82543174544;10646.787064258671;11107.777844443228;11138.888611113947;11146.721331966604;11328.830067548994;21037.89481052585;21041;21071.367858964255;21183.364499065054;21217.469563261042;7841.098273698483;7874.173211812792;7885.211478852184;7961.880571791427;8006.079300880357;4415.139047885631;4432.335149727567;4481.663875209307;4500.707454015478;4607.9308810367875;3322.318924620447;3464.255185135208;3470.2712430389397;3614.728895332808;3615.493830863681;639.4468784501373;711.1537270648016;745.9104907411037;750.159821000472;761.1437133225122;587.759018802289;675.8817206988757;698.340068635145;698.72400401842;706.3572149344072;9005.369624126379;9315.534223288809;9338.20625246848;9786;9992.10071093605;562.9521490673338;605.6850437772333;612.9785457508966;621.3972446726668;658.5949640970864;4642.675012749142;4753.572178503951;4767.404074490689;4795.592374648124;4874.317595536628;76938;84816.57591712002;93232;93507;93595;128422;138138;138965.26068220258;141324.2933785345;144071;98709.50645246872;121355;121909.39045304716;125101;125929;9127.954360228421;9194.209722956635;9199.310051746012;9200.769980750267;9365.438073715599;60072;62604.37395625986;63780;64357.35642643514;65649;69144.96282555866;74101;83727.16272837437;87071;89930.55034724784;12683;12904.741905162033;13429.462821487032;13479.932600336935;13588.45646174142;14858.777118343018;14989.100653960797;15473.381064757734;16451.67096658084;16480.67038659244;13749.31253437304;14052.297385130498;14393;14635.780463292846;14669.853301466848;14271.857281427052;14305.356258968162;14316.284185790877;14481.637959051108;14737.263136843329;13185.670358241121;13397.933010334886;13835.930820346237;13922;13947.651308716957;9560.76098097553;11472;11499.080073593925;11987.460564274803;12427.751444970869;10898.891011089787;11895.940520297343;11902.523899044287;12040.21738587024;12133.514659413524 13:10:36 INFO - PROCESS | 1934 | __end_tp_report 13:10:36 INFO - PROCESS | 1934 | __start_cc_report 13:10:36 INFO - PROCESS | 1934 | _x_x_mozilla_cycle_collect,1298 13:10:36 INFO - PROCESS | 1934 | __end_cc_report 13:10:36 INFO - PROCESS | 1934 | __startTimestamp1472501436156__endTimestamp 13:10:36 INFO - PROCESS | 1934 | 13:10:36 INFO - PROCESS | 1934 | ------- Summary: start ------- 13:10:36 INFO - PROCESS | 1934 | Number of tests: 6 13:10:36 INFO - PROCESS | 1934 | 13:10:36 INFO - PROCESS | 1934 | [#0] dojo.html Cycles:165 Average:5825.51 Median:6012.49 stddev:647.81 (10.8%) stddev-sans-first:619.42 13:10:36 INFO - PROCESS | 1934 | Values: 3326.3 3339.9 3351.3 3360.7 3413.2 5771.1 5859.7 5871.3 5882.7 5915.4 5839.1 5883.9 5888.6 5896.9 5905.0 5186.6 5238.5 5255.4 5276.7 5386.6 5317.2 5342.3 5352.1 5362.9 5363.1 5226.8 5257.2 5260.4 5272.4 5356.0 5336.5 5349.9 5352.0 5358.0 5389.1 5082.2 5207.1 5209.3 5269.0 5281.2 5677.2 5680.7 5731.1 5755.4 5773.5 6276.8 6347.8 6363.9 6368.6 6381.0 6164.3 6254.0 6266.2 6324.1 6328.3 5873.9 5907.6 5924.3 5928.2 5942.3 6321.6 6333.1 6334.9 6339.1 6344.3 6005.3 6061.4 6070.5 6221.0 6232.1 6273.3 6321.0 6327.2 6328.2 6350.9 6226.1 6275.3 6297.7 6298.8 6310.1 6116.6 6189.4 6333.5 6338.4 6340.2 4992.6 4993.3 5019.2 5026.9 5027.9 3894.2 4487.8 4509.8 4564.3 4595.0 5852.1 5865.9 5880.8 5904.5 5924.3 6198.9 6267.0 6285.1 6333.6 6359.6 5787.1 5839.1 5848.0 5854.8 5876.4 5529.8 5534.2 5547.7 5550.5 5553.6 6241.0 6309.3 6369.7 6376.3 6383.2 6270.9 6308.7 6338.8 6342.5 6367.4 6338.8 6344.0 6344.9 6348.4 6352.5 6113.0 6274.4 6290.6 6307.3 6315.3 6263.4 6291.4 6294.7 6296.8 6355.3 6192.2 6194.1 6206.3 6207.1 6208.1 5961.2 6211.9 6214.0 6216.4 6221.5 5936.9 5973.4 6012.5 6082.1 6086.1 6162.0 6191.6 6191.6 6194.8 6212.7 5836.9 6003.0 6009.7 6022.7 6065.4 13:10:36 INFO - PROCESS | 1934 | 13:10:36 INFO - PROCESS | 1934 | [#1] ext.html Cycles:180 Average:79357.69 Median:40838.69 stddev:177769.69 (435.3%) stddev-sans-first:178169.81 13:10:36 INFO - PROCESS | 1934 | Values: 509.9 524.3 543.8 548.1 553.3 24904.6 30990.2 32445.8 41334.2 41960.4 43161.0 43496.6 43623.6 43626.3 43645.6 9595.1 9772.2 9788.3 9810.4 9813.7 8655.6 8817.1 9639.4 11521.9 11756.6 11799.5 11850.3 11853.7 11863.7 11872.3 11773.9 11835.4 11847.8 11859.4 11884.6 8553.5 11468.7 11736.3 11781.4 12013.5 41065.0 41431.8 41468.6 41537.2 41554.8 3350.7 3443.9 3496.5 3560.8 3580.5 25815.2 31528.8 32533.8 32831.5 33124.2 3095.1 3211.4 3226.1 3257.7 3287.5 546108.0 546409.0 547896.0 548358.3 549045.0 66017.3 66646.3 66665.7 66717.3 66719.0 67877.0 67976.0 68222.3 68231.0 68456.7 15005.7 17643.0 17697.8 17720.0 17752.5 10663.3 12187.0 12352.9 12645.4 12664.7 16669.9 18630.7 18957.4 19534.0 19556.4 6958.0 7842.0 8015.0 8015.8 8053.0 884.2 945.5 947.4 996.7 1193.7 29169.7 29885.4 30546.9 30628.0 30731.8 1007.1 1117.8 1119.3 1135.7 1184.4 15586.8 15921.6 16055.1 16109.5 16399.8 745053.0 751744.0 753766.0 754280.0 754410.0 61305.4 61548.4 61990.0 62014.7 62191.0 1122.9 1308.6 1421.7 1531.4 1635.6 27439.9 30650.0 32933.0 32980.8 33168.2 51974.2 52651.7 52693.7 52877.9 52928.5 662122.0 677380.0 677902.0 678751.0 679931.0 45618.8 45824.3 45929.0 45949.0 48007.3 50140.0 50451.0 50548.0 50682.7 51078.7 40818.0 40859.4 41315.6 41357.0 42985.9 41924.0 42002.2 42398.7 42760.8 43055.6 39856.0 40037.6 40247.6 40572.0 40695.2 34634.3 36386.8 36474.8 37012.3 37043.6 26239.6 26345.8 26420.5 26764.4 27332.0 13:10:36 INFO - PROCESS | 1934 | 13:10:36 INFO - PROCESS | 1934 | [#2] jquery.html Cycles:180 Average:36672.32 Median:34438.41 stddev:105203.72 (305.5%) stddev-sans-first:105463.49 13:10:36 INFO - PROCESS | 1934 | Values: 353.5 373.2 379.9 385.5 407.9 15844.0 16077.5 17100.5 21106.7 21388.3 20200.5 20383.5 20820.5 21075.7 21095.9 5011.7 5066.0 5316.2 5443.5 5675.2 5923.3 6086.2 6091.9 6093.8 6102.4 5757.3 5872.5 5942.8 6050.7 6051.8 5954.7 5977.5 5983.2 5993.2 5994.1 5041.2 5058.2 5182.8 5188.7 5199.9 25497.4 25601.0 25659.6 25761.2 26125.9 2312.7 2322.7 2443.5 2513.1 2834.5 15291.2 16993.7 17403.2 17445.1 17594.9 1947.9 1995.5 2079.7 2093.5 2109.1 62986.7 65253.0 66016.0 116009.0 128576.4 14883.6 16992.7 21540.1 21785.7 21903.3 34316.8 34560.0 35162.1 35175.5 35211.8 10821.4 11089.0 11252.5 11301.2 11370.5 9245.1 9254.1 9309.0 9416.4 9466.9 8186.8 8737.3 9383.1 9716.7 9802.9 3467.0 3589.7 3766.2 3767.7 3904.4 849.3 863.8 929.5 948.2 990.2 17916.4 19850.0 20406.1 20924.1 21028.1 952.7 971.6 986.9 996.8 1036.8 10530.0 10981.3 11002.2 11002.6 11036.0 616845.9 632920.0 637511.0 641927.8 646070.0 124602.0 132184.0 133772.0 136351.0 136408.0 961.0 972.2 1001.9 1002.7 1002.8 18390.8 18675.4 18826.8 18881.5 18887.4 47228.3 55782.2 56429.7 57287.1 57520.4 78832.6 80146.0 81170.6 81595.2 84607.6 6308.6 7358.6 7377.7 7844.6 8247.0 12122.0 12334.8 12370.8 13703.2 13742.2 11177.7 11362.3 11620.2 11777.6 12189.7 9765.6 10795.7 11259.5 11596.5 11706.6 11525.4 11568.2 11913.6 11961.8 12035.9 6570.2 7338.8 7360.9 7469.9 7589.1 10012.5 11491.5 11511.3 11841.4 11871.3 13:10:36 INFO - PROCESS | 1934 | 13:10:36 INFO - PROCESS | 1934 | [#3] mootools.html Cycles:180 Average:5736.57 Median:4617.71 stddev:3279.90 (71.0%) stddev-sans-first:3272.87 13:10:36 INFO - PROCESS | 1934 | Values: 1393.9 1394.4 1529.4 1624.7 1967.2 7292.8 7915.7 8870.3 8871.2 9027.5 8829.0 8892.3 8898.6 8910.5 8913.3 2358.5 2404.2 2406.7 2409.8 2410.5 2104.0 2377.8 2401.9 2412.0 2424.1 2368.6 2390.4 2396.9 2399.7 2406.0 1578.9 2378.2 2380.7 2392.3 2393.8 2329.6 2376.5 2376.8 2384.7 2384.8 8782.9 8949.0 9020.8 9031.5 9056.2 4466.7 5411.5 5412.1 5416.2 5426.3 3526.1 3558.3 3569.6 3622.3 3631.2 1044.4 1052.8 1066.4 1069.1 1071.9 11863.6 11908.3 12002.4 12005.7 12010.0 4461.4 4511.9 4687.2 4690.1 4690.1 9374.3 9403.2 9409.9 9428.9 9448.4 5316.2 5320.5 5323.0 5324.3 5333.1 4355.3 4533.5 4788.8 4945.1 4979.8 3429.9 3641.2 3646.3 3766.5 3883.3 1137.0 1179.0 1181.3 1190.5 1192.9 835.5 852.3 925.3 926.7 976.9 5697.7 5811.7 6015.1 6062.6 6126.9 419.8 447.2 450.6 981.0 1102.2 3827.0 3896.2 3942.2 4038.8 4081.9 10399.3 10818.0 10839.8 10848.6 10909.9 10767.1 10799.8 10800.0 10813.0 10829.9 6902.4 8375.0 8437.0 8459.9 8545.4 5974.8 6023.0 6045.8 6074.3 6080.7 10104.2 10372.7 10376.3 10398.8 10406.5 10028.0 10569.9 10582.0 10607.4 10609.5 6147.9 7181.1 7371.2 7739.8 7885.3 8449.2 8771.5 8778.1 8784.4 8801.0 7602.9 7712.3 7712.8 7833.0 7893.1 6501.0 7485.5 7613.3 7615.2 7615.2 7113.9 7264.4 7270.3 7271.3 7275.5 4924.5 4961.3 5055.7 5065.2 5145.1 4088.9 4548.2 5053.6 5081.7 5227.2 13:10:36 INFO - PROCESS | 1934 | 13:10:36 INFO - PROCESS | 1934 | [#4] prototype.html Cycles:180 Average:4350.45 Median:4697.57 stddev:1325.54 (28.2%) stddev-sans-first:1293.73 13:10:36 INFO - PROCESS | 1934 | Values: 289.4 296.6 308.2 310.9 316.1 4058.9 4871.0 5335.5 5337.8 5355.7 4437.4 4495.8 4779.4 4965.5 5379.3 4096.0 4108.3 4120.7 4137.4 4149.2 3644.8 3753.9 3957.3 4106.9 4140.6 3346.4 3871.4 4027.7 4072.2 4087.6 3410.4 4030.7 4031.8 4038.9 4096.8 3535.2 3545.3 3548.2 3553.7 3581.6 4790.2 4999.1 5237.5 5261.0 5280.3 3977.5 4483.3 4650.9 4683.8 4742.4 5299.9 5546.1 5551.9 5580.9 5593.7 3759.4 3913.6 3917.9 3928.7 3998.7 5266.4 5575.6 5706.1 5756.3 5811.3 3539.9 3576.8 3805.7 3964.3 3968.2 4014.7 4084.5 4095.6 4113.0 4117.8 3971.6 4502.7 4670.5 4772.3 4796.8 4940.9 5425.6 5649.8 5666.7 5693.5 1348.7 1382.3 1383.0 1400.4 1403.3 790.8 798.3 806.8 846.7 869.0 3661.6 4313.9 4320.5 4328.7 4392.2 4713.3 4732.3 4752.4 4757.9 4803.3 4157.9 4269.2 4277.1 4313.6 4413.9 2217.7 2307.6 2362.9 2371.4 2598.8 4276.5 4634.6 4934.7 4992.8 5025.9 3984.0 4839.6 4908.8 4910.4 5009.5 4599.2 4658.0 4668.0 4844.6 4849.8 4668.8 4682.7 4690.3 4704.8 4723.6 4480.4 4746.8 4783.5 4823.9 4877.1 4915.3 5497.4 6031.8 6040.5 6058.6 3890.5 3929.8 3966.3 3973.0 3979.5 4818.1 5963.9 5964.6 5980.4 5987.4 5596.3 5606.4 5638.8 5654.9 5676.0 5598.9 5625.8 5648.3 5656.8 5804.8 5161.4 5448.0 5552.8 5646.3 5668.6 4990.5 5627.6 5663.7 5665.9 5748.5 4657.6 5072.0 5658.0 5667.7 5719.8 13:10:36 INFO - PROCESS | 1934 | 13:10:36 INFO - PROCESS | 1934 | [#5] yui.html Cycles:180 Average:23671.84 Median:4491.19 stddev:36502.83 (812.8%) stddev-sans-first:36564.50 13:10:36 INFO - PROCESS | 1934 | Values: 706.4 736.0 775.7 799.0 860.6 14128.9 15238.3 16593.2 16632.8 16674.5 14282.3 14388.6 14411.7 14419.0 14421.1 705.2 719.5 720.2 729.8 882.1 724.1 878.4 908.0 910.0 911.1 943.3 961.9 962.2 963.0 963.3 955.3 955.8 956.5 957.1 957.4 547.4 691.8 709.6 711.7 713.4 15553.4 15678.7 15704.7 15710.4 15722.1 10807.0 13155.6 14414.1 14570.5 14682.4 11247.6 11688.0 11793.5 12728.5 13348.7 4654.0 4674.8 4721.8 4870.0 4880.8 111569.0 111845.3 112791.0 114239.0 117456.8 10646.8 11107.8 11138.9 11146.7 11328.8 21037.9 21041.0 21071.4 21183.4 21217.5 7841.1 7874.2 7885.2 7961.9 8006.1 4415.1 4432.3 4481.7 4500.7 4607.9 3322.3 3464.3 3470.3 3614.7 3615.5 639.4 711.2 745.9 750.2 761.1 587.8 675.9 698.3 698.7 706.4 9005.4 9315.5 9338.2 9786.0 9992.1 563.0 605.7 613.0 621.4 658.6 4642.7 4753.6 4767.4 4795.6 4874.3 76938.0 84816.6 93232.0 93507.0 93595.0 128422.0 138138.0 138965.3 141324.3 144071.0 98709.5 121355.0 121909.4 125101.0 125929.0 9128.0 9194.2 9199.3 9200.8 9365.4 60072.0 62604.4 63780.0 64357.4 65649.0 69145.0 74101.0 83727.2 87071.0 89930.6 12683.0 12904.7 13429.5 13479.9 13588.5 14858.8 14989.1 15473.4 16451.7 16480.7 13749.3 14052.3 14393.0 14635.8 14669.9 14271.9 14305.4 14316.3 14481.6 14737.3 13185.7 13397.9 13835.9 13922.0 13947.7 9560.8 11472.0 11499.1 11987.5 12427.8 10898.9 11895.9 11902.5 12040.2 12133.5 13:10:36 INFO - PROCESS | 1934 | -------- Summary: end -------- 13:10:36 INFO - PROCESS | 1934 | 13:10:36 INFO - TEST-INFO | 1934: exit 0 13:10:36 INFO - TEST-OK | dromaeo_css | took 1116724ms 13:10:36 INFO - TEST-START | kraken 13:10:36 INFO - operating with platform_type : mac_ 13:10:36 INFO - Initialising browser for kraken test... 13:10:36 INFO - TEST-INFO | started process 1938 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/tmpR3LcSR/profile http://localhost:50882/getInfo.html) 13:10:39 INFO - PROCESS | 1938 | __metrics Screen width/height:1600/1200 13:10:39 INFO - PROCESS | 1938 | colorDepth:24 13:10:39 INFO - PROCESS | 1938 | Browser inner width/height: 1024/689 13:10:39 INFO - PROCESS | 1938 | __metrics 13:10:39 INFO - PROCESS | 1938 | 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" 13:10:39 INFO - PROCESS | 1938 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:10:39 INFO - PROCESS | 1938 | ************************* 13:10:39 INFO - PROCESS | 1938 | A coding exception was thrown in a Promise resolution callback. 13:10:39 INFO - PROCESS | 1938 | See https://developer.mozilla.org/Mozilla/JavaScript_code_modules/Promise.jsm/Promise 13:10:39 INFO - PROCESS | 1938 | 13:10:39 ERROR - PROCESS | 1938 | Full message: TypeError: win is null 13:10:39 INFO - PROCESS | 1938 | Full stack: onSuccess@resource://app/components/nsBrowserGlue.js:844:17 13:10:39 INFO - PROCESS | 1938 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 13:10:39 INFO - PROCESS | 1938 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 13:10:39 INFO - PROCESS | 1938 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 13:10:39 INFO - PROCESS | 1938 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 13:10:39 INFO - PROCESS | 1938 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 13:10:39 INFO - PROCESS | 1938 | get _worker/worker.onmessage@resource://gre/modules/PromiseWorker.jsm:235:9 13:10:39 INFO - PROCESS | 1938 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 13:10:39 INFO - PROCESS | 1938 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 13:10:39 INFO - PROCESS | 1938 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 13:10:39 INFO - PROCESS | 1938 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 13:10:39 INFO - PROCESS | 1938 | Barrier/this.client.addBlocker/promise53.7% 13:11:36 INFO - TinderboxPrint: I/O read bytes / time
1,171,456 / 17 13:11:36 INFO - TinderboxPrint: I/O write bytes / time
666,015,744 / 2,334 13:11:36 INFO - TinderboxPrint: CPU idle
2,241.4 (46.3%) 13:11:36 INFO - TinderboxPrint: CPU system
971.2 (20.1%) 13:11:36 INFO - TinderboxPrint: CPU user
1,625.1 (33.6%) 13:11:36 INFO - TinderboxPrint: Swap in / out
365,735,936 / 0 13:11:36 INFO - install - Wall time: 33s; CPU: 55.0%; Read bytes: 365023744; Write bytes: 367912960; Read time: 28658; Write time: 722 13:11:36 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 1024000; Write bytes: 296460288; Read time: 13; Write time: 1604 13:11:36 INFO - Running post-run listener: _upload_blobber_files 13:11:36 INFO - Blob upload gear active. 13:11:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:11:36 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 13:11:36 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'] 13:11:36 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 13:11:37 INFO - (blobuploader) - INFO - Open directory for files ... 13:11:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:11:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:11:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:11:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:11:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:11:38 INFO - (blobuploader) - INFO - Done attempting. 13:11:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:11:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:11:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:11:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:11:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:11:39 INFO - (blobuploader) - INFO - Done attempting. 13:11:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:11:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:11:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:11:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:11:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:11:42 INFO - (blobuploader) - INFO - Done attempting. 13:11:42 INFO - (blobuploader) - INFO - Iteration through files over. 13:11:42 INFO - Return code: 0 13:11:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:11:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:11:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/99b266b2c61ae41385c5d5c4d7cad87fd4beaced302190e7a59d053147c1def7d3775f1df4e3ee1689658c3a8924a193f4735554326e9691982fefc97b66e52f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e2c847a6d7a7e6cd581b65ff645897715c2166c3cc1f2b82321876665f4bbbebef0b3e628d9c7aa11257b4fddc6fc3c1a73be24fe66aec7fb10dec47138f8d54", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4167317a9bc6690668106cf38a5228307c03b31eb30a8ecee4abce7fa21f1a1fc899b6ca3ed63359c03c5b79a4ad2a0571fbbbc188a83d09ccdd4a3acc1634fd"} 13:11:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:11:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:11:42 INFO - Contents: 13:11:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/99b266b2c61ae41385c5d5c4d7cad87fd4beaced302190e7a59d053147c1def7d3775f1df4e3ee1689658c3a8924a193f4735554326e9691982fefc97b66e52f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e2c847a6d7a7e6cd581b65ff645897715c2166c3cc1f2b82321876665f4bbbebef0b3e628d9c7aa11257b4fddc6fc3c1a73be24fe66aec7fb10dec47138f8d54", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4167317a9bc6690668106cf38a5228307c03b31eb30a8ecee4abce7fa21f1a1fc899b6ca3ed63359c03c5b79a4ad2a0571fbbbc188a83d09ccdd4a3acc1634fd"} 13:11:42 INFO - Running post-run listener: copy_logs_to_upload_dir 13:11:42 INFO - Copying logs to upload dir... 13:11:42 INFO - mkdir: /builds/slave/test/build/upload/logs 13:11:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1717.165011 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 37 secs) (at 2016-08-29 13:11:42.983428) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-29 13:11:42.987146) ========= 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.y2gnHV7Bhx/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.lkWpvolxMM/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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/99b266b2c61ae41385c5d5c4d7cad87fd4beaced302190e7a59d053147c1def7d3775f1df4e3ee1689658c3a8924a193f4735554326e9691982fefc97b66e52f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e2c847a6d7a7e6cd581b65ff645897715c2166c3cc1f2b82321876665f4bbbebef0b3e628d9c7aa11257b4fddc6fc3c1a73be24fe66aec7fb10dec47138f8d54", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4167317a9bc6690668106cf38a5228307c03b31eb30a8ecee4abce7fa21f1a1fc899b6ca3ed63359c03c5b79a4ad2a0571fbbbc188a83d09ccdd4a3acc1634fd"} build_url:https://queue.taskcluster.net/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/artifacts/public/build/firefox-50.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011060 build_url: 'https://queue.taskcluster.net/v1/task/UwCSDZB7Tw6VGOWhB_v3kw/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/99b266b2c61ae41385c5d5c4d7cad87fd4beaced302190e7a59d053147c1def7d3775f1df4e3ee1689658c3a8924a193f4735554326e9691982fefc97b66e52f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e2c847a6d7a7e6cd581b65ff645897715c2166c3cc1f2b82321876665f4bbbebef0b3e628d9c7aa11257b4fddc6fc3c1a73be24fe66aec7fb10dec47138f8d54", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4167317a9bc6690668106cf38a5228307c03b31eb30a8ecee4abce7fa21f1a1fc899b6ca3ed63359c03c5b79a4ad2a0571fbbbc188a83d09ccdd4a3acc1634fd"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-29 13:11:43.020603) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 13:11:43.020942) ========= 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.y2gnHV7Bhx/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.lkWpvolxMM/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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.005141 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 13:11:43.069780) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-29 13:11:43.070098) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-29 13:11:43.070449) ========= ========= Total master_lag: 3.52 =========