builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0253 starttime: 1464885311.96 results: success (0) buildid: 20160602074854 builduid: fb5540662f084481ae7af33147d81f7a revision: 0b1b9a827cb00e2051e2df795b1316856d1c190f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 09:35:11.961203) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 09:35:11.961697) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 09:35:11.961995) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 09:35:11.991821) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 09:35:11.992247) ========= 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.Ctk0eXsQhn/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.BdTl3P2Rk6/Listeners TMPDIR=/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-06-02 09:35:12-- 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% 18.1M=0.001s 2016-06-02 09:35:12 (18.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.123444 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 09:35:12.142815) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 09:35:12.143180) ========= 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.Ctk0eXsQhn/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.BdTl3P2Rk6/Listeners TMPDIR=/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.025771 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 09:35:12.196598) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-02 09:35:12.196955) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 0b1b9a827cb00e2051e2df795b1316856d1c190f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 0b1b9a827cb00e2051e2df795b1316856d1c190f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Ctk0eXsQhn/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.BdTl3P2Rk6/Listeners TMPDIR=/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-06-02 09:35:12,286 truncating revision to first 12 chars 2016-06-02 09:35:12,286 Setting DEBUG logging. 2016-06-02 09:35:12,287 attempt 1/10 2016-06-02 09:35:12,287 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/0b1b9a827cb0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-02 09:35:13,433 unpacking tar archive at: fx-team-0b1b9a827cb0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.399279 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-02 09:35:13.620316) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 09:35:13.620616) ========= script_repo_revision: 0b1b9a827cb00e2051e2df795b1316856d1c190f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 09:35:13.621007) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 09:35:13.621269) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 09:35:13.637244) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2016-06-02 09:35:13.637639) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Ctk0eXsQhn/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.BdTl3P2Rk6/Listeners TMPDIR=/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:35:13 INFO - MultiFileLogger online at 20160602 09:35:13 in /builds/slave/test 09:35:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 09:35:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:35:13 INFO - {'append_to_log': False, 09:35:13 INFO - 'base_work_dir': '/builds/slave/test', 09:35:13 INFO - 'blob_upload_branch': 'Fx-Team', 09:35:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:35:13 INFO - 'branch': 'Fx-Team', 09:35:13 INFO - 'buildbot_json_path': 'buildprops.json', 09:35:13 INFO - 'config_files': ('talos/mac_config.py',), 09:35:13 INFO - 'default_actions': ('clobber', 09:35:13 INFO - 'read-buildbot-config', 09:35:13 INFO - 'download-and-extract', 09:35:13 INFO - 'populate-webroot', 09:35:13 INFO - 'create-virtualenv', 09:35:13 INFO - 'install', 09:35:13 INFO - 'run-tests'), 09:35:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:35:13 INFO - 'download_minidump_stackwalk': True, 09:35:13 INFO - 'download_symbols': 'ondemand', 09:35:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:35:13 INFO - 'tooltool.py': '/tools/tooltool.py', 09:35:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:35:13 INFO - '/tools/misc-python/virtualenv.py')}, 09:35:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:35:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:35:13 INFO - 'installer_path': 'installer.exe', 09:35:13 INFO - 'log_level': 'info', 09:35:13 INFO - 'log_name': 'talos', 09:35:13 INFO - 'log_to_console': True, 09:35:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:35:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:35:13 INFO - 'opt_config_files': (), 09:35:13 INFO - 'pip_index': False, 09:35:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:35:13 INFO - 'cmd': ('bash', 09:35:13 INFO - '-c', 09:35:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:35:13 INFO - 'enabled': True, 09:35:13 INFO - 'halt_on_failure': False, 09:35:13 INFO - 'name': 'check_screen_resolution'},), 09:35:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:35:13 INFO - 'cmd': ('bash', 09:35:13 INFO - '-c', 09:35:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:35:13 INFO - 'enabled': True, 09:35:13 INFO - 'halt_on_failure': False, 09:35:13 INFO - 'name': 'check_screen_resolution'},), 09:35:13 INFO - 'run_cmd_checks_enabled': True, 09:35:13 INFO - 'sps_profile': False, 09:35:13 INFO - 'sps_profile_interval': 0, 09:35:13 INFO - 'suite': 'dromaeojs', 09:35:13 INFO - 'system_bits': '32', 09:35:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:35:13 INFO - 'title': 't-yosemite-r7-0253', 09:35:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:35:13 INFO - 'use_talos_json': True, 09:35:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:35:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:35:13 INFO - 'work_dir': 'build'} 09:35:13 INFO - ##### 09:35:13 INFO - ##### Running clobber step. 09:35:13 INFO - ##### 09:35:13 INFO - Running pre-action listener: _resource_record_pre_action 09:35:13 INFO - Running main action method: clobber 09:35:13 INFO - rmtree: /builds/slave/test/build 09:35:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:35:15 INFO - Running post-action listener: _resource_record_post_action 09:35:15 INFO - ##### 09:35:15 INFO - ##### Finished clobber step (success) 09:35:15 INFO - ##### 09:35:15 INFO - ##### 09:35:15 INFO - ##### Running read-buildbot-config step. 09:35:15 INFO - ##### 09:35:15 INFO - Running pre-action listener: _resource_record_pre_action 09:35:15 INFO - Running main action method: read_buildbot_config 09:35:15 INFO - Using buildbot properties: 09:35:15 INFO - { 09:35:15 INFO - "project": "", 09:35:15 INFO - "product": "firefox", 09:35:15 INFO - "script_repo_revision": "production", 09:35:15 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:35:15 INFO - "repository": "", 09:35:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:35:15 INFO - "buildid": "20160602074854", 09:35:15 INFO - "pgo_build": "False", 09:35:15 INFO - "basedir": "/builds/slave/test", 09:35:15 INFO - "buildnumber": 742, 09:35:15 INFO - "slavename": "t-yosemite-r7-0253", 09:35:15 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:35:15 INFO - "platform": "yosemite_r7", 09:35:15 INFO - "scheduler": "tests-fx-team-macosx64-talos", 09:35:15 INFO - "branch": "fx-team", 09:35:15 INFO - "repo_path": "integration/fx-team", 09:35:15 INFO - "revision": "0b1b9a827cb00e2051e2df795b1316856d1c190f", 09:35:15 INFO - "stage_platform": "macosx64", 09:35:15 INFO - "builduid": "fb5540662f084481ae7af33147d81f7a", 09:35:15 INFO - "slavebuilddir": "test" 09:35:15 INFO - } 09:35:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/EANK0UdmScCBNrhBRG_ITg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 09:35:15 INFO - Running post-action listener: _resource_record_post_action 09:35:15 INFO - ##### 09:35:15 INFO - ##### Finished read-buildbot-config step (success) 09:35:15 INFO - ##### 09:35:15 INFO - ##### 09:35:15 INFO - ##### Running download-and-extract step. 09:35:15 INFO - ##### 09:35:15 INFO - Running pre-action listener: _resource_record_pre_action 09:35:15 INFO - Running main action method: download_and_extract 09:35:15 INFO - mkdir: /builds/slave/test/build/tests 09:35: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')]} 09:35:15 INFO - https://queue.taskcluster.net/v1/task/EANK0UdmScCBNrhBRG_ITg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:35:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EANK0UdmScCBNrhBRG_ITg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 09:35:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EANK0UdmScCBNrhBRG_ITg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 09:35:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EANK0UdmScCBNrhBRG_ITg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 09:35:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EANK0UdmScCBNrhBRG_ITg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 09:35:18 INFO - Downloaded 1199 bytes. 09:35:18 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 09:35:18 INFO - Using the following test package requirements: 09:35:18 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 09:35:18 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:35:18 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 09:35:18 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:35:18 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 09:35:18 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:35:18 INFO - u'jsshell-mac.zip'], 09:35:18 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:35:18 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 09:35:18 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 09:35:18 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:35:18 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 09:35:18 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:35:18 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 09:35:18 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:35:18 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 09:35:18 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:35:18 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 09:35:18 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:35:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:35:18 INFO - https://queue.taskcluster.net/v1/task/EANK0UdmScCBNrhBRG_ITg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:35:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EANK0UdmScCBNrhBRG_ITg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:35:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EANK0UdmScCBNrhBRG_ITg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:35:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EANK0UdmScCBNrhBRG_ITg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:35:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EANK0UdmScCBNrhBRG_ITg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:35:20 INFO - Downloaded 18713546 bytes. 09:35:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:35:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:35:23 INFO - Return code: 0 09:35:23 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:35:23 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')]} 09:35:23 INFO - https://queue.taskcluster.net/v1/task/EANK0UdmScCBNrhBRG_ITg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:35:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EANK0UdmScCBNrhBRG_ITg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:35:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EANK0UdmScCBNrhBRG_ITg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:35:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EANK0UdmScCBNrhBRG_ITg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:35:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EANK0UdmScCBNrhBRG_ITg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:35:24 INFO - Downloaded 18713546 bytes. 09:35:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:35:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:35:27 INFO - Return code: 0 09:35:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:35:27 INFO - https://queue.taskcluster.net/v1/task/EANK0UdmScCBNrhBRG_ITg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:35:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EANK0UdmScCBNrhBRG_ITg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 09:35:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EANK0UdmScCBNrhBRG_ITg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 09:35:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EANK0UdmScCBNrhBRG_ITg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 09:35:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EANK0UdmScCBNrhBRG_ITg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:35:30 INFO - Downloaded 10834055 bytes. 09:35:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:35:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:35:30 INFO - Return code: 0 09:35:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:35:30 INFO - https://queue.taskcluster.net/v1/task/EANK0UdmScCBNrhBRG_ITg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:35:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EANK0UdmScCBNrhBRG_ITg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:35:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EANK0UdmScCBNrhBRG_ITg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:35:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EANK0UdmScCBNrhBRG_ITg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 09:35:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EANK0UdmScCBNrhBRG_ITg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 09:35:38 INFO - Downloaded 111081908 bytes. 09:35:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EANK0UdmScCBNrhBRG_ITg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:35:38 INFO - mkdir: /builds/slave/test/properties 09:35:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:35:38 INFO - Writing to file /builds/slave/test/properties/build_url 09:35:38 INFO - Contents: 09:35:38 INFO - build_url:https://queue.taskcluster.net/v1/task/EANK0UdmScCBNrhBRG_ITg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:35:40 INFO - Running post-action listener: _resource_record_post_action 09:35:40 INFO - Running post-action listener: set_extra_try_arguments 09:35:40 INFO - ##### 09:35:40 INFO - ##### Finished download-and-extract step (success) 09:35:40 INFO - ##### 09:35:40 INFO - ##### 09:35:40 INFO - ##### Running populate-webroot step. 09:35:40 INFO - ##### 09:35:40 INFO - Running pre-action listener: _resource_record_pre_action 09:35:40 INFO - Running main action method: populate_webroot 09:35:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:35:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:35:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:35:40 INFO - '--tppagecycles', 09:35:40 INFO - '7'], 09:35:40 INFO - 'tests': ['tsvgm']}}, 09:35:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:35:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:35:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:35:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:35:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:35:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:35:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:35:40 INFO - 'talos_options': ['--disable-e10s'], 09:35:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:35:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:35:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:35:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:35:40 INFO - 'talos_options': ['--disable-e10s'], 09:35:40 INFO - 'tests': ['damp', 'tps']}, 09:35:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:35:40 INFO - 'tests': ['damp', 'tps']}, 09:35:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:35:40 INFO - 'tests': ['dromaeo_dom']}, 09:35:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:35:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:35:40 INFO - 'tests': ['a11yr', 09:35:40 INFO - 'ts_paint', 09:35:40 INFO - 'tpaint', 09:35:40 INFO - 'sessionrestore', 09:35:40 INFO - 'sessionrestore_no_auto_restore', 09:35:40 INFO - 'tabpaint']}, 09:35:40 INFO - 'other-e10s': {'tests': ['a11yr', 09:35:40 INFO - 'ts_paint', 09:35:40 INFO - 'tpaint', 09:35:40 INFO - 'sessionrestore', 09:35:40 INFO - 'sessionrestore_no_auto_restore', 09:35:40 INFO - 'tabpaint']}, 09:35:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:35:40 INFO - 'ts_paint', 09:35:40 INFO - 'tpaint', 09:35:40 INFO - 'sessionrestore', 09:35:40 INFO - 'sessionrestore_no_auto_restore', 09:35:40 INFO - 'tabpaint']}, 09:35:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:35:40 INFO - 'ts_paint', 09:35:40 INFO - 'tpaint', 09:35:40 INFO - 'sessionrestore', 09:35:40 INFO - 'sessionrestore_no_auto_restore', 09:35:40 INFO - 'tabpaint']}, 09:35:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:35:40 INFO - 'tests': ['a11yr', 09:35:40 INFO - 'ts_paint', 09:35:40 INFO - 'tpaint', 09:35:40 INFO - 'sessionrestore', 09:35:40 INFO - 'sessionrestore_no_auto_restore', 09:35:40 INFO - 'tabpaint']}, 09:35:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:35:40 INFO - 'tests': ['a11yr', 09:35:40 INFO - 'ts_paint', 09:35:40 INFO - 'tpaint', 09:35:40 INFO - 'sessionrestore', 09:35:40 INFO - 'sessionrestore_no_auto_restore', 09:35:40 INFO - 'tabpaint']}, 09:35:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:35:40 INFO - 'tests': ['tsvgx', 09:35:40 INFO - 'tsvgr_opacity', 09:35:40 INFO - 'tart', 09:35:40 INFO - 'tscrollx', 09:35:40 INFO - 'cart']}, 09:35:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:35:40 INFO - 'tsvgr_opacity', 09:35:40 INFO - 'tart', 09:35:40 INFO - 'tscrollx', 09:35:40 INFO - 'cart']}, 09:35:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:35:40 INFO - 'talos_options': ['--disable-e10s'], 09:35:40 INFO - 'tests': ['tp5o']}, 09:35:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:35:40 INFO - 'tests': ['tp5o']}, 09:35:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:35:40 INFO - 'talos_options': ['--disable-e10s', 09:35:40 INFO - '--xperf_path', 09:35:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:35:40 INFO - 'tests': ['tp5n']}, 09:35:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:35:40 INFO - 'talos_options': ['--xperf_path', 09:35:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:35:40 INFO - 'tests': ['tp5n']}}, 09:35:40 INFO - 'talos.zip': {'path': '', 09:35:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:35:40 INFO - Running post-action listener: _resource_record_post_action 09:35:40 INFO - ##### 09:35:40 INFO - ##### Finished populate-webroot step (success) 09:35:40 INFO - ##### 09:35:40 INFO - ##### 09:35:40 INFO - ##### Running create-virtualenv step. 09:35:40 INFO - ##### 09:35:40 INFO - Running pre-action listener: _resource_record_pre_action 09:35:40 INFO - Running main action method: create_virtualenv 09:35:40 INFO - Creating virtualenv /builds/slave/test/build/venv 09:35:40 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 09:35:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:35:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:35:40 INFO - Using real prefix '/tools/python27' 09:35:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:35:41 INFO - Installing distribute.............................................................................................................................................................................................done. 09:35:43 INFO - Installing pip.................done. 09:35:43 INFO - Return code: 0 09:35:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:35:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:35:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:35:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:43 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 0x1078dea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078e4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb833c6ac60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107782d30>, '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 0x1075a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107779b20>, '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.Ctk0eXsQhn/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.BdTl3P2Rk6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:35:43 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 09:35:43 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 09:35:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ctk0eXsQhn/Render', 09:35:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:43 INFO - 'HOME': '/Users/cltbld', 09:35:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:43 INFO - 'LOGNAME': 'cltbld', 09:35:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:43 INFO - 'MOZ_NO_REMOTE': '1', 09:35:43 INFO - 'NO_EM_RESTART': '1', 09:35:43 INFO - 'PAGER': '/bin/cat', 09:35:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:43 INFO - 'PWD': '/builds/slave/test', 09:35:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:43 INFO - 'SHELL': '/bin/bash', 09:35:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BdTl3P2Rk6/Listeners', 09:35:43 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 09:35:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:43 INFO - 'USER': 'cltbld', 09:35:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:43 INFO - 'XPC_FLAGS': '0x0', 09:35:43 INFO - 'XPC_SERVICE_NAME': '0', 09:35:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:44 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 09:35:44 INFO - Cleaning up... 09:35:44 INFO - Return code: 0 09:35:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:35:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:35:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:35:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:44 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 0x1078dea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078e4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb833c6ac60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107782d30>, '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 0x1075a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107779b20>, '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.Ctk0eXsQhn/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.BdTl3P2Rk6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:35:44 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 09:35:44 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 09:35:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ctk0eXsQhn/Render', 09:35:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:44 INFO - 'HOME': '/Users/cltbld', 09:35:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:44 INFO - 'LOGNAME': 'cltbld', 09:35:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:44 INFO - 'MOZ_NO_REMOTE': '1', 09:35:44 INFO - 'NO_EM_RESTART': '1', 09:35:44 INFO - 'PAGER': '/bin/cat', 09:35:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:44 INFO - 'PWD': '/builds/slave/test', 09:35:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:44 INFO - 'SHELL': '/bin/bash', 09:35:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BdTl3P2Rk6/Listeners', 09:35:44 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 09:35:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:44 INFO - 'USER': 'cltbld', 09:35:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:44 INFO - 'XPC_FLAGS': '0x0', 09:35:44 INFO - 'XPC_SERVICE_NAME': '0', 09:35:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:44 INFO - Downloading/unpacking psutil>=3.1.1 09:35:44 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 09:35:44 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 09:35:44 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 09:35:44 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 09:35:44 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 09:35:44 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 09:35:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:35:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:35:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:35:46 INFO - Installing collected packages: psutil 09:35:46 INFO - Running setup.py install for psutil 09:35:47 INFO - building 'psutil._psutil_osx' extension 09:35:47 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 09:35:47 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 09:35:47 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 09:35:47 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 09:35:47 INFO - building 'psutil._psutil_posix' extension 09:35:47 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 09:35:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:35:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:35:47 INFO - ^ 09:35:47 INFO - 1 warning generated. 09:35:47 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 09:35:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:35:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:35:48 INFO - Successfully installed psutil 09:35:48 INFO - Cleaning up... 09:35:48 INFO - Return code: 0 09:35:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:35:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:35:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:35:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:48 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 0x1078dea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078e4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb833c6ac60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107782d30>, '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 0x1075a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107779b20>, '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.Ctk0eXsQhn/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.BdTl3P2Rk6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:35:48 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 09:35:48 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 09:35:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ctk0eXsQhn/Render', 09:35:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:48 INFO - 'HOME': '/Users/cltbld', 09:35:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:48 INFO - 'LOGNAME': 'cltbld', 09:35:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:48 INFO - 'MOZ_NO_REMOTE': '1', 09:35:48 INFO - 'NO_EM_RESTART': '1', 09:35:48 INFO - 'PAGER': '/bin/cat', 09:35:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:48 INFO - 'PWD': '/builds/slave/test', 09:35:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:48 INFO - 'SHELL': '/bin/bash', 09:35:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BdTl3P2Rk6/Listeners', 09:35:48 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 09:35:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:48 INFO - 'USER': 'cltbld', 09:35:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:48 INFO - 'XPC_FLAGS': '0x0', 09:35:48 INFO - 'XPC_SERVICE_NAME': '0', 09:35:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:35:48 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 09:35:48 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 09:35:48 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 09:35:48 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 09:35:48 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 09:35:48 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 09:35:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:35:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:35:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:35:50 INFO - Installing collected packages: mozsystemmonitor 09:35:50 INFO - Running setup.py install for mozsystemmonitor 09:35:51 INFO - Successfully installed mozsystemmonitor 09:35:51 INFO - Cleaning up... 09:35:51 INFO - Return code: 0 09:35:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:35:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:35:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:35:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:51 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 0x1078dea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078e4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb833c6ac60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107782d30>, '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 0x1075a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107779b20>, '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.Ctk0eXsQhn/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.BdTl3P2Rk6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:35:51 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 09:35:51 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 09:35:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ctk0eXsQhn/Render', 09:35:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:51 INFO - 'HOME': '/Users/cltbld', 09:35:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:51 INFO - 'LOGNAME': 'cltbld', 09:35:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:51 INFO - 'MOZ_NO_REMOTE': '1', 09:35:51 INFO - 'NO_EM_RESTART': '1', 09:35:51 INFO - 'PAGER': '/bin/cat', 09:35:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:51 INFO - 'PWD': '/builds/slave/test', 09:35:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:51 INFO - 'SHELL': '/bin/bash', 09:35:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BdTl3P2Rk6/Listeners', 09:35:51 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 09:35:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:51 INFO - 'USER': 'cltbld', 09:35:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:51 INFO - 'XPC_FLAGS': '0x0', 09:35:51 INFO - 'XPC_SERVICE_NAME': '0', 09:35:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:51 INFO - Downloading/unpacking blobuploader==1.2.4 09:35:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:35:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:35:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:53 INFO - Downloading blobuploader-1.2.4.tar.gz 09:35:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:35:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:35:53 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 09:35:53 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 09:35:53 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 09:35:53 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 09:35:53 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 09:35:53 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 09:35:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:35:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:35:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:35:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:35:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:55 INFO - Downloading docopt-0.6.1.tar.gz 09:35:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:35:55 INFO - Installing collected packages: blobuploader, requests, docopt 09:35:55 INFO - Running setup.py install for blobuploader 09:35:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:35:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:35:55 INFO - Running setup.py install for requests 09:35:55 INFO - Running setup.py install for docopt 09:35:55 INFO - Successfully installed blobuploader requests docopt 09:35:55 INFO - Cleaning up... 09:35:55 INFO - Return code: 0 09:35:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:35:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:35:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:35:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:55 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 0x1078dea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078e4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb833c6ac60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107782d30>, '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 0x1075a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107779b20>, '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.Ctk0eXsQhn/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.BdTl3P2Rk6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:35:55 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 09:35:55 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 09:35:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ctk0eXsQhn/Render', 09:35:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:55 INFO - 'HOME': '/Users/cltbld', 09:35:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:55 INFO - 'LOGNAME': 'cltbld', 09:35:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:55 INFO - 'MOZ_NO_REMOTE': '1', 09:35:55 INFO - 'NO_EM_RESTART': '1', 09:35:55 INFO - 'PAGER': '/bin/cat', 09:35:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:55 INFO - 'PWD': '/builds/slave/test', 09:35:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:55 INFO - 'SHELL': '/bin/bash', 09:35:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BdTl3P2Rk6/Listeners', 09:35:55 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 09:35:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:55 INFO - 'USER': 'cltbld', 09:35:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:55 INFO - 'XPC_FLAGS': '0x0', 09:35:55 INFO - 'XPC_SERVICE_NAME': '0', 09:35:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:35:56 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-lb80rJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:35:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:35:56 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-3stIRT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:35:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:35:56 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-c8hUon-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:35:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:35:56 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-MMPqBl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:35:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:35:56 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-aSQi0n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:35:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:35:56 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-F84VC_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:35:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:35:56 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-hvmHd9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:35:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:35:56 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-yI_3jB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:35:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:35:57 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-f8xrYl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:35:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:35:57 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-FvxSOq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:35:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:35:57 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-4uZFrK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:35:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:35:57 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-M1yGOF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:35:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:35:57 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-epyGt2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:35:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:35:57 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-xazCY9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:35:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:35:57 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-XAVjs2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:35:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:35:57 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-SQhQ7A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:35:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:35:57 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-j5cvmx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:35:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:35:57 INFO - Running setup.py install for manifestparser 09:35:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:35:58 INFO - Running setup.py install for mozcrash 09:35:58 INFO - Running setup.py install for mozdebug 09:35:58 INFO - Running setup.py install for mozdevice 09:35:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:35:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:35:58 INFO - Running setup.py install for mozfile 09:35:58 INFO - Running setup.py install for mozhttpd 09:35:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:35:58 INFO - Running setup.py install for mozinfo 09:35:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:35:59 INFO - Running setup.py install for mozInstall 09:35:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:35:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:35:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:35:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:35:59 INFO - Running setup.py install for mozleak 09:35:59 INFO - Running setup.py install for mozlog 09:35:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:35:59 INFO - Running setup.py install for moznetwork 09:35:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:35:59 INFO - Running setup.py install for mozprocess 09:35:59 INFO - Running setup.py install for mozprofile 09:35:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:35:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:35:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:35:59 INFO - Running setup.py install for mozrunner 09:36:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:36:00 INFO - Running setup.py install for mozscreenshot 09:36:00 INFO - Running setup.py install for moztest 09:36:00 INFO - Running setup.py install for mozversion 09:36:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:36:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:36:00 INFO - Cleaning up... 09:36:00 INFO - Return code: 0 09:36:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:36:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:00 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 0x1078dea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078e4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb833c6ac60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107782d30>, '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 0x1075a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107779b20>, '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.Ctk0eXsQhn/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.BdTl3P2Rk6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:36:00 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 09:36:00 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 09:36:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ctk0eXsQhn/Render', 09:36:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:00 INFO - 'HOME': '/Users/cltbld', 09:36:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:00 INFO - 'LOGNAME': 'cltbld', 09:36:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:00 INFO - 'MOZ_NO_REMOTE': '1', 09:36:00 INFO - 'NO_EM_RESTART': '1', 09:36:00 INFO - 'PAGER': '/bin/cat', 09:36:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:00 INFO - 'PWD': '/builds/slave/test', 09:36:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:00 INFO - 'SHELL': '/bin/bash', 09:36:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BdTl3P2Rk6/Listeners', 09:36:00 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 09:36:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:00 INFO - 'USER': 'cltbld', 09:36:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:00 INFO - 'XPC_FLAGS': '0x0', 09:36:00 INFO - 'XPC_SERVICE_NAME': '0', 09:36:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:36:00 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-53_q5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:36:00 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)) 09:36:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:36:00 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-x4EWpu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:36:01 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)) 09:36:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:36:01 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-6LateD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:36:01 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)) 09:36:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:36:01 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-hUhw8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:36:01 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)) 09:36:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:36:01 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-hTGVPl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:36:01 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)) 09:36:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:36:01 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-HM6E56-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:36:01 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)) 09:36:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:36:01 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-YsCDxH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:36:01 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)) 09:36:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:36:01 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-KuqzyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:36:01 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)) 09:36:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:36:01 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-JF4h1y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:36:01 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)) 09:36:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:36:01 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-EfOc15-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:36:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:36:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:36:01 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-WTZvSy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:36:01 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)) 09:36:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:36:01 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-4NPkQ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:36:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:36:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:36:02 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-jwI_bU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:36:02 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)) 09:36:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:36:02 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-uLFkTO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:36:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:36:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:36:02 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-stw5yS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:36:02 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)) 09:36:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:36:02 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-pnrZJb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:36:02 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)) 09:36:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:36:02 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-vUtbXJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:36:02 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)) 09:36:02 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)) 09:36:02 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)) 09:36:02 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)) 09:36:02 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)) 09:36:02 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)) 09:36:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:36:02 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 09:36:02 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 09:36:02 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 09:36:02 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 09:36:02 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 09:36:02 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 09:36:05 INFO - Downloading blessings-1.6.tar.gz 09:36:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:36:05 INFO - Installing collected packages: blessings 09:36:05 INFO - Running setup.py install for blessings 09:36:05 INFO - Successfully installed blessings 09:36:05 INFO - Cleaning up... 09:36:05 INFO - Return code: 0 09:36:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:36:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:36:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:36:06 INFO - Reading from file tmpfile_stdout 09:36:06 INFO - Current package versions: 09:36:06 INFO - blessings == 1.6 09:36:06 INFO - blobuploader == 1.2.4 09:36:06 INFO - docopt == 0.6.1 09:36:06 INFO - manifestparser == 1.1 09:36:06 INFO - mozInstall == 1.12 09:36:06 INFO - mozcrash == 0.17 09:36:06 INFO - mozdebug == 0.1 09:36:06 INFO - mozdevice == 0.48 09:36:06 INFO - mozfile == 1.2 09:36:06 INFO - mozhttpd == 0.7 09:36:06 INFO - mozinfo == 0.9 09:36:06 INFO - mozleak == 0.1 09:36:06 INFO - mozlog == 3.2 09:36:06 INFO - moznetwork == 0.27 09:36:06 INFO - mozprocess == 0.22 09:36:06 INFO - mozprofile == 0.28 09:36:06 INFO - mozrunner == 6.11 09:36:06 INFO - mozscreenshot == 0.1 09:36:06 INFO - mozsystemmonitor == 0.3 09:36:06 INFO - moztest == 0.7 09:36:06 INFO - mozversion == 1.4 09:36:06 INFO - psutil == 3.1.1 09:36:06 INFO - requests == 1.2.3 09:36:06 INFO - wsgiref == 0.1.2 09:36:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:36:06 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')]} 09:36:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:06 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')]} 09:36:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:06 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 0x1078dea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078e4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb833c6ac60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107782d30>, '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 0x1075a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107779b20>, '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.Ctk0eXsQhn/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.BdTl3P2Rk6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:36:06 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 09:36:06 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 09:36:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ctk0eXsQhn/Render', 09:36:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:06 INFO - 'HOME': '/Users/cltbld', 09:36:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:06 INFO - 'LOGNAME': 'cltbld', 09:36:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:06 INFO - 'MOZ_NO_REMOTE': '1', 09:36:06 INFO - 'NO_EM_RESTART': '1', 09:36:06 INFO - 'PAGER': '/bin/cat', 09:36:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:06 INFO - 'PWD': '/builds/slave/test', 09:36:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:06 INFO - 'SHELL': '/bin/bash', 09:36:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BdTl3P2Rk6/Listeners', 09:36:06 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 09:36:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:06 INFO - 'USER': 'cltbld', 09:36:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:06 INFO - 'XPC_FLAGS': '0x0', 09:36:06 INFO - 'XPC_SERVICE_NAME': '0', 09:36:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:06 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)) 09:36:06 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)) 09:36:06 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)) 09:36:06 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)) 09:36:06 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)) 09:36:06 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)) 09:36:06 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)) 09:36:06 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)) 09:36:06 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)) 09:36:06 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)) 09:36:06 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)) 09:36:06 INFO - Cleaning up... 09:36:06 INFO - Return code: 0 09:36:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:36:06 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')]} 09:36:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:06 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')]} 09:36:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:06 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 0x1078dea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078e4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb833c6ac60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107782d30>, '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 0x1075a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107779b20>, '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.Ctk0eXsQhn/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.BdTl3P2Rk6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:36:06 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 09:36:06 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 09:36:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ctk0eXsQhn/Render', 09:36:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:06 INFO - 'HOME': '/Users/cltbld', 09:36:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:06 INFO - 'LOGNAME': 'cltbld', 09:36:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:06 INFO - 'MOZ_NO_REMOTE': '1', 09:36:06 INFO - 'NO_EM_RESTART': '1', 09:36:06 INFO - 'PAGER': '/bin/cat', 09:36:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:06 INFO - 'PWD': '/builds/slave/test', 09:36:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:06 INFO - 'SHELL': '/bin/bash', 09:36:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BdTl3P2Rk6/Listeners', 09:36:06 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 09:36:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:06 INFO - 'USER': 'cltbld', 09:36:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:06 INFO - 'XPC_FLAGS': '0x0', 09:36:06 INFO - 'XPC_SERVICE_NAME': '0', 09:36:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:07 INFO - Downloading/unpacking jsonschema 09:36: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 09:36: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 09:36: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 09:36: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 09:36: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 09:36: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 09:36:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:36:09 INFO - Downloading/unpacking functools32 (from jsonschema) 09:36:09 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 09:36:09 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 09:36:09 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 09:36:09 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 09:36:09 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 09:36:09 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 09:36:10 INFO - Downloading functools32-3.2.3-2.tar.gz 09:36:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:36:10 INFO - warning: no files found matching '*.txt' 09:36:10 INFO - no previously-included directories found matching 'build' 09:36:10 INFO - no previously-included directories found matching 'dist' 09:36:10 INFO - no previously-included directories found matching '.git*' 09:36:10 INFO - Installing collected packages: jsonschema, functools32 09:36:10 INFO - Running setup.py install for functools32 09:36:10 INFO - warning: no files found matching '*.txt' 09:36:10 INFO - no previously-included directories found matching 'build' 09:36:10 INFO - no previously-included directories found matching 'dist' 09:36:10 INFO - no previously-included directories found matching '.git*' 09:36:10 INFO - Successfully installed jsonschema functools32 09:36:10 INFO - Cleaning up... 09:36:10 INFO - Return code: 0 09:36:10 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:36: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')]} 09:36:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36: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')]} 09:36:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1078dea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078e4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb833c6ac60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107782d30>, '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 0x1075a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107779b20>, '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.Ctk0eXsQhn/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.BdTl3P2Rk6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:36: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', 'flake8'] in /builds/slave/test/build 09:36: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 flake8 09:36:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ctk0eXsQhn/Render', 09:36:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:10 INFO - 'HOME': '/Users/cltbld', 09:36:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:10 INFO - 'LOGNAME': 'cltbld', 09:36:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:10 INFO - 'MOZ_NO_REMOTE': '1', 09:36:10 INFO - 'NO_EM_RESTART': '1', 09:36:10 INFO - 'PAGER': '/bin/cat', 09:36:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:10 INFO - 'PWD': '/builds/slave/test', 09:36:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:10 INFO - 'SHELL': '/bin/bash', 09:36:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BdTl3P2Rk6/Listeners', 09:36:10 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 09:36:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:10 INFO - 'USER': 'cltbld', 09:36:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:10 INFO - 'XPC_FLAGS': '0x0', 09:36:10 INFO - 'XPC_SERVICE_NAME': '0', 09:36:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:10 INFO - Downloading/unpacking flake8 09:36: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 09:36: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 09:36: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 09:36: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 09:36: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 09:36: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 09:36:13 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:36:13 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:36: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 09:36: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 09:36: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 09:36: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 09:36: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 09:36: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 09:36:13 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:36:13 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:36: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 09:36: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 09:36: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 09:36: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 09:36: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 09:36: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 09:36:14 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:36: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 09:36: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 09:36: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 09:36: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 09:36: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 09:36: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 09:36:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:36:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:36:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:36:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:36:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:36:14 INFO - no previously-included directories found matching 'docs/_build' 09:36:14 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:36:14 INFO - Running setup.py install for pep8 09:36:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:36:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:36:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:36:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:36:14 INFO - no previously-included directories found matching 'docs/_build' 09:36:14 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:36:14 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:36:14 INFO - Cleaning up... 09:36:14 INFO - Return code: 0 09:36:14 INFO - Running post-action listener: _resource_record_post_action 09:36:14 INFO - Running post-action listener: _start_resource_monitoring 09:36:14 INFO - Starting resource monitoring. 09:36:14 INFO - ##### 09:36:14 INFO - ##### Finished create-virtualenv step (success) 09:36:14 INFO - ##### 09:36:14 INFO - ##### 09:36:14 INFO - ##### Running install step. 09:36:14 INFO - ##### 09:36:14 INFO - Running pre-action listener: _resource_record_pre_action 09:36:14 INFO - Running main action method: install 09:36:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:36:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:36:15 INFO - Reading from file tmpfile_stdout 09:36:15 INFO - Detecting whether we're running mozinstall >=1.0... 09:36:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:36:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:36:15 INFO - Reading from file tmpfile_stdout 09:36:15 INFO - Output received: 09:36:15 INFO - Usage: mozinstall [options] installer 09:36:15 INFO - Options: 09:36:15 INFO - -h, --help show this help message and exit 09:36:15 INFO - -d DEST, --destination=DEST 09:36:15 INFO - Directory to install application into. [default: 09:36:15 INFO - "/builds/slave/test"] 09:36:15 INFO - --app=APP Application being installed. [default: firefox] 09:36:15 INFO - mkdir: /builds/slave/test/build/application 09:36:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:36:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:36:49 INFO - Reading from file tmpfile_stdout 09:36:49 INFO - Output received: 09:36:49 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:36:49 INFO - Running post-action listener: _resource_record_post_action 09:36:49 INFO - ##### 09:36:49 INFO - ##### Finished install step (success) 09:36:49 INFO - ##### 09:36:49 INFO - ##### 09:36:49 INFO - ##### Running run-tests step. 09:36:49 INFO - ##### 09:36:49 INFO - Running pre-action listener: _resource_record_pre_action 09:36:49 INFO - Running main action method: run_tests 09:36:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:36:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:36:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:36:49 INFO - 2016-06-02 09:36:49.489 screenresolution[1640:7610] starting screenresolution argv=screenresolution get 09:36:49 INFO - 2016-06-02 09:36:49.492 screenresolution[1640:7610] Display 0: 1600x1200x32@60 09:36:49 INFO - 2016-06-02 09:36:49.498 screenresolution[1641:7612] starting screenresolution argv=screenresolution list 09:36:49 INFO - Available Modes on Display 0 09:36:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:36:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:36:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:36:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:36:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:36:49 INFO - Intel Iris: 09:36:49 INFO - Chipset Model: Intel Iris 09:36:49 INFO - Type: GPU 09:36:49 INFO - Bus: Built-In 09:36:49 INFO - VRAM (Dynamic, Max): 1536 MB 09:36:49 INFO - Vendor: Intel (0x8086) 09:36:49 INFO - Device ID: 0x0a2e 09:36:49 INFO - Revision ID: 0x0009 09:36:49 INFO - Displays: 09:36:49 INFO - Display: 09:36:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:36:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:36:49 INFO - Display Serial Number: 1600x1200 60 09:36:49 INFO - Main Display: Yes 09:36:49 INFO - Mirror: Off 09:36:49 INFO - Online: Yes 09:36:49 INFO - Rotation: Supported 09:36:49 INFO - Return code: 0 09:36:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:36:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:36:49 INFO - Python 2.7.3 09:36:49 INFO - Return code: 0 09:36:49 INFO - grabbing minidump binary from tooltool 09:36: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')]} 09:36:49 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 0x107782d30>, '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 0x1075a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107779b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:36:49 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 09:36:49 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 09:36:49 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 09:36:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:36:49 INFO - Return code: 0 09:36:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:36:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:36:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:36:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:36:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:36:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:36:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:36:50 INFO - Return code: 0 09:36:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EANK0UdmScCBNrhBRG_ITg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0253', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:36:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EANK0UdmScCBNrhBRG_ITg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0253 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:36:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ctk0eXsQhn/Render', 09:36:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:50 INFO - 'HOME': '/Users/cltbld', 09:36:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:50 INFO - 'LOGNAME': 'cltbld', 09:36:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:36:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:36:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:50 INFO - 'MOZ_NO_REMOTE': '1', 09:36:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:36:50 INFO - 'NO_EM_RESTART': '1', 09:36:50 INFO - 'PAGER': '/bin/cat', 09:36:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:50 INFO - 'PWD': '/builds/slave/test', 09:36:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:36:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:50 INFO - 'SHELL': '/bin/bash', 09:36:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BdTl3P2Rk6/Listeners', 09:36:50 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 09:36:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:50 INFO - 'USER': 'cltbld', 09:36:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:50 INFO - 'XPC_FLAGS': '0x0', 09:36:50 INFO - 'XPC_SERVICE_NAME': '0', 09:36:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EANK0UdmScCBNrhBRG_ITg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0253', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:36:51 INFO - mozversion application_buildid: 20160602074854 09:36:51 INFO - mozversion application_changeset: 0b1b9a827cb00e2051e2df795b1316856d1c190f 09:36:51 INFO - mozversion application_display_name: Nightly 09:36:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:36:51 INFO - mozversion application_name: Firefox 09:36:51 INFO - mozversion application_remotingname: firefox 09:36:51 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:36:51 INFO - mozversion application_vendor: Mozilla 09:36:51 INFO - mozversion application_version: 49.0a1 09:36:51 INFO - mozversion platform_buildid: 20160602074854 09:36:51 INFO - mozversion platform_changeset: 0b1b9a827cb00e2051e2df795b1316856d1c190f 09:36:51 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:36:51 INFO - mozversion platform_version: 49.0a1 09:36:51 INFO - using testdate: 1464885411 09:36:51 INFO - actual date: 1464885411 09:36:51 INFO - starting webserver on 'localhost:49275' 09:36:51 INFO - SUITE-START | Running 2 tests 09:36:51 INFO - TEST-START | dromaeo_css 09:36:51 INFO - operating with platform_type : mac_ 09:36:51 INFO - Initialising browser for dromaeo_css test... 09:36:51 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/tmpRKW_2B/profile http://localhost:49275/getInfo.html) 09:36:53 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 09:36:53 INFO - PROCESS | 1659 | colorDepth:24 09:36:53 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 09:36:53 INFO - PROCESS | 1659 | __metrics 09:36:53 INFO - PROCESS | 1659 | 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" 09:36:53 ERROR - PROCESS | 1659 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:36:54 INFO - PROCESS | 1659 | ************************* 09:36:54 INFO - PROCESS | 1659 | A coding exception was thrown in a Promise resolution callback. 09:36:54 INFO - PROCESS | 1659 | See https://developer.mozilla.org/Mozilla/JavaScript_code_modules/Promise.jsm/Promise 09:36:54 INFO - PROCESS | 1659 | 09:36:54 ERROR - PROCESS | 1659 | Full message: TypeError: win is null 09:36:54 INFO - PROCESS | 1659 | Full stack: onSuccess@resource://app/components/nsBrowserGlue.js:845:17 09:36:54 INFO - PROCESS | 1659 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 09:36:54 INFO - PROCESS | 1659 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 09:36:54 INFO - PROCESS | 1659 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 09:36:54 INFO - PROCESS | 1659 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 09:36:54 INFO - PROCESS | 1659 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 09:36:54 INFO - PROCESS | 1659 | get _worker/worker.onmessage@resource://gre/modules/PromiseWorker.jsm:235:9 09:36:54 INFO - PROCESS | 1659 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 09:36:54 INFO - PROCESS | 1659 | 09:36:54 INFO - PROCESS | 1659 | ************************* 09:36:54 INFO - TEST-INFO | 1659: exit 0 09:36:54 INFO - Browser initialized. 09:36:54 INFO - Running cycle 1/1 for dromaeo_css test... 09:36:54 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/tmpRKW_2B/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:37:06 INFO - PROCESS | 1661 | RSS: Main: 260587520 09:37:06 INFO - PROCESS | 1661 | 09:37:58 INFO - PROCESS | 1661 | 1464885478155 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] 09:40:08 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 09:40:10 INFO - PROCESS | 1661 | RSS: Main: 397602816 09:40:10 INFO - PROCESS | 1661 | 09:43:13 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 09:43:13 INFO - PROCESS | 1661 | RSS: Main: 398295040 09:43:13 INFO - PROCESS | 1661 | 09:46:16 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 09:46:16 INFO - PROCESS | 1661 | RSS: Main: 394440704 09:46:16 INFO - PROCESS | 1661 | 09:49:19 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 09:49:19 INFO - PROCESS | 1661 | RSS: Main: 392863744 09:49:19 INFO - PROCESS | 1661 | 09:52:23 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 09:52:23 INFO - PROCESS | 1661 | RSS: Main: 360869888 09:52:23 INFO - PROCESS | 1661 | 09:55:27 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 09:55:27 INFO - PROCESS | 1661 | RSS: Main: 429858816 09:55:27 INFO - PROCESS | 1661 | 09:55:27 INFO - PROCESS | 1661 | __start_tp_report 09:55:27 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 09:55:27 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 09:55:27 INFO - PROCESS | 1661 | |i|pagename|runs| 09:55:27 INFO - PROCESS | 1661 | |0;dojo.html;3429.485577163425;3451.912647516033;3460.2041530447996;3470.0630829676;3507.5966263879645;5522.889542209154;5557.333120025598;5611.0741727614895;5640.294963129609;5675.290588676416;5627.465390787879;5688.488036076752;5689.061304884699;5694.4590263924865;5697.088465845465;5080.288759573663;5128.1282182029145;5146.8713282168055;5148.613853960968;5212.244224587433;5219.42586315505;5229.973850130744;5231.921521177185;5232.7383630818495;5236.895262094756;5114.974425127869;5137.614678899079;5149.922751158736;5151.639385243035;5216.165413533835;5220.164773636238;5243.2659427680155;5258.3689957205;5274.156135018417;5299.5230429261555;5053;5054.393472783289;5076.390833100015;5152.252923326134;5161.5870730341485;5493.478119578634;5515.227868098429;5545.750441230114;5566.526845218162;5604.8318550443555;6191;6226.128342032119;6239.220097487814;6243.7814676486105;6248.187735594389;6072.089186621998;6170.660613666247;6177.84555386119;6289.496840252768;6307.432331090178;5783.652980821164;5845.1232315153065;5856.238688970492;5859.882802344015;5861;6278.183836101278;6286.685665716695;6307;6314.0844577536;6316.968415157895;5953.791617293374;6101.908471372933;6102.883130681719;6153.815385538441;6169.691515424211;5417.566594672417;6134.2332208473945;6274.811755647338;6275.121482992385;6307.369263073656;6160.815175544741;6184.443400094013;6230.7819226326865;6232.501399887998;6235.282942461675;6056.576039677268;6122.367660829195;6123.587669131048;6135.814547850547;6215.409536094064;4920.237363208708;4931.211006238985;4937.259411088294;4989.925151122736;4992.750362481861;4414.337849322627;4417.558244175621;4418.7127836690515;4428.867133985986;4468.932966005513;5568.916466253009;5638.971805140948;5681.0058239808695;5686;5720.256366672389;6160.045192995093;6238.563300568917;6239.78160764371;6271.3728627136925;6305.747770089237;5648.4633959773755;5650.7174641267775;5652.886942261214;5659.801906933238;5701.572382071278;5269.78920843162;5320.17537281722;5355.732213389315;5362.008028514737;5395.514403703607;6213.099100630383;6217.285012223652;6270.561060725706;6313.05304204373;6341.302456729663;6308.621482710869;6315.936840631535;6323.20959880015;6329.461995730415;6333.90499142504;6223.128761973237;6232.781852635136;6311.337309582428;6315.115883776183;6327.493800496041;5084.2881996521255;6206.441420272197;6212.316645168938;6217.844553886189;6220.346863579259;6234.407731265523;6269.62382257066;6272;6278.748850045947;6302.779402720883;5783.768649253982;5921.111833225051;5978.940210597838;6044.486218671462;6156;6019.939800602113;6098.512119030555;6123;6126.5098792097415;6138.723757431016;5925.703714814156;5928.081147422156;5938.61399009063;5943.910841337297;5975.611585246945;5879.088741245114;5991.580589358703;6066.423689749467;6082.378568274129;6109.785202863926;5935.139404786196;5948.315943666361;5955.76176952917;5959.970200148971;5994.940050599438 09:55:27 INFO - PROCESS | 1661 | |1;ext.html;484.97817598208076;491.7737840593327;501.86951392637906;527.5199568392763;531.4021725558746;31108.377832443366;31809;31856.203594910137;31903.202419939513;31966.840165799167;33141.00576982689;42043.94890127749;43264.134717305635;43305.566944330545;46166.30750538736;9457.810843783138;9640.180584650314;9666.75833104171;9678.17735492484;9682.661106861262;8539.274161696263;9469.715908522721;9637.855432168488;9637.951810240938;9650.179734722557;8665.870011949826;11322.377269250188;11597.130215233849;11622.012128969047;11839.704007399841;11659.183857129961;11756.235844670155;11758.529658813637;11758.764824703543;11767.411629418537;8476.78808029798;11160.944195279053;11457.026152777024;11583.131265155147;11600.883991160064;39797.80101099505;40639.7968010161;40677;40716.18567628631;40750.592494074976;3418.67522585354;3450.741194410429;3452.154222215548;3485.7385696072897;3573.26748016656;30800.691993080007;31493.212669683213;31613.8419307902;32028.679713202804;32456.701731930694;3231.628362738292;3248.707616314543;3253.96746032539;3264.477683570641;3294.5387645729616;516893;517026;518289;518315.40842295927;518606;32750.508742368882;32785.67214327874;33409.49885751716;35327.4700879487;58711.82576348449;35529.822350888084;35689.46465803015;35809.46285805715;35814.92555223347;35848.82075589606;15018.774718379233;15191.544253672408;15195.240237988057;15197.6200594986;15235.542933711784;12769.61691149267;12832;12837.101402901708;12847.357632118543;12989.28558929249;7359;7484.962575187089;7642.19756925526;7790.415718821111;7840.098388685375;2788.3586775041854;3026.3644634626535;3247.1395080303737;3268.6731326867125;3288.3094550144738;923.8198551282435;1011.2819897872592;1037.0407373179778;1055.6991257491732;1073.3398959639765;26462.809173587233;29445.11664650064;29674.55488167679;29995.550066749016;30366;1086.2884810449168;1095.4905968724581;1150.1316506037888;1169.2867350915935;1193.2065176657425;12492.687682808002;15225.6193595161;15369.385224590891;15625.921870390803;15784.842151578567;669455;672720;693835;696600;698958;59017;59546;59637;59689;59776;1400.6148309215048;1428.7428262912777;1443.8917081218951;1446.3636000159686;1448.073233130776;29104.563431548966;29111.85444072766;29189.270268243465;29812;30093.849530752206;48325.5167448321;48799.5120048795;49553.75223123861;49558.752206238736;49581.760455988886;594142;599856;600412;603051;605101;22852.657210142195;22862.971166297222;23015.424614384774;26378.68106594701;26694.733052669224;47876.80307992328;48437.757811210715;48706.782330440605;51029.72425689239;51100;27891;37190.62809371872;38280;39176.412353815285;39947.80026099967;37205.81397092997;38580.45678172842;38869.6113038866;39215.60784392119;39783.80108099441;37236;38014;38123.42814857835;38851;40197.799011005925;33361.07028077412;34743;34974.650253498156;35025.82487087548;35117.41971611335;23044.769552304264;23640.527189456458;25348.619770703084;25549;26337 09:55:27 INFO - PROCESS | 1661 | |2;jquery.html;367.7517675568991;382.50656652917735;387.13282247765017;399.315174475774;407.3828150352217;20152;22008.119675213013;22365.44086397841;22463.55072898541;22497.55004899903;20957.371278861658;21260.192883604872;21678.89160554199;21872.343829685095;21892.482364528085;5726.255586773724;5911.172435858984;6075.33171351151;6091;6105.175801266839;4120.546739858613;4358.346248062784;5865.912011319812;6292.811215663537;6657.567258128231;6379.915414379567;6663.800085997404;6664.2002959644915;6674.866502669968;6679.33206679333;5889.793857214999;6472.805815825485;6626.337366263383;6657.46740260778;6660.900086498731;3728.81355932205;5420.674759514442;5729.1119876419225;5750.367459579443;5753;24991.750082499126;25468.726563671742;25541.85061672229;25577.976880924925;25877.482450351075;2252.7521972583168;2377.037299893546;2419.2742177346727;2830.26413132587;3248.415285248654;8068.798280042987;15619.578965260403;15806.841931580651;16086.5978350541;16609.252583633766;1841.090100776509;1871.4572773895554;1880.2291060665061;1885.811418858117;1904.4477101640507;83950.64191447177;108253.91746082518;125964.18250722453;129201;151559;17715.822841771675;20936.790632093787;21653.91730413473;21882.89058554697;22180;36792;36874;36953.26093478115;36979.63020369816;37042.62957370446;9476.241900647932;10607.893921060844;10820.89179108199;10899.455027248765;11150.21948463616;9080.591373388213;9094.636214551476;9119.270458363315;9214.677486287948;9243.76890577741;8194.467359621605;9848.50757462124;9906.257030722725;10088.092071713581;10121.139703125316;2987.4323878463024;3063.356695094011;3350.637800350747;3754.2866855297952;3813.7521061131492;884.6770928611139;931.7484279244566;977.5600979559084;984.5471083301761;1054.7415883108688;20923.953802309825;20976;21257.78742212558;21292.36122916315;21311.614745041523;846.9195426434602;946.3091942881734;961.5000199896015;981.6073570571829;1057.9735506612396;10525.315854469434;10923.071538919281;11088.5564577416;11099.83350249747;11136.053435458076;599432;606374;612559;625268;625941;137311;139066.30466847803;142237;146326;148608.25695871667;1028.2031425645034;1040.0743338428804;1046.4767616192057;1066.7279843639978;1073.044989958937;16789.664206716043;17546.91226543859;18836.811631884055;18879.716804247673;19108;54833.72583137059;55403.44596554144;56535.71732141478;56741;56936.145957811495;78563.60718196601;79101.60449197717;79539;79732.20267797248;82037;5438.728063596725;5555.666660000413;5727.369989301257;7752.689892404466;8031.036275646959;9639.90360096418;11206.215564910704;12504.812427813771;12610.36948152607;12724.618261452171;10747.355158690503;10960.191568391127;11009.394483303495;11163.665090047312;11182.608608698656;10719.464026798472;10859.34843909368;10935.781284374427;10974.725631859268;11130.721731956766;5838.241028666246;5905.202797622266;6821.3519715626935;8567.700130495403;11406.543738250377;8026.1572534883;8038.959805200937;8067.193280671886;8094.352451803724;8094.838103237785;10257.281990260908;11102.500387482422;11163.888361116286;11207.047400971009;11234 09:55:27 INFO - PROCESS | 1661 | |3;mootools.html;1198.3289357959538;1541.9614509637258;1545.103839772932;1752.2903224194197;1913.3685883658395;7808.258215469528;8537.829243415128;8761.036286008533;8779.165979231979;8948.239399651038;8540.402171847973;8795.208431241186;8796.868046979285;8798.252148567379;8820.029796722356;2081.157131361801;2145.5065334972887;2145.8497905146646;2337.6142936415513;2372.4424760181346;2169.913203471859;2200.867947923122;2316.698829152208;2394.317619478449;2398.4123889646976;2373.679553260305;2378.4291769975252;2378.4886249456363;2385.189035727852;2387.7254115776645;2315.641075633271;2360.0677732295817;2361.8110551155864;2362.3739708977137;2366.6331718583647;2082.5834833033455;2268.432891777056;2330.592146374395;2336.2874323331375;2353.576356255873;7389.5935723535185;8664.740057798275;8732.51971141587;8734.344924130715;8750.081241469681;5177.818776342848;5333.986542556941;5336.252924590561;5350.117230656937;5361.115415956362;3388.119089036868;3540.345036168317;3590.3716849551224;3606.314800187956;3625.9274814503824;932.6129656192672;1015.9593616255341;1030.855680204772;1083.4853444613746;1086.7120213143523;11490.368029758276;11578.76842463146;11578.884211157949;11582.015528680073;11597.014253788439;4571.954280457153;4672.57946784791;4745.83389581363;4757.333973243749;4767.380240568773;9755.65855195079;9798.65704700332;9806.803863922682;9816.361936474106;9818.165455936325;4495;5356.91964620531;5361.865953351119;5362.463753624606;5365.463453654604;4742.07529531738;4837.733924634109;4918.065567542156;4921.064997650435;4940.90118197634;3906.1797022625565;3917.804109794499;3966.9801650991562;4002.7598344099447;4017.337139371971;1185.62874251497;1187.228301603947;1192.5945178639304;1202.1584890576632;1247.9937600311941;907.9105073911333;922.5433410461865;931.59475628102;932.6782260120248;980.843065109579;5888.087346461305;5923.437273459014;5993.940060599425;6106.480949119375;6135.815925522242;947.8199142162968;949.2927768812141;987.6000219910948;991.6231831903831;1006.2151521812998;3873.7869417182324;3981.1838573092446;3981.243563722883;4042.4946881639794;4102.835886564504;10462.738431539121;10599.470026498644;10782.838257426145;10820.891791082146;10822;8476.49141051539;10688.465576721133;10699.893001070046;10716.571337146583;10727.409992450333;7989.241022102891;8241.175882411711;8316.209960053786;8319.126491718403;8329.916700833035;5227.790888364575;5947.137665038632;6027.638341699512;6069.969650151721;6160.414760597736;9962.252831037556;10088.798224035625;10107.898921010696;10119.494025298558;10149;9173.40372875761;9554.187894029086;9954.45250511229;9975.950120249352;10179.338343007681;6976.407005404598;7292.927070729437;7474.925250747423;7504.849903002018;7582.203868593939;8096.433249672466;8147.981502312211;8244.093149753642;8291.58542072882;8308.252257296872;7496.400287977058;7559.584222867575;7560.432967527568;7616.200298968529;7681.1934746850775;7377.0409846719585;7384.556926584422;7447.180810110775;7541.35898448638;7551;7017.403520700797;7037.507374483737;7043.436525078084;7105.82235444118;7112;3829.9659092044435;4700.811967521261;4988.176950803077;4998.625103117353;5123.974380128076;4945.950540494549;5032;5081.288619593186;5111.258867464271;5182.429932707475 09:55:27 INFO - PROCESS | 1661 | |4;prototype.html;296.9703029697031;305.08626663143883;312.6638863222036;316.21420769388084;317.3684368107467;4211.620954114129;5038.21907604321;5126.026479805274;5327.467253274675;5365.463453654637;4426.2475379185535;5294.814681486148;5314.548263397616;5315.601329900253;5318.388385335687;3596.496490491333;3875.050612599917;4032.2943484890175;4060.614241647039;4062.8577999770014;3254.7558933079995;3376.864925402981;4049.817758200888;4057.2696914555368;4059.2084543514025;3971.721979461439;3978.9602103979023;4056.533498647649;4067.8983025424304;4095.5290141633795;3310.9006729798143;3992.441058251847;3992.580779018205;4009.6190861868076;4014.558398576154;2773.9445211095867;3527.523784289114;3528.894133176009;3543.964560354389;3561.2521370512222;3973.5033120859894;4519.389882365905;4603;5124.385073791132;5282.57370509964;4485.887852803706;4520.389747384094;4524.547545245449;4538.863834084983;4542.02346495505;5551.8889622207735;5558.720354990534;5559.861003474905;5577.609567330289;5589.524890384322;3570.0916165895037;3611.4041183205004;3759.4172903200047;3777.017975326435;3804.771713697187;5832.521178499121;5892.377891838134;5905.291365036137;5924.703764811742;5936.762529498815;3446.7070299024363;3665.596784353719;3862.3434016216747;3876.3604005339366;3887.1836914248324;3620.185458271868;3883.96116038836;3893.260280546687;3895.7078219133678;3901.258760835432;4282.571742825692;4322.96248900268;4508.909821803545;4552.9772351138035;4555.337301884788;4781.306710526954;4921.557059864629;5562.276904002454;5628.859278517988;5699.601027928005;1257.390165769601;1287.2340957130493;1291.2188126183712;1308.9920761013966;1337;714.8248679073556;784.8619501722525;787.2403130978633;788.7476007677488;810.2869474862084;3616.620254873253;3948.998760941705;4026.63760261572;4044.676425885922;4170.498620496619;4323.978380108142;4491.977540112279;4724.944649077662;4781.569658730731;4804.519548045238;3902.6682731967535;4170.35359519269;4280.743155410685;4287.335463003177;4290.666426908782;1887.7923428422862;2266.8299877509253;2316.698829152233;2482.8647363267573;2596.688397392325;4349.652027837766;4620.8151673933335;4936.901261974812;4938.333325001079;4965.379327584052;4271.743695378287;4418.254789358186;4453.933191002137;4527.981204229022;4539.409876716006;4148.585141485888;4311.2455320318195;4535.183666939983;4875.780589873464;4950.727709975986;4082.571330010306;4748.1094472693185;4766.928496072493;4791.377120974251;4799.736014519235;3994.7603143811466;4018.678505719477;4695.906081878412;4709.293605959134;4748.833790817304;4303.655707543452;4434.224010798033;4575.7483338416705;4596.034832685174;4989.750512474435;2840.730130637586;3239.935201296008;3307.0984184093763;3310.6854848789326;3320.6015278166774;3911.572276119253;4167.395727619539;4280.801375614833;4348.565143485626;5231.476852314738;4451.888702782327;4982.900341993068;5103.106956282561;5212.504812043002;5478.342598888;5579.888402231852;5595.272614560081;5617.494425501724;5622.8313150605545;5667.40492248308;4451.443569553806;4964.276792405696;5168.198929165986;5480.068388374066;5517.337919449692;5277;5378.327709036371;5489.752961116679;5690.150701022267;5746.2529871116485;4920.6555541111775;5112.412072611549;5543.501084902378;5584.944150558443;5669.744861481161 09:55:27 INFO - PROCESS | 1661 | |5;yui.html;687.1033301541488;786.241272227387;801.3829351399422;810.6392655268406;816.452976505741;15355.385784568618;15535.689286214269;15843.287052082655;15878.285477153513;15950.601234969103;13992.580222593313;14231.288435578232;14279.4288228471;14409.423623055065;14792.482263120794;708.3978618174536;884.8274586455643;886.3131073418105;892.7098692924793;895.3598177303227;725.542907967982;880.9559522023906;912.0150237743221;912.2291663544295;916.3860213656833;945.0927109974433;947.1239103828965;948.0140653720157;950.4439902656954;951.6574033347989;751.6918063593901;765.9540427574308;943.9528023598862;945.6406565505098;947.1522986926697;555.3641080962276;558.0540983033759;682.4301708073764;702.5187746393685;707.476467414115;15080.396784128621;15163.696726065527;15166.620834479116;15191.772123418157;15200.695986080216;13043.717294495744;13716.588502344946;13920.443182272697;13926.51257206003;14191.290435478184;12584.4337004835;12675.17611355259;12718.618441446772;12753.426095825711;13058.412371443308;3861.4593956846065;4508.053308805155;4627.95372046282;4675.906481870344;4686.929696054562;98396;99967.50016249945;103752.44371334436;104070;105881;9343.299252556086;10809.516206290897;10901.127909767358;11233.213675042669;11373.829392559119;18373.7243941341;20975.370738877857;21505.032273547728;21523.246686365903;21529.56940861174;8010.078840933251;8016.478928869605;8017.7995550110545;8078.919210807934;8250.46371985819;4417.3815665806815;4473.977630111894;4475.26158183896;4491.416115904912;4613.492515823256;3375.814330211813;3488.9825550872083;3493.3013397320638;3497.842597083137;3507.31607336572;555.2947756349495;638.8434833465737;644.887144749667;657.9998402428282;659.3077268867765;618.9566730328924;638.7253481003213;640.8558074433215;644.2269276867684;653.7450394346209;8909.287257019423;9124.361294709439;9232.7691807704;9361.812763744687;9433.575489103006;571.5913122117706;605.585174155696;607.8601921557978;612.353966565269;665.1685393258427;4347.108842687241;4443.977780111079;4484.708493947883;4497.775111244425;4644.558766917137;97651.51174244225;147613;149161;154174;156131.43868561398;62398.68800655968;81274.18725812667;87460;103773;125393.37303313425;74071.88892166622;74196;100684.49657751665;109392.35911461334;109486;9650.420974741539;9917.801643967225;9949.452780097165;10163.898361016296;10191.541380638037;48375.5162448371;56640.716796415756;59167.40832591619;59327.51681207832;61647.691761540904;59293.40706592879;70758;86888;87443.56278218568;88471.11528884628;12872.806907896576;13035.739285214433;13078.542251021168;13220.735585288432;13538.59384218475;14717.264136793332;14769.30153032372;14788.038777479429;14978.925105374403;15115.31981060833;13514;13663.316834158451;13913.234772087633;13915.234662093682;13981.650458738613;13371.866281337063;13736.587902362946;13779.862201377859;14055.367508461939;14306.356213970605;13215.373228486975;13505.527306544223;13524.256165910576;13590.728185436434;14008.929955350566;9799.559019843982;11400.885991139983;11727.944484996391;11808.70478238051;11898.821517677068;8142.59287035634;11067.738231917401;11458.695285276162;11527.596534121265;11581.420928953687 09:55:27 INFO - PROCESS | 1661 | __end_tp_report 09:55:27 INFO - PROCESS | 1661 | __start_cc_report 09:55:27 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,1452 09:55:27 INFO - PROCESS | 1661 | __end_cc_report 09:55:27 INFO - PROCESS | 1661 | __startTimestamp1464886527283__endTimestamp 09:55:27 INFO - PROCESS | 1661 | 09:55:27 INFO - PROCESS | 1661 | ------- Summary: start ------- 09:55:27 INFO - PROCESS | 1661 | Number of tests: 6 09:55:27 INFO - PROCESS | 1661 | 09:55:27 INFO - PROCESS | 1661 | [#0] dojo.html Cycles:165 Average:5718.13 Median:5935.14 stddev:629.18 (10.6%) stddev-sans-first:604.96 09:55:27 INFO - PROCESS | 1661 | Values: 3429.5 3451.9 3460.2 3470.1 3507.6 5522.9 5557.3 5611.1 5640.3 5675.3 5627.5 5688.5 5689.1 5694.5 5697.1 5080.3 5128.1 5146.9 5148.6 5212.2 5219.4 5230.0 5231.9 5232.7 5236.9 5115.0 5137.6 5149.9 5151.6 5216.2 5220.2 5243.3 5258.4 5274.2 5299.5 5053.0 5054.4 5076.4 5152.3 5161.6 5493.5 5515.2 5545.8 5566.5 5604.8 6191.0 6226.1 6239.2 6243.8 6248.2 6072.1 6170.7 6177.8 6289.5 6307.4 5783.7 5845.1 5856.2 5859.9 5861.0 6278.2 6286.7 6307.0 6314.1 6317.0 5953.8 6101.9 6102.9 6153.8 6169.7 5417.6 6134.2 6274.8 6275.1 6307.4 6160.8 6184.4 6230.8 6232.5 6235.3 6056.6 6122.4 6123.6 6135.8 6215.4 4920.2 4931.2 4937.3 4989.9 4992.8 4414.3 4417.6 4418.7 4428.9 4468.9 5568.9 5639.0 5681.0 5686.0 5720.3 6160.0 6238.6 6239.8 6271.4 6305.7 5648.5 5650.7 5652.9 5659.8 5701.6 5269.8 5320.2 5355.7 5362.0 5395.5 6213.1 6217.3 6270.6 6313.1 6341.3 6308.6 6315.9 6323.2 6329.5 6333.9 6223.1 6232.8 6311.3 6315.1 6327.5 5084.3 6206.4 6212.3 6217.8 6220.3 6234.4 6269.6 6272.0 6278.7 6302.8 5783.8 5921.1 5978.9 6044.5 6156.0 6019.9 6098.5 6123.0 6126.5 6138.7 5925.7 5928.1 5938.6 5943.9 5975.6 5879.1 5991.6 6066.4 6082.4 6109.8 5935.1 5948.3 5955.8 5960.0 5994.9 09:55:27 INFO - PROCESS | 1661 | 09:55:27 INFO - PROCESS | 1661 | [#1] ext.html Cycles:180 Average:70997.14 Median:19097.58 stddev:162463.37 (850.7%) stddev-sans-first:162832.86 09:55:27 INFO - PROCESS | 1661 | Values: 485.0 491.8 501.9 527.5 531.4 31108.4 31809.0 31856.2 31903.2 31966.8 33141.0 42043.9 43264.1 43305.6 46166.3 9457.8 9640.2 9666.8 9678.2 9682.7 8539.3 9469.7 9637.9 9638.0 9650.2 8665.9 11322.4 11597.1 11622.0 11839.7 11659.2 11756.2 11758.5 11758.8 11767.4 8476.8 11160.9 11457.0 11583.1 11600.9 39797.8 40639.8 40677.0 40716.2 40750.6 3418.7 3450.7 3452.2 3485.7 3573.3 30800.7 31493.2 31613.8 32028.7 32456.7 3231.6 3248.7 3254.0 3264.5 3294.5 516893.0 517026.0 518289.0 518315.4 518606.0 32750.5 32785.7 33409.5 35327.5 58711.8 35529.8 35689.5 35809.5 35814.9 35848.8 15018.8 15191.5 15195.2 15197.6 15235.5 12769.6 12832.0 12837.1 12847.4 12989.3 7359.0 7485.0 7642.2 7790.4 7840.1 2788.4 3026.4 3247.1 3268.7 3288.3 923.8 1011.3 1037.0 1055.7 1073.3 26462.8 29445.1 29674.6 29995.6 30366.0 1086.3 1095.5 1150.1 1169.3 1193.2 12492.7 15225.6 15369.4 15625.9 15784.8 669455.0 672720.0 693835.0 696600.0 698958.0 59017.0 59546.0 59637.0 59689.0 59776.0 1400.6 1428.7 1443.9 1446.4 1448.1 29104.6 29111.9 29189.3 29812.0 30093.8 48325.5 48799.5 49553.8 49558.8 49581.8 594142.0 599856.0 600412.0 603051.0 605101.0 22852.7 22863.0 23015.4 26378.7 26694.7 47876.8 48437.8 48706.8 51029.7 51100.0 27891.0 37190.6 38280.0 39176.4 39947.8 37205.8 38580.5 38869.6 39215.6 39783.8 37236.0 38014.0 38123.4 38851.0 40197.8 33361.1 34743.0 34974.7 35025.8 35117.4 23044.8 23640.5 25348.6 25549.0 26337.0 09:55:27 INFO - PROCESS | 1661 | 09:55:27 INFO - PROCESS | 1661 | [#2] jquery.html Cycles:180 Average:37254.48 Median:3025.39 stddev:102684.45 (3394.1%) stddev-sans-first:102935.15 09:55:27 INFO - PROCESS | 1661 | Values: 367.8 382.5 387.1 399.3 407.4 20152.0 22008.1 22365.4 22463.6 22497.6 20957.4 21260.2 21678.9 21872.3 21892.5 5726.3 5911.2 6075.3 6091.0 6105.2 4120.5 4358.3 5865.9 6292.8 6657.6 6379.9 6663.8 6664.2 6674.9 6679.3 5889.8 6472.8 6626.3 6657.5 6660.9 3728.8 5420.7 5729.1 5750.4 5753.0 24991.8 25468.7 25541.9 25578.0 25877.5 2252.8 2377.0 2419.3 2830.3 3248.4 8068.8 15619.6 15806.8 16086.6 16609.3 1841.1 1871.5 1880.2 1885.8 1904.4 83950.6 108253.9 125964.2 129201.0 151559.0 17715.8 20936.8 21653.9 21882.9 22180.0 36792.0 36874.0 36953.3 36979.6 37042.6 9476.2 10607.9 10820.9 10899.5 11150.2 9080.6 9094.6 9119.3 9214.7 9243.8 8194.5 9848.5 9906.3 10088.1 10121.1 2987.4 3063.4 3350.6 3754.3 3813.8 884.7 931.7 977.6 984.5 1054.7 20924.0 20976.0 21257.8 21292.4 21311.6 846.9 946.3 961.5 981.6 1058.0 10525.3 10923.1 11088.6 11099.8 11136.1 599432.0 606374.0 612559.0 625268.0 625941.0 137311.0 139066.3 142237.0 146326.0 148608.3 1028.2 1040.1 1046.5 1066.7 1073.0 16789.7 17546.9 18836.8 18879.7 19108.0 54833.7 55403.4 56535.7 56741.0 56936.1 78563.6 79101.6 79539.0 79732.2 82037.0 5438.7 5555.7 5727.4 7752.7 8031.0 9639.9 11206.2 12504.8 12610.4 12724.6 10747.4 10960.2 11009.4 11163.7 11182.6 10719.5 10859.3 10935.8 10974.7 11130.7 5838.2 5905.2 6821.4 8567.7 11406.5 8026.2 8039.0 8067.2 8094.4 8094.8 10257.3 11102.5 11163.9 11207.0 11234.0 09:55:27 INFO - PROCESS | 1661 | 09:55:27 INFO - PROCESS | 1661 | [#3] mootools.html Cycles:180 Average:5678.12 Median:5056.64 stddev:3169.91 (62.7%) stddev-sans-first:3160.92 09:55:27 INFO - PROCESS | 1661 | Values: 1198.3 1542.0 1545.1 1752.3 1913.4 7808.3 8537.8 8761.0 8779.2 8948.2 8540.4 8795.2 8796.9 8798.3 8820.0 2081.2 2145.5 2145.8 2337.6 2372.4 2169.9 2200.9 2316.7 2394.3 2398.4 2373.7 2378.4 2378.5 2385.2 2387.7 2315.6 2360.1 2361.8 2362.4 2366.6 2082.6 2268.4 2330.6 2336.3 2353.6 7389.6 8664.7 8732.5 8734.3 8750.1 5177.8 5334.0 5336.3 5350.1 5361.1 3388.1 3540.3 3590.4 3606.3 3625.9 932.6 1016.0 1030.9 1083.5 1086.7 11490.4 11578.8 11578.9 11582.0 11597.0 4572.0 4672.6 4745.8 4757.3 4767.4 9755.7 9798.7 9806.8 9816.4 9818.2 4495.0 5356.9 5361.9 5362.5 5365.5 4742.1 4837.7 4918.1 4921.1 4940.9 3906.2 3917.8 3967.0 4002.8 4017.3 1185.6 1187.2 1192.6 1202.2 1248.0 907.9 922.5 931.6 932.7 980.8 5888.1 5923.4 5993.9 6106.5 6135.8 947.8 949.3 987.6 991.6 1006.2 3873.8 3981.2 3981.2 4042.5 4102.8 10462.7 10599.5 10782.8 10820.9 10822.0 8476.5 10688.5 10699.9 10716.6 10727.4 7989.2 8241.2 8316.2 8319.1 8329.9 5227.8 5947.1 6027.6 6070.0 6160.4 9962.3 10088.8 10107.9 10119.5 10149.0 9173.4 9554.2 9954.5 9976.0 10179.3 6976.4 7292.9 7474.9 7504.8 7582.2 8096.4 8148.0 8244.1 8291.6 8308.3 7496.4 7559.6 7560.4 7616.2 7681.2 7377.0 7384.6 7447.2 7541.4 7551.0 7017.4 7037.5 7043.4 7105.8 7112.0 3830.0 4700.8 4988.2 4998.6 5124.0 4946.0 5032.0 5081.3 5111.3 5182.4 09:55:27 INFO - PROCESS | 1661 | 09:55:27 INFO - PROCESS | 1661 | [#4] prototype.html Cycles:180 Average:4166.68 Median:4451.67 stddev:1289.18 (29.0%) stddev-sans-first:1259.65 09:55:27 INFO - PROCESS | 1661 | Values: 297.0 305.1 312.7 316.2 317.4 4211.6 5038.2 5126.0 5327.5 5365.5 4426.2 5294.8 5314.5 5315.6 5318.4 3596.5 3875.1 4032.3 4060.6 4062.9 3254.8 3376.9 4049.8 4057.3 4059.2 3971.7 3979.0 4056.5 4067.9 4095.5 3310.9 3992.4 3992.6 4009.6 4014.6 2773.9 3527.5 3528.9 3544.0 3561.3 3973.5 4519.4 4603.0 5124.4 5282.6 4485.9 4520.4 4524.5 4538.9 4542.0 5551.9 5558.7 5559.9 5577.6 5589.5 3570.1 3611.4 3759.4 3777.0 3804.8 5832.5 5892.4 5905.3 5924.7 5936.8 3446.7 3665.6 3862.3 3876.4 3887.2 3620.2 3884.0 3893.3 3895.7 3901.3 4282.6 4323.0 4508.9 4553.0 4555.3 4781.3 4921.6 5562.3 5628.9 5699.6 1257.4 1287.2 1291.2 1309.0 1337.0 714.8 784.9 787.2 788.7 810.3 3616.6 3949.0 4026.6 4044.7 4170.5 4324.0 4492.0 4724.9 4781.6 4804.5 3902.7 4170.4 4280.7 4287.3 4290.7 1887.8 2266.8 2316.7 2482.9 2596.7 4349.7 4620.8 4936.9 4938.3 4965.4 4271.7 4418.3 4453.9 4528.0 4539.4 4148.6 4311.2 4535.2 4875.8 4950.7 4082.6 4748.1 4766.9 4791.4 4799.7 3994.8 4018.7 4695.9 4709.3 4748.8 4303.7 4434.2 4575.7 4596.0 4989.8 2840.7 3239.9 3307.1 3310.7 3320.6 3911.6 4167.4 4280.8 4348.6 5231.5 4451.9 4982.9 5103.1 5212.5 5478.3 5579.9 5595.3 5617.5 5622.8 5667.4 4451.4 4964.3 5168.2 5480.1 5517.3 5277.0 5378.3 5489.8 5690.2 5746.3 4920.7 5112.4 5543.5 5584.9 5669.7 09:55:27 INFO - PROCESS | 1661 | 09:55:27 INFO - PROCESS | 1661 | [#5] yui.html Cycles:180 Average:22505.21 Median:4474.62 stddev:34758.99 (776.8%) stddev-sans-first:34817.89 09:55:27 INFO - PROCESS | 1661 | Values: 687.1 786.2 801.4 810.6 816.5 15355.4 15535.7 15843.3 15878.3 15950.6 13992.6 14231.3 14279.4 14409.4 14792.5 708.4 884.8 886.3 892.7 895.4 725.5 881.0 912.0 912.2 916.4 945.1 947.1 948.0 950.4 951.7 751.7 766.0 944.0 945.6 947.2 555.4 558.1 682.4 702.5 707.5 15080.4 15163.7 15166.6 15191.8 15200.7 13043.7 13716.6 13920.4 13926.5 14191.3 12584.4 12675.2 12718.6 12753.4 13058.4 3861.5 4508.1 4628.0 4675.9 4686.9 98396.0 99967.5 103752.4 104070.0 105881.0 9343.3 10809.5 10901.1 11233.2 11373.8 18373.7 20975.4 21505.0 21523.2 21529.6 8010.1 8016.5 8017.8 8078.9 8250.5 4417.4 4474.0 4475.3 4491.4 4613.5 3375.8 3489.0 3493.3 3497.8 3507.3 555.3 638.8 644.9 658.0 659.3 619.0 638.7 640.9 644.2 653.7 8909.3 9124.4 9232.8 9361.8 9433.6 571.6 605.6 607.9 612.4 665.2 4347.1 4444.0 4484.7 4497.8 4644.6 97651.5 147613.0 149161.0 154174.0 156131.4 62398.7 81274.2 87460.0 103773.0 125393.4 74071.9 74196.0 100684.5 109392.4 109486.0 9650.4 9917.8 9949.5 10163.9 10191.5 48375.5 56640.7 59167.4 59327.5 61647.7 59293.4 70758.0 86888.0 87443.6 88471.1 12872.8 13035.7 13078.5 13220.7 13538.6 14717.3 14769.3 14788.0 14978.9 15115.3 13514.0 13663.3 13913.2 13915.2 13981.7 13371.9 13736.6 13779.9 14055.4 14306.4 13215.4 13505.5 13524.3 13590.7 14008.9 9799.6 11400.9 11727.9 11808.7 11898.8 8142.6 11067.7 11458.7 11527.6 11581.4 09:55:27 INFO - PROCESS | 1661 | -------- Summary: end -------- 09:55:27 INFO - PROCESS | 1661 | 09:55:27 INFO - TEST-INFO | 1661: exit 0 09:55:27 INFO - TEST-OK | dromaeo_css | took 1116665ms 09:55:27 INFO - TEST-START | kraken 09:55:27 INFO - operating with platform_type : mac_ 09:55:27 INFO - Initialising browser for kraken test... 09:55:27 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/tmpuPKeMg/profile http://localhost:49275/getInfo.html) 09:55:30 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 09:55:30 INFO - PROCESS | 1667 | colorDepth:24 09:55:30 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 09:55:30 INFO - PROCESS | 1667 | __metrics 09:55:30 INFO - PROCESS | 1667 | 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" 09:55:30 ERROR - PROCESS | 1667 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:55:30 INFO - PROCESS | 1667 | ************************* 09:55:30 INFO - PROCESS | 1667 | A coding exception was thrown in a Promise resolution callback. 09:55:30 INFO - PROCESS | 1667 | See https://developer.mozilla.org/Mozilla/JavaScript_code_modules/Promise.jsm/Promise 09:55:30 INFO - PROCESS | 1667 | 09:55:30 ERROR - PROCESS | 1667 | Full message: TypeError: win is null 09:55:30 INFO - PROCESS | 1667 | Full stack: onSuccess@resource://app/components/nsBrowserGlue.js:845:17 09:55:30 INFO - PROCESS | 1667 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 09:55:30 INFO - PROCESS | 1667 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 09:55:30 INFO - PROCESS | 1667 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 09:55:30 INFO - PROCESS | 1667 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 09:55:30 INFO - PROCESS | 1667 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 09:55:30 INFO - PROCESS | 1667 | get _worker/worker.onmessage@resource://gre/modules/PromiseWorker.jsm:235:9 09:55:30 INFO - PROCESS | 1667 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 09:55:30 INFO - PROCESS | 1667 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 09:55:30 INFO - PROCESS | 1667 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 09:55:30 INFO - PROCESS | 1667 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 09:55:30 INFO - PROCESS | 1667 | Barrier/this.client.addBlocker/promise53.8% 09:56:27 INFO - TinderboxPrint: I/O read bytes / time
36,976,128 / 244 09:56:27 INFO - TinderboxPrint: I/O write bytes / time
700,549,120 / 1,899 09:56:27 INFO - TinderboxPrint: CPU idle
2,242.0 (46.2%) 09:56:27 INFO - TinderboxPrint: CPU system
965.6 (19.9%) 09:56:27 INFO - TinderboxPrint: CPU user
1,642.0 (33.9%) 09:56:27 INFO - TinderboxPrint: Swap in / out
435,892,224 / 0 09:56:27 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 394791424; Write bytes: 385222656; Read time: 29130; Write time: 771 09:56:27 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 29810176; Write bytes: 295661568; Read time: 182; Write time: 1085 09:56:28 INFO - Running post-run listener: _upload_blobber_files 09:56:28 INFO - Blob upload gear active. 09:56:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:56:28 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 09:56:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:56:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:56:28 INFO - (blobuploader) - INFO - Open directory for files ... 09:56:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:56:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:56:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:56:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:56:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:56:29 INFO - (blobuploader) - INFO - Done attempting. 09:56:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:56:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:56:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:56:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:56:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:56:30 INFO - (blobuploader) - INFO - Done attempting. 09:56:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:56:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:56:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:56:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:56:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:56:33 INFO - (blobuploader) - INFO - Done attempting. 09:56:33 INFO - (blobuploader) - INFO - Iteration through files over. 09:56:33 INFO - Return code: 0 09:56:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:56:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:56:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/322168c7bdcb48c297ed1c33cbe8ddb479e8934610c62936a7c57db6cb0c94198568ced1a4bcd0c5a4464699b57f2fb096af5e39114fc4f7fdafe24375980afa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aefe8d5f9135fcd79661826f2353a3f65a64c5a5b9df7e18b72f846dc95b6d55fa6aef0f02b8a0f0152b964e3088b10fb2558a81dbc95075a745e79f16f87dca", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a4efa2bd8052c5948e1e96e1319727ee90e35e82263cd251efd41a0f4dd21e31e5ea831724e920a7cf0d1dbd845a1dddd01f0b6e9a4ca95b9e1f5788f6650d67"} 09:56:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:56:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:56:33 INFO - Contents: 09:56:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/322168c7bdcb48c297ed1c33cbe8ddb479e8934610c62936a7c57db6cb0c94198568ced1a4bcd0c5a4464699b57f2fb096af5e39114fc4f7fdafe24375980afa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aefe8d5f9135fcd79661826f2353a3f65a64c5a5b9df7e18b72f846dc95b6d55fa6aef0f02b8a0f0152b964e3088b10fb2558a81dbc95075a745e79f16f87dca", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a4efa2bd8052c5948e1e96e1319727ee90e35e82263cd251efd41a0f4dd21e31e5ea831724e920a7cf0d1dbd845a1dddd01f0b6e9a4ca95b9e1f5788f6650d67"} 09:56:33 INFO - Running post-run listener: copy_logs_to_upload_dir 09:56:33 INFO - Copying logs to upload dir... 09:56:33 INFO - mkdir: /builds/slave/test/build/upload/logs 09:56:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1279.405649 ========= master_lag: 1.72 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2016-06-02 09:56:34.765609) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 09:56:34.769180) ========= 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.Ctk0eXsQhn/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.BdTl3P2Rk6/Listeners TMPDIR=/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/322168c7bdcb48c297ed1c33cbe8ddb479e8934610c62936a7c57db6cb0c94198568ced1a4bcd0c5a4464699b57f2fb096af5e39114fc4f7fdafe24375980afa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aefe8d5f9135fcd79661826f2353a3f65a64c5a5b9df7e18b72f846dc95b6d55fa6aef0f02b8a0f0152b964e3088b10fb2558a81dbc95075a745e79f16f87dca", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a4efa2bd8052c5948e1e96e1319727ee90e35e82263cd251efd41a0f4dd21e31e5ea831724e920a7cf0d1dbd845a1dddd01f0b6e9a4ca95b9e1f5788f6650d67"} build_url:https://queue.taskcluster.net/v1/task/EANK0UdmScCBNrhBRG_ITg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011197 build_url: 'https://queue.taskcluster.net/v1/task/EANK0UdmScCBNrhBRG_ITg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/322168c7bdcb48c297ed1c33cbe8ddb479e8934610c62936a7c57db6cb0c94198568ced1a4bcd0c5a4464699b57f2fb096af5e39114fc4f7fdafe24375980afa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aefe8d5f9135fcd79661826f2353a3f65a64c5a5b9df7e18b72f846dc95b6d55fa6aef0f02b8a0f0152b964e3088b10fb2558a81dbc95075a745e79f16f87dca", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a4efa2bd8052c5948e1e96e1319727ee90e35e82263cd251efd41a0f4dd21e31e5ea831724e920a7cf0d1dbd845a1dddd01f0b6e9a4ca95b9e1f5788f6650d67"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 09:56:34.802906) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 09:56:34.803252) ========= 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.Ctk0eXsQhn/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.BdTl3P2Rk6/Listeners TMPDIR=/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005298 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 09:56:34.852163) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 09:56:34.852469) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 09:56:34.852841) ========= ========= Total master_lag: 1.87 =========