builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0205 starttime: 1465363392.59 results: success (0) buildid: 20160607203328 builduid: 876e1df49c984e8cb3c6907d5aebb538 revision: 1401fcd673699f8d6e431ccb643ab46e166da2a9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-07 22:23:12.585815) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-07 22:23:12.586324) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-07 22:23:12.586829) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-07 22:23:12.640139) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 22:23:12.640613) ========= 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.aWeEzgzXX1/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.QUWSzrWsio/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-06-07 22:23:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.9M=0.001s 2016-06-07 22:23:12 (19.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.158582 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 22:23:12.849366) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 22:23:12.849729) ========= 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.aWeEzgzXX1/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.QUWSzrWsio/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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.025247 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 22:23:12.899962) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-07 22:23:12.900353) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 1401fcd673699f8d6e431ccb643ab46e166da2a9 --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 1401fcd673699f8d6e431ccb643ab46e166da2a9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.aWeEzgzXX1/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.QUWSzrWsio/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-06-07 22:23:12,980 truncating revision to first 12 chars 2016-06-07 22:23:12,980 Setting DEBUG logging. 2016-06-07 22:23:12,980 attempt 1/10 2016-06-07 22:23:12,980 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/1401fcd67369?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-07 22:23:13,843 unpacking tar archive at: fx-team-1401fcd67369/testing/mozharness/ program finished with exit code 0 elapsedTime=1.129657 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-07 22:23:14.051695) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-07 22:23:14.052065) ========= script_repo_revision: 1401fcd673699f8d6e431ccb643ab46e166da2a9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-07 22:23:14.052506) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-07 22:23:14.052812) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-07 22:23:14.070315) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2016-06-07 22:23:14.070776) ========= /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.aWeEzgzXX1/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.QUWSzrWsio/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 22:23:14 INFO - MultiFileLogger online at 20160607 22:23:14 in /builds/slave/test 22:23:14 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 22:23:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:23:14 INFO - {'append_to_log': False, 22:23:14 INFO - 'base_work_dir': '/builds/slave/test', 22:23:14 INFO - 'blob_upload_branch': 'Fx-Team', 22:23:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:23:14 INFO - 'branch': 'Fx-Team', 22:23:14 INFO - 'buildbot_json_path': 'buildprops.json', 22:23:14 INFO - 'config_files': ('talos/mac_config.py',), 22:23:14 INFO - 'default_actions': ('clobber', 22:23:14 INFO - 'read-buildbot-config', 22:23:14 INFO - 'download-and-extract', 22:23:14 INFO - 'populate-webroot', 22:23:14 INFO - 'create-virtualenv', 22:23:14 INFO - 'install', 22:23:14 INFO - 'run-tests'), 22:23:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:23:14 INFO - 'download_minidump_stackwalk': True, 22:23:14 INFO - 'download_symbols': 'ondemand', 22:23:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:23:14 INFO - 'tooltool.py': '/tools/tooltool.py', 22:23:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:23:14 INFO - '/tools/misc-python/virtualenv.py')}, 22:23:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:23:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:23:14 INFO - 'installer_path': 'installer.exe', 22:23:14 INFO - 'log_level': 'info', 22:23:14 INFO - 'log_name': 'talos', 22:23:14 INFO - 'log_to_console': True, 22:23:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:23:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:23:14 INFO - 'opt_config_files': (), 22:23:14 INFO - 'pip_index': False, 22:23:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:23:14 INFO - 'cmd': ('bash', 22:23:14 INFO - '-c', 22:23:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:23:14 INFO - 'enabled': True, 22:23:14 INFO - 'halt_on_failure': False, 22:23:14 INFO - 'name': 'check_screen_resolution'},), 22:23:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:23:14 INFO - 'cmd': ('bash', 22:23:14 INFO - '-c', 22:23:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:23:14 INFO - 'enabled': True, 22:23:14 INFO - 'halt_on_failure': False, 22:23:14 INFO - 'name': 'check_screen_resolution'},), 22:23:14 INFO - 'run_cmd_checks_enabled': True, 22:23:14 INFO - 'sps_profile': False, 22:23:14 INFO - 'sps_profile_interval': 0, 22:23:14 INFO - 'suite': 'dromaeojs', 22:23:14 INFO - 'system_bits': '32', 22:23:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:23:14 INFO - 'title': 't-yosemite-r7-0205', 22:23:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:23:14 INFO - 'use_talos_json': True, 22:23:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:23:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:23:14 INFO - 'work_dir': 'build'} 22:23:14 INFO - ##### 22:23:14 INFO - ##### Running clobber step. 22:23:14 INFO - ##### 22:23:14 INFO - Running pre-action listener: _resource_record_pre_action 22:23:14 INFO - Running main action method: clobber 22:23:14 INFO - rmtree: /builds/slave/test/build 22:23:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:23:16 INFO - Running post-action listener: _resource_record_post_action 22:23:16 INFO - ##### 22:23:16 INFO - ##### Finished clobber step (success) 22:23:16 INFO - ##### 22:23:16 INFO - ##### 22:23:16 INFO - ##### Running read-buildbot-config step. 22:23:16 INFO - ##### 22:23:16 INFO - Running pre-action listener: _resource_record_pre_action 22:23:16 INFO - Running main action method: read_buildbot_config 22:23:16 INFO - Using buildbot properties: 22:23:16 INFO - { 22:23:16 INFO - "project": "", 22:23:16 INFO - "product": "firefox", 22:23:16 INFO - "script_repo_revision": "production", 22:23:16 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 22:23:16 INFO - "repository": "", 22:23:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 22:23:16 INFO - "buildid": "20160607203328", 22:23:16 INFO - "pgo_build": "False", 22:23:16 INFO - "basedir": "/builds/slave/test", 22:23:16 INFO - "buildnumber": 760, 22:23:16 INFO - "slavename": "t-yosemite-r7-0205", 22:23:16 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 22:23:16 INFO - "platform": "yosemite_r7", 22:23:16 INFO - "scheduler": "tests-fx-team-macosx64-talos", 22:23:16 INFO - "branch": "fx-team", 22:23:16 INFO - "repo_path": "integration/fx-team", 22:23:16 INFO - "revision": "1401fcd673699f8d6e431ccb643ab46e166da2a9", 22:23:16 INFO - "stage_platform": "macosx64", 22:23:16 INFO - "builduid": "876e1df49c984e8cb3c6907d5aebb538", 22:23:16 INFO - "slavebuilddir": "test" 22:23:16 INFO - } 22:23:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/RnWcIvrUTgeZI6AuVfNNvg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 22:23:16 INFO - Running post-action listener: _resource_record_post_action 22:23:16 INFO - ##### 22:23:16 INFO - ##### Finished read-buildbot-config step (success) 22:23:16 INFO - ##### 22:23:16 INFO - ##### 22:23:16 INFO - ##### Running download-and-extract step. 22:23:16 INFO - ##### 22:23:16 INFO - Running pre-action listener: _resource_record_pre_action 22:23:16 INFO - Running main action method: download_and_extract 22:23:16 INFO - mkdir: /builds/slave/test/build/tests 22:23:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:23:16 INFO - https://queue.taskcluster.net/v1/task/RnWcIvrUTgeZI6AuVfNNvg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 22:23:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RnWcIvrUTgeZI6AuVfNNvg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 22:23:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RnWcIvrUTgeZI6AuVfNNvg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 22:23:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RnWcIvrUTgeZI6AuVfNNvg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 22:23:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RnWcIvrUTgeZI6AuVfNNvg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 22:23:24 INFO - Downloaded 1199 bytes. 22:23:24 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 22:23:24 INFO - Using the following test package requirements: 22:23:24 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 22:23:24 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:23:24 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 22:23:24 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:23:24 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 22:23:24 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:23:24 INFO - u'jsshell-mac.zip'], 22:23:24 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:23:24 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 22:23:24 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 22:23:24 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:23:24 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 22:23:24 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:23:24 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 22:23:24 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:23:24 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 22:23:24 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:23:24 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 22:23:24 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:23:24 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')]} 22:23:24 INFO - https://queue.taskcluster.net/v1/task/RnWcIvrUTgeZI6AuVfNNvg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:23:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RnWcIvrUTgeZI6AuVfNNvg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 22:23:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RnWcIvrUTgeZI6AuVfNNvg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 22:23:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RnWcIvrUTgeZI6AuVfNNvg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 22:23:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RnWcIvrUTgeZI6AuVfNNvg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:23:33 INFO - Downloaded 18720586 bytes. 22:23:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:23:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:23:36 INFO - Return code: 0 22:23:36 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 22:23:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:23:36 INFO - https://queue.taskcluster.net/v1/task/RnWcIvrUTgeZI6AuVfNNvg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:23:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RnWcIvrUTgeZI6AuVfNNvg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 22:23:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RnWcIvrUTgeZI6AuVfNNvg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 22:23:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RnWcIvrUTgeZI6AuVfNNvg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 22:23:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RnWcIvrUTgeZI6AuVfNNvg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:23:39 INFO - Downloaded 18720586 bytes. 22:23:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:23:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:23:42 INFO - Return code: 0 22:23:42 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')]} 22:23:42 INFO - https://queue.taskcluster.net/v1/task/RnWcIvrUTgeZI6AuVfNNvg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 22:23:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RnWcIvrUTgeZI6AuVfNNvg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 22:23:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RnWcIvrUTgeZI6AuVfNNvg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 22:23:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RnWcIvrUTgeZI6AuVfNNvg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 22:23:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RnWcIvrUTgeZI6AuVfNNvg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:23:43 INFO - Downloaded 10833369 bytes. 22:23:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:23:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 22:23:44 INFO - Return code: 0 22:23: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')]} 22:23:44 INFO - https://queue.taskcluster.net/v1/task/RnWcIvrUTgeZI6AuVfNNvg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 22:23:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RnWcIvrUTgeZI6AuVfNNvg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 22:23:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RnWcIvrUTgeZI6AuVfNNvg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 22:23:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RnWcIvrUTgeZI6AuVfNNvg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 22:23:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RnWcIvrUTgeZI6AuVfNNvg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 22:23:47 INFO - Downloaded 111411900 bytes. 22:23:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RnWcIvrUTgeZI6AuVfNNvg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 22:23:47 INFO - mkdir: /builds/slave/test/properties 22:23:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:23:47 INFO - Writing to file /builds/slave/test/properties/build_url 22:23:47 INFO - Contents: 22:23:47 INFO - build_url:https://queue.taskcluster.net/v1/task/RnWcIvrUTgeZI6AuVfNNvg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 22:23:48 INFO - Running post-action listener: _resource_record_post_action 22:23:48 INFO - Running post-action listener: set_extra_try_arguments 22:23:48 INFO - ##### 22:23:48 INFO - ##### Finished download-and-extract step (success) 22:23:48 INFO - ##### 22:23:48 INFO - ##### 22:23:48 INFO - ##### Running populate-webroot step. 22:23:48 INFO - ##### 22:23:48 INFO - Running pre-action listener: _resource_record_pre_action 22:23:48 INFO - Running main action method: populate_webroot 22:23:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:23:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:23:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:23:48 INFO - '--tppagecycles', 22:23:48 INFO - '7'], 22:23:48 INFO - 'tests': ['tsvgm']}}, 22:23:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:23:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:23:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:23:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:23:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:23:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:23:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:48 INFO - 'talos_options': ['--disable-e10s'], 22:23:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:23:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:23:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:48 INFO - 'talos_options': ['--disable-e10s'], 22:23:48 INFO - 'tests': ['damp', 'tps']}, 22:23:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:48 INFO - 'tests': ['damp', 'tps']}, 22:23:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:23:48 INFO - 'tests': ['dromaeo_dom']}, 22:23:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:23:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:23:48 INFO - 'tests': ['a11yr', 22:23:48 INFO - 'ts_paint', 22:23:48 INFO - 'tpaint', 22:23:48 INFO - 'sessionrestore', 22:23:48 INFO - 'sessionrestore_no_auto_restore', 22:23:48 INFO - 'tabpaint']}, 22:23:48 INFO - 'other-e10s': {'tests': ['a11yr', 22:23:48 INFO - 'ts_paint', 22:23:48 INFO - 'tpaint', 22:23:48 INFO - 'sessionrestore', 22:23:48 INFO - 'sessionrestore_no_auto_restore', 22:23:48 INFO - 'tabpaint']}, 22:23:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:23:48 INFO - 'ts_paint', 22:23:48 INFO - 'tpaint', 22:23:48 INFO - 'sessionrestore', 22:23:48 INFO - 'sessionrestore_no_auto_restore', 22:23:48 INFO - 'tabpaint']}, 22:23:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:23:48 INFO - 'ts_paint', 22:23:48 INFO - 'tpaint', 22:23:48 INFO - 'sessionrestore', 22:23:48 INFO - 'sessionrestore_no_auto_restore', 22:23:48 INFO - 'tabpaint']}, 22:23:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:23:48 INFO - 'tests': ['a11yr', 22:23:48 INFO - 'ts_paint', 22:23:48 INFO - 'tpaint', 22:23:48 INFO - 'sessionrestore', 22:23:48 INFO - 'sessionrestore_no_auto_restore', 22:23:48 INFO - 'tabpaint']}, 22:23:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:23:48 INFO - 'tests': ['a11yr', 22:23:48 INFO - 'ts_paint', 22:23:48 INFO - 'tpaint', 22:23:48 INFO - 'sessionrestore', 22:23:48 INFO - 'sessionrestore_no_auto_restore', 22:23:48 INFO - 'tabpaint']}, 22:23:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:23:48 INFO - 'tests': ['tsvgx', 22:23:48 INFO - 'tsvgr_opacity', 22:23:48 INFO - 'tart', 22:23:48 INFO - 'tscrollx', 22:23:48 INFO - 'cart']}, 22:23:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:23:48 INFO - 'tsvgr_opacity', 22:23:48 INFO - 'tart', 22:23:48 INFO - 'tscrollx', 22:23:48 INFO - 'cart']}, 22:23:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:48 INFO - 'talos_options': ['--disable-e10s'], 22:23:48 INFO - 'tests': ['tp5o']}, 22:23:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:48 INFO - 'tests': ['tp5o']}, 22:23:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:48 INFO - 'talos_options': ['--disable-e10s', 22:23:48 INFO - '--xperf_path', 22:23:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:23:48 INFO - 'tests': ['tp5n']}, 22:23:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:48 INFO - 'talos_options': ['--xperf_path', 22:23:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:23:48 INFO - 'tests': ['tp5n']}}, 22:23:48 INFO - 'talos.zip': {'path': '', 22:23:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:23:48 INFO - Running post-action listener: _resource_record_post_action 22:23:48 INFO - ##### 22:23:48 INFO - ##### Finished populate-webroot step (success) 22:23:48 INFO - ##### 22:23:48 INFO - ##### 22:23:48 INFO - ##### Running create-virtualenv step. 22:23:48 INFO - ##### 22:23:48 INFO - Running pre-action listener: _resource_record_pre_action 22:23:48 INFO - Running main action method: create_virtualenv 22:23:48 INFO - Creating virtualenv /builds/slave/test/build/venv 22:23:48 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 22:23:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:23:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:23:48 INFO - Using real prefix '/tools/python27' 22:23:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:23:49 INFO - Installing distribute.............................................................................................................................................................................................done. 22:23:52 INFO - Installing pip.................done. 22:23:52 INFO - Return code: 0 22:23:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:23:52 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')]} 22:23:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:52 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')]} 22:23:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:52 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 0x107a2ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a33570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f903b48ae80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078d1d30>, '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 0x1076f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078c8b20>, '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.aWeEzgzXX1/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.QUWSzrWsio/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 22:23:52 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 22:23:52 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 22:23:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aWeEzgzXX1/Render', 22:23:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:23:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:23:52 INFO - 'HOME': '/Users/cltbld', 22:23:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:23:52 INFO - 'LOGNAME': 'cltbld', 22:23:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:52 INFO - 'MOZ_NO_REMOTE': '1', 22:23:52 INFO - 'NO_EM_RESTART': '1', 22:23:52 INFO - 'PAGER': '/bin/cat', 22:23:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:23:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:52 INFO - 'PWD': '/builds/slave/test', 22:23:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:23:52 INFO - 'SHELL': '/bin/bash', 22:23:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QUWSzrWsio/Listeners', 22:23:52 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 22:23:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:23:52 INFO - 'USER': 'cltbld', 22:23:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:23:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:23:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:52 INFO - 'XPC_FLAGS': '0x0', 22:23:52 INFO - 'XPC_SERVICE_NAME': '0', 22:23:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:23:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:52 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 22:23:52 INFO - Cleaning up... 22:23:52 INFO - Return code: 0 22:23:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:23:52 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')]} 22:23:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:52 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')]} 22:23:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:52 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 0x107a2ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a33570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f903b48ae80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078d1d30>, '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 0x1076f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078c8b20>, '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.aWeEzgzXX1/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.QUWSzrWsio/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 22:23:52 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 22:23:52 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 22:23:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aWeEzgzXX1/Render', 22:23:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:23:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:23:52 INFO - 'HOME': '/Users/cltbld', 22:23:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:23:52 INFO - 'LOGNAME': 'cltbld', 22:23:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:52 INFO - 'MOZ_NO_REMOTE': '1', 22:23:52 INFO - 'NO_EM_RESTART': '1', 22:23:52 INFO - 'PAGER': '/bin/cat', 22:23:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:23:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:52 INFO - 'PWD': '/builds/slave/test', 22:23:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:23:52 INFO - 'SHELL': '/bin/bash', 22:23:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QUWSzrWsio/Listeners', 22:23:52 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 22:23:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:23:52 INFO - 'USER': 'cltbld', 22:23:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:23:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:23:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:52 INFO - 'XPC_FLAGS': '0x0', 22:23:52 INFO - 'XPC_SERVICE_NAME': '0', 22:23:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:23:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:52 INFO - Downloading/unpacking psutil>=3.1.1 22:23:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:23:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:23:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:23:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:23:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:23:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:23:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:23:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:23:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:23:56 INFO - Installing collected packages: psutil 22:23:56 INFO - Running setup.py install for psutil 22:23:56 INFO - building 'psutil._psutil_osx' extension 22:23:56 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 22:23:56 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 22:23:56 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 22:23:57 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 22:23:57 INFO - building 'psutil._psutil_posix' extension 22:23:57 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 22:23:57 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:23:57 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:23:57 INFO - ^ 22:23:57 INFO - 1 warning generated. 22:23:57 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 22:23:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:23:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:23:57 INFO - Successfully installed psutil 22:23:57 INFO - Cleaning up... 22:23:57 INFO - Return code: 0 22:23:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:23:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:23:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:23:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:57 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 0x107a2ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a33570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f903b48ae80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078d1d30>, '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 0x1076f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078c8b20>, '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.aWeEzgzXX1/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.QUWSzrWsio/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 22:23:57 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 22:23:57 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 22:23:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aWeEzgzXX1/Render', 22:23:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:23:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:23:57 INFO - 'HOME': '/Users/cltbld', 22:23:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:23:57 INFO - 'LOGNAME': 'cltbld', 22:23:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:57 INFO - 'MOZ_NO_REMOTE': '1', 22:23:57 INFO - 'NO_EM_RESTART': '1', 22:23:57 INFO - 'PAGER': '/bin/cat', 22:23:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:23:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:57 INFO - 'PWD': '/builds/slave/test', 22:23:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:23:57 INFO - 'SHELL': '/bin/bash', 22:23:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QUWSzrWsio/Listeners', 22:23:57 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 22:23:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:23:57 INFO - 'USER': 'cltbld', 22:23:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:23:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:23:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:57 INFO - 'XPC_FLAGS': '0x0', 22:23:57 INFO - 'XPC_SERVICE_NAME': '0', 22:23:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:23:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:23:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:23:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:23:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:23:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:23:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:23:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:24:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:24:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:24:00 INFO - Installing collected packages: mozsystemmonitor 22:24:00 INFO - Running setup.py install for mozsystemmonitor 22:24:00 INFO - Successfully installed mozsystemmonitor 22:24:00 INFO - Cleaning up... 22:24:00 INFO - Return code: 0 22:24:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:24: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')]} 22:24:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24: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')]} 22:24:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107a2ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a33570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f903b48ae80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078d1d30>, '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 0x1076f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078c8b20>, '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.aWeEzgzXX1/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.QUWSzrWsio/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 22:24:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:24:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:24:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aWeEzgzXX1/Render', 22:24:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:00 INFO - 'HOME': '/Users/cltbld', 22:24:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:00 INFO - 'LOGNAME': 'cltbld', 22:24:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:00 INFO - 'MOZ_NO_REMOTE': '1', 22:24:00 INFO - 'NO_EM_RESTART': '1', 22:24:00 INFO - 'PAGER': '/bin/cat', 22:24:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:00 INFO - 'PWD': '/builds/slave/test', 22:24:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:00 INFO - 'SHELL': '/bin/bash', 22:24:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QUWSzrWsio/Listeners', 22:24:00 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 22:24:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:00 INFO - 'USER': 'cltbld', 22:24:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:00 INFO - 'XPC_FLAGS': '0x0', 22:24:00 INFO - 'XPC_SERVICE_NAME': '0', 22:24:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:00 INFO - Downloading/unpacking jsonschema==2.5.1 22:24:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:24:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:24:03 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 22:24:03 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 22:24:03 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 22:24:03 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 22:24:03 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 22:24:03 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 22:24:03 INFO - Downloading functools32-3.2.3-2.tar.gz 22:24:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:24:03 INFO - warning: no files found matching '*.txt' 22:24:03 INFO - no previously-included directories found matching 'build' 22:24:03 INFO - no previously-included directories found matching 'dist' 22:24:03 INFO - no previously-included directories found matching '.git*' 22:24:03 INFO - Installing collected packages: jsonschema, functools32 22:24:03 INFO - Running setup.py install for functools32 22:24:03 INFO - warning: no files found matching '*.txt' 22:24:03 INFO - no previously-included directories found matching 'build' 22:24:03 INFO - no previously-included directories found matching 'dist' 22:24:03 INFO - no previously-included directories found matching '.git*' 22:24:03 INFO - Successfully installed jsonschema functools32 22:24:03 INFO - Cleaning up... 22:24:03 INFO - Return code: 0 22:24:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:24:03 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')]} 22:24:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:03 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')]} 22:24:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:03 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 0x107a2ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a33570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f903b48ae80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078d1d30>, '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 0x1076f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078c8b20>, '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.aWeEzgzXX1/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.QUWSzrWsio/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 22:24:03 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 22:24:03 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 22:24:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aWeEzgzXX1/Render', 22:24:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:03 INFO - 'HOME': '/Users/cltbld', 22:24:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:03 INFO - 'LOGNAME': 'cltbld', 22:24:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:03 INFO - 'MOZ_NO_REMOTE': '1', 22:24:03 INFO - 'NO_EM_RESTART': '1', 22:24:03 INFO - 'PAGER': '/bin/cat', 22:24:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:03 INFO - 'PWD': '/builds/slave/test', 22:24:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:03 INFO - 'SHELL': '/bin/bash', 22:24:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QUWSzrWsio/Listeners', 22:24:03 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 22:24:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:03 INFO - 'USER': 'cltbld', 22:24:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:03 INFO - 'XPC_FLAGS': '0x0', 22:24:03 INFO - 'XPC_SERVICE_NAME': '0', 22:24:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:04 INFO - Downloading/unpacking blobuploader==1.2.4 22:24:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:06 INFO - Downloading blobuploader-1.2.4.tar.gz 22:24:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:24:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:24:06 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 22:24:06 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 22:24:06 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 22:24:06 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 22:24:06 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 22:24:06 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 22:24:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:24:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:24: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 22:24: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 22:24: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 22:24: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 22:24: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 22:24: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 22:24:07 INFO - Downloading docopt-0.6.1.tar.gz 22:24:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:24:07 INFO - Installing collected packages: blobuploader, requests, docopt 22:24:07 INFO - Running setup.py install for blobuploader 22:24:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:24:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:24:08 INFO - Running setup.py install for requests 22:24:08 INFO - Running setup.py install for docopt 22:24:08 INFO - Successfully installed blobuploader requests docopt 22:24:08 INFO - Cleaning up... 22:24:08 INFO - Return code: 0 22:24:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:24:08 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')]} 22:24:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:08 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')]} 22:24:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:08 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 0x107a2ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a33570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f903b48ae80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078d1d30>, '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 0x1076f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078c8b20>, '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.aWeEzgzXX1/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.QUWSzrWsio/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 22:24:08 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 22:24:08 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 22:24:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aWeEzgzXX1/Render', 22:24:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:08 INFO - 'HOME': '/Users/cltbld', 22:24:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:08 INFO - 'LOGNAME': 'cltbld', 22:24:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:08 INFO - 'MOZ_NO_REMOTE': '1', 22:24:08 INFO - 'NO_EM_RESTART': '1', 22:24:08 INFO - 'PAGER': '/bin/cat', 22:24:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:08 INFO - 'PWD': '/builds/slave/test', 22:24:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:08 INFO - 'SHELL': '/bin/bash', 22:24:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QUWSzrWsio/Listeners', 22:24:08 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 22:24:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:08 INFO - 'USER': 'cltbld', 22:24:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:08 INFO - 'XPC_FLAGS': '0x0', 22:24:08 INFO - 'XPC_SERVICE_NAME': '0', 22:24:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:24:09 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-fEh14b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:24:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:24:09 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-FpiVYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:24:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:24:09 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-sXg_Xn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:24:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:24:09 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-yeWoUE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:24:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:24:09 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-lShm8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:24:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:24:09 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-_kv4DA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:24:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:24:09 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-Ip22Fp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:24:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:24:09 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-rQRbrm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:24:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:24:09 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-5W81vE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:24:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:24:09 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-MVt_wG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:24:10 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-LsEngP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:24:10 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-QWwhne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:24:10 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-gfoUVt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:24:10 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-fiR4jM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:24:10 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-N5Juzd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:24:10 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-0XamKI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:24:10 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-0YJlRt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:24:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:24:10 INFO - Running setup.py install for manifestparser 22:24:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:24:10 INFO - Running setup.py install for mozcrash 22:24:11 INFO - Running setup.py install for mozdebug 22:24:11 INFO - Running setup.py install for mozdevice 22:24:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:24:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:24:11 INFO - Running setup.py install for mozfile 22:24:11 INFO - Running setup.py install for mozhttpd 22:24:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:24:11 INFO - Running setup.py install for mozinfo 22:24:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:24:11 INFO - Running setup.py install for mozInstall 22:24:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:24:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:24:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:24:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:24:11 INFO - Running setup.py install for mozleak 22:24:12 INFO - Running setup.py install for mozlog 22:24:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:24:12 INFO - Running setup.py install for moznetwork 22:24:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:24:12 INFO - Running setup.py install for mozprocess 22:24:12 INFO - Running setup.py install for mozprofile 22:24:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:24:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:24:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:24:12 INFO - Running setup.py install for mozrunner 22:24:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:24:12 INFO - Running setup.py install for mozscreenshot 22:24:13 INFO - Running setup.py install for moztest 22:24:13 INFO - Running setup.py install for mozversion 22:24:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:24:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:24:13 INFO - Cleaning up... 22:24:13 INFO - Return code: 0 22:24:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:24:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:24:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:24:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:13 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 0x107a2ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a33570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f903b48ae80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078d1d30>, '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 0x1076f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078c8b20>, '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.aWeEzgzXX1/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.QUWSzrWsio/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 22:24:13 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 22:24:13 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 22:24:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aWeEzgzXX1/Render', 22:24:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:13 INFO - 'HOME': '/Users/cltbld', 22:24:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:13 INFO - 'LOGNAME': 'cltbld', 22:24:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:13 INFO - 'MOZ_NO_REMOTE': '1', 22:24:13 INFO - 'NO_EM_RESTART': '1', 22:24:13 INFO - 'PAGER': '/bin/cat', 22:24:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:13 INFO - 'PWD': '/builds/slave/test', 22:24:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:13 INFO - 'SHELL': '/bin/bash', 22:24:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QUWSzrWsio/Listeners', 22:24:13 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 22:24:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:13 INFO - 'USER': 'cltbld', 22:24:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:13 INFO - 'XPC_FLAGS': '0x0', 22:24:13 INFO - 'XPC_SERVICE_NAME': '0', 22:24:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:24:13 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-Dd9OAa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:24:13 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)) 22:24:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:24:13 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-EHkBIL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:24:13 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)) 22:24:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:24:13 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-_edQ5l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:24:13 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)) 22:24:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:24:13 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-vtFws8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:24:14 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)) 22:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:24:14 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-VzRMaQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:24:14 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)) 22:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:24:14 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-U9PlCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:24:14 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)) 22:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:24:14 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-irqXpC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:24:14 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)) 22:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:24:14 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-1jWoNv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:24:14 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)) 22:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:24:14 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-CCgLQ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:24:14 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)) 22:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:24:14 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-7CrEcY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:24:14 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)) 22:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:24:14 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-zmjzns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:24:14 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)) 22:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:24:14 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-kZ62oQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:24:14 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)) 22:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:24:14 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-3S7Ca0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:24:15 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)) 22:24:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:24:15 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-VHd9t6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:24:15 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)) 22:24:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:24:15 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-K32WcE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:24:15 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)) 22:24:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:24:15 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-Q2ek9f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:24:15 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)) 22:24:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:24:15 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-7aiylt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:24:15 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)) 22:24:15 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)) 22:24:15 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)) 22:24:15 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)) 22:24:15 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)) 22:24:15 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)) 22:24:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:24:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:17 INFO - Downloading blessings-1.6.tar.gz 22:24:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:24:18 INFO - Installing collected packages: blessings 22:24:18 INFO - Running setup.py install for blessings 22:24:18 INFO - Successfully installed blessings 22:24:18 INFO - Cleaning up... 22:24:18 INFO - Return code: 0 22:24:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:24:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:24:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:24:18 INFO - Reading from file tmpfile_stdout 22:24:18 INFO - Current package versions: 22:24:18 INFO - blessings == 1.6 22:24:18 INFO - blobuploader == 1.2.4 22:24:18 INFO - docopt == 0.6.1 22:24:18 INFO - functools32 == 3.2.3-2 22:24:18 INFO - jsonschema == 2.5.1 22:24:18 INFO - manifestparser == 1.1 22:24:18 INFO - mozInstall == 1.12 22:24:18 INFO - mozcrash == 0.17 22:24:18 INFO - mozdebug == 0.1 22:24:18 INFO - mozdevice == 0.48 22:24:18 INFO - mozfile == 1.2 22:24:18 INFO - mozhttpd == 0.7 22:24:18 INFO - mozinfo == 0.9 22:24:18 INFO - mozleak == 0.1 22:24:18 INFO - mozlog == 3.2 22:24:18 INFO - moznetwork == 0.27 22:24:18 INFO - mozprocess == 0.22 22:24:18 INFO - mozprofile == 0.28 22:24:18 INFO - mozrunner == 6.11 22:24:18 INFO - mozscreenshot == 0.1 22:24:18 INFO - mozsystemmonitor == 0.3 22:24:18 INFO - moztest == 0.7 22:24:18 INFO - mozversion == 1.4 22:24:18 INFO - psutil == 3.1.1 22:24:18 INFO - requests == 1.2.3 22:24:18 INFO - wsgiref == 0.1.2 22:24:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:24: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')]} 22:24:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:24:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:19 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 0x107a2ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a33570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f903b48ae80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078d1d30>, '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 0x1076f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078c8b20>, '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.aWeEzgzXX1/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.QUWSzrWsio/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 22:24:19 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 22:24:19 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 22:24:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aWeEzgzXX1/Render', 22:24:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:19 INFO - 'HOME': '/Users/cltbld', 22:24:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:19 INFO - 'LOGNAME': 'cltbld', 22:24:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:19 INFO - 'MOZ_NO_REMOTE': '1', 22:24:19 INFO - 'NO_EM_RESTART': '1', 22:24:19 INFO - 'PAGER': '/bin/cat', 22:24:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:19 INFO - 'PWD': '/builds/slave/test', 22:24:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:19 INFO - 'SHELL': '/bin/bash', 22:24:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QUWSzrWsio/Listeners', 22:24:19 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 22:24:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:19 INFO - 'USER': 'cltbld', 22:24:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:19 INFO - 'XPC_FLAGS': '0x0', 22:24:19 INFO - 'XPC_SERVICE_NAME': '0', 22:24:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:19 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)) 22:24:19 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)) 22:24:19 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)) 22:24:19 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)) 22:24:19 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)) 22:24:19 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)) 22:24:19 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)) 22:24:19 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)) 22:24:19 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)) 22:24:19 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)) 22:24:19 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)) 22:24:19 INFO - Cleaning up... 22:24:19 INFO - Return code: 0 22:24:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:24:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:24:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:24:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:19 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 0x107a2ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a33570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f903b48ae80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078d1d30>, '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 0x1076f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078c8b20>, '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.aWeEzgzXX1/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.QUWSzrWsio/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 22:24:19 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 22:24:19 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 22:24:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aWeEzgzXX1/Render', 22:24:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:19 INFO - 'HOME': '/Users/cltbld', 22:24:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:19 INFO - 'LOGNAME': 'cltbld', 22:24:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:19 INFO - 'MOZ_NO_REMOTE': '1', 22:24:19 INFO - 'NO_EM_RESTART': '1', 22:24:19 INFO - 'PAGER': '/bin/cat', 22:24:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:19 INFO - 'PWD': '/builds/slave/test', 22:24:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:19 INFO - 'SHELL': '/bin/bash', 22:24:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QUWSzrWsio/Listeners', 22:24:19 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 22:24:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:19 INFO - 'USER': 'cltbld', 22:24:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:19 INFO - 'XPC_FLAGS': '0x0', 22:24:19 INFO - 'XPC_SERVICE_NAME': '0', 22:24:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:24:19 INFO - Cleaning up... 22:24:19 INFO - Return code: 0 22:24:19 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:24:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:24:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:24:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:19 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 0x107a2ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a33570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f903b48ae80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078d1d30>, '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 0x1076f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078c8b20>, '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.aWeEzgzXX1/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.QUWSzrWsio/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 22:24:19 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 22:24:19 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 22:24:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aWeEzgzXX1/Render', 22:24:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:19 INFO - 'HOME': '/Users/cltbld', 22:24:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:19 INFO - 'LOGNAME': 'cltbld', 22:24:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:19 INFO - 'MOZ_NO_REMOTE': '1', 22:24:19 INFO - 'NO_EM_RESTART': '1', 22:24:19 INFO - 'PAGER': '/bin/cat', 22:24:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:19 INFO - 'PWD': '/builds/slave/test', 22:24:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:19 INFO - 'SHELL': '/bin/bash', 22:24:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QUWSzrWsio/Listeners', 22:24:19 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 22:24:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:19 INFO - 'USER': 'cltbld', 22:24:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:19 INFO - 'XPC_FLAGS': '0x0', 22:24:19 INFO - 'XPC_SERVICE_NAME': '0', 22:24:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:19 INFO - Downloading/unpacking flake8 22:24:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:22 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:24:22 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:24:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:22 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:24:22 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:24:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:23 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:24:23 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 22:24:23 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 22:24:23 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 22:24:23 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 22:24:23 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 22:24:23 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 22:24:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:24:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:24:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:24:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:24:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:24:23 INFO - no previously-included directories found matching 'docs/_build' 22:24:23 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:24:23 INFO - Running setup.py install for pep8 22:24:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:24:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:24:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:24:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:24:23 INFO - no previously-included directories found matching 'docs/_build' 22:24:23 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:24:23 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:24:23 INFO - Cleaning up... 22:24:23 INFO - Return code: 0 22:24:23 INFO - Running post-action listener: _resource_record_post_action 22:24:23 INFO - Running post-action listener: _start_resource_monitoring 22:24:23 INFO - Starting resource monitoring. 22:24:23 INFO - ##### 22:24:23 INFO - ##### Finished create-virtualenv step (success) 22:24:23 INFO - ##### 22:24:23 INFO - ##### 22:24:23 INFO - ##### Running install step. 22:24:23 INFO - ##### 22:24:23 INFO - Running pre-action listener: _resource_record_pre_action 22:24:23 INFO - Running main action method: install 22:24:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:24:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:24:24 INFO - Reading from file tmpfile_stdout 22:24:24 INFO - Detecting whether we're running mozinstall >=1.0... 22:24:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:24:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:24:24 INFO - Reading from file tmpfile_stdout 22:24:24 INFO - Output received: 22:24:24 INFO - Usage: mozinstall [options] installer 22:24:24 INFO - Options: 22:24:24 INFO - -h, --help show this help message and exit 22:24:24 INFO - -d DEST, --destination=DEST 22:24:24 INFO - Directory to install application into. [default: 22:24:24 INFO - "/builds/slave/test"] 22:24:24 INFO - --app=APP Application being installed. [default: firefox] 22:24:24 INFO - mkdir: /builds/slave/test/build/application 22:24:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:24:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 22:24:58 INFO - Reading from file tmpfile_stdout 22:24:58 INFO - Output received: 22:24:58 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:24:58 INFO - Running post-action listener: _resource_record_post_action 22:24:58 INFO - ##### 22:24:58 INFO - ##### Finished install step (success) 22:24:58 INFO - ##### 22:24:58 INFO - ##### 22:24:58 INFO - ##### Running run-tests step. 22:24:58 INFO - ##### 22:24:58 INFO - Running pre-action listener: _resource_record_pre_action 22:24:58 INFO - Running main action method: run_tests 22:24:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:24:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:24:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:24:58 INFO - 2016-06-07 22:24:58.029 screenresolution[1654:8374] starting screenresolution argv=screenresolution get 22:24:58 INFO - 2016-06-07 22:24:58.031 screenresolution[1654:8374] Display 0: 1600x1200x32@60 22:24:58 INFO - 2016-06-07 22:24:58.037 screenresolution[1655:8376] starting screenresolution argv=screenresolution list 22:24:58 INFO - Available Modes on Display 0 22:24:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:24:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:24:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:24:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:24:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:24:58 INFO - Intel Iris: 22:24:58 INFO - Chipset Model: Intel Iris 22:24:58 INFO - Type: GPU 22:24:58 INFO - Bus: Built-In 22:24:58 INFO - VRAM (Dynamic, Max): 1536 MB 22:24:58 INFO - Vendor: Intel (0x8086) 22:24:58 INFO - Device ID: 0x0a2e 22:24:58 INFO - Revision ID: 0x0009 22:24:58 INFO - Displays: 22:24:58 INFO - Display: 22:24:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:24:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:24:58 INFO - Display Serial Number: 1600x1200 60 22:24:58 INFO - Main Display: Yes 22:24:58 INFO - Mirror: Off 22:24:58 INFO - Online: Yes 22:24:58 INFO - Rotation: Supported 22:24:58 INFO - Return code: 0 22:24:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:24:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:24:58 INFO - Python 2.7.3 22:24:58 INFO - Return code: 0 22:24:58 INFO - grabbing minidump binary from tooltool 22:24:58 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')]} 22:24:58 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 0x1078d1d30>, '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 0x1076f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078c8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:24:58 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 22:24:58 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 22:24:58 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 22:24:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:24:58 INFO - Return code: 0 22:24:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:24:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:24:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:24:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:24:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:24:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:24:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:24:59 INFO - Return code: 0 22:24:59 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/RnWcIvrUTgeZI6AuVfNNvg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0205', '--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 22:24:59 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/RnWcIvrUTgeZI6AuVfNNvg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0205 --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 22:24:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aWeEzgzXX1/Render', 22:24:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:59 INFO - 'HOME': '/Users/cltbld', 22:24:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:59 INFO - 'LOGNAME': 'cltbld', 22:24:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:24:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:24:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:59 INFO - 'MOZ_NO_REMOTE': '1', 22:24:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:24:59 INFO - 'NO_EM_RESTART': '1', 22:24:59 INFO - 'PAGER': '/bin/cat', 22:24:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:59 INFO - 'PWD': '/builds/slave/test', 22:24:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:24:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:59 INFO - 'SHELL': '/bin/bash', 22:24:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QUWSzrWsio/Listeners', 22:24:59 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 22:24:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:59 INFO - 'USER': 'cltbld', 22:24:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:59 INFO - 'XPC_FLAGS': '0x0', 22:24:59 INFO - 'XPC_SERVICE_NAME': '0', 22:24:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:59 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/RnWcIvrUTgeZI6AuVfNNvg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0205', '--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 22:24:59 INFO - mozversion application_buildid: 20160607203328 22:24:59 INFO - mozversion application_changeset: 1401fcd673699f8d6e431ccb643ab46e166da2a9 22:24:59 INFO - mozversion application_display_name: Nightly 22:24:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:24:59 INFO - mozversion application_name: Firefox 22:24:59 INFO - mozversion application_remotingname: firefox 22:24:59 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:24:59 INFO - mozversion application_vendor: Mozilla 22:24:59 INFO - mozversion application_version: 50.0a1 22:24:59 INFO - mozversion platform_buildid: 20160607203328 22:24:59 INFO - mozversion platform_changeset: 1401fcd673699f8d6e431ccb643ab46e166da2a9 22:24:59 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:24:59 INFO - mozversion platform_version: 50.0a1 22:24:59 INFO - using testdate: 1465363499 22:24:59 INFO - actual date: 1465363499 22:24:59 INFO - starting webserver on 'localhost:49275' 22:24:59 INFO - SUITE-START | Running 2 tests 22:24:59 INFO - TEST-START | dromaeo_css 22:24:59 INFO - operating with platform_type : mac_ 22:24:59 INFO - Initialising browser for dromaeo_css test... 22:24:59 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/tmppnOPtQ/profile http://localhost:49275/getInfo.html) 22:25:02 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 22:25:02 INFO - PROCESS | 1673 | colorDepth:24 22:25:02 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 22:25:02 INFO - PROCESS | 1673 | __metrics 22:25:02 INFO - PROCESS | 1673 | 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" 22:25:02 ERROR - PROCESS | 1673 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:25:02 INFO - PROCESS | 1673 | ************************* 22:25:02 INFO - PROCESS | 1673 | A coding exception was thrown in a Promise resolution callback. 22:25:02 INFO - PROCESS | 1673 | See https://developer.mozilla.org/Mozilla/JavaScript_code_modules/Promise.jsm/Promise 22:25:02 INFO - PROCESS | 1673 | 22:25:02 ERROR - PROCESS | 1673 | Full message: TypeError: win is null 22:25:02 INFO - PROCESS | 1673 | Full stack: onSuccess@resource://app/components/nsBrowserGlue.js:850:17 22:25:02 INFO - PROCESS | 1673 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 22:25:02 INFO - PROCESS | 1673 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 22:25:02 INFO - PROCESS | 1673 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 22:25:02 INFO - PROCESS | 1673 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 22:25:02 INFO - PROCESS | 1673 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 22:25:02 INFO - PROCESS | 1673 | get _worker/worker.onmessage@resource://gre/modules/PromiseWorker.jsm:235:9 22:25:02 INFO - PROCESS | 1673 | EventHandlerNonNull*get _worker@resource://gre/modules/PromiseWorker.jsm:217:5 22:25:02 INFO - PROCESS | 1673 | postMessage@resource://gre/modules/PromiseWorker.jsm:292:9 22:25:02 INFO - PROCESS | 1673 | TaskImpl_run@resource://gre/modules/Task.jsm:319:40 22:25:02 INFO - PROCESS | 1673 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 22:25:02 INFO - PROCESS | 1673 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 22:25:02 INFO - PROCESS | 1673 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 22:25:02 INFO - PROCESS | 1673 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 22:25:02 INFO - PROCESS | 1673 | Promise.prototype.then@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:454:5 22:25:02 INFO - PROCESS | 1673 | this.DeferredSave.prototype._deferredSave@resource://gre/modules/DeferredSave.jsm:220:5 22:25:02 INFO - PROCESS | 1673 | this.DeferredSave.prototype._startTimer/<@resource://gre/modules/DeferredSave.jsm:175:40 22:25:02 INFO - PROCESS | 1673 | syncLoadManifestFromFile@resource://gre/modules/addons/XPIProvider.jsm:1485:5 22:25:02 INFO - PROCESS | 1673 | addMetadata@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:1647:21 22:25:02 INFO - PROCESS | 1673 | processFileChanges@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2011:23 22:25:02 INFO - PROCESS | 1673 | this.XPIProvider.checkForChanges@resource://gre/modules/addons/XPIProvider.jsm:3696:34 22:25:02 INFO - PROCESS | 1673 | this.XPIProvider.startup@resource://gre/modules/addons/XPIProvider.jsm:2685:25 22:25:02 INFO - PROCESS | 1673 | callProvider@resource://gre/modules/AddonManager.jsm:227:12 22:25:02 INFO - PROCESS | 1673 | _startProvider@resource://gre/modules/AddonManager.jsm:778:5 22:25:02 INFO - PROCESS | 1673 | AddonManagerInternal.startup@resource://gre/modules/AddonManager.jsm:962:9 22:25:02 INFO - PROCESS | 1673 | this.AddonManagerPrivate.startup@resource://gre/modules/AddonManager.jsm:2922:5 22:25:02 INFO - PROCESS | 1673 | amManager.prototype.observe@resource://gre/components/addonManager.js:71:9 22:25:02 INFO - PROCESS | 1673 | 22:25:02 INFO - PROCESS | 1673 | ************************* 22:25:02 INFO - TEST-INFO | 1673: exit 0 22:25:02 INFO - Browser initialized. 22:25:02 INFO - Running cycle 1/1 for dromaeo_css test... 22:25:02 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/tmppnOPtQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:25:14 INFO - PROCESS | 1676 | RSS: Main: 266457088 22:25:14 INFO - PROCESS | 1676 | 22:26:06 INFO - PROCESS | 1676 | 1465363566084 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] 22:28:16 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 22:28:18 INFO - PROCESS | 1676 | RSS: Main: 395784192 22:28:18 INFO - PROCESS | 1676 | 22:31:21 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 22:31:21 INFO - PROCESS | 1676 | RSS: Main: 398303232 22:31:21 INFO - PROCESS | 1676 | 22:34:24 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 22:34:24 INFO - PROCESS | 1676 | RSS: Main: 397881344 22:34:24 INFO - PROCESS | 1676 | 22:37:26 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 22:37:27 INFO - PROCESS | 1676 | RSS: Main: 395677696 22:37:27 INFO - PROCESS | 1676 | 22:40:30 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 22:40:31 INFO - PROCESS | 1676 | RSS: Main: 365285376 22:40:31 INFO - PROCESS | 1676 | 22:43:34 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 22:43:35 INFO - PROCESS | 1676 | RSS: Main: 404561920 22:43:35 INFO - PROCESS | 1676 | 22:43:35 INFO - PROCESS | 1676 | __start_tp_report 22:43:35 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 22:43:35 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 22:43:35 INFO - PROCESS | 1676 | |i|pagename|runs| 22:43:35 INFO - PROCESS | 1676 | |0;dojo.html;2808.7331703488167;3410.096324474014;3421.5380923575326;3443.7245020398373;3478.9826050869756;5477;5527.944720552793;5579.46995035471;5631.493165615099;5645.802396916109;5566.165075238716;5569.053260945639;5680.886382272352;5693.430656934304;5712.828615141542;5037.017781532602;5049.596032317406;5109.744512774365;5116.692998420088;5243.161094224924;5206.479352064801;5222.112240919053;5240.6593571417925;5243.528082472577;5243.6853788772605;3919.627635374635;5128.179491281415;5142.202958541432;5154.536091751723;5167.612429067816;5089.745512724386;5109.463506331856;5170.172772356443;5282.181261904411;5292.364916210079;5100.566451851597;5137.948620513785;5155.355580552431;5192.428832828386;5231.215317702375;5432.891342173174;5573.5819813514145;5576.776928922838;5616.52259557938;5646.632968857052;6054.353271463811;6072.878542429171;6134.78528251509;6137.407629318945;6183.907241391383;6172.9074063889075;6209.192804935375;6239.812805615838;6241.843953901143;6262.592931459486;4646.790894409692;5466.9179962300595;5750.539956803445;5761.913571296392;5771.971140144273;6180.6909654517995;6212.378762123842;6216.844578885473;6218.844528886723;6245.6252624842655;6088.560885608874;6092.238470191226;6109.77804439114;6114.681412843158;6133.080037994337;6009.849753756103;6162.106494558264;6239.126522286883;6251.374862513803;6257.68711564429;5985.281766187998;6054.061620448838;6060.121282414026;6064.818055458343;6066.514678825683;4784.665073444826;6043.244594425697;6109;6118.174046503754;6122.602030867979;4836.540528649773;4866.756662166948;4877.09773691861;4891.364122664031;4928.310036594879;4366.366876802846;4375.934360984587;4382.715123516961;4418.624416924533;4420.049689316812;5632.662040277596;5641.266635337462;5641.915371269434;5649.237352957381;5656.8020119295625;6066.545009124245;6134.78528251509;6145.002874065946;6221.5333849961435;6231.376862313732;5633.718314084279;5651.350094739076;5661.235733175969;5662.150677398341;5687.914681279784;5345.037893179187;5354.571634269249;5355.866103347447;5377.435369286247;5389.9191512127345;6151.2618485781995;6179.443850053517;6183.876322473436;6251.687415629201;6290.685465726695;6209.348018457997;6245.68771561411;6246.843828904313;6248.250209974832;6281.905771413341;6256.092866534236;6268.341824108403;6280.183576135073;6282.340354262737;6284.120223168668;5914.467697907209;6080.118382834376;6105.884339887077;6112.572119951561;6121.3572574879;5220.843374698765;5315.644510649914;6146.323904370605;6179.073139029181;6230.626162430269;5922.763089476545;5991.610545314541;6039.5470339723815;6056.212692349966;6064.848378790565;5794.565407594363;5931.9406805931385;5972.462478376793;6025.126356678345;6065.939340606537;4875.341828853059;5852.882942341214;5865.73604187804;5878.941210587839;5925.762969481172;5792.044312688359;6058.502382640739;6079.787207447718;6088.056351265561;6097.7865774697675;5713.771449141987;5836.358000619844;5938.584299099021;5942.257217847769;5992.580519363602 22:43:35 INFO - PROCESS | 1676 | |1;ext.html;520.6381564812455;523.5262087810532;538.5637633516851;549.2228496677202;554.5840619535348;30756.692433075663;39294.410583841265;42300.34696183421;42900.78549607255;43118.784406078004;44561.77719111408;44941.7752911235;45248.77375613125;45251;45301.54698453014;7377.926220737778;9358;9578.467445208767;9602.327837051409;9642.807143857119;8437.957810210972;11337.829932551018;11564.768704625902;11588.246763960358;11804.822927656092;11506.079513638932;11601.477933493014;11602.361870097142;11610.883891161107;11616.593419230328;8461.49231046133;11299.03958163557;11381;11553.13351498641;11573.768524629502;11478.540858365655;11581.76836463266;11587.420628968517;11597.014253788439;11598.536058557647;40611.78164655065;40970;41006.38490422646;41057.589424105674;41072.97317567085;3486.3201675673254;3505.7983942908068;3632.5822530409077;4021.416894550303;4104.0970986382945;31028.689713102805;32242.677573224202;32541.511877321624;32553.83723081393;32619.836900815582;3219.645838957713;3263.461528847761;3263.7552183586326;3282.917927051819;3344.3311337732553;477650;479520;480148;480233;480527;34475.13812154716;62879.685601572615;62882.68558657177;63620.68189659022;63645;35469.29061418757;36297.81851090727;36532.45201321982;36541.634583654355;36698.44952325717;14930.178840163679;15122.848771512145;15126.546203613909;15149.166795826115;15152.16663083541;11595.594154204731;11737.471813768401;11758.529658813723;11872.940635296769;12685.429155688016;7459.179490256068;7727;7911.446198766032;7932.881006784903;8012.439129260896;3220.3914828988595;3247.594050743657;3256.1371236622313;3260.690234427773;3277.2953814929906;957.8563215517728;1010.7725761703558;1046.1526163807189;1095.2804097566902;1098.747288123736;23648.290551283488;28024;29024;30495.69504304973;31238.53142202869;1115.0856297835696;1145.8281257811395;1151.5566506894738;1177.0759953436511;1189.5539172810197;15856.762148567781;15877.52367428979;15944.601384965237;16330.183490825411;16375.344986200418;699017;707922;708701;709018;709354;60741.69629151826;61194;61296.38703612996;61308;61422.69288653528;1308.1431662261234;1339.4173534512324;1426.6362077670542;1445.1401416157369;1482.9480968166063;31004.22489437782;31789.68210317867;32182.678173218905;32584.533695983264;32681.34637307288;52317.95364092773;52671.47328526666;52776.47223527716;52861;52935.20597190968;538566.3071684616;541170;542437;542825;542898;21504.354869353945;27130.32174195661;27376.589351160146;27426.31434214081;27907.302317441416;48269.51730482791;48668.783280418276;48907.75546122246;49747.751261243466;49861.501384985684;38330.61669383271;40015.91010788949;40028.799856000536;40258;40747.796261018506;39112.413313800884;39287;40102.796916092564;40251.79874100611;40369;38540;38956;39308.41037384384;39320.2135957285;39370.015749606486;32875.835620821745;34016.48975265324;34733.826330868185;34813.47779783356;34823.30353392867;25263.989440422178;25500.48999020046;25509.84309837131;25630.871845640653;26220.606690900033 22:43:35 INFO - PROCESS | 1676 | |2;jquery.html;312.49220017471606;386.3528589612399;395.50759304665695;405.19164267286766;410.84182589702976;15169.089854608703;15750.13374264415;19817.20731170753;21904.671429928563;22001.22995695151;20046.599068018666;20630.48423789406;21394.89302553489;21627.35117946464;21836.89081554594;5001.44984051754;5308.256844041818;5848.67832269225;6069.878602427949;6080;5337.14605663094;5884.911726324109;5969.731362088716;5981.162637230791;6039.6980150992495;5727.140928860663;6014.609050411731;6023.704903445759;6072.666003369813;6089.817305480843;6018.909716354258;6059.484943779784;6072.878542429126;6075.848103797418;6077.17958075663;5089.312942752718;5407.5133238008375;5409.56723462122;5412.431694672082;5417.8103766367985;13199.142055766344;13511.864881351159;17424.65150696979;18992.715109273373;27021.729782702118;2201.5816994771103;2319.3969567912463;2354.0113152476;2499.512595043967;2577.3427775917207;9509;9847.261455390802;9920.801583968352;10230.181585473145;10447;1788.579683774312;1808.4032269351214;1813.2112531049036;1817.1148949713515;1860.637175750729;95731;99878.50060749649;103259;103949.48025259825;104357.47821260845;8222.17778222185;9376.765580860532;10102.444365559964;10107.7473063174;10235.82689366284;12310.322932238812;12337.444814983348;12416.317102559446;12418.75162496763;12439.502419903283;8074.798130046795;8299.8340033199;8311.25198732117;8374.246317831303;8374.958125209458;7401.888971665429;7870.488418252795;8196;8641.049484556694;8762.430442021376;3892.649661530476;3905.8127448628293;3915.6084391560616;3961.920761584752;3967.6230758077936;1661.335465813684;1789.91945362459;1795.3461201441103;1913.7320775091498;1950.8439324853978;899.9145081217274;927.332320729074;966.9573175887779;988.2538683294067;1000.3747657713928;16530.92548984312;19505.219791208194;20580;20592.970351482367;21020.894895525424;972.0862389353985;987.3243510087742;992.2409356842022;997.217184510156;1011.0647650922867;10769.192310576738;10862.619808306672;10881.347119172875;10901.291416057931;10975.451227438596;582798;584786;590484;600745;602518;115715;129764;130753;132286;133878;993.9403635781877;1015.0610685116238;1033.5968972100886;1035.0684384054261;1036.6319956415514;16468.318106993687;16927.238274277443;17722.20250088775;17992.460226193234;18344.991025493193;45802.984477716935;49096.75451622719;51390;52242.738786307345;52648.94702106013;75137.24862751302;77640;79118.6044069776;79666.20333796588;80090;7878.330341921001;8184.058833234016;8238.917610824055;8270.793230169053;8441.662333506836;9109.362344636076;12814.487420503076;13142.080054396101;13225.40485678166;13227.22228332061;10989.175811813986;11338.07118699619;11360.204785664924;11488.827667584826;11577.768444631229;11140.33158010522;11166.1067114629;11284.492197850952;11449.885501145216;11518.308901465938;11245.493952772309;11625.825612615648;11784.410779461165;11802.586909458129;11884.930356267978;8091.757247282591;8115.75652730419;8142.226488483585;8187.754367368989;8359.707410240613;8294.958525207336;11270.211085223955;11368;11378.487968041292;11573.59492417795 22:43:35 INFO - PROCESS | 1676 | |3;mootools.html;1394.8186735724355;1517.848215178482;1557.0268582136164;1771.46855943217;1942.0969249299064;7781.260780225876;8209.712660056899;8229.83540329193;8239.423240373162;8241.752747417571;8341.249287564118;8588.227059564639;8600.698975535857;8737.432066915646;8738.781530461742;2356.7878890899815;2435.7320694723567;2438.4757277185404;2439.4633180700216;2440.5851005329137;2071.1818831561523;2221.522372689871;2225.721784776903;2359.8820058997067;2403;2358.1982126077132;2389.498205376873;2396.6884305040408;2398.340456374502;2401.5677178107935;2336.5794157051723;2338.3920180753125;2375.916842910507;2380.9285721428387;2387.997041242665;2390.53384590005;2396.8681722505257;2421.3583400398907;2422.442838147236;2427.635854621803;8698.869516957251;8776.122387761237;8777.210051095431;8792.384533082686;8823.11768823119;5226.268322434862;5249.86875328116;5433.755481003325;5442.863928401782;5452.182172674091;3578.946315805265;3693.464447655102;3743.12036671382;3755.4366844973474;3767.1335592813534;1073.7798751256053;1096.740714128897;1108.2131686502594;1113.6603335982522;1135.3188087147728;11606.245594036445;11677.532898684043;11684.182107252496;11722.179447438682;11732.061435085172;4520.751358675305;4581.816727330869;4582.450105987303;4629.560191781843;4680;9076.455412675261;9160.496172710566;9162.679306224383;9163.58763855628;9171.678991235274;5272.235525848807;5281.0758117329315;5286.07493688603;5287.762050707727;5289.947100528945;3812.542494900574;4459.86620401382;4556.498785133633;4954.777035033432;4963.181075122637;3776.3202623528027;3813.580506144322;3834.271488417192;3869.632384923484;3886.125621735087;1170.449888552379;1185.211834130311;1185.82805493203;1198.0954379443465;1200.4897918384652;670.4770279182246;841.2807049972185;880.1330689271191;990.4651488196438;1007.1137399088755;5066.493350664904;5310.6813591184655;5701.942980570224;6115.327313995457;6304.085907543381;920.2453987730131;958.0993865766158;974.4640447753707;998.9150922171551;1019.1948360795037;3959.6832253419657;4086.4074709167003;4095.1195492969155;4152.854650087233;4157.875263742093;10525.421101839473;10840.349579025284;10867.945660271647;10887.346759194474;10895;10762.730931726614;10862.891371086345;10865.674029779118;10875.401852898009;10899.509522071527;7075.787726368218;7708.49894756839;8175.100738918714;8179.222973817477;8307.792305192297;5965.28416590012;6003.279606447254;6063.302720187235;6113.480354169946;6211;9783.951080244553;9932.40790016848;10088.394696318244;10104.646337378157;10115.039071288216;9978.401295922267;9990.900090998997;10135.290529662852;10140.594376225163;10274.383536987805;7475.43934204926;7539.434542409451;7652.311291983526;7653.0816302041885;7659.463837531319;8072.112067672434;8219.630116644885;8219.876701849358;8222.958885205535;8235.13531079228;7323.670434830339;7374.410047196104;7408.592527411044;7472.252774722484;7556.168821429528;7293.234210408043;7294.452916031213;7354.669039893324;7487.251274872687;7511.985881905873;6763.560368576027;7028.051213086168;7032.296770323132;7036.155661320469;7107.82230444243;4812.759362031815;5072.644914855925;5163.3545806774155;5304.867378315573;5313.654612450333;5223.973880130575;5375.623706340519;5416.404195538409;5438.23864658956;5550.084236100998 22:43:35 INFO - PROCESS | 1676 | |4;prototype.html;299.8815467890182;306.90179142674344;314.8347117763174;318.7577441144732;322.0900954802683;4561.6806823522365;5116;5287.831988884558;5331.333583302087;5357.249985002103;5056.443791182967;5213.400458947222;5260.342457192851;5280.656757310772;5337.158680255306;3145.4967205247167;4043.898902527431;4064.0652649890544;4083.7141400102078;4353.325234588644;3650.6166852480374;3758.6993040556827;3822.617738226183;4043;4099.836006559749;4030.125991413992;4037.454943582623;4046.271671099201;4050.291199040171;4083.4283200351974;3554.8044857532827;3970.7419017763755;3990.9600903990877;4033.657139143176;4080.8571699990357;2971.539411391238;3481.3907566175817;3522.9119272018147;3555.093451169961;3556.5198698175673;4743.454502732161;4896.559309662111;5009.741440736663;5030.547250747413;5031.899362012776;3922.6077392260545;4583.3125031245245;4591.380163677894;4615.353850460938;4623.42207224097;4817.470078291385;5460.726963651801;5464.371597266326;5487.286652735119;5498.1752737089355;3513.578370595519;3522.2779330237495;3631.4915911772373;3801.2777572261184;3805.847766089353;5338.466153384669;5801.216835727208;5803.564732645069;5836.474717275466;5854.385289544579;3285.3922024425556;3853.2871418787613;3854.6530812227033;3862.7789021312856;3870.632289932452;2970.514742628686;3909.9413508797393;3917.941230881539;3942.72400931932;3972.483577134954;4512.390827238347;4547.545245475492;4547.818087276538;4564.908701825945;4582.9770851146195;4938.234573641092;5535.557155427556;5542.97228513863;5550.195221692832;5643.657881979015;1173.9610444756452;1205.8492688413949;1225.5894275417656;1248.6389835079806;1293.6442478318538;832.8792325112825;857.9699710510102;897.555709923592;900.5677274908081;907.3149771922157;4889.779959901742;5102.336696229467;5202.24567437727;5202.401723801811;5211.557017653542;4410.7725598572515;4486.327050942319;5571.941331147069;5683.403242659544;5734.455226753452;4311.525732169459;4563.247064234431;4879.463259041502;4970.826021089245;5203.817866374735;2978.3298757779326;2988.7608991280645;2992.264486597768;2993.7904346695523;3001.1146711719657;4943.739346466628;5349.34170407175;5711.8000869969355;5910.320313164041;5942.8811423771285;5380.461953804666;5537.7784888604;5920.230370051866;6000.949833779071;6018.578699490994;5930.851728706817;5979.86382587307;6042.637441753509;6051.092336149525;6076.5442591805795;4911.238757992517;5100.5409513143995;5443.156310771837;5776.740046697824;5820.621659592113;5018.648694591344;5646.656637495394;5665.2326203076145;5682.289713785777;5686;5015.749212539431;5609.523190528851;5956.766212670249;5966.19456373384;5980.3421623622235;3654.0134163776165;3780.4707340971736;3821.1020410203023;3827.6220560598717;3851.730378873452;4674.439067311944;5297.284866543121;5447.373552041566;5624.35319938196;5701.0593252114595;4733.266343716729;4775.5224477551965;5107.846764597068;5233.450487698737;5359.892802143857;4979.477154898684;5253.816116435906;5571.777128914798;5591.524720398812;5612.242347283064;4698.365720627671;5287.735613219246;5303.310569625924;5459.508644222039;5496.608975095846;5552.724402210356;5578;5600.187972744011;5600.271964644733;5608.383077861514;4589.632829373575;4763.976180119077;4877.707337559757;4934.55588996992;5028.472010438852 22:43:35 INFO - PROCESS | 1676 | |5;yui.html;770.7302444144547;788.5938741548102;795.4153697032683;815.7022686719346;868.9478631282124;12677.61967140985;15431.845681543182;15583.532494025198;15629.609259768511;17031;15882.205889705527;15900;15917;15928.283227254773;15984.520464386058;722.6964674836581;903.1916434790875;919.0166521821653;919.7838507950627;920.4339331311262;730.9122905251377;884.0319849764529;916.6287653500343;917.4862077236735;917.8439665256889;950.5152372290147;954.7422196007074;956.2111258211968;956.3879117364892;956.4882787708577;753.9170691223961;927.4342650982895;951.7525443384735;952.6284748947916;953.3040880157489;709.684190535212;710.449401713676;710.9502334836563;711.6975285503692;712.7647876200894;15405.306761195772;15487.148206848618;15488.07071575709;15496.845031549765;15517.612059698486;11317.943410282978;13946.721065578733;13951.302434878215;13980.93009534956;14057.87536997048;12928.806067908989;13240.933795331059;13324;13377.33113344329;13601.931990340085;4556.3393307970155;4603.746793926332;4725.31482934976;4770.833020844254;4781.904361912777;108805.91194088037;109391.45304273507;111857;112378.43810780975;113908;10852.782944341068;10903.781924361627;10918.01737843598;10954.233203675667;11007.394593297286;21253.149874005176;21267.149314027265;21308;21316;21637.459063523536;7920.584849526777;7921.089074756362;7928.841423171504;8070;8120.431569790177;4438.933415998763;4461.598456138963;4578.175928332932;4687.648426368036;4704.858854234379;3441.5698037745283;3561.358955388055;3563.127033876665;3564.928701426009;3572.392693242103;648.9870202595922;653.5000724445803;663.3466035954581;665.0722242471725;666.3170250493187;626.6960524145786;629.1380808292577;630.4420587779848;644.1497223664718;659.9670016499156;8659.48043117415;9056.773580660405;9064.320175986828;9184.219341355925;9321.347505674537;468.89449873778125;631.5989346764745;645.3772109913956;692.5660612906146;702.5784429826776;4476.216662084123;4594.241950078266;4673.485916549177;4703.247480403187;4710.670253082251;118824.40587797179;122403;124958.37520812338;127878;131429;94522.527387364;97592;106634;107667;111176;90412.5479372599;90701.09298906926;90766.09233907709;91153;94357;8974.878140232471;9012.504312262627;9041.276697864023;9083.45499270046;9234;52462.737686311324;55419.16871247015;55774;55790.72104639451;55841.44158558363;77059.22940770745;84875;85237.1476285254;85901.57049214713;88350;10136.594536218468;11522.366269855238;11667;11875.76248475043;11991.820122697993;13010.154339967872;13080.476780929037;13147.09446419719;13406.664833378853;13414.731705366034;11912.238224795808;12242.816357754818;12277;12407;12584.05619578517;12026.939865300967;12296.20074695142;12372.4432400544;12541.811872821732;12765.74468510606;11789.351585662871;12973.610791676265;13032.54386096482;13666.52167174144;13696.52062177819;11164.720881978015;11295.491702873554;11313.547458101913;11628.88371116278;11869.389209466022;10611.840822387825;10634.41510716918;10696.144308455461;10760;11055.834162487408 22:43:35 INFO - PROCESS | 1676 | __end_tp_report 22:43:35 INFO - PROCESS | 1676 | __start_cc_report 22:43:35 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,1381 22:43:35 INFO - PROCESS | 1676 | __end_cc_report 22:43:35 INFO - PROCESS | 1676 | __startTimestamp1465364615059__endTimestamp 22:43:35 INFO - PROCESS | 1676 | 22:43:35 INFO - PROCESS | 1676 | ------- Summary: start ------- 22:43:35 INFO - PROCESS | 1676 | Number of tests: 6 22:43:35 INFO - PROCESS | 1676 | 22:43:35 INFO - PROCESS | 1676 | [#0] dojo.html Cycles:165 Average:5641.96 Median:5836.36 stddev:652.35 (11.2%) stddev-sans-first:615.32 22:43:35 INFO - PROCESS | 1676 | Values: 2808.7 3410.1 3421.5 3443.7 3479.0 5477.0 5527.9 5579.5 5631.5 5645.8 5566.2 5569.1 5680.9 5693.4 5712.8 5037.0 5049.6 5109.7 5116.7 5243.2 5206.5 5222.1 5240.7 5243.5 5243.7 3919.6 5128.2 5142.2 5154.5 5167.6 5089.7 5109.5 5170.2 5282.2 5292.4 5100.6 5137.9 5155.4 5192.4 5231.2 5432.9 5573.6 5576.8 5616.5 5646.6 6054.4 6072.9 6134.8 6137.4 6183.9 6172.9 6209.2 6239.8 6241.8 6262.6 4646.8 5466.9 5750.5 5761.9 5772.0 6180.7 6212.4 6216.8 6218.8 6245.6 6088.6 6092.2 6109.8 6114.7 6133.1 6009.8 6162.1 6239.1 6251.4 6257.7 5985.3 6054.1 6060.1 6064.8 6066.5 4784.7 6043.2 6109.0 6118.2 6122.6 4836.5 4866.8 4877.1 4891.4 4928.3 4366.4 4375.9 4382.7 4418.6 4420.0 5632.7 5641.3 5641.9 5649.2 5656.8 6066.5 6134.8 6145.0 6221.5 6231.4 5633.7 5651.4 5661.2 5662.2 5687.9 5345.0 5354.6 5355.9 5377.4 5389.9 6151.3 6179.4 6183.9 6251.7 6290.7 6209.3 6245.7 6246.8 6248.3 6281.9 6256.1 6268.3 6280.2 6282.3 6284.1 5914.5 6080.1 6105.9 6112.6 6121.4 5220.8 5315.6 6146.3 6179.1 6230.6 5922.8 5991.6 6039.5 6056.2 6064.8 5794.6 5931.9 5972.5 6025.1 6065.9 4875.3 5852.9 5865.7 5878.9 5925.8 5792.0 6058.5 6079.8 6088.1 6097.8 5713.8 5836.4 5938.6 5942.3 5992.6 22:43:35 INFO - PROCESS | 1676 | 22:43:35 INFO - PROCESS | 1676 | [#1] ext.html Cycles:180 Average:70209.78 Median:19487.54 stddev:156417.72 (802.7%) stddev-sans-first:156769.00 22:43:35 INFO - PROCESS | 1676 | Values: 520.6 523.5 538.6 549.2 554.6 30756.7 39294.4 42300.3 42900.8 43118.8 44561.8 44941.8 45248.8 45251.0 45301.5 7377.9 9358.0 9578.5 9602.3 9642.8 8438.0 11337.8 11564.8 11588.2 11804.8 11506.1 11601.5 11602.4 11610.9 11616.6 8461.5 11299.0 11381.0 11553.1 11573.8 11478.5 11581.8 11587.4 11597.0 11598.5 40611.8 40970.0 41006.4 41057.6 41073.0 3486.3 3505.8 3632.6 4021.4 4104.1 31028.7 32242.7 32541.5 32553.8 32619.8 3219.6 3263.5 3263.8 3282.9 3344.3 477650.0 479520.0 480148.0 480233.0 480527.0 34475.1 62879.7 62882.7 63620.7 63645.0 35469.3 36297.8 36532.5 36541.6 36698.4 14930.2 15122.8 15126.5 15149.2 15152.2 11595.6 11737.5 11758.5 11872.9 12685.4 7459.2 7727.0 7911.4 7932.9 8012.4 3220.4 3247.6 3256.1 3260.7 3277.3 957.9 1010.8 1046.2 1095.3 1098.7 23648.3 28024.0 29024.0 30495.7 31238.5 1115.1 1145.8 1151.6 1177.1 1189.6 15856.8 15877.5 15944.6 16330.2 16375.3 699017.0 707922.0 708701.0 709018.0 709354.0 60741.7 61194.0 61296.4 61308.0 61422.7 1308.1 1339.4 1426.6 1445.1 1482.9 31004.2 31789.7 32182.7 32584.5 32681.3 52318.0 52671.5 52776.5 52861.0 52935.2 538566.3 541170.0 542437.0 542825.0 542898.0 21504.4 27130.3 27376.6 27426.3 27907.3 48269.5 48668.8 48907.8 49747.8 49861.5 38330.6 40015.9 40028.8 40258.0 40747.8 39112.4 39287.0 40102.8 40251.8 40369.0 38540.0 38956.0 39308.4 39320.2 39370.0 32875.8 34016.5 34733.8 34813.5 34823.3 25264.0 25500.5 25509.8 25630.9 26220.6 22:43:35 INFO - PROCESS | 1676 | 22:43:35 INFO - PROCESS | 1676 | [#2] jquery.html Cycles:180 Average:33924.40 Median:14799.54 stddev:98648.35 (666.6%) stddev-sans-first:98892.80 22:43:35 INFO - PROCESS | 1676 | Values: 312.5 386.4 395.5 405.2 410.8 15169.1 15750.1 19817.2 21904.7 22001.2 20046.6 20630.5 21394.9 21627.4 21836.9 5001.4 5308.3 5848.7 6069.9 6080.0 5337.1 5884.9 5969.7 5981.2 6039.7 5727.1 6014.6 6023.7 6072.7 6089.8 6018.9 6059.5 6072.9 6075.8 6077.2 5089.3 5407.5 5409.6 5412.4 5417.8 13199.1 13511.9 17424.7 18992.7 27021.7 2201.6 2319.4 2354.0 2499.5 2577.3 9509.0 9847.3 9920.8 10230.2 10447.0 1788.6 1808.4 1813.2 1817.1 1860.6 95731.0 99878.5 103259.0 103949.5 104357.5 8222.2 9376.8 10102.4 10107.7 10235.8 12310.3 12337.4 12416.3 12418.8 12439.5 8074.8 8299.8 8311.3 8374.2 8375.0 7401.9 7870.5 8196.0 8641.0 8762.4 3892.6 3905.8 3915.6 3961.9 3967.6 1661.3 1789.9 1795.3 1913.7 1950.8 899.9 927.3 967.0 988.3 1000.4 16530.9 19505.2 20580.0 20593.0 21020.9 972.1 987.3 992.2 997.2 1011.1 10769.2 10862.6 10881.3 10901.3 10975.5 582798.0 584786.0 590484.0 600745.0 602518.0 115715.0 129764.0 130753.0 132286.0 133878.0 993.9 1015.1 1033.6 1035.1 1036.6 16468.3 16927.2 17722.2 17992.5 18345.0 45803.0 49096.8 51390.0 52242.7 52648.9 75137.2 77640.0 79118.6 79666.2 80090.0 7878.3 8184.1 8238.9 8270.8 8441.7 9109.4 12814.5 13142.1 13225.4 13227.2 10989.2 11338.1 11360.2 11488.8 11577.8 11140.3 11166.1 11284.5 11449.9 11518.3 11245.5 11625.8 11784.4 11802.6 11884.9 8091.8 8115.8 8142.2 8187.8 8359.7 8295.0 11270.2 11368.0 11378.5 11573.6 22:43:35 INFO - PROCESS | 1676 | 22:43:35 INFO - PROCESS | 1676 | [#3] mootools.html Cycles:180 Average:5693.26 Median:5118.00 stddev:3160.20 (61.7%) stddev-sans-first:3152.55 22:43:35 INFO - PROCESS | 1676 | Values: 1394.8 1517.8 1557.0 1771.5 1942.1 7781.3 8209.7 8229.8 8239.4 8241.8 8341.2 8588.2 8600.7 8737.4 8738.8 2356.8 2435.7 2438.5 2439.5 2440.6 2071.2 2221.5 2225.7 2359.9 2403.0 2358.2 2389.5 2396.7 2398.3 2401.6 2336.6 2338.4 2375.9 2380.9 2388.0 2390.5 2396.9 2421.4 2422.4 2427.6 8698.9 8776.1 8777.2 8792.4 8823.1 5226.3 5249.9 5433.8 5442.9 5452.2 3578.9 3693.5 3743.1 3755.4 3767.1 1073.8 1096.7 1108.2 1113.7 1135.3 11606.2 11677.5 11684.2 11722.2 11732.1 4520.8 4581.8 4582.5 4629.6 4680.0 9076.5 9160.5 9162.7 9163.6 9171.7 5272.2 5281.1 5286.1 5287.8 5289.9 3812.5 4459.9 4556.5 4954.8 4963.2 3776.3 3813.6 3834.3 3869.6 3886.1 1170.4 1185.2 1185.8 1198.1 1200.5 670.5 841.3 880.1 990.5 1007.1 5066.5 5310.7 5701.9 6115.3 6304.1 920.2 958.1 974.5 998.9 1019.2 3959.7 4086.4 4095.1 4152.9 4157.9 10525.4 10840.3 10867.9 10887.3 10895.0 10762.7 10862.9 10865.7 10875.4 10899.5 7075.8 7708.5 8175.1 8179.2 8307.8 5965.3 6003.3 6063.3 6113.5 6211.0 9784.0 9932.4 10088.4 10104.6 10115.0 9978.4 9990.9 10135.3 10140.6 10274.4 7475.4 7539.4 7652.3 7653.1 7659.5 8072.1 8219.6 8219.9 8223.0 8235.1 7323.7 7374.4 7408.6 7472.3 7556.2 7293.2 7294.5 7354.7 7487.3 7512.0 6763.6 7028.1 7032.3 7036.2 7107.8 4812.8 5072.6 5163.4 5304.9 5313.7 5224.0 5375.6 5416.4 5438.2 5550.1 22:43:35 INFO - PROCESS | 1676 | 22:43:35 INFO - PROCESS | 1676 | [#4] prototype.html Cycles:180 Average:4470.91 Median:4957.28 stddev:1377.17 (27.8%) stddev-sans-first:1344.97 22:43:35 INFO - PROCESS | 1676 | Values: 299.9 306.9 314.8 318.8 322.1 4561.7 5116.0 5287.8 5331.3 5357.2 5056.4 5213.4 5260.3 5280.7 5337.2 3145.5 4043.9 4064.1 4083.7 4353.3 3650.6 3758.7 3822.6 4043.0 4099.8 4030.1 4037.5 4046.3 4050.3 4083.4 3554.8 3970.7 3991.0 4033.7 4080.9 2971.5 3481.4 3522.9 3555.1 3556.5 4743.5 4896.6 5009.7 5030.5 5031.9 3922.6 4583.3 4591.4 4615.4 4623.4 4817.5 5460.7 5464.4 5487.3 5498.2 3513.6 3522.3 3631.5 3801.3 3805.8 5338.5 5801.2 5803.6 5836.5 5854.4 3285.4 3853.3 3854.7 3862.8 3870.6 2970.5 3909.9 3917.9 3942.7 3972.5 4512.4 4547.5 4547.8 4564.9 4583.0 4938.2 5535.6 5543.0 5550.2 5643.7 1174.0 1205.8 1225.6 1248.6 1293.6 832.9 858.0 897.6 900.6 907.3 4889.8 5102.3 5202.2 5202.4 5211.6 4410.8 4486.3 5571.9 5683.4 5734.5 4311.5 4563.2 4879.5 4970.8 5203.8 2978.3 2988.8 2992.3 2993.8 3001.1 4943.7 5349.3 5711.8 5910.3 5942.9 5380.5 5537.8 5920.2 6000.9 6018.6 5930.9 5979.9 6042.6 6051.1 6076.5 4911.2 5100.5 5443.2 5776.7 5820.6 5018.6 5646.7 5665.2 5682.3 5686.0 5015.7 5609.5 5956.8 5966.2 5980.3 3654.0 3780.5 3821.1 3827.6 3851.7 4674.4 5297.3 5447.4 5624.4 5701.1 4733.3 4775.5 5107.8 5233.5 5359.9 4979.5 5253.8 5571.8 5591.5 5612.2 4698.4 5287.7 5303.3 5459.5 5496.6 5552.7 5578.0 5600.2 5600.3 5608.4 4589.6 4764.0 4877.7 4934.6 5028.5 22:43:35 INFO - PROCESS | 1676 | 22:43:35 INFO - PROCESS | 1676 | [#5] yui.html Cycles:180 Average:22544.18 Median:4516.28 stddev:34266.33 (758.7%) stddev-sans-first:34323.46 22:43:35 INFO - PROCESS | 1676 | Values: 770.7 788.6 795.4 815.7 868.9 12677.6 15431.8 15583.5 15629.6 17031.0 15882.2 15900.0 15917.0 15928.3 15984.5 722.7 903.2 919.0 919.8 920.4 730.9 884.0 916.6 917.5 917.8 950.5 954.7 956.2 956.4 956.5 753.9 927.4 951.8 952.6 953.3 709.7 710.4 711.0 711.7 712.8 15405.3 15487.1 15488.1 15496.8 15517.6 11317.9 13946.7 13951.3 13980.9 14057.9 12928.8 13240.9 13324.0 13377.3 13601.9 4556.3 4603.7 4725.3 4770.8 4781.9 108805.9 109391.5 111857.0 112378.4 113908.0 10852.8 10903.8 10918.0 10954.2 11007.4 21253.1 21267.1 21308.0 21316.0 21637.5 7920.6 7921.1 7928.8 8070.0 8120.4 4438.9 4461.6 4578.2 4687.6 4704.9 3441.6 3561.4 3563.1 3564.9 3572.4 649.0 653.5 663.3 665.1 666.3 626.7 629.1 630.4 644.1 660.0 8659.5 9056.8 9064.3 9184.2 9321.3 468.9 631.6 645.4 692.6 702.6 4476.2 4594.2 4673.5 4703.2 4710.7 118824.4 122403.0 124958.4 127878.0 131429.0 94522.5 97592.0 106634.0 107667.0 111176.0 90412.5 90701.1 90766.1 91153.0 94357.0 8974.9 9012.5 9041.3 9083.5 9234.0 52462.7 55419.2 55774.0 55790.7 55841.4 77059.2 84875.0 85237.1 85901.6 88350.0 10136.6 11522.4 11667.0 11875.8 11991.8 13010.2 13080.5 13147.1 13406.7 13414.7 11912.2 12242.8 12277.0 12407.0 12584.1 12026.9 12296.2 12372.4 12541.8 12765.7 11789.4 12973.6 13032.5 13666.5 13696.5 11164.7 11295.5 11313.5 11628.9 11869.4 10611.8 10634.4 10696.1 10760.0 11055.8 22:43:35 INFO - PROCESS | 1676 | -------- Summary: end -------- 22:43:35 INFO - PROCESS | 1676 | 22:43:35 INFO - TEST-INFO | 1676: exit 0 22:43:35 INFO - TEST-OK | dromaeo_css | took 1115936ms 22:43:35 INFO - TEST-START | kraken 22:43:35 INFO - operating with platform_type : mac_ 22:43:35 INFO - Initialising browser for kraken test... 22:43:35 INFO - TEST-INFO | started process 1684 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/tmpxsMPTh/profile http://localhost:49275/getInfo.html) 22:43:37 INFO - PROCESS | 1684 | __metrics Screen width/height:1600/1200 22:43:37 INFO - PROCESS | 1684 | colorDepth:24 22:43:37 INFO - PROCESS | 1684 | Browser inner width/height: 1024/689 22:43:37 INFO - PROCESS | 1684 | __metrics 22:43:37 INFO - PROCESS | 1684 | 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" 22:43:37 ERROR - PROCESS | 1684 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:43:38 INFO - PROCESS | 1684 | ************************* 22:43:38 INFO - PROCESS | 1684 | A coding exception was thrown in a Promise resolution callback. 22:43:38 INFO - PROCESS | 1684 | See https://developer.mozilla.org/Mozilla/JavaScript_code_modules/Promise.jsm/Promise 22:43:38 INFO - PROCESS | 1684 | 22:43:38 ERROR - PROCESS | 1684 | Full message: TypeError: win is null 22:43:38 INFO - PROCESS | 1684 | Full stack: onSuccess@resource://app/components/nsBrowserGlue.js:850:17 22:43:38 INFO - PROCESS | 1684 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 22:43:38 INFO - PROCESS | 1684 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 22:43:38 INFO - PROCESS | 1684 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 22:43:38 INFO - PROCESS | 1684 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 22:43:38 INFO - PROCESS | 1684 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 22:43:38 INFO - PROCESS | 1684 | get _worker/worker.onmessage@resource://gre/modules/PromiseWorker.jsm:235:9 22:43:38 INFO - PROCESS | 1684 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 22:43:38 INFO - PROCESS | 1684 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 22:43:38 INFO - PROCESS | 1684 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 22:43:38 INFO - PROCESS | 1684 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 22:43:38 INFO - PROCESS | 1684 | Barrier/this.client.addBlocker/promise53.7% 22:44:35 INFO - TinderboxPrint: I/O read bytes / time
31,313,408 / 196 22:44:35 INFO - TinderboxPrint: I/O write bytes / time
700,868,096 / 2,022 22:44:35 INFO - TinderboxPrint: CPU idle
2,244.0 (46.3%) 22:44:35 INFO - TinderboxPrint: CPU system
962.7 (19.9%) 22:44:35 INFO - TinderboxPrint: CPU user
1,639.0 (33.8%) 22:44:35 INFO - TinderboxPrint: Swap in / out
429,600,768 / 0 22:44:35 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 402436608; Write bytes: 403731968; Read time: 29984; Write time: 886 22:44:35 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 29531648; Write bytes: 296222720; Read time: 183; Write time: 1133 22:44:35 INFO - Running post-run listener: _upload_blobber_files 22:44:35 INFO - Blob upload gear active. 22:44:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:44:35 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 22:44:35 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'] 22:44:35 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 22:44:36 INFO - (blobuploader) - INFO - Open directory for files ... 22:44:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:44:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:44:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:44:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:44:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:44:37 INFO - (blobuploader) - INFO - Done attempting. 22:44:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:44:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:44:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:44:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:44:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:44:38 INFO - (blobuploader) - INFO - Done attempting. 22:44:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:44:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:44:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:44:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:44:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:44:40 INFO - (blobuploader) - INFO - Done attempting. 22:44:40 INFO - (blobuploader) - INFO - Iteration through files over. 22:44:40 INFO - Return code: 0 22:44:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:44:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:44:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/828b1a1ab3fb7c08fff69a7dc948737d5bcc7b0400c37550cd65a4907e31749e03fbc2975574de4be51be24f79e9d32e74ba50c679661cc9caf37dc35551cec7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a826393d00de996a1c02d2ac21d36a17219e234760e70c3087a5b5a7e28d5103f834cbfb729613171ebffed1e43c97b057be27e2ff96000ca0372a4d131f69b1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/af32402f0c963166045a5b65f7020b3c4095b709c0a46d343e28b94b73d7c0237e4a0469637172627b3d84a742e870615e64ab939dbeee44fbcc51705b0b1951"} 22:44:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:44:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:44:40 INFO - Contents: 22:44:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/828b1a1ab3fb7c08fff69a7dc948737d5bcc7b0400c37550cd65a4907e31749e03fbc2975574de4be51be24f79e9d32e74ba50c679661cc9caf37dc35551cec7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a826393d00de996a1c02d2ac21d36a17219e234760e70c3087a5b5a7e28d5103f834cbfb729613171ebffed1e43c97b057be27e2ff96000ca0372a4d131f69b1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/af32402f0c963166045a5b65f7020b3c4095b709c0a46d343e28b94b73d7c0237e4a0469637172627b3d84a742e870615e64ab939dbeee44fbcc51705b0b1951"} 22:44:40 INFO - Running post-run listener: copy_logs_to_upload_dir 22:44:40 INFO - Copying logs to upload dir... 22:44:40 INFO - mkdir: /builds/slave/test/build/upload/logs 22:44:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.645446 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2016-06-07 22:44:40.754850) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-07 22:44:40.758477) ========= 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.aWeEzgzXX1/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.QUWSzrWsio/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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/828b1a1ab3fb7c08fff69a7dc948737d5bcc7b0400c37550cd65a4907e31749e03fbc2975574de4be51be24f79e9d32e74ba50c679661cc9caf37dc35551cec7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a826393d00de996a1c02d2ac21d36a17219e234760e70c3087a5b5a7e28d5103f834cbfb729613171ebffed1e43c97b057be27e2ff96000ca0372a4d131f69b1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/af32402f0c963166045a5b65f7020b3c4095b709c0a46d343e28b94b73d7c0237e4a0469637172627b3d84a742e870615e64ab939dbeee44fbcc51705b0b1951"} build_url:https://queue.taskcluster.net/v1/task/RnWcIvrUTgeZI6AuVfNNvg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011244 build_url: 'https://queue.taskcluster.net/v1/task/RnWcIvrUTgeZI6AuVfNNvg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/828b1a1ab3fb7c08fff69a7dc948737d5bcc7b0400c37550cd65a4907e31749e03fbc2975574de4be51be24f79e9d32e74ba50c679661cc9caf37dc35551cec7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a826393d00de996a1c02d2ac21d36a17219e234760e70c3087a5b5a7e28d5103f834cbfb729613171ebffed1e43c97b057be27e2ff96000ca0372a4d131f69b1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/af32402f0c963166045a5b65f7020b3c4095b709c0a46d343e28b94b73d7c0237e4a0469637172627b3d84a742e870615e64ab939dbeee44fbcc51705b0b1951"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-07 22:44:40.790341) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 22:44:40.790677) ========= 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.aWeEzgzXX1/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.QUWSzrWsio/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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.005075 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 22:44:40.838759) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-07 22:44:40.839082) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-07 22:44:40.839426) ========= ========= Total master_lag: 0.20 =========